builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0114 starttime: 1495707202.06 results: success (0) revision: 6fae88419de6c9bf1980ef5ad1ad75471e12cc69 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-25 03:13:22.055788) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-25 03:13:22.056239) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-25 03:13:22.056592) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-25 03:13:22.082704) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 03:13:22.083116) ========= 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.Va3I2cScMr/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.gCvqd5sD2a/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-25 03:13:22-- 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% 149M=0s 2017-05-25 03:13:22 (149 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.132937 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 03:13:22.240121) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 03:13:22.240456) ========= 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.Va3I2cScMr/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.gCvqd5sD2a/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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.028578 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 03:13:22.290188) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-25 03:13:22.290569) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 6fae88419de6c9bf1980ef5ad1ad75471e12cc69 --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 6fae88419de6c9bf1980ef5ad1ad75471e12cc69 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Va3I2cScMr/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.gCvqd5sD2a/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-25 03:13:22,383 truncating revision to first 12 chars 2017-05-25 03:13:22,383 Setting DEBUG logging. 2017-05-25 03:13:22,383 attempt 1/10 2017-05-25 03:13:22,383 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6fae88419de6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-25 03:13:24,046 unpacking tar archive at: mozilla-central-6fae88419de6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.942672 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-25 03:13:24.256550) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-25 03:13:24.257027) ========= script_repo_revision: 6fae88419de6c9bf1980ef5ad1ad75471e12cc69 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-25 03:13:24.257610) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-25 03:13:24.258012) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-25 03:13:24.278141) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 20 secs) (at 2017-05-25 03:13:24.278633) ========= /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.Va3I2cScMr/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.gCvqd5sD2a/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 03:13:24 INFO - MultiFileLogger online at 20170525 03:13:24 in /builds/slave/test 03:13:24 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 03:13:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:13:24 INFO - {'append_to_log': False, 03:13:24 INFO - 'base_work_dir': '/builds/slave/test', 03:13:24 INFO - 'blob_upload_branch': 'Firefox', 03:13:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:13:24 INFO - 'branch': 'Firefox', 03:13:24 INFO - 'buildbot_json_path': 'buildprops.json', 03:13:24 INFO - 'config_files': ('talos/mac_config.py',), 03:13:24 INFO - 'default_actions': ('clobber', 03:13:24 INFO - 'read-buildbot-config', 03:13:24 INFO - 'download-and-extract', 03:13:24 INFO - 'populate-webroot', 03:13:24 INFO - 'create-virtualenv', 03:13:24 INFO - 'install', 03:13:24 INFO - 'run-tests'), 03:13:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:13:24 INFO - 'download_minidump_stackwalk': True, 03:13:24 INFO - 'download_symbols': 'ondemand', 03:13:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:13:24 INFO - 'tooltool.py': '/tools/tooltool.py', 03:13:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:13:24 INFO - '/tools/misc-python/virtualenv.py')}, 03:13:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:13:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:13:24 INFO - 'gecko_profile': False, 03:13:24 INFO - 'gecko_profile_interval': 0, 03:13:24 INFO - 'installer_path': 'installer.exe', 03:13:24 INFO - 'log_level': 'info', 03:13:24 INFO - 'log_name': 'talos', 03:13:24 INFO - 'log_to_console': True, 03:13:24 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:13:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:13:24 INFO - 'opt_config_files': (), 03:13:24 INFO - 'pip_index': False, 03:13:24 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:13:24 INFO - 'cmd': ('bash', 03:13:24 INFO - '-c', 03:13:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:13:24 INFO - 'enabled': True, 03:13:24 INFO - 'halt_on_failure': False, 03:13:24 INFO - 'name': 'check_screen_resolution'},), 03:13:24 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:13:24 INFO - 'cmd': ('bash', 03:13:24 INFO - '-c', 03:13:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:13:24 INFO - 'enabled': True, 03:13:24 INFO - 'halt_on_failure': False, 03:13:24 INFO - 'name': 'check_screen_resolution'},), 03:13:24 INFO - 'run_cmd_checks_enabled': True, 03:13:24 INFO - 'suite': 'dromaeojs-e10s', 03:13:24 INFO - 'system_bits': '32', 03:13:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:13:24 INFO - 'title': 't-yosemite-r7-0114', 03:13:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:13:24 INFO - 'use_talos_json': True, 03:13:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:13:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:13:24 INFO - 'work_dir': 'build'} 03:13:24 INFO - [mozharness: 2017-05-25 10:13:24.488829Z] Running clobber step. 03:13:24 INFO - Running pre-action listener: _resource_record_pre_action 03:13:24 INFO - Running main action method: clobber 03:13:24 INFO - rmtree: /builds/slave/test/build 03:13:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:13:27 INFO - Running post-action listener: _resource_record_post_action 03:13:27 INFO - [mozharness: 2017-05-25 10:13:27.106923Z] Finished clobber step (success) 03:13:27 INFO - [mozharness: 2017-05-25 10:13:27.107078Z] Running read-buildbot-config step. 03:13:27 INFO - Running pre-action listener: _resource_record_pre_action 03:13:27 INFO - Running main action method: read_buildbot_config 03:13:27 INFO - Using buildbot properties: 03:13:27 INFO - { 03:13:27 INFO - "project": "", 03:13:27 INFO - "product": "firefox", 03:13:27 INFO - "who": "archaeopteryx@coole-files.de", 03:13:27 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 03:13:27 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 03:13:27 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 03:13:27 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 03:13:27 INFO - "stage_platform": "macosx64", 03:13:27 INFO - "basedir": "/builds/slave/test", 03:13:27 INFO - "buildnumber": 83, 03:13:27 INFO - "platform": "yosemite_r7", 03:13:27 INFO - "parent_task_id": "DcUSSidiSViXKQ3xcxuRIA", 03:13:27 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 03:13:27 INFO - "slavebuilddir": "test", 03:13:27 INFO - "taskId": "CB0Y4CiiSFesCRZQb0xdNQ", 03:13:27 INFO - "branch": "mozilla-central", 03:13:27 INFO - "script_repo_revision": "production", 03:13:27 INFO - "revision": "6fae88419de6c9bf1980ef5ad1ad75471e12cc69", 03:13:27 INFO - "slavename": "t-yosemite-r7-0114", 03:13:27 INFO - "repo_path": "mozilla-central" 03:13:27 INFO - } 03:13:27 INFO - Finding installer, test and symbols from parent task. 03:13:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CB0Y4CiiSFesCRZQb0xdNQ'}, attempt #1 03:13:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CB0Y4CiiSFesCRZQb0xdNQ'}, attempt #1 03:13:28 INFO - Task dependencies: CWxokr6IQbO326W0KkYzEw DcUSSidiSViXKQ3xcxuRIA 03:13:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DcUSSidiSViXKQ3xcxuRIA'}, attempt #1 03:13:28 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/DcUSSidiSViXKQ3xcxuRIA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:13:28 INFO - Set test_url: https://queue.taskcluster.net/v1/task/DcUSSidiSViXKQ3xcxuRIA/artifacts/public/build/test_packages.json 03:13:28 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/DcUSSidiSViXKQ3xcxuRIA/artifacts/public/build/target.crashreporter-symbols.zip 03:13:28 INFO - Running post-action listener: _resource_record_post_action 03:13:28 INFO - [mozharness: 2017-05-25 10:13:28.858245Z] Finished read-buildbot-config step (success) 03:13:28 INFO - [mozharness: 2017-05-25 10:13:28.858381Z] Running download-and-extract step. 03:13:28 INFO - Running pre-action listener: _resource_record_pre_action 03:13:28 INFO - Running main action method: download_and_extract 03:13:28 INFO - mkdir: /builds/slave/test/build/tests 03:13: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')]} 03:13:28 INFO - trying https://queue.taskcluster.net/v1/task/DcUSSidiSViXKQ3xcxuRIA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 03:13:28 INFO - Downloading https://queue.taskcluster.net/v1/task/DcUSSidiSViXKQ3xcxuRIA/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 03:13:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DcUSSidiSViXKQ3xcxuRIA/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 03:13:29 INFO - Downloaded 1328 bytes. 03:13:29 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 03:13:29 INFO - Using the following test package requirements: 03:13:29 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:13:29 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 03:13:29 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 03:13:29 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:13:29 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 03:13:29 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:13:29 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 03:13:29 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:13:29 INFO - u'jsshell-mac.zip'], 03:13:29 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:13:29 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 03:13:29 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 03:13:29 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:13:29 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 03:13:29 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:13:29 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 03:13:29 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:13:29 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 03:13:29 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:13:29 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 03:13:29 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:13:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DcUSSidiSViXKQ3xcxuRIA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 03:13:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DcUSSidiSViXKQ3xcxuRIA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:13:29 INFO - Fetch https://queue.taskcluster.net/v1/task/DcUSSidiSViXKQ3xcxuRIA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 03:13:31 INFO - Content-Length response header: 57631923 03:13:31 INFO - Bytes received: 57631923 03:13:49 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 03:13:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DcUSSidiSViXKQ3xcxuRIA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 03:13:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DcUSSidiSViXKQ3xcxuRIA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:13:49 INFO - Fetch https://queue.taskcluster.net/v1/task/DcUSSidiSViXKQ3xcxuRIA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 03:13:51 INFO - Content-Length response header: 57631923 03:13:51 INFO - Bytes received: 57631923 03:14:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DcUSSidiSViXKQ3xcxuRIA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 03:14:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DcUSSidiSViXKQ3xcxuRIA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 03:14:08 INFO - Fetch https://queue.taskcluster.net/v1/task/DcUSSidiSViXKQ3xcxuRIA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 03:14:09 INFO - Content-Length response header: 14411859 03:14:09 INFO - Bytes received: 14411859 03:14:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:14:10 INFO - trying https://queue.taskcluster.net/v1/task/DcUSSidiSViXKQ3xcxuRIA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:14:10 INFO - Downloading https://queue.taskcluster.net/v1/task/DcUSSidiSViXKQ3xcxuRIA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 03:14:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DcUSSidiSViXKQ3xcxuRIA/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 03:14:12 INFO - Downloaded 67200927 bytes. 03:14:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DcUSSidiSViXKQ3xcxuRIA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:14:12 INFO - mkdir: /builds/slave/test/properties 03:14:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:14:12 INFO - Writing to file /builds/slave/test/properties/build_url 03:14:12 INFO - Contents: 03:14:12 INFO - build_url:https://queue.taskcluster.net/v1/task/DcUSSidiSViXKQ3xcxuRIA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:14:12 INFO - Running post-action listener: _resource_record_post_action 03:14:12 INFO - Running post-action listener: set_extra_try_arguments 03:14:12 INFO - [mozharness: 2017-05-25 10:14:12.216776Z] Finished download-and-extract step (success) 03:14:12 INFO - [mozharness: 2017-05-25 10:14:12.216909Z] Running populate-webroot step. 03:14:12 INFO - Running pre-action listener: _resource_record_pre_action 03:14:12 INFO - Running main action method: populate_webroot 03:14:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:14:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:14:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:14:12 INFO - '--tppagecycles', 03:14:12 INFO - '7'], 03:14:12 INFO - 'tests': ['tsvgm']}}, 03:14:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:14:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:14:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:14:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:14:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:14:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:14:12 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 03:14:12 INFO - 'talos_options': ['--disable-e10s'], 03:14:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:14:12 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:14:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:14:12 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 03:14:12 INFO - 'talos_options': ['--disable-e10s'], 03:14:12 INFO - 'tests': ['damp', 'tps']}, 03:14:12 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:14:12 INFO - 'tests': ['damp', 'tps']}, 03:14:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:14:12 INFO - 'tests': ['dromaeo_dom']}, 03:14:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:14:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:14:12 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 03:14:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:14:12 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:14:12 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:14:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:14:12 INFO - 'tests': ['a11yr', 03:14:12 INFO - 'ts_paint', 03:14:12 INFO - 'tpaint', 03:14:12 INFO - 'sessionrestore', 03:14:12 INFO - 'sessionrestore_no_auto_restore', 03:14:12 INFO - 'tabpaint']}, 03:14:12 INFO - 'other-e10s': {'tests': ['a11yr', 03:14:12 INFO - 'ts_paint', 03:14:12 INFO - 'tpaint', 03:14:12 INFO - 'sessionrestore', 03:14:12 INFO - 'sessionrestore_no_auto_restore', 03:14:12 INFO - 'tabpaint']}, 03:14:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:14:12 INFO - 'ts_paint', 03:14:12 INFO - 'tpaint', 03:14:12 INFO - 'sessionrestore', 03:14:12 INFO - 'sessionrestore_no_auto_restore', 03:14:12 INFO - 'tabpaint']}, 03:14:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:14:12 INFO - 'ts_paint', 03:14:12 INFO - 'tpaint', 03:14:12 INFO - 'sessionrestore', 03:14:12 INFO - 'sessionrestore_no_auto_restore', 03:14:12 INFO - 'tabpaint']}, 03:14:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:14:12 INFO - 'tests': ['a11yr', 03:14:12 INFO - 'ts_paint', 03:14:12 INFO - 'tpaint', 03:14:12 INFO - 'sessionrestore', 03:14:12 INFO - 'sessionrestore_no_auto_restore', 03:14:12 INFO - 'tabpaint']}, 03:14:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:14:12 INFO - 'tests': ['a11yr', 03:14:12 INFO - 'ts_paint', 03:14:12 INFO - 'tpaint', 03:14:12 INFO - 'sessionrestore', 03:14:12 INFO - 'sessionrestore_no_auto_restore', 03:14:12 INFO - 'tabpaint']}, 03:14:12 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 03:14:12 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 03:14:12 INFO - 'bloom_basic_ref']}, 03:14:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:14:12 INFO - 'tests': ['tsvgx', 03:14:12 INFO - 'tsvgr_opacity', 03:14:12 INFO - 'tart', 03:14:12 INFO - 'tscrollx', 03:14:12 INFO - 'cart', 03:14:12 INFO - 'tsvg_static']}, 03:14:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:14:12 INFO - 'tsvgr_opacity', 03:14:12 INFO - 'tart', 03:14:12 INFO - 'tscrollx', 03:14:12 INFO - 'cart', 03:14:12 INFO - 'tsvg_static']}, 03:14:12 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 03:14:12 INFO - 'talos_options': ['--disable-e10s'], 03:14:12 INFO - 'tests': ['tp5o']}, 03:14:12 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:14:12 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 03:14:12 INFO - 'talos_options': ['--disable-e10s', 03:14:12 INFO - '--xperf_path', 03:14:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:14:12 INFO - 'tests': ['tp5n']}, 03:14:12 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:14:12 INFO - 'talos_options': ['--xperf_path', 03:14:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:14:12 INFO - 'tests': ['tp5n']}}, 03:14:12 INFO - 'talos.zip': {'path': '', 03:14:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:14:12 INFO - Running post-action listener: _resource_record_post_action 03:14:12 INFO - [mozharness: 2017-05-25 10:14:12.238610Z] Finished populate-webroot step (success) 03:14:12 INFO - [mozharness: 2017-05-25 10:14:12.238715Z] Running create-virtualenv step. 03:14:12 INFO - Running pre-action listener: _resource_record_pre_action 03:14:12 INFO - Running main action method: create_virtualenv 03:14:12 INFO - Creating virtualenv /builds/slave/test/build/venv 03:14:12 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 03:14:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:14:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:14:12 INFO - Using real prefix '/tools/python27' 03:14:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:14:13 INFO - Installing distribute.............................................................................................................................................................................................done. 03:14:15 INFO - Installing pip.................done. 03:14:15 INFO - Return code: 0 03:14:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:14:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:14:16 INFO - Reading from file tmpfile_stdout 03:14:16 INFO - Output received: 03:14:16 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) 03:14:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:14:16 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')]} 03:14:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:14:16 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')]} 03:14:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:14:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fc1fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc23768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbc8ca3910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f92fd30>, '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 0x10f8e3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f924be8>, '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.Va3I2cScMr/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.gCvqd5sD2a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 03:14:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:14:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:14:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Va3I2cScMr/Render', 03:14:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:14:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:14:16 INFO - 'HOME': '/Users/cltbld', 03:14:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:14:16 INFO - 'LOGNAME': 'cltbld', 03:14:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:14:16 INFO - 'MOZ_NO_REMOTE': '1', 03:14:16 INFO - 'NO_EM_RESTART': '1', 03:14:16 INFO - 'PAGER': '/bin/cat', 03:14:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:14:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:14:16 INFO - 'PWD': '/builds/slave/test', 03:14:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:14:16 INFO - 'SHELL': '/bin/bash', 03:14:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gCvqd5sD2a/Listeners', 03:14:16 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 03:14:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:14:16 INFO - 'USER': 'cltbld', 03:14:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:14:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:14:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:14:16 INFO - 'XPC_FLAGS': '0x0', 03:14:16 INFO - 'XPC_SERVICE_NAME': '0', 03:14:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:14:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:14:16 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 03:14:16 INFO - Cleaning up... 03:14:16 INFO - Return code: 0 03:14:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:14:16 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')]} 03:14:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:14:16 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')]} 03:14:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:14:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10fc1fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc23768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbc8ca3910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f92fd30>, '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 0x10f8e3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f924be8>, '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.Va3I2cScMr/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.gCvqd5sD2a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 03:14:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:14:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:14:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Va3I2cScMr/Render', 03:14:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:14:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:14:16 INFO - 'HOME': '/Users/cltbld', 03:14:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:14:16 INFO - 'LOGNAME': 'cltbld', 03:14:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:14:16 INFO - 'MOZ_NO_REMOTE': '1', 03:14:16 INFO - 'NO_EM_RESTART': '1', 03:14:16 INFO - 'PAGER': '/bin/cat', 03:14:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:14:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:14:16 INFO - 'PWD': '/builds/slave/test', 03:14:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:14:16 INFO - 'SHELL': '/bin/bash', 03:14:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gCvqd5sD2a/Listeners', 03:14:16 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 03:14:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:14:16 INFO - 'USER': 'cltbld', 03:14:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:14:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:14:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:14:16 INFO - 'XPC_FLAGS': '0x0', 03:14:16 INFO - 'XPC_SERVICE_NAME': '0', 03:14:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:14:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:14:16 INFO - Downloading/unpacking psutil>=3.1.1 03:14: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 03:14: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 03:14: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 03:14: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 03:14:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:14:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:14:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:14:18 INFO - Installing collected packages: psutil 03:14:18 INFO - Running setup.py install for psutil 03:14:18 INFO - building 'psutil._psutil_osx' extension 03:14:18 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 03:14:19 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 03:14:19 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 03:14:19 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 03:14:19 INFO - building 'psutil._psutil_posix' extension 03:14:19 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 03:14:19 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:14:19 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:14:19 INFO - ^ 03:14:19 INFO - 1 warning generated. 03:14:19 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 03:14:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:14:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:14:19 INFO - Successfully installed psutil 03:14:19 INFO - Cleaning up... 03:14:19 INFO - Return code: 0 03:14:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:14:19 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')]} 03:14:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:14: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')]} 03:14:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:14: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fc1fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc23768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbc8ca3910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f92fd30>, '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 0x10f8e3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f924be8>, '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.Va3I2cScMr/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.gCvqd5sD2a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 03:14: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:14: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 mozsystemmonitor==0.3 03:14:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Va3I2cScMr/Render', 03:14:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:14:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:14:20 INFO - 'HOME': '/Users/cltbld', 03:14:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:14:20 INFO - 'LOGNAME': 'cltbld', 03:14:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:14:20 INFO - 'MOZ_NO_REMOTE': '1', 03:14:20 INFO - 'NO_EM_RESTART': '1', 03:14:20 INFO - 'PAGER': '/bin/cat', 03:14:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:14:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:14:20 INFO - 'PWD': '/builds/slave/test', 03:14:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:14:20 INFO - 'SHELL': '/bin/bash', 03:14:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gCvqd5sD2a/Listeners', 03:14:20 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 03:14:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:14:20 INFO - 'USER': 'cltbld', 03:14:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:14:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:14:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:14:20 INFO - 'XPC_FLAGS': '0x0', 03:14:20 INFO - 'XPC_SERVICE_NAME': '0', 03:14:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:14:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:14:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:14: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 03:14: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 03:14: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 03:14: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 03:14:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:14:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:14:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:14:22 INFO - Installing collected packages: mozsystemmonitor 03:14:22 INFO - Running setup.py install for mozsystemmonitor 03:14:23 INFO - Successfully installed mozsystemmonitor 03:14:23 INFO - Cleaning up... 03:14:23 INFO - Return code: 0 03:14:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:14:23 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')]} 03:14:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:14:23 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')]} 03:14:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:14:23 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 0x10fc1fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc23768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbc8ca3910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f92fd30>, '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 0x10f8e3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f924be8>, '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.Va3I2cScMr/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.gCvqd5sD2a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 03:14:23 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 03:14:23 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 03:14:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Va3I2cScMr/Render', 03:14:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:14:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:14:23 INFO - 'HOME': '/Users/cltbld', 03:14:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:14:23 INFO - 'LOGNAME': 'cltbld', 03:14:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:14:23 INFO - 'MOZ_NO_REMOTE': '1', 03:14:23 INFO - 'NO_EM_RESTART': '1', 03:14:23 INFO - 'PAGER': '/bin/cat', 03:14:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:14:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:14:23 INFO - 'PWD': '/builds/slave/test', 03:14:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:14:23 INFO - 'SHELL': '/bin/bash', 03:14:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gCvqd5sD2a/Listeners', 03:14:23 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 03:14:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:14:23 INFO - 'USER': 'cltbld', 03:14:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:14:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:14:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:14:23 INFO - 'XPC_FLAGS': '0x0', 03:14:23 INFO - 'XPC_SERVICE_NAME': '0', 03:14:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:14:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:14:23 INFO - Downloading/unpacking jsonschema==2.5.1 03:14: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 03:14: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 03:14: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 03:14: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 03:14:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:14:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:14:24 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 03:14:24 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 03:14:24 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 03:14:24 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 03:14:25 INFO - Downloading functools32-3.2.3-2.tar.gz 03:14:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:14:25 INFO - warning: no files found matching '*.txt' 03:14:25 INFO - no previously-included directories found matching 'build' 03:14:25 INFO - no previously-included directories found matching 'dist' 03:14:25 INFO - no previously-included directories found matching '.git*' 03:14:25 INFO - Installing collected packages: jsonschema, functools32 03:14:25 INFO - Running setup.py install for functools32 03:14:25 INFO - warning: no files found matching '*.txt' 03:14:25 INFO - no previously-included directories found matching 'build' 03:14:25 INFO - no previously-included directories found matching 'dist' 03:14:25 INFO - no previously-included directories found matching '.git*' 03:14:25 INFO - Successfully installed jsonschema functools32 03:14:25 INFO - Cleaning up... 03:14:25 INFO - Return code: 0 03:14:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:14:25 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')]} 03:14:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:14:25 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')]} 03:14:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:14:25 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 0x10fc1fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc23768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbc8ca3910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f92fd30>, '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 0x10f8e3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f924be8>, '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.Va3I2cScMr/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.gCvqd5sD2a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 03:14:25 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 03:14:25 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 03:14:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Va3I2cScMr/Render', 03:14:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:14:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:14:25 INFO - 'HOME': '/Users/cltbld', 03:14:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:14:25 INFO - 'LOGNAME': 'cltbld', 03:14:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:14:25 INFO - 'MOZ_NO_REMOTE': '1', 03:14:25 INFO - 'NO_EM_RESTART': '1', 03:14:25 INFO - 'PAGER': '/bin/cat', 03:14:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:14:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:14:25 INFO - 'PWD': '/builds/slave/test', 03:14:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:14:25 INFO - 'SHELL': '/bin/bash', 03:14:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gCvqd5sD2a/Listeners', 03:14:25 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 03:14:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:14:25 INFO - 'USER': 'cltbld', 03:14:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:14:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:14:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:14:25 INFO - 'XPC_FLAGS': '0x0', 03:14:25 INFO - 'XPC_SERVICE_NAME': '0', 03:14:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:14:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:14:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:14:25 INFO - Cleaning up... 03:14:25 INFO - Return code: 0 03:14:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:14:25 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')]} 03:14:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:14:25 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')]} 03:14:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:14:25 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 0x10fc1fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc23768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbc8ca3910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f92fd30>, '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 0x10f8e3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f924be8>, '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.Va3I2cScMr/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.gCvqd5sD2a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 03:14:25 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 03:14:25 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 03:14:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Va3I2cScMr/Render', 03:14:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:14:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:14:25 INFO - 'HOME': '/Users/cltbld', 03:14:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:14:25 INFO - 'LOGNAME': 'cltbld', 03:14:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:14:25 INFO - 'MOZ_NO_REMOTE': '1', 03:14:25 INFO - 'NO_EM_RESTART': '1', 03:14:25 INFO - 'PAGER': '/bin/cat', 03:14:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:14:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:14:25 INFO - 'PWD': '/builds/slave/test', 03:14:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:14:25 INFO - 'SHELL': '/bin/bash', 03:14:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gCvqd5sD2a/Listeners', 03:14:25 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 03:14:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:14:25 INFO - 'USER': 'cltbld', 03:14:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:14:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:14:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:14:25 INFO - 'XPC_FLAGS': '0x0', 03:14:25 INFO - 'XPC_SERVICE_NAME': '0', 03:14:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:14:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:14:25 INFO - Downloading/unpacking blobuploader==1.2.4 03:14:25 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 03:14:25 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 03:14:25 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 03:14:25 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 03:14:27 INFO - Downloading blobuploader-1.2.4.tar.gz 03:14:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:14:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:14:27 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 03:14:27 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 03:14:27 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 03:14:27 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 03:14:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:14:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:14:28 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 03:14:28 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 03:14:28 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 03:14:28 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 03:14:28 INFO - Downloading docopt-0.6.1.tar.gz 03:14:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:14:28 INFO - Installing collected packages: blobuploader, requests, docopt 03:14:28 INFO - Running setup.py install for blobuploader 03:14:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:14:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:14:28 INFO - Running setup.py install for requests 03:14:29 INFO - Running setup.py install for docopt 03:14:29 INFO - Successfully installed blobuploader requests docopt 03:14:29 INFO - Cleaning up... 03:14:29 INFO - Return code: 0 03:14:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:14:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:14:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:14:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:14:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:14:29 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 0x10fc1fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc23768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbc8ca3910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f92fd30>, '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 0x10f8e3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f924be8>, '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.Va3I2cScMr/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.gCvqd5sD2a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 03:14:29 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 03:14:29 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 03:14:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Va3I2cScMr/Render', 03:14:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:14:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:14:29 INFO - 'HOME': '/Users/cltbld', 03:14:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:14:29 INFO - 'LOGNAME': 'cltbld', 03:14:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:14:29 INFO - 'MOZ_NO_REMOTE': '1', 03:14:29 INFO - 'NO_EM_RESTART': '1', 03:14:29 INFO - 'PAGER': '/bin/cat', 03:14:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:14:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:14:29 INFO - 'PWD': '/builds/slave/test', 03:14:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:14:29 INFO - 'SHELL': '/bin/bash', 03:14:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gCvqd5sD2a/Listeners', 03:14:29 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 03:14:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:14:29 INFO - 'USER': 'cltbld', 03:14:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:14:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:14:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:14:29 INFO - 'XPC_FLAGS': '0x0', 03:14:29 INFO - 'XPC_SERVICE_NAME': '0', 03:14:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:14:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:14:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:14:29 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-ehT_fL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:14:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:14:29 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-Ze2kMz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:14:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:14:29 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-jr8_gi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:14:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:14:29 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-NyxO3Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:14:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:14:29 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-qmlG9P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:14:30 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-a2Encx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:14:30 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-pJfrZQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:14:30 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-Ql4F0f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:14:30 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-HDwu1D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:14:30 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-x71Z8g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:14:30 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-knleJ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:14:30 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-rJo3FD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:14:30 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-q7bUua-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:14:30 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-8ctIrP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:14:31 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-jcpXkP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:14:31 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-YfZX7h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:14:31 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-HNcjVy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:14:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:14:31 INFO - Running setup.py install for manifestparser 03:14:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:14:31 INFO - Running setup.py install for mozcrash 03:14:31 INFO - Running setup.py install for mozdebug 03:14:31 INFO - Running setup.py install for mozdevice 03:14:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:14:31 INFO - Running setup.py install for mozfile 03:14:32 INFO - Running setup.py install for mozhttpd 03:14:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:14:32 INFO - Running setup.py install for mozinfo 03:14:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:14:32 INFO - Running setup.py install for mozInstall 03:14:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:14:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:14:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:14:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:14:32 INFO - Running setup.py install for mozleak 03:14:32 INFO - Running setup.py install for mozlog 03:14:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:14:32 INFO - Running setup.py install for moznetwork 03:14:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:14:32 INFO - Running setup.py install for mozprocess 03:14:33 INFO - Running setup.py install for mozprofile 03:14:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:14:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:14:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:14:33 INFO - Running setup.py install for mozrunner 03:14:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:14:33 INFO - Running setup.py install for mozscreenshot 03:14:33 INFO - Running setup.py install for moztest 03:14:33 INFO - Running setup.py install for mozversion 03:14:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:14:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:14:33 INFO - Cleaning up... 03:14:33 INFO - Return code: 0 03:14:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:14:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:14:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:14:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:14:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:14:33 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 0x10fc1fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc23768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbc8ca3910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f92fd30>, '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 0x10f8e3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f924be8>, '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.Va3I2cScMr/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.gCvqd5sD2a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 03:14:33 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 03:14:33 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 03:14:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Va3I2cScMr/Render', 03:14:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:14:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:14:33 INFO - 'HOME': '/Users/cltbld', 03:14:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:14:33 INFO - 'LOGNAME': 'cltbld', 03:14:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:14:33 INFO - 'MOZ_NO_REMOTE': '1', 03:14:33 INFO - 'NO_EM_RESTART': '1', 03:14:33 INFO - 'PAGER': '/bin/cat', 03:14:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:14:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:14:33 INFO - 'PWD': '/builds/slave/test', 03:14:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:14:33 INFO - 'SHELL': '/bin/bash', 03:14:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gCvqd5sD2a/Listeners', 03:14:33 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 03:14:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:14:33 INFO - 'USER': 'cltbld', 03:14:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:14:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:14:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:14:33 INFO - 'XPC_FLAGS': '0x0', 03:14:33 INFO - 'XPC_SERVICE_NAME': '0', 03:14:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:14:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:14:34 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-DzAluY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:14:34 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)) 03:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:14:34 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-xwkygw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:14:34 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)) 03:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:14:34 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-ZDRir6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:14:34 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)) 03:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:14:34 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-tgjdFc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:14:34 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)) 03:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:14:34 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-KdxcJm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:14:34 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)) 03:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:14:34 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-7Wqh5e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:14:34 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)) 03:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:14:34 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-PcBEUQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:14:34 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)) 03:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:14:34 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-O3KmOF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:14:34 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)) 03:14:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:14:34 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-NSLA5t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:14:35 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)) 03:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:14:35 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-0BcKeA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:14:35 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)) 03:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:14:35 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-V6a5XS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:14:35 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)) 03:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:14:35 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-i335mg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:14:35 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)) 03:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:14:35 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-vGAO3Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:14:35 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)) 03:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:14:35 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-Pgevak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:14:35 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)) 03:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:14:35 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-PcPeGq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:14:35 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)) 03:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:14:35 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-WH35uF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:14:35 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)) 03:14:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:14:35 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-CUhw7V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:14:35 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)) 03:14:35 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)) 03:14:35 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)) 03:14:35 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)) 03:14:35 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)) 03:14:35 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)) 03:14:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:14:35 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 03:14:35 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 03:14:35 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 03:14:35 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 03:14:37 INFO - Downloading blessings-1.6.tar.gz 03:14:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:14:37 INFO - Installing collected packages: blessings 03:14:37 INFO - Running setup.py install for blessings 03:14:37 INFO - Successfully installed blessings 03:14:37 INFO - Cleaning up... 03:14:37 INFO - Return code: 0 03:14:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:14:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:14:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:14:37 INFO - Reading from file tmpfile_stdout 03:14:37 INFO - Current package versions: 03:14:37 INFO - blessings == 1.6 03:14:37 INFO - blobuploader == 1.2.4 03:14:37 INFO - docopt == 0.6.1 03:14:37 INFO - functools32 == 3.2.3-2 03:14:37 INFO - jsonschema == 2.5.1 03:14:37 INFO - manifestparser == 1.1 03:14:37 INFO - mozInstall == 1.12 03:14:37 INFO - mozcrash == 1.0 03:14:37 INFO - mozdebug == 0.1 03:14:37 INFO - mozdevice == 0.51 03:14:37 INFO - mozfile == 1.2 03:14:37 INFO - mozhttpd == 0.7 03:14:37 INFO - mozinfo == 0.9 03:14:37 INFO - mozleak == 0.1 03:14:37 INFO - mozlog == 3.4 03:14:37 INFO - moznetwork == 0.27 03:14:37 INFO - mozprocess == 0.25 03:14:37 INFO - mozprofile == 0.28 03:14:37 INFO - mozrunner == 6.13 03:14:37 INFO - mozscreenshot == 0.1 03:14:37 INFO - mozsystemmonitor == 0.3 03:14:37 INFO - moztest == 0.8 03:14:37 INFO - mozversion == 1.4 03:14:37 INFO - psutil == 3.1.1 03:14:37 INFO - requests == 1.2.3 03:14:37 INFO - wsgiref == 0.1.2 03:14:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:14:37 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')]} 03:14:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:14:37 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')]} 03:14:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:14:37 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 0x10fc1fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc23768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbc8ca3910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f92fd30>, '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 0x10f8e3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f924be8>, '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.Va3I2cScMr/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.gCvqd5sD2a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 03:14:37 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 03:14:37 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 03:14:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Va3I2cScMr/Render', 03:14:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:14:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:14:37 INFO - 'HOME': '/Users/cltbld', 03:14:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:14:37 INFO - 'LOGNAME': 'cltbld', 03:14:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:14:37 INFO - 'MOZ_NO_REMOTE': '1', 03:14:37 INFO - 'NO_EM_RESTART': '1', 03:14:37 INFO - 'PAGER': '/bin/cat', 03:14:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:14:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:14:37 INFO - 'PWD': '/builds/slave/test', 03:14:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:14:37 INFO - 'SHELL': '/bin/bash', 03:14:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gCvqd5sD2a/Listeners', 03:14:37 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 03:14:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:14:37 INFO - 'USER': 'cltbld', 03:14:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:14:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:14:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:14:37 INFO - 'XPC_FLAGS': '0x0', 03:14:37 INFO - 'XPC_SERVICE_NAME': '0', 03:14:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:14:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:14:38 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)) 03:14:38 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)) 03:14:38 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)) 03:14:38 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)) 03:14:38 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)) 03:14:38 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)) 03:14:38 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)) 03:14:38 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)) 03:14:38 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)) 03:14:38 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:14:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:14:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:14:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:14:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:14:39 INFO - Downloading marionette_driver-1.4.0.tar.gz 03:14:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 03:14:39 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)) 03:14:39 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)) 03:14:39 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)) 03:14:39 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)) 03:14:39 INFO - Installing collected packages: marionette-driver 03:14:39 INFO - Running setup.py install for marionette-driver 03:14:39 INFO - Successfully installed marionette-driver 03:14:39 INFO - Cleaning up... 03:14:39 INFO - Return code: 0 03:14:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:14:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:14:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:14:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:14:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:14:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fc1fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fc23768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdbc8ca3910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f92fd30>, '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 0x10f8e3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f924be8>, '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.Va3I2cScMr/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.gCvqd5sD2a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 03:14:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:14:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:14:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Va3I2cScMr/Render', 03:14:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:14:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:14:39 INFO - 'HOME': '/Users/cltbld', 03:14:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:14:39 INFO - 'LOGNAME': 'cltbld', 03:14:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:14:39 INFO - 'MOZ_NO_REMOTE': '1', 03:14:39 INFO - 'NO_EM_RESTART': '1', 03:14:39 INFO - 'PAGER': '/bin/cat', 03:14:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:14:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:14:39 INFO - 'PWD': '/builds/slave/test', 03:14:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:14:39 INFO - 'SHELL': '/bin/bash', 03:14:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gCvqd5sD2a/Listeners', 03:14:39 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 03:14:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:14:39 INFO - 'USER': 'cltbld', 03:14:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:14:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:14:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:14:39 INFO - 'XPC_FLAGS': '0x0', 03:14:39 INFO - 'XPC_SERVICE_NAME': '0', 03:14:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:14:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:14:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:14:40 INFO - Cleaning up... 03:14:40 INFO - Return code: 0 03:14:40 INFO - Running post-action listener: _resource_record_post_action 03:14:40 INFO - Running post-action listener: _start_resource_monitoring 03:14:40 INFO - Starting resource monitoring. 03:14:40 INFO - [mozharness: 2017-05-25 10:14:40.169741Z] Finished create-virtualenv step (success) 03:14:40 INFO - [mozharness: 2017-05-25 10:14:40.170687Z] Running install step. 03:14:40 INFO - Running pre-action listener: _resource_record_pre_action 03:14:40 INFO - Running main action method: install 03:14:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:14:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:14:40 INFO - Reading from file tmpfile_stdout 03:14:40 INFO - Detecting whether we're running mozinstall >=1.0... 03:14:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:14:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:14:40 INFO - Reading from file tmpfile_stdout 03:14:40 INFO - Output received: 03:14:40 INFO - Usage: mozinstall [options] installer 03:14:40 INFO - Options: 03:14:40 INFO - -h, --help show this help message and exit 03:14:40 INFO - -d DEST, --destination=DEST 03:14:40 INFO - Directory to install application into. [default: 03:14:40 INFO - "/builds/slave/test"] 03:14:40 INFO - --app=APP Application being installed. [default: firefox] 03:14:40 INFO - mkdir: /builds/slave/test/build/application 03:14:40 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'] 03:14:40 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 03:15:02 INFO - Reading from file tmpfile_stdout 03:15:02 INFO - Output received: 03:15:02 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:15:02 INFO - Running post-action listener: _resource_record_post_action 03:15:02 INFO - [mozharness: 2017-05-25 10:15:02.098788Z] Finished install step (success) 03:15:02 INFO - [mozharness: 2017-05-25 10:15:02.098965Z] Running run-tests step. 03:15:02 INFO - Running pre-action listener: _resource_record_pre_action 03:15:02 INFO - Running main action method: run_tests 03:15:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:15:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:15:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:15:02 INFO - 2017-05-25 03:15:02.113 screenresolution[1625:7535] starting screenresolution argv=screenresolution get 03:15:02 INFO - 2017-05-25 03:15:02.116 screenresolution[1625:7535] Display 0: 1600x1200x32@60 03:15:02 INFO - 2017-05-25 03:15:02.122 screenresolution[1626:7537] starting screenresolution argv=screenresolution list 03:15:02 INFO - Available Modes on Display 0 03:15:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:15:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:15:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:15:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:15:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:15:02 INFO - Intel Iris: 03:15:02 INFO - Chipset Model: Intel Iris 03:15:02 INFO - Type: GPU 03:15:02 INFO - Bus: Built-In 03:15:02 INFO - VRAM (Dynamic, Max): 1536 MB 03:15:02 INFO - Vendor: Intel (0x8086) 03:15:02 INFO - Device ID: 0x0a2e 03:15:02 INFO - Revision ID: 0x0009 03:15:02 INFO - Displays: 03:15:02 INFO - Display: 03:15:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:15:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:15:02 INFO - Display Serial Number: 1600x1200 60 03:15:02 INFO - Main Display: Yes 03:15:02 INFO - Mirror: Off 03:15:02 INFO - Online: Yes 03:15:02 INFO - Rotation: Supported 03:15:02 INFO - Return code: 0 03:15:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:15:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:15:02 INFO - Python 2.7.3 03:15:02 INFO - Return code: 0 03:15:02 INFO - grabbing minidump binary from tooltool 03:15:02 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')]} 03:15:02 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 0x10f92fd30>, '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 0x10f8e3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f924be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:15:02 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 03:15:02 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 03:15:02 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 03:15:02 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:15:02 INFO - Return code: 0 03:15:02 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:15:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:15:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:15:02 INFO - ENV: RUST_BACKTRACE is now 1 03:15:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:15:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:15:02 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/DcUSSidiSViXKQ3xcxuRIA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0114', '--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 03:15:02 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/DcUSSidiSViXKQ3xcxuRIA/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0114 --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 03:15:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Va3I2cScMr/Render', 03:15:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:15:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:15:02 INFO - 'HOME': '/Users/cltbld', 03:15:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:15:02 INFO - 'LOGNAME': 'cltbld', 03:15:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:15:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:15:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:15:02 INFO - 'MOZ_NO_REMOTE': '1', 03:15:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:15:02 INFO - 'NO_EM_RESTART': '1', 03:15:02 INFO - 'PAGER': '/bin/cat', 03:15:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:15:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:15:02 INFO - 'PWD': '/builds/slave/test', 03:15:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:15:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:15:02 INFO - 'RUST_BACKTRACE': '1', 03:15:02 INFO - 'SHELL': '/bin/bash', 03:15:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gCvqd5sD2a/Listeners', 03:15:02 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 03:15:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:15:02 INFO - 'USER': 'cltbld', 03:15:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:15:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:15:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:15:02 INFO - 'XPC_FLAGS': '0x0', 03:15:02 INFO - 'XPC_SERVICE_NAME': '0', 03:15:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:15:02 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/DcUSSidiSViXKQ3xcxuRIA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0114', '--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 03:15:02 INFO - mozversion application_buildid: 20170525013420 03:15:02 INFO - mozversion application_changeset: 6fae88419de6c9bf1980ef5ad1ad75471e12cc69 03:15:02 INFO - mozversion application_display_name: Nightly 03:15:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:15:02 INFO - mozversion application_name: Firefox 03:15:02 INFO - mozversion application_remotingname: firefox 03:15:02 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:15:02 INFO - mozversion application_vendor: Mozilla 03:15:02 INFO - mozversion application_version: 55.0a1 03:15:02 INFO - mozversion platform_buildid: 20170525013420 03:15:02 INFO - mozversion platform_changeset: 6fae88419de6c9bf1980ef5ad1ad75471e12cc69 03:15:02 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:15:02 INFO - mozversion platform_version: 55.0a1 03:15:02 INFO - using testdate: 1495707302 03:15:02 INFO - actual date: 1495707302 03:15:02 INFO - starting webserver on 'localhost:49227' 03:15:02 INFO - SUITE-START | Running 2 tests 03:15:02 INFO - TEST-START | dromaeo_css 03:15:02 INFO - operating with platform_type : mac_ 03:15:02 INFO - Initialising browser for dromaeo_css test... 03:15:02 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground -profile /var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/tmpSeQSXt/profile 03:15:02 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground) 03:15:36 INFO - TEST-INFO | 1636: killed by SIGTERM 03:15:36 INFO - Browser initialized. 03:15:36 INFO - Running cycle 1/1 for dromaeo_css test... 03:15:36 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/tmpSeQSXt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:15:38 INFO - PID 1647 | 2017-05-25 03:15:38.514 plugin-container[1648:8106] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 03:15:38 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:15:38 INFO - PID 1647 | 2017-05-25 03:15:38.514 plugin-container[1648:8106] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 03:15:38 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:15:38 INFO - PID 1647 | 2017-05-25 03:15:38.514 plugin-container[1648:8106] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:15:39 INFO - PID 1647 | 2017-05-25 03:15:39.011 plugin-container[1649:8149] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 03:15:39 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:15:39 INFO - PID 1647 | 2017-05-25 03:15:39.011 plugin-container[1649:8149] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e03, name = 'com.apple.CFPasteboardClient' 03:15:39 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:15:39 INFO - PID 1647 | 2017-05-25 03:15:39.011 plugin-container[1649:8149] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:15:48 INFO - PID 1647 | RSS: Main: 245460992 03:15:48 INFO - PID 1647 | 03:18:52 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49227/tests/dromaeo/cssquery-ext.html) 03:18:53 INFO - PID 1647 | RSS: Main: 260792320 03:18:53 INFO - PID 1647 | 03:21:55 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-ext.html (next: http://localhost:49227/tests/dromaeo/cssquery-jquery.html) 03:21:56 INFO - PID 1647 | RSS: Main: 274362368 03:21:56 INFO - PID 1647 | 03:24:58 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49227/tests/dromaeo/cssquery-mootools.html) 03:24:58 INFO - PID 1647 | RSS: Main: 273416192 03:24:58 INFO - PID 1647 | 03:28:00 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49227/tests/dromaeo/cssquery-prototype.html) 03:28:01 INFO - PID 1647 | RSS: Main: 276967424 03:28:01 INFO - PID 1647 | 03:31:03 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49227/tests/dromaeo/cssquery-yui.html) 03:31:04 INFO - PID 1647 | RSS: Main: 277618688 03:31:04 INFO - PID 1647 | 03:34:06 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-yui.html (next: http://localhost:49227/tests/dromaeo/cssquery-dojo.html) 03:34:06 INFO - PID 1647 | RSS: Main: 277028864 03:34:06 INFO - PID 1647 | 03:34:06 INFO - PID 1647 | __start_tp_report 03:34:06 INFO - PID 1647 | _x_x_mozilla_page_load 03:34:06 INFO - PID 1647 | _x_x_mozilla_page_load_details 03:34:06 INFO - PID 1647 | |i|pagename|runs| 03:34:06 INFO - PID 1647 | |0;dojo.html;3534.964650353496;3568.5182500362444;3583.1221350769033;3607.693346065585;3609.819509024548;4747.8813029674275;6135.875804921881;6230.252369715635;6248.250769661346;6258.6557739324435;6194.3805619438035;6208.844778880519;6212.937870621293;6279.246490421145;6308.9684551577175;4975.950240497603;4987.877727511299;5096.41677448095;5526;5530.889382212344;5610;5707;5717.371089180187;5726.3414707308775;5760.308762948452;4841.878953026167;5253.369595648508;5490.286262785852;5507.889842203173;5605.859853503654;5562.916556251659;5574.58190635704;5587.580931430159;5610.971945140289;5620.662760234358;5150.253213284052;5436.831081317536;5539.473749993785;5573.108302671593;5594.24477695514;5703.258576385043;6165.660888651122;6181.609137943971;6215.28524219716;6313.2424109107205;6635.203775546917;6728.158980127484;6745.696443660047;6756.966215168942;6761.0872532208505;6592.373724496165;6839.110915580993;6868.210155832044;6872.725090996354;6877.105976223059;6250.3749625037135;6314.494840412848;6322.367763223687;6339.334369891188;6352.618842869397;6796.728130874809;6835.384815366541;6914.965425172842;6926.307369263033;6978.965105174441;6393.712282947278;6441.935580644227;6509.44669703071;6593.406593406617;6599.340065993362;6651.301613330531;6764.222114456802;6773.966130169416;6788.2193547742645;6793.320667933167;6442.8389290268115;6516.120323756327;6645.102911106938;6649.069130321759;6660.8667826643195;6616.768413105518;6663.4669226461765;6717.596944183365;6734.966325168441;6826.351496607815;5367.78528858849;5385.596080293915;5402.91895621566;5411.729413529308;5428.9185662215095;4689.437267527918;4798.880027999258;4819.879503012383;4829.758512074382;4856.3201151838775;6161.876762464725;6300.464460520907;6309.369063093745;6344.936550634527;6370.1718776558755;6829.112215411965;6833.692483838239;6844.760433384808;6850.417714494324;6861.313868613099;6267.749290028348;6323.525735569759;6351.936480635134;6355.841103972438;6395.328490508523;5869;5937.495312898452;5967.254093238345;6042.607230530001;6072.666003369857;6969.965150174216;7017.789466316019;7031.507794454339;7032.015517827406;7034;6747.325267473213;6856.485763567653;6866.210385805696;6873.518853680194;6895.448364130958;5818.301803783573;6755.86488270222;6851.143607049119;6918.654067296515;7022.12223472066;6677.399034086761;6744.291849355747;6746.527743057939;6749.595024298558;6753.696083676148;6597;6773.932260677528;6874.278200788845;6877.724891004504;6881.518293719592;5371.677699338053;5831.854203644943;6519.185101862267;6621.1392518972225;6653.833654158685;6391.776287829904;6453.709583068679;6592.110065141336;6625.17185351831;6643.966780166068;5360.812371566976;6354.078658594571;6495.188101487314;6515.02274658805;6533.346665333429;6658.467322614276;6660.63366514827;6663.900041499284;6669.1330127083165;6696;6261.906071408841;6484.870302594016;6489.123968264224;6498.967505162444;6513.511486638616 03:34:06 INFO - PID 1647 | |1;ext.html;766.7278116268725;788.2905385153362;811.6023148657158;1203.9398030098498;1257.9056570757184;38319.80840095799;38656.806715966384;38736;38924.22151556967;39083.80458097709;39107.60892391075;39113.608863911424;39532;39582;39622.405663915066;7724;7906.367490600738;8050.798730031766;8268.338532917352;8279.95860020699;8275.917240827604;8377.6230069647;8401.327893768485;8422;8453.408261421702;8376;8403.663853445854;8465.915340846605;8468.364872634545;8470.195331443534;8409.83180336393;8425.11536288687;8437.873431898464;8450.366222533334;8453.83092338156;8128.959355203246;8157.265846073882;8216.589170541449;8218.753437396888;8239.711610093618;36355.45466818;36466.45300320497;36546.634533654586;36610.26779464396;36658.45012324817;3619.402798538238;3657.487951686766;3660.5973342932257;3665.321915445651;3672.981635091834;30012.24969375761;30125.849370753225;30364.240893977607;30378.696213038027;30490.390192196253;3079.8922037728794;3108.8134711917355;3127.781055326128;3129.405412971528;3135.890243841455;215324;243595;247817;297711;299152;39633.61282355104;50511.989760205324;51357;51422;51486;45386.546134538235;53199.936001279755;53785;53866;54688.90622187534;14373;14388;14541.200233987232;14543.563693089225;14558.781618275734;11575.42122893852;11664.891835275635;11692.181547291608;11702.941485292518;11728.70678233051;12559.748805023848;13218.008649351337;14023.579292621238;14329.713405731827;14486.841052715969;6073.180120683651;6144.38556144382;6146.354632763585;6154.261488621304;6155.84610384744;937.8733870927474;961.9422834629804;980.9411435313904;997.7904640025675;1049.2445439283704;19402.029898505018;24192.879035605063;30713.2321691955;30820.921267755522;30930.53604195939;1051.726551096705;1055.2929537210089;1091.3942761767137;1094.3433939636313;1094.6332978452149;10772.353658780341;11121.110311175087;11451.912068353493;12410.193337433038;12501.3749312534;300431;329532;342431;343517;343664;52450;52791;52813;52836.56562323513;53013.204801928005;1430.4374614910455;1444.097439100562;1471.7865909443071;1477.7709455034487;1513.3643869574805;22830.31509054731;23814.642780358656;23860;23909.641355379335;23920.641190382506;29778.404431911673;36226.8188659055;36251.63748362483;36256.09359766027;36311.636883630825;296667;298999;299185;300546.497267521;300853;25508;33072.66927330792;33742.831285843415;34097;34263.82868085644;43786.56213437825;44250.672479825655;44258;44294;44312;24801.379965501008;27440;27639.170824875288;29399.706002939696;30341;29637;29680.406391872475;30105;30562.84718576393;30798.076057718303;30071.849640751658;36671.44992825163;36742;36784.81607591945;36874.8156259217;28566.57150142708;28854.711452885203;30234.395312094075;30853.69146308508;30886.382272353978;22143.667844982017;23738;24073.75926240715;24098.87950560236;24194.153204637754 03:34:06 INFO - PID 1647 | |2;jquery.html;394.9229900169468;416.5563674686459;428.0860363124728;453.91375638628654;454.58859731942584;17194.48416547504;18707.971061591623;22082.447938801517;22813.885930570366;23953.802309884522;23532;23945.401364965885;23985.64021539674;24195.274141775775;24279;6033.366496517857;6300.369963003686;6323.367663233686;6332.525060620449;6347.809565713036;5972.581919265654;6116.571839971204;6133.815985520441;6181.381861813805;6244.843878903041;6139.98690216116;6169.2596888373455;6176.598521096137;6178.07328900664;6181.07283907413;5944.494717948979;6138.785142519991;6157.907631385533;6201.906971395432;6205.72074256655;5528.530074943635;5536.252605898233;5540.972295138539;5546.306711661043;5546.55627549795;22629.886850565643;28055.43889122206;28419.85790071057;28456.146315610564;28805.855970720222;2150.3441450357636;2222.055626358791;2226.4990377165173;2227.9554408911895;2230.5984922713906;18090.45728628143;18178.45464636063;18203.45389638313;18615.720764188547;18730.063496825103;2160.179131929857;2184.4102092435114;2209.823214142865;2213.6015517206897;2225.0321110317063;169812;169950;173225;173317;175026;12781.872181278068;13085.869141308465;13175.60473185806;13189.934050329686;13287.269200193889;16912.492625221264;16945.915270423568;16973;16981.660366792596;17028.06345651001;8861.778455538662;9141.588628511732;9153.405028673114;9178.265738740885;9260.444373337486;7755.961220193975;8333.999920009517;8600.956995214983;8605.784855378666;8717.215450609476;8073.233042860918;8215.301699355621;8218.238906222068;8259.917400826034;8281.627326770309;2023.0997206243376;2206.183712026528;2207.0840601150344;2209.2488553891753;2406.759324067579;862.9482231066156;943.3632298198688;953.9046095390544;989.9158571521501;1005.3666190300064;20874.791252087587;21108.47228819292;21243.78756212418;21284.574308514053;21532;943.4762857984306;977.6480467031863;1010.6058637131524;1015.5227043289642;1016.4663551635302;10921.672349829518;11167.994880460798;11174.050205732603;11184.328940263611;11195.216334856483;434375;442032;549010;551480;558062;109078;126930;127190;128505;144845.27577362047;1210.2677879882544;1210.9333986630643;1252.8872401483911;1253.1415980053691;1255.2029461292145;21621.567568648225;22577.209797657004;22795.88602056979;22911.770882290963;22986.08055677821;58619;59453;59664.80670386655;59778.402215979026;59922.40077599168;83352;83482;83705.16294836973;84199.73700394377;84300.57849710713;8811.295096392401;8881.689140880038;8976.68581599667;8994.325425592975;9268.675596354095;14203.360848761624;14240;14316.355763990437;14545.563633091024;14558.927205363905;10334.638287659896;11684.766304674029;12004.75990480203;12152.027837773134;12158.635240942785;9502.097300756417;11025.889741102486;11274.7745045097;11345.319280843176;11550;10786.191035672513;12163.209391389531;12201.389930503618;12290.508379664714;12412.689682757642;8793.736187914374;8846.15961483658;8857.247133993682;9203.539823008956;9220.40067395644;12037.157398981988;12142.939285303517;12224.38878056076;12285.631431057083;12553.932915702268 03:34:06 INFO - PID 1647 | |3;mootools.html;1382.7303675783223;1430.8068410764556;1432.706295209482;1436.8778653814425;1442.3725679329493;11730.648080557587;12004.639860804167;12159.939200303997;12310.13829031968;12337.876621233785;11999.520019199243;12010.039196864253;12183.756324873519;12191.695207619814;12203.206791558543;2528.178342038831;2587.098482615276;2587.1721049264243;2590.300618832914;2594.44219492809;2500.199936020474;2529.140092368594;2544.4656622109287;2546.6052761821948;2546.6180072989014;2516.987415062922;2536.4166241764406;2549.7067837198683;2549.7450254974538;2550.41340491687;2491.451880586277;2505.348609361561;2524.1670248817845;2525.772680458749;2532.8606926619027;2459.114718701266;2492.8504289742673;2493.7755601995727;2503.2240005598323;2568.627549005402;11702.063834893188;11789.233699809485;11790.410479475991;11858.525658973631;11882.168248222628;6449.6130232186215;6713.966430167818;6718.328167183291;6718.966405167992;6744.224414192333;4073.9185216295805;4271.508776490712;4336.046069864625;4337.522872484024;4381.07997320563;1258.811178323259;1261.564760157744;1264.8229247905301;1265.7848165811833;1288.0790234982032;15031.774523382159;15536.533903983012;16660.16699165037;16783.580410489714;16823.663526729397;4990.326305948723;5423.538999185034;5475.972620136953;5604.97197514018;5663.377028526858;11856.05151587871;11863.644090677293;11868.940655296668;11874.28754274729;11890.940545297219;6384.244332808515;6485.578437401554;6503.9349606504275;6504.739810407531;6511.73953041873;5943.465088142088;6157.384261573807;6201.906971395432;6221.222347206599;6240.31356550779;4288.270993931039;4333.739975601474;4353.956460435418;4388.27593447084;4395.912081758347;1124.3647339253296;1127.656064900197;1133.2916926920675;1134.2683968840051;1135.744457497073;797.1589972578985;797.8125140591957;820.4134044158455;822.576373167819;848.7453763870938;6319.873602527924;6589.385600527901;7339.192688804227;7542.358899493494;7586.62066896653;792.225625858248;945.3193700535603;951.6431338248157;972.5429048347265;991.4844280973997;3855.515626483768;4418.293073108287;4492.460904691393;4576.336431217522;4646.907061858811;14932.552023439313;15255.466058687893;15372.61568460775;15409.845901541064;15525;10379;15052.247387630794;15416.075035497906;15449.459268925533;15450.150241736588;8283;8416.284615807725;8478.533680647624;8552.95723521391;8568.22885940256;6471.352864713491;7297.963510182628;7322.414206863545;7412;7412.592307423143;13387.330633468482;13401.463941442624;13842.100263482842;14018.29908504591;14071.085379450304;13761.105528140639;14036.736693697616;14200.78698819498;14321.57035288943;14369.922255830645;8354.832903342021;8517.148285171434;8577.270931970852;8582.442141261048;8616.655333786579;10578;10831.783364332827;10907.96374344436;10953.014228719454;10979;9360.578773955325;9383.92084910244;9397.389169703947;9496.382735122195;9558.235341172829;9216;9235.030321816113;9264;9321.347505674808;9359.532023398666;8463.28062114727;8539.74380768578;8601;8628.784280392789;8629.352798540009;6214.130021797043;6269.341719119427;6359.87280254383;6385.808425747235;6415.679216039272;5597.80407685729;6299.30707622152;6454.548181627241;6475.514336424692;6490.156279683611 03:34:06 INFO - PID 1647 | |4;prototype.html;331.6318886036499;332.3187465695324;334.79744754423575;359.408772569124;411.9691023173262;6332.493400527958;6368.52236082294;6415.711292991815;6433.087302617783;6527.347265273476;4909.509049095089;6165.506759459244;6401.647909364995;6411.775587854426;6436.517261205417;4555.476120246164;4625.4911959684405;4648.953510464903;4691.225947718623;4728.9763551182195;4193.454850869398;4540.97729511352;4673.9065218695605;4677.36855524505;4873.683210591318;3998.340273854825;4575.26795712686;4629.79165937532;4636.4899861015265;4650.534946505356;4055.5538890721996;4419.580139886722;4553.772311384418;4629.1436084324505;4679.134360143385;3086.6141732283463;3253.7885037472724;3555.5555555555557;3577.4761859568302;3908.475694479155;5665.2351932489;6297.748090076391;6303.086052522359;6311.179546658951;6343.936560634381;4648.651351148644;4988.950110498885;5163.45096471061;5484.287042684426;5526.972365138189;6806.18325800907;6816.4546836252985;6843.247242803337;6852.862942741117;6900;4011.876674531136;4118.794060296973;4292.763897985609;4436.73379597222;4451.933221001687;6732.326767323278;6930.44556435489;6958.965205173992;7097.5031747777675;7253.528520646141;3645.453182022718;3831.501904752364;4402.8679139625865;4505.234110201273;4527.47933987589;3692;4633.72197668141;4635.860924172281;4643.8374656886845;4652.418447694038;4594.977025114853;5141.871453213699;5433.2936718227165;5490.945090549043;5519.365272993577;5423.864403389868;5685.020485660055;6897.586144831326;6897.655117244118;6909.447244220451;1303.3222724183372;1582.6597281584516;1586.825449200587;1596.5050834241422;1606.9116198609188;842.4860834890711;892.2683399612256;1015.7714514234386;1023.0996722883815;1030.8814486334168;5932.584719069624;6427.357264273629;6513.54405191632;6516.152900122954;6519.989401642753;6845.828854278683;7116.786496405116;7125.893111603226;7143.214246432888;7190.352868241884;5345.7594408251725;5806.996586177762;6224.470919971763;6464.5798023128345;6517.51118666102;3609.4224924012033;3716.2381711749026;3746.6324791450925;3750.024993501709;3760.266747984117;6506.219253689587;6661.068290195886;6908.758193463204;7253.637318134072;7351.632418379167;6777.560078371782;7124.536905101152;7329.486935914541;7384.298491643285;7393.852122957511;5850.3564607893095;6116.195722261855;6219.315875253718;7091.787246382617;7362.042934418491;5958.582899197015;5999.250093738283;7132.750353737594;7135.8572828544175;7163.176234733072;6384.361563843579;6455.838604034937;6781.2879647638265;6814.727410903508;6824.215215250111;5844.47399734026;6647.836628590074;6942.570100464797;7216.7834964951135;7382;4247.87256382309;5188.558972487381;5241.842744717665;5276.788928442819;5304.355649748591;5418.376886658085;5781.971090144691;6049.455549000611;6895.6552172392185;7202.243764404871;5929.466348028698;6635.421559124189;6930.584164950119;6964.338387853146;6974.197967233833;5811.506021988178;5966.820995370146;6127.356627554221;6778.389944904982;6890.2420733718245;5908.645481271137;6197.101420293942;6222.844428889314;6781.152355955505;6790.93209067903;6215.316315205414;6656.267810540933;6901.930980690129;6910.896336554855;6912.308769123048;5885.55858310637;6182.227221597301;6700.195976482853;6713;6744.494162937701 03:34:06 INFO - PID 1647 | |5;yui.html;842.6882053640146;850.629975960457;853.8804567360571;865.50233615671;883.4787475389521;16857.325706971722;17348.959062456262;17397.652046959054;19199.13603887825;19837.90081049593;18283.360082397085;18335.724964125548;18530.629387412242;18567.72148417775;18695.158717857696;998.9621172807464;1016.7966406718649;1034.5086084110026;1036.9637062702807;1039.0959864917531;1021.9386836789779;1027.7893031928475;1027.964021259256;1030.443560477345;1033.457434846704;1063.006089306497;1073.2058276875134;1079.169039839327;1080.3140006096146;1081.389015206406;1047.732828128826;1055.8521806947033;1063.3247887591358;1071.919606029551;1075.1989767623093;767.7543186180425;784.8704963680985;786.0528063683248;789.5775759968446;789.8657228271207;17631.206595703225;18086.457406277834;18095.63808723818;18138.727919081226;18386.540336491562;14599.927000365036;15756.842431575653;16985.55622772066;17850.107494625347;18145.7278140828;11430.71423214418;14728.631784205374;14731.96876218665;14740.70518589633;14763.704725905422;3495.8252087395526;3521.841517131735;3631.0559254593995;3672.540932383452;3681.2821499807546;108819;148356;150848;151359.24320378437;152824.9999999989;13497.055206135476;13717.393695197881;13781.862181378057;13798.103123296954;13835.930820345833;22385.77614223837;26470.47059058808;26533.46933061328;26951.595726064126;27162;6911.550749201286;7865.685372585032;7907.406944479187;7938.5236885787035;7946.251824598579;3293.7859039162377;3964.0486283291802;3977.8209980550946;3980.5024371953505;3984.541777695544;3902.70729695274;4358.912821743547;4415.51429342772;4458.643308535309;4474.6420286377015;858.0004294996385;876.6449587916904;885.9557022148996;889.2397000564424;890.0476490155471;655.8229278094888;670.1991120610824;681.2097966358999;684.4455990647492;695.8782213112786;11138.164637652208;12028.63914082579;12167.452464639113;12299.631011069681;12546;645.6924084029272;650.1677852349001;656.6749459017817;685.5269863793966;686.3685409423342;5451.318585176853;5473.31583552056;5486.6982315973;5584.190292407659;5764.855878602984;132088.33955830158;135595;135816;154593.68109478368;161858.38141618433;89030.10969890347;117145.8285417152;117938;118454.4077279608;120968;69033.61932761317;96863;100139.49930250303;103191.48404257931;105018;10987.450627468754;12178.817317740064;12532.749345013232;13071.738565228832;13246.403911824184;35476.46785298274;35853;43327.13345733131;43922.34116488319;44013.559864400944;58564.535886603175;60057.69971150291;69784.53232015239;84397.73403399077;84995.5750221245;17205.139743012547;17957.461276161735;17966.5508362292;18001.909990449964;18159.092045397414;18864.056797160363;19080.904595476935;19230.23079076821;19254.61490770205;19457.902710486924;16104.355825766837;16624.667506650043;17255.91372043132;18315.725264121316;18345.81654183441;17277.39529116475;17322.48032559025;17913.910430447766;17953.910230448764;17998.91000544989;17305;17379.56551086233;17774.289028439234;17788.91105544464;17837.840540364836;10731.40977246259;11037.944810275898;11841.703957400789;12200.328981906081;12323.815142773044;11159.944200278947;13278.468861245829;13579.302587176602;13588.796168057685;13615.931920340336 03:34:06 INFO - PID 1647 | __end_tp_report 03:34:06 INFO - PID 1647 | __start_cc_report 03:34:06 INFO - PID 1647 | _x_x_mozilla_cycle_collect,398 03:34:06 INFO - PID 1647 | __end_cc_report 03:34:06 INFO - PID 1647 | __startTimestamp1495708446538__endTimestamp 03:34:06 INFO - PID 1647 | 03:34:06 INFO - PID 1647 | ------- Summary: start ------- 03:34:06 INFO - PID 1647 | Number of tests: 6 03:34:06 INFO - PID 1647 | 03:34:06 INFO - PID 1647 | [#0] dojo.html Cycles:165 Average:6191.47 Median:6395.33 stddev:751.40 (11.7%) stddev-sans-first:724.23 03:34:06 INFO - PID 1647 | Values: 3535.0 3568.5 3583.1 3607.7 3609.8 4747.9 6135.9 6230.3 6248.3 6258.7 6194.4 6208.8 6212.9 6279.2 6309.0 4976.0 4987.9 5096.4 5526.0 5530.9 5610.0 5707.0 5717.4 5726.3 5760.3 4841.9 5253.4 5490.3 5507.9 5605.9 5562.9 5574.6 5587.6 5611.0 5620.7 5150.3 5436.8 5539.5 5573.1 5594.2 5703.3 6165.7 6181.6 6215.3 6313.2 6635.2 6728.2 6745.7 6757.0 6761.1 6592.4 6839.1 6868.2 6872.7 6877.1 6250.4 6314.5 6322.4 6339.3 6352.6 6796.7 6835.4 6915.0 6926.3 6979.0 6393.7 6441.9 6509.4 6593.4 6599.3 6651.3 6764.2 6774.0 6788.2 6793.3 6442.8 6516.1 6645.1 6649.1 6660.9 6616.8 6663.5 6717.6 6735.0 6826.4 5367.8 5385.6 5402.9 5411.7 5428.9 4689.4 4798.9 4819.9 4829.8 4856.3 6161.9 6300.5 6309.4 6344.9 6370.2 6829.1 6833.7 6844.8 6850.4 6861.3 6267.7 6323.5 6351.9 6355.8 6395.3 5869.0 5937.5 5967.3 6042.6 6072.7 6970.0 7017.8 7031.5 7032.0 7034.0 6747.3 6856.5 6866.2 6873.5 6895.4 5818.3 6755.9 6851.1 6918.7 7022.1 6677.4 6744.3 6746.5 6749.6 6753.7 6597.0 6773.9 6874.3 6877.7 6881.5 5371.7 5831.9 6519.2 6621.1 6653.8 6391.8 6453.7 6592.1 6625.2 6644.0 5360.8 6354.1 6495.2 6515.0 6533.3 6658.5 6660.6 6663.9 6669.1 6696.0 6261.9 6484.9 6489.1 6499.0 6513.5 03:34:06 INFO - PID 1647 | 03:34:06 INFO - PID 1647 | [#1] ext.html Cycles:180 Average:44730.50 Median:188264.00 stddev:78620.98 (41.8%) stddev-sans-first:78772.24 03:34:06 INFO - PID 1647 | Values: 766.7 788.3 811.6 1203.9 1257.9 38319.8 38656.8 38736.0 38924.2 39083.8 39107.6 39113.6 39532.0 39582.0 39622.4 7724.0 7906.4 8050.8 8268.3 8280.0 8275.9 8377.6 8401.3 8422.0 8453.4 8376.0 8403.7 8465.9 8468.4 8470.2 8409.8 8425.1 8437.9 8450.4 8453.8 8129.0 8157.3 8216.6 8218.8 8239.7 36355.5 36466.5 36546.6 36610.3 36658.5 3619.4 3657.5 3660.6 3665.3 3673.0 30012.2 30125.8 30364.2 30378.7 30490.4 3079.9 3108.8 3127.8 3129.4 3135.9 215324.0 243595.0 247817.0 297711.0 299152.0 39633.6 50512.0 51357.0 51422.0 51486.0 45386.5 53199.9 53785.0 53866.0 54688.9 14373.0 14388.0 14541.2 14543.6 14558.8 11575.4 11664.9 11692.2 11702.9 11728.7 12559.7 13218.0 14023.6 14329.7 14486.8 6073.2 6144.4 6146.4 6154.3 6155.8 937.9 961.9 980.9 997.8 1049.2 19402.0 24192.9 30713.2 30820.9 30930.5 1051.7 1055.3 1091.4 1094.3 1094.6 10772.4 11121.1 11451.9 12410.2 12501.4 300431.0 329532.0 342431.0 343517.0 343664.0 52450.0 52791.0 52813.0 52836.6 53013.2 1430.4 1444.1 1471.8 1477.8 1513.4 22830.3 23814.6 23860.0 23909.6 23920.6 29778.4 36226.8 36251.6 36256.1 36311.6 296667.0 298999.0 299185.0 300546.5 300853.0 25508.0 33072.7 33742.8 34097.0 34263.8 43786.6 44250.7 44258.0 44294.0 44312.0 24801.4 27440.0 27639.2 29399.7 30341.0 29637.0 29680.4 30105.0 30562.8 30798.1 30071.8 36671.4 36742.0 36784.8 36874.8 28566.6 28854.7 30234.4 30853.7 30886.4 22143.7 23738.0 24073.8 24098.9 24194.2 03:34:06 INFO - PID 1647 | 03:34:06 INFO - PID 1647 | [#2] jquery.html Cycles:180 Average:35299.83 Median:22948.93 stddev:87950.30 (383.2%) stddev-sans-first:88157.98 03:34:06 INFO - PID 1647 | Values: 394.9 416.6 428.1 453.9 454.6 17194.5 18708.0 22082.4 22813.9 23953.8 23532.0 23945.4 23985.6 24195.3 24279.0 6033.4 6300.4 6323.4 6332.5 6347.8 5972.6 6116.6 6133.8 6181.4 6244.8 6140.0 6169.3 6176.6 6178.1 6181.1 5944.5 6138.8 6157.9 6201.9 6205.7 5528.5 5536.3 5541.0 5546.3 5546.6 22629.9 28055.4 28419.9 28456.1 28805.9 2150.3 2222.1 2226.5 2228.0 2230.6 18090.5 18178.5 18203.5 18615.7 18730.1 2160.2 2184.4 2209.8 2213.6 2225.0 169812.0 169950.0 173225.0 173317.0 175026.0 12781.9 13085.9 13175.6 13189.9 13287.3 16912.5 16945.9 16973.0 16981.7 17028.1 8861.8 9141.6 9153.4 9178.3 9260.4 7756.0 8334.0 8601.0 8605.8 8717.2 8073.2 8215.3 8218.2 8259.9 8281.6 2023.1 2206.2 2207.1 2209.2 2406.8 862.9 943.4 953.9 989.9 1005.4 20874.8 21108.5 21243.8 21284.6 21532.0 943.5 977.6 1010.6 1015.5 1016.5 10921.7 11168.0 11174.1 11184.3 11195.2 434375.0 442032.0 549010.0 551480.0 558062.0 109078.0 126930.0 127190.0 128505.0 144845.3 1210.3 1210.9 1252.9 1253.1 1255.2 21621.6 22577.2 22795.9 22911.8 22986.1 58619.0 59453.0 59664.8 59778.4 59922.4 83352.0 83482.0 83705.2 84199.7 84300.6 8811.3 8881.7 8976.7 8994.3 9268.7 14203.4 14240.0 14316.4 14545.6 14558.9 10334.6 11684.8 12004.8 12152.0 12158.6 9502.1 11025.9 11274.8 11345.3 11550.0 10786.2 12163.2 12201.4 12290.5 12412.7 8793.7 8846.2 8857.2 9203.5 9220.4 12037.2 12142.9 12224.4 12285.6 12553.9 03:34:06 INFO - PID 1647 | 03:34:06 INFO - PID 1647 | [#3] mootools.html Cycles:180 Average:7210.70 Median:3964.72 stddev:4540.98 (114.5%) stddev-sans-first:4532.60 03:34:06 INFO - PID 1647 | Values: 1382.7 1430.8 1432.7 1436.9 1442.4 11730.6 12004.6 12159.9 12310.1 12337.9 11999.5 12010.0 12183.8 12191.7 12203.2 2528.2 2587.1 2587.2 2590.3 2594.4 2500.2 2529.1 2544.5 2546.6 2546.6 2517.0 2536.4 2549.7 2549.7 2550.4 2491.5 2505.3 2524.2 2525.8 2532.9 2459.1 2492.9 2493.8 2503.2 2568.6 11702.1 11789.2 11790.4 11858.5 11882.2 6449.6 6714.0 6718.3 6719.0 6744.2 4073.9 4271.5 4336.0 4337.5 4381.1 1258.8 1261.6 1264.8 1265.8 1288.1 15031.8 15536.5 16660.2 16783.6 16823.7 4990.3 5423.5 5476.0 5605.0 5663.4 11856.1 11863.6 11868.9 11874.3 11890.9 6384.2 6485.6 6503.9 6504.7 6511.7 5943.5 6157.4 6201.9 6221.2 6240.3 4288.3 4333.7 4354.0 4388.3 4395.9 1124.4 1127.7 1133.3 1134.3 1135.7 797.2 797.8 820.4 822.6 848.7 6319.9 6589.4 7339.2 7542.4 7586.6 792.2 945.3 951.6 972.5 991.5 3855.5 4418.3 4492.5 4576.3 4646.9 14932.6 15255.5 15372.6 15409.8 15525.0 10379.0 15052.2 15416.1 15449.5 15450.2 8283.0 8416.3 8478.5 8553.0 8568.2 6471.4 7298.0 7322.4 7412.0 7412.6 13387.3 13401.5 13842.1 14018.3 14071.1 13761.1 14036.7 14200.8 14321.6 14369.9 8354.8 8517.1 8577.3 8582.4 8616.7 10578.0 10831.8 10908.0 10953.0 10979.0 9360.6 9383.9 9397.4 9496.4 9558.2 9216.0 9235.0 9264.0 9321.3 9359.5 8463.3 8539.7 8601.0 8628.8 8629.4 6214.1 6269.3 6359.9 6385.8 6415.7 5597.8 6299.3 6454.5 6475.5 6490.2 03:34:06 INFO - PID 1647 | 03:34:06 INFO - PID 1647 | [#4] prototype.html Cycles:180 Average:5406.11 Median:6024.35 stddev:1743.49 (28.9%) stddev-sans-first:1706.27 03:34:06 INFO - PID 1647 | Values: 331.6 332.3 334.8 359.4 412.0 6332.5 6368.5 6415.7 6433.1 6527.3 4909.5 6165.5 6401.6 6411.8 6436.5 4555.5 4625.5 4649.0 4691.2 4729.0 4193.5 4541.0 4673.9 4677.4 4873.7 3998.3 4575.3 4629.8 4636.5 4650.5 4055.6 4419.6 4553.8 4629.1 4679.1 3086.6 3253.8 3555.6 3577.5 3908.5 5665.2 6297.7 6303.1 6311.2 6343.9 4648.7 4989.0 5163.5 5484.3 5527.0 6806.2 6816.5 6843.2 6852.9 6900.0 4011.9 4118.8 4292.8 4436.7 4451.9 6732.3 6930.4 6959.0 7097.5 7253.5 3645.5 3831.5 4402.9 4505.2 4527.5 3692.0 4633.7 4635.9 4643.8 4652.4 4595.0 5141.9 5433.3 5490.9 5519.4 5423.9 5685.0 6897.6 6897.7 6909.4 1303.3 1582.7 1586.8 1596.5 1606.9 842.5 892.3 1015.8 1023.1 1030.9 5932.6 6427.4 6513.5 6516.2 6520.0 6845.8 7116.8 7125.9 7143.2 7190.4 5345.8 5807.0 6224.5 6464.6 6517.5 3609.4 3716.2 3746.6 3750.0 3760.3 6506.2 6661.1 6908.8 7253.6 7351.6 6777.6 7124.5 7329.5 7384.3 7393.9 5850.4 6116.2 6219.3 7091.8 7362.0 5958.6 5999.3 7132.8 7135.9 7163.2 6384.4 6455.8 6781.3 6814.7 6824.2 5844.5 6647.8 6942.6 7216.8 7382.0 4247.9 5188.6 5241.8 5276.8 5304.4 5418.4 5782.0 6049.5 6895.7 7202.2 5929.5 6635.4 6930.6 6964.3 6974.2 5811.5 5966.8 6127.4 6778.4 6890.2 5908.6 6197.1 6222.8 6781.2 6790.9 6215.3 6656.3 6901.9 6910.9 6912.3 5885.6 6182.2 6700.2 6713.0 6744.5 03:34:06 INFO - PID 1647 | 03:34:06 INFO - PID 1647 | [#5] yui.html Cycles:180 Average:25083.07 Median:18044.18 stddev:38267.57 (212.1%) stddev-sans-first:38331.64 03:34:06 INFO - PID 1647 | Values: 842.7 850.6 853.9 865.5 883.5 16857.3 17349.0 17397.7 19199.1 19837.9 18283.4 18335.7 18530.6 18567.7 18695.2 999.0 1016.8 1034.5 1037.0 1039.1 1021.9 1027.8 1028.0 1030.4 1033.5 1063.0 1073.2 1079.2 1080.3 1081.4 1047.7 1055.9 1063.3 1071.9 1075.2 767.8 784.9 786.1 789.6 789.9 17631.2 18086.5 18095.6 18138.7 18386.5 14599.9 15756.8 16985.6 17850.1 18145.7 11430.7 14728.6 14732.0 14740.7 14763.7 3495.8 3521.8 3631.1 3672.5 3681.3 108819.0 148356.0 150848.0 151359.2 152825.0 13497.1 13717.4 13781.9 13798.1 13835.9 22385.8 26470.5 26533.5 26951.6 27162.0 6911.6 7865.7 7907.4 7938.5 7946.3 3293.8 3964.0 3977.8 3980.5 3984.5 3902.7 4358.9 4415.5 4458.6 4474.6 858.0 876.6 886.0 889.2 890.0 655.8 670.2 681.2 684.4 695.9 11138.2 12028.6 12167.5 12299.6 12546.0 645.7 650.2 656.7 685.5 686.4 5451.3 5473.3 5486.7 5584.2 5764.9 132088.3 135595.0 135816.0 154593.7 161858.4 89030.1 117145.8 117938.0 118454.4 120968.0 69033.6 96863.0 100139.5 103191.5 105018.0 10987.5 12178.8 12532.7 13071.7 13246.4 35476.5 35853.0 43327.1 43922.3 44013.6 58564.5 60057.7 69784.5 84397.7 84995.6 17205.1 17957.5 17966.6 18001.9 18159.1 18864.1 19080.9 19230.2 19254.6 19457.9 16104.4 16624.7 17255.9 18315.7 18345.8 17277.4 17322.5 17913.9 17953.9 17998.9 17305.0 17379.6 17774.3 17788.9 17837.8 10731.4 11037.9 11841.7 12200.3 12323.8 11159.9 13278.5 13579.3 13588.8 13615.9 03:34:06 INFO - PID 1647 | -------- Summary: end -------- 03:34:06 INFO - PID 1647 | 03:34:07 INFO - TEST-INFO | 1647: exit 0 03:34:07 INFO - TEST-OK | dromaeo_css | took 1144311ms 03:34:07 INFO - TEST-START | kraken 03:34:07 INFO - operating with platform_type : mac_ 03:34:07 INFO - Initialising browser for kraken test... 03:34:07 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground -profile /var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/tmpgkByoZ/profile 03:34:07 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground) 03:34:40 INFO - TEST-INFO | 1658: killed by SIGTERM 03:34:40 INFO - Browser initialized. 03:34:40 INFO - Running cycle 1/1 for kraken test... 03:34:40 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/tmpgkByoZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:34:42 INFO - PID 1662 | 2017-05-25 03:34:42.822 plugin-container[1663:10783] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x984b, name = 'com.apple.tsm.portname' 03:34:42 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:34:42 INFO - PID 1662 | 2017-05-25 03:34:42.823 plugin-container[1663:10783] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461b, name = 'com.apple.CFPasteboardClient' 03:34:42 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:34:42 INFO - PID 1662 | 2017-05-25 03:34:42.823 plugin-container[1663:10783] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:34:43 INFO - PID 1662 | 2017-05-25 03:34:43.362 plugin-container[1664:10826] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x942b, name = 'com.apple.tsm.portname' 03:34:43 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:34:43 INFO - PID 1662 | 2017-05-25 03:34:43.362 plugin-container[1664:10826] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 03:34:43 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:34:43 INFO - PID 1662 | 2017-05-25 03:34:43.362 plugin-container[1664:10826] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:34:52 INFO - PID 1662 | RSS: Main: 245952512 03:34:52 INFO - PID 1662 | 03:34:55 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-beat-detection) 03:34:56 INFO - PID 1662 | RSS: Main: 259469312 03:34:56 INFO - PID 1662 | 03:34:59 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-dft) 03:35:00 INFO - PID 1662 | RSS: Main: 268455936 03:35:00 INFO - PID 1662 | 03:35:03 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-fft) 03:35:04 INFO - PID 1662 | RSS: Main: 271745024 03:35:04 INFO - PID 1662 | 03:35:06 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-oscillator) 03:35:06 INFO - PID 1662 | RSS: Main: 273453056 03:35:06 INFO - PID 1662 | 03:35:08 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:35:09 INFO - PID 1662 | RSS: Main: 274042880 03:35:09 INFO - PID 1662 | 03:35:12 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-darkroom) 03:35:12 INFO - PID 1662 | RSS: Main: 283516928 03:35:12 INFO - PID 1662 | 03:35:15 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-desaturate) 03:35:16 INFO - PID 1662 | RSS: Main: 282157056 03:35:16 INFO - PID 1662 | 03:35:19 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49227/tests/kraken/driver.html?testName=json-parse-financial) 03:35:20 INFO - PID 1662 | RSS: Main: 279318528 03:35:20 INFO - PID 1662 | 03:35:22 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49227/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:35:22 INFO - PID 1662 | RSS: Main: 280506368 03:35:22 INFO - PID 1662 | 03:35:24 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:35:25 INFO - PID 1662 | RSS: Main: 280625152 03:35:25 INFO - PID 1662 | 03:35:27 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:35:28 INFO - PID 1662 | RSS: Main: 280719360 03:35:28 INFO - PID 1662 | 03:35:30 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:35:31 INFO - PID 1662 | RSS: Main: 281202688 03:35:31 INFO - PID 1662 | 03:35:34 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:35:35 INFO - PID 1662 | RSS: Main: 280358912 03:35:35 INFO - PID 1662 | 03:35:37 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49227/tests/kraken/driver.html?testName=ai-astar) 03:35:37 INFO - PID 1662 | RSS: Main: 281833472 03:35:37 INFO - PID 1662 | 03:35:37 INFO - PID 1662 | __start_tp_report 03:35:37 INFO - PID 1662 | _x_x_mozilla_page_load 03:35:37 INFO - PID 1662 | _x_x_mozilla_page_load_details 03:35:37 INFO - PID 1662 | |i|pagename|runs| 03:35:37 INFO - PID 1662 | |0;ai-astar;102;129;170;115;126;172;153;133;166;96 03:35:37 INFO - PID 1662 | |1;audio-beat-detection;189;145;139;185;103;141;96;145;163;103 03:35:37 INFO - PID 1662 | |2;audio-dft;197;210;213;223;196;197;197;198;205;201 03:35:37 INFO - PID 1662 | |3;audio-fft;82;56;61;83;58;69;93;86;82;57 03:35:37 INFO - PID 1662 | |4;audio-oscillator;81;72;82;73;74;70;86;69;83;77 03:35:37 INFO - PID 1662 | |5;imaging-gaussian-blur;115;129;91;92;91;91;92;92;94;92 03:35:37 INFO - PID 1662 | |6;imaging-darkroom;98;100;98;98;98;98;98;98;98;103 03:35:37 INFO - PID 1662 | |7;imaging-desaturate;91;103;142;139;143;81;167;149;90;138 03:35:37 INFO - PID 1662 | |8;json-parse-financial;51;50;48;52;51;51;51;53;70;77 03:35:37 INFO - PID 1662 | |9;json-stringify-tinderbox;57;61;59;63;61;59;57;61;57;56 03:35:37 INFO - PID 1662 | |10;stanford-crypto-aes;98;110;86;85;86;87;89;89;140;97 03:35:37 INFO - PID 1662 | |11;stanford-crypto-ccm;137;151;169;154;142;139;139;150;152;171 03:35:37 INFO - PID 1662 | |12;stanford-crypto-pbkdf2;184;188;191;195;229;164;241;223;187;236 03:35:37 INFO - PID 1662 | |13;stanford-crypto-sha256-iterative;66;67;69;65;69;87;89;63;92;100 03:35:37 INFO - PID 1662 | __end_tp_report 03:35:37 INFO - PID 1662 | __start_cc_report 03:35:37 INFO - PID 1662 | _x_x_mozilla_cycle_collect,1075 03:35:37 INFO - PID 1662 | __end_cc_report 03:35:37 INFO - PID 1662 | __startTimestamp1495708537550__endTimestamp 03:35:37 INFO - PID 1662 | 03:35:37 INFO - PID 1662 | ------- Summary: start ------- 03:35:37 INFO - PID 1662 | Number of tests: 14 03:35:37 INFO - PID 1662 | 03:35:37 INFO - PID 1662 | [#0] ai-astar Cycles:10 Average:136.20 Median:159.50 stddev:27.88 (17.5%) stddev-sans-first:26.68 03:35:37 INFO - PID 1662 | Values: 102.0 129.0 170.0 115.0 126.0 172.0 153.0 133.0 166.0 96.0 03:35:37 INFO - PID 1662 | 03:35:37 INFO - PID 1662 | [#1] audio-beat-detection Cycles:10 Average:140.90 Median:154.00 stddev:32.78 (21.3%) stddev-sans-first:29.80 03:35:37 INFO - PID 1662 | Values: 189.0 145.0 139.0 185.0 103.0 141.0 96.0 145.0 163.0 103.0 03:35:37 INFO - PID 1662 | 03:35:37 INFO - PID 1662 | [#2] audio-dft Cycles:10 Average:203.70 Median:203.00 stddev:9.03 (4.4%) stddev-sans-first:9.25 03:35:37 INFO - PID 1662 | Values: 197.0 210.0 213.0 223.0 196.0 197.0 197.0 198.0 205.0 201.0 03:35:37 INFO - PID 1662 | 03:35:37 INFO - PID 1662 | [#3] audio-fft Cycles:10 Average:72.70 Median:82.00 stddev:13.98 (17.1%) stddev-sans-first:14.42 03:35:37 INFO - PID 1662 | Values: 82.0 56.0 61.0 83.0 58.0 69.0 93.0 86.0 82.0 57.0 03:35:37 INFO - PID 1662 | 03:35:37 INFO - PID 1662 | [#4] audio-oscillator Cycles:10 Average:76.70 Median:79.00 stddev:5.96 (7.5%) stddev-sans-first:6.12 03:35:37 INFO - PID 1662 | Values: 81.0 72.0 82.0 73.0 74.0 70.0 86.0 69.0 83.0 77.0 03:35:37 INFO - PID 1662 | 03:35:37 INFO - PID 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:97.90 Median:92.00 stddev:13.15 (14.3%) stddev-sans-first:12.41 03:35:37 INFO - PID 1662 | Values: 115.0 129.0 91.0 92.0 91.0 91.0 92.0 92.0 94.0 92.0 03:35:37 INFO - PID 1662 | 03:35:37 INFO - PID 1662 | [#6] imaging-darkroom Cycles:10 Average:98.70 Median:98.00 stddev:1.64 (1.7%) stddev-sans-first:1.72 03:35:37 INFO - PID 1662 | Values: 98.0 100.0 98.0 98.0 98.0 98.0 98.0 98.0 98.0 103.0 03:35:37 INFO - PID 1662 | 03:35:37 INFO - PID 1662 | [#7] imaging-desaturate Cycles:10 Average:124.30 Median:158.00 stddev:30.03 (19.0%) stddev-sans-first:29.33 03:35:37 INFO - PID 1662 | Values: 91.0 103.0 142.0 139.0 143.0 81.0 167.0 149.0 90.0 138.0 03:35:37 INFO - PID 1662 | 03:35:37 INFO - PID 1662 | [#8] json-parse-financial Cycles:10 Average:55.40 Median:51.50 stddev:9.77 (19.0%) stddev-sans-first:10.23 03:35:37 INFO - PID 1662 | Values: 51.0 50.0 48.0 52.0 51.0 51.0 51.0 53.0 70.0 77.0 03:35:37 INFO - PID 1662 | 03:35:37 INFO - PID 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:59.10 Median:60.00 stddev:2.33 (3.9%) stddev-sans-first:2.35 03:35:37 INFO - PID 1662 | Values: 57.0 61.0 59.0 63.0 61.0 59.0 57.0 61.0 57.0 56.0 03:35:37 INFO - PID 1662 | 03:35:37 INFO - PID 1662 | [#10] stanford-crypto-aes Cycles:10 Average:96.70 Median:88.00 stddev:17.10 (19.4%) stddev-sans-first:18.13 03:35:37 INFO - PID 1662 | Values: 98.0 110.0 86.0 85.0 86.0 87.0 89.0 89.0 140.0 97.0 03:35:37 INFO - PID 1662 | 03:35:37 INFO - PID 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:150.40 Median:151.50 stddev:12.00 (7.9%) stddev-sans-first:11.71 03:35:37 INFO - PID 1662 | Values: 137.0 151.0 169.0 154.0 142.0 139.0 139.0 150.0 152.0 171.0 03:35:37 INFO - PID 1662 | 03:35:37 INFO - PID 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:203.80 Median:209.00 stddev:26.19 (12.5%) stddev-sans-first:26.78 03:35:37 INFO - PID 1662 | Values: 184.0 188.0 191.0 195.0 229.0 164.0 241.0 223.0 187.0 236.0 03:35:37 INFO - PID 1662 | 03:35:37 INFO - PID 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.70 Median:69.00 stddev:13.69 (19.8%) stddev-sans-first:13.96 03:35:37 INFO - PID 1662 | Values: 66.0 67.0 69.0 65.0 69.0 87.0 89.0 63.0 92.0 100.0 03:35:37 INFO - PID 1662 | -------- Summary: end -------- 03:35:37 INFO - PID 1662 | 03:35:38 INFO - TEST-INFO | 1662: exit 0 03:35:38 INFO - TEST-OK | kraken | took 91396ms 03:35:38 INFO - SUITE-END | took 1235s 03:35:38 INFO - Completed test suite (00:20:35) 03:35:38 INFO - javascript benchmark 03:35:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8014.656290732753, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3534.964650353496, 3568.5182500362444, 3583.1221350769033, 3607.693346065585, 3609.819509024548, 4747.8813029674275, 6135.875804921881, 6230.252369715635, 6248.250769661346, 6258.6557739324435, 6194.3805619438035, 6208.844778880519, 6212.937870621293, 6279.246490421145, 6308.9684551577175, 4975.950240497603, 4987.877727511299, 5096.41677448095, 5526.0, 5530.889382212344, 5610.0, 5707.0, 5717.371089180187, 5726.3414707308775, 5760.308762948452, 4841.878953026167, 5253.369595648508, 5490.286262785852, 5507.889842203173, 5605.859853503654, 5562.916556251659, 5574.58190635704, 5587.580931430159, 5610.971945140289, 5620.662760234358, 5150.253213284052, 5436.831081317536, 5539.473749993785, 5573.108302671593, 5594.24477695514, 5703.258576385043, 6165.660888651122, 6181.609137943971, 6215.28524219716, 6313.2424109107205, 6635.203775546917, 6728.158980127484, 6745.696443660047, 6756.966215168942, 6761.0872532208505, 6592.373724496165, 6839.110915580993, 6868.210155832044, 6872.725090996354, 6877.105976223059, 6250.3749625037135, 6314.494840412848, 6322.367763223687, 6339.334369891188, 6352.618842869397, 6796.728130874809, 6835.384815366541, 6914.965425172842, 6926.307369263033, 6978.965105174441, 6393.712282947278, 6441.935580644227, 6509.44669703071, 6593.406593406617, 6599.340065993362, 6651.301613330531, 6764.222114456802, 6773.966130169416, 6788.2193547742645, 6793.320667933167, 6442.8389290268115, 6516.120323756327, 6645.102911106938, 6649.069130321759, 6660.8667826643195, 6616.768413105518, 6663.4669226461765, 6717.596944183365, 6734.966325168441, 6826.351496607815, 5367.78528858849, 5385.596080293915, 5402.91895621566, 5411.729413529308, 5428.9185662215095, 4689.437267527918, 4798.880027999258, 4819.879503012383, 4829.758512074382, 4856.3201151838775, 6161.876762464725, 6300.464460520907, 6309.369063093745, 6344.936550634527, 6370.1718776558755, 6829.112215411965, 6833.692483838239, 6844.760433384808, 6850.417714494324, 6861.313868613099, 6267.749290028348, 6323.525735569759, 6351.936480635134, 6355.841103972438, 6395.328490508523, 5869.0, 5937.495312898452, 5967.254093238345, 6042.607230530001, 6072.666003369857, 6969.965150174216, 7017.789466316019, 7031.507794454339, 7032.015517827406, 7034.0, 6747.325267473213, 6856.485763567653, 6866.210385805696, 6873.518853680194, 6895.448364130958, 5818.301803783573, 6755.86488270222, 6851.143607049119, 6918.654067296515, 7022.12223472066, 6677.399034086761, 6744.291849355747, 6746.527743057939, 6749.595024298558, 6753.696083676148, 6597.0, 6773.932260677528, 6874.278200788845, 6877.724891004504, 6881.518293719592, 5371.677699338053, 5831.854203644943, 6519.185101862267, 6621.1392518972225, 6653.833654158685, 6391.776287829904, 6453.709583068679, 6592.110065141336, 6625.17185351831, 6643.966780166068, 5360.812371566976, 6354.078658594571, 6495.188101487314, 6515.02274658805, 6533.346665333429, 6658.467322614276, 6660.63366514827, 6663.900041499284, 6669.1330127083165, 6696.0, 6261.906071408841, 6484.870302594016, 6489.123968264224, 6498.967505162444, 6513.511486638616], "value": 6141.6770331402595, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [766.7278116268725, 788.2905385153362, 811.6023148657158, 1203.9398030098498, 1257.9056570757184, 38319.80840095799, 38656.806715966384, 38736.0, 38924.22151556967, 39083.80458097709, 39107.60892391075, 39113.608863911424, 39532.0, 39582.0, 39622.405663915066, 7724.0, 7906.367490600738, 8050.798730031766, 8268.338532917352, 8279.95860020699, 8275.917240827604, 8377.6230069647, 8401.327893768485, 8422.0, 8453.408261421702, 8376.0, 8403.663853445854, 8465.915340846605, 8468.364872634545, 8470.195331443534, 8409.83180336393, 8425.11536288687, 8437.873431898464, 8450.366222533334, 8453.83092338156, 8128.959355203246, 8157.265846073882, 8216.589170541449, 8218.753437396888, 8239.711610093618, 36355.45466818, 36466.45300320497, 36546.634533654586, 36610.26779464396, 36658.45012324817, 3619.402798538238, 3657.487951686766, 3660.5973342932257, 3665.321915445651, 3672.981635091834, 30012.24969375761, 30125.849370753225, 30364.240893977607, 30378.696213038027, 30490.390192196253, 3079.8922037728794, 3108.8134711917355, 3127.781055326128, 3129.405412971528, 3135.890243841455, 215324.0, 243595.0, 247817.0, 297711.0, 299152.0, 39633.61282355104, 50511.989760205324, 51357.0, 51422.0, 51486.0, 45386.546134538235, 53199.936001279755, 53785.0, 53866.0, 54688.90622187534, 14373.0, 14388.0, 14541.200233987232, 14543.563693089225, 14558.781618275734, 11575.42122893852, 11664.891835275635, 11692.181547291608, 11702.941485292518, 11728.70678233051, 12559.748805023848, 13218.008649351337, 14023.579292621238, 14329.713405731827, 14486.841052715969, 6073.180120683651, 6144.38556144382, 6146.354632763585, 6154.261488621304, 6155.84610384744, 937.8733870927474, 961.9422834629804, 980.9411435313904, 997.7904640025675, 1049.2445439283704, 19402.029898505018, 24192.879035605063, 30713.2321691955, 30820.921267755522, 30930.53604195939, 1051.726551096705, 1055.2929537210089, 1091.3942761767137, 1094.3433939636313, 1094.6332978452149, 10772.353658780341, 11121.110311175087, 11451.912068353493, 12410.193337433038, 12501.3749312534, 300431.0, 329532.0, 342431.0, 343517.0, 343664.0, 52450.0, 52791.0, 52813.0, 52836.56562323513, 53013.204801928005, 1430.4374614910455, 1444.097439100562, 1471.7865909443071, 1477.7709455034487, 1513.3643869574805, 22830.31509054731, 23814.642780358656, 23860.0, 23909.641355379335, 23920.641190382506, 29778.404431911673, 36226.8188659055, 36251.63748362483, 36256.09359766027, 36311.636883630825, 296667.0, 298999.0, 299185.0, 300546.497267521, 300853.0, 25508.0, 33072.66927330792, 33742.831285843415, 34097.0, 34263.82868085644, 43786.56213437825, 44250.672479825655, 44258.0, 44294.0, 44312.0, 24801.379965501008, 27440.0, 27639.170824875288, 29399.706002939696, 30341.0, 29637.0, 29680.406391872475, 30105.0, 30562.84718576393, 30798.076057718303, 30071.849640751658, 36671.44992825163, 36742.0, 36784.81607591945, 36874.8156259217, 28566.57150142708, 28854.711452885203, 30234.395312094075, 30853.69146308508, 30886.382272353978, 22143.667844982017, 23738.0, 24073.75926240715, 24098.87950560236, 24194.153204637754], "value": 17387.494821208715, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [394.9229900169468, 416.5563674686459, 428.0860363124728, 453.91375638628654, 454.58859731942584, 17194.48416547504, 18707.971061591623, 22082.447938801517, 22813.885930570366, 23953.802309884522, 23532.0, 23945.401364965885, 23985.64021539674, 24195.274141775775, 24279.0, 6033.366496517857, 6300.369963003686, 6323.367663233686, 6332.525060620449, 6347.809565713036, 5972.581919265654, 6116.571839971204, 6133.815985520441, 6181.381861813805, 6244.843878903041, 6139.98690216116, 6169.2596888373455, 6176.598521096137, 6178.07328900664, 6181.07283907413, 5944.494717948979, 6138.785142519991, 6157.907631385533, 6201.906971395432, 6205.72074256655, 5528.530074943635, 5536.252605898233, 5540.972295138539, 5546.306711661043, 5546.55627549795, 22629.886850565643, 28055.43889122206, 28419.85790071057, 28456.146315610564, 28805.855970720222, 2150.3441450357636, 2222.055626358791, 2226.4990377165173, 2227.9554408911895, 2230.5984922713906, 18090.45728628143, 18178.45464636063, 18203.45389638313, 18615.720764188547, 18730.063496825103, 2160.179131929857, 2184.4102092435114, 2209.823214142865, 2213.6015517206897, 2225.0321110317063, 169812.0, 169950.0, 173225.0, 173317.0, 175026.0, 12781.872181278068, 13085.869141308465, 13175.60473185806, 13189.934050329686, 13287.269200193889, 16912.492625221264, 16945.915270423568, 16973.0, 16981.660366792596, 17028.06345651001, 8861.778455538662, 9141.588628511732, 9153.405028673114, 9178.265738740885, 9260.444373337486, 7755.961220193975, 8333.999920009517, 8600.956995214983, 8605.784855378666, 8717.215450609476, 8073.233042860918, 8215.301699355621, 8218.238906222068, 8259.917400826034, 8281.627326770309, 2023.0997206243376, 2206.183712026528, 2207.0840601150344, 2209.2488553891753, 2406.759324067579, 862.9482231066156, 943.3632298198688, 953.9046095390544, 989.9158571521501, 1005.3666190300064, 20874.791252087587, 21108.47228819292, 21243.78756212418, 21284.574308514053, 21532.0, 943.4762857984306, 977.6480467031863, 1010.6058637131524, 1015.5227043289642, 1016.4663551635302, 10921.672349829518, 11167.994880460798, 11174.050205732603, 11184.328940263611, 11195.216334856483, 434375.0, 442032.0, 549010.0, 551480.0, 558062.0, 109078.0, 126930.0, 127190.0, 128505.0, 144845.27577362047, 1210.2677879882544, 1210.9333986630643, 1252.8872401483911, 1253.1415980053691, 1255.2029461292145, 21621.567568648225, 22577.209797657004, 22795.88602056979, 22911.770882290963, 22986.08055677821, 58619.0, 59453.0, 59664.80670386655, 59778.402215979026, 59922.40077599168, 83352.0, 83482.0, 83705.16294836973, 84199.73700394377, 84300.57849710713, 8811.295096392401, 8881.689140880038, 8976.68581599667, 8994.325425592975, 9268.675596354095, 14203.360848761624, 14240.0, 14316.355763990437, 14545.563633091024, 14558.927205363905, 10334.638287659896, 11684.766304674029, 12004.75990480203, 12152.027837773134, 12158.635240942785, 9502.097300756417, 11025.889741102486, 11274.7745045097, 11345.319280843176, 11550.0, 10786.191035672513, 12163.209391389531, 12201.389930503618, 12290.508379664714, 12412.689682757642, 8793.736187914374, 8846.15961483658, 8857.247133993682, 9203.539823008956, 9220.40067395644, 12037.157398981988, 12142.939285303517, 12224.38878056076, 12285.631431057083, 12553.932915702268], "value": 10656.126661531096, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1382.7303675783223, 1430.8068410764556, 1432.706295209482, 1436.8778653814425, 1442.3725679329493, 11730.648080557587, 12004.639860804167, 12159.939200303997, 12310.13829031968, 12337.876621233785, 11999.520019199243, 12010.039196864253, 12183.756324873519, 12191.695207619814, 12203.206791558543, 2528.178342038831, 2587.098482615276, 2587.1721049264243, 2590.300618832914, 2594.44219492809, 2500.199936020474, 2529.140092368594, 2544.4656622109287, 2546.6052761821948, 2546.6180072989014, 2516.987415062922, 2536.4166241764406, 2549.7067837198683, 2549.7450254974538, 2550.41340491687, 2491.451880586277, 2505.348609361561, 2524.1670248817845, 2525.772680458749, 2532.8606926619027, 2459.114718701266, 2492.8504289742673, 2493.7755601995727, 2503.2240005598323, 2568.627549005402, 11702.063834893188, 11789.233699809485, 11790.410479475991, 11858.525658973631, 11882.168248222628, 6449.6130232186215, 6713.966430167818, 6718.328167183291, 6718.966405167992, 6744.224414192333, 4073.9185216295805, 4271.508776490712, 4336.046069864625, 4337.522872484024, 4381.07997320563, 1258.811178323259, 1261.564760157744, 1264.8229247905301, 1265.7848165811833, 1288.0790234982032, 15031.774523382159, 15536.533903983012, 16660.16699165037, 16783.580410489714, 16823.663526729397, 4990.326305948723, 5423.538999185034, 5475.972620136953, 5604.97197514018, 5663.377028526858, 11856.05151587871, 11863.644090677293, 11868.940655296668, 11874.28754274729, 11890.940545297219, 6384.244332808515, 6485.578437401554, 6503.9349606504275, 6504.739810407531, 6511.73953041873, 5943.465088142088, 6157.384261573807, 6201.906971395432, 6221.222347206599, 6240.31356550779, 4288.270993931039, 4333.739975601474, 4353.956460435418, 4388.27593447084, 4395.912081758347, 1124.3647339253296, 1127.656064900197, 1133.2916926920675, 1134.2683968840051, 1135.744457497073, 797.1589972578985, 797.8125140591957, 820.4134044158455, 822.576373167819, 848.7453763870938, 6319.873602527924, 6589.385600527901, 7339.192688804227, 7542.358899493494, 7586.62066896653, 792.225625858248, 945.3193700535603, 951.6431338248157, 972.5429048347265, 991.4844280973997, 3855.515626483768, 4418.293073108287, 4492.460904691393, 4576.336431217522, 4646.907061858811, 14932.552023439313, 15255.466058687893, 15372.61568460775, 15409.845901541064, 15525.0, 10379.0, 15052.247387630794, 15416.075035497906, 15449.459268925533, 15450.150241736588, 8283.0, 8416.284615807725, 8478.533680647624, 8552.95723521391, 8568.22885940256, 6471.352864713491, 7297.963510182628, 7322.414206863545, 7412.0, 7412.592307423143, 13387.330633468482, 13401.463941442624, 13842.100263482842, 14018.29908504591, 14071.085379450304, 13761.105528140639, 14036.736693697616, 14200.78698819498, 14321.57035288943, 14369.922255830645, 8354.832903342021, 8517.148285171434, 8577.270931970852, 8582.442141261048, 8616.655333786579, 10578.0, 10831.783364332827, 10907.96374344436, 10953.014228719454, 10979.0, 9360.578773955325, 9383.92084910244, 9397.389169703947, 9496.382735122195, 9558.235341172829, 9216.0, 9235.030321816113, 9264.0, 9321.347505674808, 9359.532023398666, 8463.28062114727, 8539.74380768578, 8601.0, 8628.784280392789, 8629.352798540009, 6214.130021797043, 6269.341719119427, 6359.87280254383, 6385.808425747235, 6415.679216039272, 5597.80407685729, 6299.30707622152, 6454.548181627241, 6475.514336424692, 6490.156279683611], "value": 5449.963733523447, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [331.6318886036499, 332.3187465695324, 334.79744754423575, 359.408772569124, 411.9691023173262, 6332.493400527958, 6368.52236082294, 6415.711292991815, 6433.087302617783, 6527.347265273476, 4909.509049095089, 6165.506759459244, 6401.647909364995, 6411.775587854426, 6436.517261205417, 4555.476120246164, 4625.4911959684405, 4648.953510464903, 4691.225947718623, 4728.9763551182195, 4193.454850869398, 4540.97729511352, 4673.9065218695605, 4677.36855524505, 4873.683210591318, 3998.340273854825, 4575.26795712686, 4629.79165937532, 4636.4899861015265, 4650.534946505356, 4055.5538890721996, 4419.580139886722, 4553.772311384418, 4629.1436084324505, 4679.134360143385, 3086.6141732283463, 3253.7885037472724, 3555.5555555555557, 3577.4761859568302, 3908.475694479155, 5665.2351932489, 6297.748090076391, 6303.086052522359, 6311.179546658951, 6343.936560634381, 4648.651351148644, 4988.950110498885, 5163.45096471061, 5484.287042684426, 5526.972365138189, 6806.18325800907, 6816.4546836252985, 6843.247242803337, 6852.862942741117, 6900.0, 4011.876674531136, 4118.794060296973, 4292.763897985609, 4436.73379597222, 4451.933221001687, 6732.326767323278, 6930.44556435489, 6958.965205173992, 7097.5031747777675, 7253.528520646141, 3645.453182022718, 3831.501904752364, 4402.8679139625865, 4505.234110201273, 4527.47933987589, 3692.0, 4633.72197668141, 4635.860924172281, 4643.8374656886845, 4652.418447694038, 4594.977025114853, 5141.871453213699, 5433.2936718227165, 5490.945090549043, 5519.365272993577, 5423.864403389868, 5685.020485660055, 6897.586144831326, 6897.655117244118, 6909.447244220451, 1303.3222724183372, 1582.6597281584516, 1586.825449200587, 1596.5050834241422, 1606.9116198609188, 842.4860834890711, 892.2683399612256, 1015.7714514234386, 1023.0996722883815, 1030.8814486334168, 5932.584719069624, 6427.357264273629, 6513.54405191632, 6516.152900122954, 6519.989401642753, 6845.828854278683, 7116.786496405116, 7125.893111603226, 7143.214246432888, 7190.352868241884, 5345.7594408251725, 5806.996586177762, 6224.470919971763, 6464.5798023128345, 6517.51118666102, 3609.4224924012033, 3716.2381711749026, 3746.6324791450925, 3750.024993501709, 3760.266747984117, 6506.219253689587, 6661.068290195886, 6908.758193463204, 7253.637318134072, 7351.632418379167, 6777.560078371782, 7124.536905101152, 7329.486935914541, 7384.298491643285, 7393.852122957511, 5850.3564607893095, 6116.195722261855, 6219.315875253718, 7091.787246382617, 7362.042934418491, 5958.582899197015, 5999.250093738283, 7132.750353737594, 7135.8572828544175, 7163.176234733072, 6384.361563843579, 6455.838604034937, 6781.2879647638265, 6814.727410903508, 6824.215215250111, 5844.47399734026, 6647.836628590074, 6942.570100464797, 7216.7834964951135, 7382.0, 4247.87256382309, 5188.558972487381, 5241.842744717665, 5276.788928442819, 5304.355649748591, 5418.376886658085, 5781.971090144691, 6049.455549000611, 6895.6552172392185, 7202.243764404871, 5929.466348028698, 6635.421559124189, 6930.584164950119, 6964.338387853146, 6974.197967233833, 5811.506021988178, 5966.820995370146, 6127.356627554221, 6778.389944904982, 6890.2420733718245, 5908.645481271137, 6197.101420293942, 6222.844428889314, 6781.152355955505, 6790.93209067903, 6215.316315205414, 6656.267810540933, 6901.930980690129, 6910.896336554855, 6912.308769123048, 5885.55858310637, 6182.227221597301, 6700.195976482853, 6713.0, 6744.494162937701], "value": 4841.606234895519, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [842.6882053640146, 850.629975960457, 853.8804567360571, 865.50233615671, 883.4787475389521, 16857.325706971722, 17348.959062456262, 17397.652046959054, 19199.13603887825, 19837.90081049593, 18283.360082397085, 18335.724964125548, 18530.629387412242, 18567.72148417775, 18695.158717857696, 998.9621172807464, 1016.7966406718649, 1034.5086084110026, 1036.9637062702807, 1039.0959864917531, 1021.9386836789779, 1027.7893031928475, 1027.964021259256, 1030.443560477345, 1033.457434846704, 1063.006089306497, 1073.2058276875134, 1079.169039839327, 1080.3140006096146, 1081.389015206406, 1047.732828128826, 1055.8521806947033, 1063.3247887591358, 1071.919606029551, 1075.1989767623093, 767.7543186180425, 784.8704963680985, 786.0528063683248, 789.5775759968446, 789.8657228271207, 17631.206595703225, 18086.457406277834, 18095.63808723818, 18138.727919081226, 18386.540336491562, 14599.927000365036, 15756.842431575653, 16985.55622772066, 17850.107494625347, 18145.7278140828, 11430.71423214418, 14728.631784205374, 14731.96876218665, 14740.70518589633, 14763.704725905422, 3495.8252087395526, 3521.841517131735, 3631.0559254593995, 3672.540932383452, 3681.2821499807546, 108819.0, 148356.0, 150848.0, 151359.24320378437, 152824.9999999989, 13497.055206135476, 13717.393695197881, 13781.862181378057, 13798.103123296954, 13835.930820345833, 22385.77614223837, 26470.47059058808, 26533.46933061328, 26951.595726064126, 27162.0, 6911.550749201286, 7865.685372585032, 7907.406944479187, 7938.5236885787035, 7946.251824598579, 3293.7859039162377, 3964.0486283291802, 3977.8209980550946, 3980.5024371953505, 3984.541777695544, 3902.70729695274, 4358.912821743547, 4415.51429342772, 4458.643308535309, 4474.6420286377015, 858.0004294996385, 876.6449587916904, 885.9557022148996, 889.2397000564424, 890.0476490155471, 655.8229278094888, 670.1991120610824, 681.2097966358999, 684.4455990647492, 695.8782213112786, 11138.164637652208, 12028.63914082579, 12167.452464639113, 12299.631011069681, 12546.0, 645.6924084029272, 650.1677852349001, 656.6749459017817, 685.5269863793966, 686.3685409423342, 5451.318585176853, 5473.31583552056, 5486.6982315973, 5584.190292407659, 5764.855878602984, 132088.33955830158, 135595.0, 135816.0, 154593.68109478368, 161858.38141618433, 89030.10969890347, 117145.8285417152, 117938.0, 118454.4077279608, 120968.0, 69033.61932761317, 96863.0, 100139.49930250303, 103191.48404257931, 105018.0, 10987.450627468754, 12178.817317740064, 12532.749345013232, 13071.738565228832, 13246.403911824184, 35476.46785298274, 35853.0, 43327.13345733131, 43922.34116488319, 44013.559864400944, 58564.535886603175, 60057.69971150291, 69784.53232015239, 84397.73403399077, 84995.5750221245, 17205.139743012547, 17957.461276161735, 17966.5508362292, 18001.909990449964, 18159.092045397414, 18864.056797160363, 19080.904595476935, 19230.23079076821, 19254.61490770205, 19457.902710486924, 16104.355825766837, 16624.667506650043, 17255.91372043132, 18315.725264121316, 18345.81654183441, 17277.39529116475, 17322.48032559025, 17913.910430447766, 17953.910230448764, 17998.91000544989, 17305.0, 17379.56551086233, 17774.289028439234, 17788.91105544464, 17837.840540364836, 10731.40977246259, 11037.944810275898, 11841.703957400789, 12200.328981906081, 12323.815142773044, 11159.944200278947, 13278.468861245829, 13579.302587176602, 13588.796168057685, 13615.931920340336], "value": 8826.185839356918, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1593.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 129.0, 170.0, 115.0, 126.0, 172.0, 153.0, 133.0, 166.0, 96.0], "value": 136.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 145.0, 139.0, 185.0, 103.0, 141.0, 96.0, 145.0, 163.0, 103.0], "value": 140.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 210.0, 213.0, 223.0, 196.0, 197.0, 197.0, 198.0, 205.0, 201.0], "value": 203.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 56.0, 61.0, 83.0, 58.0, 69.0, 93.0, 86.0, 82.0, 57.0], "value": 72.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 72.0, 82.0, 73.0, 74.0, 70.0, 86.0, 69.0, 83.0, 77.0], "value": 76.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 129.0, 91.0, 92.0, 91.0, 91.0, 92.0, 92.0, 94.0, 92.0], "value": 97.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 100.0, 98.0, 98.0, 98.0, 98.0, 98.0, 98.0, 98.0, 103.0], "value": 98.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 103.0, 142.0, 139.0, 143.0, 81.0, 167.0, 149.0, 90.0, 138.0], "value": 124.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 48.0, 52.0, 51.0, 51.0, 51.0, 53.0, 70.0, 77.0], "value": 55.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 61.0, 59.0, 63.0, 61.0, 59.0, 57.0, 61.0, 57.0, 56.0], "value": 59.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 110.0, 86.0, 85.0, 86.0, 87.0, 89.0, 89.0, 140.0, 97.0], "value": 96.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 151.0, 169.0, 154.0, 142.0, 139.0, 139.0, 150.0, 152.0, 171.0], "value": 150.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 188.0, 191.0, 195.0, 229.0, 164.0, 241.0, 223.0, 187.0, 236.0], "value": 203.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 69.0, 65.0, 69.0, 87.0, 89.0, 63.0, 92.0, 100.0], "value": 76.7, "unit": "score"}]}]} 03:35:38 INFO - Return code: 0 03:35:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:35:38 INFO - # TBPL SUCCESS # 03:35:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:35:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:35:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:35:38 INFO - 2017-05-25 03:35:38.828 screenresolution[1691:11229] starting screenresolution argv=screenresolution get 03:35:38 INFO - 2017-05-25 03:35:38.830 screenresolution[1691:11229] Display 0: 1600x1200x32@60 03:35:38 INFO - 2017-05-25 03:35:38.837 screenresolution[1692:11232] starting screenresolution argv=screenresolution list 03:35:38 INFO - Available Modes on Display 0 03:35:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:35:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:35:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:35:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:35:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:35:39 INFO - Intel Iris: 03:35:39 INFO - Chipset Model: Intel Iris 03:35:39 INFO - Type: GPU 03:35:39 INFO - Bus: Built-In 03:35:39 INFO - VRAM (Dynamic, Max): 1536 MB 03:35:39 INFO - Vendor: Intel (0x8086) 03:35:39 INFO - Device ID: 0x0a2e 03:35:39 INFO - Revision ID: 0x0009 03:35:39 INFO - Displays: 03:35:39 INFO - Display: 03:35:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:35:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:35:39 INFO - Display Serial Number: 1600x1200 60 03:35:39 INFO - Main Display: Yes 03:35:39 INFO - Mirror: Off 03:35:39 INFO - Online: Yes 03:35:39 INFO - Rotation: Supported 03:35:39 INFO - Return code: 0 03:35:39 INFO - Running post-action listener: _resource_record_post_action 03:35:39 INFO - [mozharness: 2017-05-25 10:35:39.076637Z] Finished run-tests step (success) 03:35:39 INFO - Running post-run listener: _resource_record_post_run 03:35:39 INFO - Total resource usage - Wall time: 1258s; CPU: 53.0%; Read bytes: 52649472; Write bytes: 568945664; Read time: 531; Write time: 1816 03:35:39 INFO - TinderboxPrint: CPU usage
53.3% 03:35:39 INFO - TinderboxPrint: I/O read bytes / time
52,649,472 / 531 03:35:39 INFO - TinderboxPrint: I/O write bytes / time
568,945,664 / 1,816 03:35:39 INFO - TinderboxPrint: CPU idle
2,348.5 (46.7%) 03:35:39 INFO - TinderboxPrint: CPU system
1,021.7 (20.3%) 03:35:39 INFO - TinderboxPrint: CPU user
1,663.6 (33.0%) 03:35:39 INFO - TinderboxPrint: Swap in / out
299,978,752 / 0 03:35:39 INFO - install - Wall time: 22s; CPU: 55.0%; Read bytes: 224244224; Write bytes: 220370944; Read time: 16584; Write time: 417 03:35:39 INFO - run-tests - Wall time: 1237s; CPU: 53.0%; Read bytes: 51625472; Write bytes: 320267264; Read time: 515; Write time: 1331 03:35:39 INFO - Running post-run listener: _upload_blobber_files 03:35:39 INFO - Blob upload gear active. 03:35:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:35:39 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 03:35:39 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'] 03:35:39 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 03:35:39 INFO - (blobuploader) - INFO - Open directory for files ... 03:35:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:35:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:35:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:35:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:35:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:35:40 INFO - (blobuploader) - INFO - Done attempting. 03:35:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:35:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:35:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:35:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:35:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:35:41 INFO - (blobuploader) - INFO - Done attempting. 03:35:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:35:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:35:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:35:42 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:35:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:35:42 INFO - (blobuploader) - INFO - Done attempting. 03:35:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:35:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:35:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:35:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:35:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:35:44 INFO - (blobuploader) - INFO - Done attempting. 03:35:44 INFO - (blobuploader) - INFO - Iteration through files over. 03:35:44 INFO - Return code: 0 03:35:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:35:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:35:44 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1dde4f08926465afd81c0dc122368549ab6c1ad114cbcbb5ad1acf9092ad93b3dc0c89b1abb98400d559658b4f46dcf92aeef72cceafcdc689f2e5fccbcaa6ad", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f36b43afe78535a8aceaae869e3ac72e4733099978b4041ee2e8032991971da3b380a64a10faf4efb591fef3842cf23e2d0e9a810bacfddee5ef0a3a1883a155", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f43277cc4e44360e45c67b212b3444ae23245f368b634ebee56adc071370851413b1c10f0d8c8f6a2d003c709690545a9cc0a169d10f8da47530c611db6db30f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a53151ab46812fadee9180bfba7ea15bbc07e3ebacc89e09931c981540f25bace59036df2537f4e9f8cb420bd919e1f4ee210e6c2c7e710efde696b8b10d6c43"} 03:35:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:35:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:35:44 INFO - Contents: 03:35:44 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1dde4f08926465afd81c0dc122368549ab6c1ad114cbcbb5ad1acf9092ad93b3dc0c89b1abb98400d559658b4f46dcf92aeef72cceafcdc689f2e5fccbcaa6ad", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f36b43afe78535a8aceaae869e3ac72e4733099978b4041ee2e8032991971da3b380a64a10faf4efb591fef3842cf23e2d0e9a810bacfddee5ef0a3a1883a155", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f43277cc4e44360e45c67b212b3444ae23245f368b634ebee56adc071370851413b1c10f0d8c8f6a2d003c709690545a9cc0a169d10f8da47530c611db6db30f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a53151ab46812fadee9180bfba7ea15bbc07e3ebacc89e09931c981540f25bace59036df2537f4e9f8cb420bd919e1f4ee210e6c2c7e710efde696b8b10d6c43"} 03:35:44 INFO - Running post-run listener: copy_logs_to_upload_dir 03:35:44 INFO - Copying logs to upload dir... 03:35:44 INFO - mkdir: /builds/slave/test/build/upload/logs 03:35:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1340.082508 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 20 secs) (at 2017-05-25 03:35:44.400424) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-25 03:35:44.403769) ========= 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.Va3I2cScMr/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.gCvqd5sD2a/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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/1dde4f08926465afd81c0dc122368549ab6c1ad114cbcbb5ad1acf9092ad93b3dc0c89b1abb98400d559658b4f46dcf92aeef72cceafcdc689f2e5fccbcaa6ad", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f36b43afe78535a8aceaae869e3ac72e4733099978b4041ee2e8032991971da3b380a64a10faf4efb591fef3842cf23e2d0e9a810bacfddee5ef0a3a1883a155", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f43277cc4e44360e45c67b212b3444ae23245f368b634ebee56adc071370851413b1c10f0d8c8f6a2d003c709690545a9cc0a169d10f8da47530c611db6db30f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a53151ab46812fadee9180bfba7ea15bbc07e3ebacc89e09931c981540f25bace59036df2537f4e9f8cb420bd919e1f4ee210e6c2c7e710efde696b8b10d6c43"} build_url:https://queue.taskcluster.net/v1/task/DcUSSidiSViXKQ3xcxuRIA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010650 build_url: 'https://queue.taskcluster.net/v1/task/DcUSSidiSViXKQ3xcxuRIA/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/1dde4f08926465afd81c0dc122368549ab6c1ad114cbcbb5ad1acf9092ad93b3dc0c89b1abb98400d559658b4f46dcf92aeef72cceafcdc689f2e5fccbcaa6ad", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f36b43afe78535a8aceaae869e3ac72e4733099978b4041ee2e8032991971da3b380a64a10faf4efb591fef3842cf23e2d0e9a810bacfddee5ef0a3a1883a155", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f43277cc4e44360e45c67b212b3444ae23245f368b634ebee56adc071370851413b1c10f0d8c8f6a2d003c709690545a9cc0a169d10f8da47530c611db6db30f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a53151ab46812fadee9180bfba7ea15bbc07e3ebacc89e09931c981540f25bace59036df2537f4e9f8cb420bd919e1f4ee210e6c2c7e710efde696b8b10d6c43"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-25 03:35:44.437137) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 03:35:44.437498) ========= 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.Va3I2cScMr/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.gCvqd5sD2a/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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.005122 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 03:35:44.489125) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-25 03:35:44.492526) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-25 03:35:44.492881) ========= ========= Total master_lag: 0.18 =========