builder: cedar_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0238 starttime: 1496362399.1 results: success (0) revision: 22feec40b9f57198fbe271a28bc33f6a157b694c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-01 17:13:19.096032) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-01 17:13:19.096513) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-01 17:13:19.096791) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-01 17:13:19.122608) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 17:13:19.122994) ========= 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.JMZNwaFdVQ/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.fy2M4Jznr3/Listeners TMPDIR=/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-01 17:13:19-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 23.8M=0s 2017-06-01 17:13:19 (23.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.105063 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 17:13:19.251521) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 17:13:19.251850) ========= 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.JMZNwaFdVQ/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.fy2M4Jznr3/Listeners TMPDIR=/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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.028091 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 17:13:19.301512) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-01 17:13:19.301851) ========= bash -c 'python archiver_client.py mozharness --repo projects/cedar --rev 22feec40b9f57198fbe271a28bc33f6a157b694c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo projects/cedar --rev 22feec40b9f57198fbe271a28bc33f6a157b694c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.JMZNwaFdVQ/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.fy2M4Jznr3/Listeners TMPDIR=/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-01 17:13:19,368 truncating revision to first 12 chars 2017-06-01 17:13:19,368 Setting DEBUG logging. 2017-06-01 17:13:19,368 attempt 1/10 2017-06-01 17:13:19,368 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/cedar/22feec40b9f5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-01 17:13:20,436 unpacking tar archive at: cedar-22feec40b9f5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.339865 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-01 17:13:20.662873) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-01 17:13:20.663221) ========= script_repo_revision: 22feec40b9f57198fbe271a28bc33f6a157b694c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-01 17:13:20.663594) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-01 17:13:20.663863) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-01 17:13:20.682859) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 22 secs) (at 2017-06-01 17:13:20.683234) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar 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', 'Cedar', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Cedar'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.JMZNwaFdVQ/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.fy2M4Jznr3/Listeners TMPDIR=/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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 17:13:20 INFO - MultiFileLogger online at 20170601 17:13:20 in /builds/slave/test 17:13:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar 17:13:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:13:20 INFO - {'append_to_log': False, 17:13:20 INFO - 'base_work_dir': '/builds/slave/test', 17:13:20 INFO - 'blob_upload_branch': 'Cedar', 17:13:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:13:20 INFO - 'branch': 'Cedar', 17:13:20 INFO - 'buildbot_json_path': 'buildprops.json', 17:13:20 INFO - 'config_files': ('talos/mac_config.py',), 17:13:20 INFO - 'default_actions': ('clobber', 17:13:20 INFO - 'read-buildbot-config', 17:13:20 INFO - 'download-and-extract', 17:13:20 INFO - 'populate-webroot', 17:13:20 INFO - 'create-virtualenv', 17:13:20 INFO - 'install', 17:13:20 INFO - 'setup-mitmproxy', 17:13:20 INFO - 'run-tests'), 17:13:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:13:20 INFO - 'download_minidump_stackwalk': True, 17:13:20 INFO - 'download_symbols': 'ondemand', 17:13:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:13:20 INFO - 'tooltool.py': '/tools/tooltool.py', 17:13:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:13:20 INFO - '/tools/misc-python/virtualenv.py')}, 17:13:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:13:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:13:20 INFO - 'gecko_profile': False, 17:13:20 INFO - 'gecko_profile_interval': 0, 17:13:20 INFO - 'installer_path': 'installer.exe', 17:13:20 INFO - 'log_level': 'info', 17:13:20 INFO - 'log_name': 'talos', 17:13:20 INFO - 'log_to_console': True, 17:13:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:13:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:13:20 INFO - 'opt_config_files': (), 17:13:20 INFO - 'pip_index': False, 17:13:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:13:20 INFO - 'cmd': ('bash', 17:13:20 INFO - '-c', 17:13:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:13:20 INFO - 'enabled': True, 17:13:20 INFO - 'halt_on_failure': False, 17:13:20 INFO - 'name': 'check_screen_resolution'},), 17:13:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:13:20 INFO - 'cmd': ('bash', 17:13:20 INFO - '-c', 17:13:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:13:20 INFO - 'enabled': True, 17:13:20 INFO - 'halt_on_failure': False, 17:13:20 INFO - 'name': 'check_screen_resolution'},), 17:13:20 INFO - 'run_cmd_checks_enabled': True, 17:13:20 INFO - 'suite': 'dromaeojs-e10s', 17:13:20 INFO - 'system_bits': '32', 17:13:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:13:20 INFO - 'title': 't-yosemite-r7-0238', 17:13:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:13:20 INFO - 'use_talos_json': True, 17:13:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:13:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:13:20 INFO - 'work_dir': 'build'} 17:13:20 INFO - [mozharness: 2017-06-02 00:13:20.860129Z] Running clobber step. 17:13:20 INFO - Running pre-action listener: _resource_record_pre_action 17:13:20 INFO - Running main action method: clobber 17:13:20 INFO - rmtree: /builds/slave/test/build 17:13:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:13:23 INFO - Running post-action listener: _resource_record_post_action 17:13:23 INFO - [mozharness: 2017-06-02 00:13:23.266356Z] Finished clobber step (success) 17:13:23 INFO - [mozharness: 2017-06-02 00:13:23.266524Z] Running read-buildbot-config step. 17:13:23 INFO - Running pre-action listener: _resource_record_pre_action 17:13:23 INFO - Running main action method: read_buildbot_config 17:13:23 INFO - Using buildbot properties: 17:13:23 INFO - { 17:13:23 INFO - "project": "", 17:13:23 INFO - "product": "firefox", 17:13:23 INFO - "who": "mconley@mozilla.com", 17:13:23 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 17:13:23 INFO - "builddir": "cedar_yosemite_r7_test-dromaeojs-e10s", 17:13:23 INFO - "repository": "https://hg.mozilla.org/projects/cedar/", 17:13:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 cedar talos dromaeojs-e10s", 17:13:23 INFO - "stage_platform": "macosx64", 17:13:23 INFO - "basedir": "/builds/slave/test", 17:13:23 INFO - "buildnumber": 1, 17:13:23 INFO - "platform": "yosemite_r7", 17:13:23 INFO - "parent_task_id": "B6K62yKLQzGk5raU6dSi9Q", 17:13:23 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 17:13:23 INFO - "slavebuilddir": "test", 17:13:23 INFO - "taskId": "U-vGpfSKSYuDFHAYY8TOAA", 17:13:23 INFO - "branch": "cedar", 17:13:23 INFO - "script_repo_revision": "default", 17:13:23 INFO - "revision": "22feec40b9f57198fbe271a28bc33f6a157b694c", 17:13:23 INFO - "slavename": "t-yosemite-r7-0238", 17:13:23 INFO - "repo_path": "projects/cedar" 17:13:23 INFO - } 17:13:23 INFO - Finding installer, test and symbols from parent task. 17:13:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/U-vGpfSKSYuDFHAYY8TOAA'}, attempt #1 17:13:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/U-vGpfSKSYuDFHAYY8TOAA'}, attempt #1 17:13:24 INFO - Task dependencies: Z6ZXsFo_RRCdpnIllnlTcQ B6K62yKLQzGk5raU6dSi9Q 17:13:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/B6K62yKLQzGk5raU6dSi9Q'}, attempt #1 17:13:24 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/B6K62yKLQzGk5raU6dSi9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:13:24 INFO - Set test_url: https://queue.taskcluster.net/v1/task/B6K62yKLQzGk5raU6dSi9Q/artifacts/public/build/test_packages.json 17:13:24 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/B6K62yKLQzGk5raU6dSi9Q/artifacts/public/build/target.crashreporter-symbols.zip 17:13:24 INFO - Running post-action listener: _resource_record_post_action 17:13:24 INFO - [mozharness: 2017-06-02 00:13:24.948594Z] Finished read-buildbot-config step (success) 17:13:24 INFO - [mozharness: 2017-06-02 00:13:24.948756Z] Running download-and-extract step. 17:13:24 INFO - Running pre-action listener: _resource_record_pre_action 17:13:24 INFO - Running main action method: download_and_extract 17:13:24 INFO - mkdir: /builds/slave/test/build/tests 17:13:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:13:24 INFO - trying https://queue.taskcluster.net/v1/task/B6K62yKLQzGk5raU6dSi9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 17:13:24 INFO - Downloading https://queue.taskcluster.net/v1/task/B6K62yKLQzGk5raU6dSi9Q/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 17:13:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/B6K62yKLQzGk5raU6dSi9Q/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 17:13:25 INFO - Downloaded 1325 bytes. 17:13:25 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 17:13:25 INFO - Using the following test package requirements: 17:13:25 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:13:25 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 17:13:25 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:13:25 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:13:25 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 17:13:25 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:13:25 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 17:13:25 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:13:25 INFO - u'jsshell-mac.zip'], 17:13:25 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:13:25 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 17:13:25 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:13:25 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:13:25 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 17:13:25 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:13:25 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 17:13:25 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:13:25 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 17:13:25 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:13:25 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 17:13:25 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:13:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/B6K62yKLQzGk5raU6dSi9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 17:13:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B6K62yKLQzGk5raU6dSi9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:13:25 INFO - Fetch https://queue.taskcluster.net/v1/task/B6K62yKLQzGk5raU6dSi9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 17:13:27 INFO - Content-Length response header: 57632988 17:13:27 INFO - Bytes received: 57632988 17:13:46 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 17:13:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/B6K62yKLQzGk5raU6dSi9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 17:13:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B6K62yKLQzGk5raU6dSi9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:13:46 INFO - Fetch https://queue.taskcluster.net/v1/task/B6K62yKLQzGk5raU6dSi9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 17:13:48 INFO - Content-Length response header: 57632988 17:13:48 INFO - Bytes received: 57632988 17:14:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/B6K62yKLQzGk5raU6dSi9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 17:14:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B6K62yKLQzGk5raU6dSi9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 17:14:05 INFO - Fetch https://queue.taskcluster.net/v1/task/B6K62yKLQzGk5raU6dSi9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 17:14:06 INFO - Content-Length response header: 14447425 17:14:06 INFO - Bytes received: 14447425 17:14:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:14:06 INFO - trying https://queue.taskcluster.net/v1/task/B6K62yKLQzGk5raU6dSi9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:14:06 INFO - Downloading https://queue.taskcluster.net/v1/task/B6K62yKLQzGk5raU6dSi9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 17:14:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/B6K62yKLQzGk5raU6dSi9Q/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 17:14:08 INFO - Downloaded 67914444 bytes. 17:14:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/B6K62yKLQzGk5raU6dSi9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:14:08 INFO - mkdir: /builds/slave/test/properties 17:14:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:14:08 INFO - Writing to file /builds/slave/test/properties/build_url 17:14:08 INFO - Contents: 17:14:08 INFO - build_url:https://queue.taskcluster.net/v1/task/B6K62yKLQzGk5raU6dSi9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:14:08 INFO - Running post-action listener: _resource_record_post_action 17:14:08 INFO - Running post-action listener: set_extra_try_arguments 17:14:08 INFO - [mozharness: 2017-06-02 00:14:08.708993Z] Finished download-and-extract step (success) 17:14:08 INFO - [mozharness: 2017-06-02 00:14:08.709156Z] Running populate-webroot step. 17:14:08 INFO - Running pre-action listener: _resource_record_pre_action 17:14:08 INFO - Running main action method: populate_webroot 17:14:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:14:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:14:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:14:08 INFO - '--tppagecycles', 17:14:08 INFO - '7'], 17:14:08 INFO - 'tests': ['tsvgm']}}, 17:14:08 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:14:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:14:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:14:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:14:08 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:14:08 INFO - 'talos_options': ['--disable-e10s'], 17:14:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:14:08 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:14:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:14:08 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:14:08 INFO - 'talos_options': ['--disable-e10s'], 17:14:08 INFO - 'tests': ['damp', 'tps']}, 17:14:08 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:14:08 INFO - 'tests': ['damp', 'tps']}, 17:14:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:14:08 INFO - 'tests': ['dromaeo_dom']}, 17:14:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:14:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:14:08 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 17:14:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 17:14:08 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 17:14:08 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 17:14:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:14:08 INFO - 'tests': ['a11yr', 17:14:08 INFO - 'ts_paint', 17:14:08 INFO - 'tpaint', 17:14:08 INFO - 'sessionrestore', 17:14:08 INFO - 'sessionrestore_no_auto_restore', 17:14:08 INFO - 'tabpaint']}, 17:14:08 INFO - 'other-e10s': {'tests': ['a11yr', 17:14:08 INFO - 'ts_paint', 17:14:08 INFO - 'tpaint', 17:14:08 INFO - 'sessionrestore', 17:14:08 INFO - 'sessionrestore_no_auto_restore', 17:14:08 INFO - 'tabpaint']}, 17:14:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:14:08 INFO - 'ts_paint', 17:14:08 INFO - 'tpaint', 17:14:08 INFO - 'sessionrestore', 17:14:08 INFO - 'sessionrestore_no_auto_restore', 17:14:08 INFO - 'tabpaint']}, 17:14:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:14:08 INFO - 'ts_paint', 17:14:08 INFO - 'tpaint', 17:14:08 INFO - 'sessionrestore', 17:14:08 INFO - 'sessionrestore_no_auto_restore', 17:14:08 INFO - 'tabpaint']}, 17:14:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:14:08 INFO - 'tests': ['a11yr', 17:14:08 INFO - 'ts_paint', 17:14:08 INFO - 'tpaint', 17:14:08 INFO - 'sessionrestore', 17:14:08 INFO - 'sessionrestore_no_auto_restore', 17:14:08 INFO - 'tabpaint']}, 17:14:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:14:08 INFO - 'tests': ['a11yr', 17:14:08 INFO - 'ts_paint', 17:14:08 INFO - 'tpaint', 17:14:08 INFO - 'sessionrestore', 17:14:08 INFO - 'sessionrestore_no_auto_restore', 17:14:08 INFO - 'tabpaint']}, 17:14:08 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 17:14:08 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 17:14:08 INFO - 'bloom_basic_ref']}, 17:14:08 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 17:14:08 INFO - 'talos_options': ['--mitmproxy', 17:14:08 INFO - 'mitmproxy-recording-1.mp', 17:14:08 INFO - '--firstNonBlankPaint'], 17:14:08 INFO - 'tests': ['Quantum_1']}, 17:14:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:14:08 INFO - 'tests': ['tsvgx', 17:14:08 INFO - 'tsvgr_opacity', 17:14:08 INFO - 'tart', 17:14:08 INFO - 'tscrollx', 17:14:08 INFO - 'cart', 17:14:08 INFO - 'tsvg_static']}, 17:14:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:14:08 INFO - 'tsvgr_opacity', 17:14:08 INFO - 'tart', 17:14:08 INFO - 'tscrollx', 17:14:08 INFO - 'cart', 17:14:08 INFO - 'tsvg_static']}, 17:14:08 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:14:08 INFO - 'talos_options': ['--disable-e10s'], 17:14:08 INFO - 'tests': ['tp5o']}, 17:14:08 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:14:08 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:14:08 INFO - 'talos_options': ['--disable-e10s', 17:14:08 INFO - '--xperf_path', 17:14:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:14:08 INFO - 'tests': ['tp5n']}, 17:14:08 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:14:08 INFO - 'talos_options': ['--xperf_path', 17:14:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:14:08 INFO - 'tests': ['tp5n']}}, 17:14:08 INFO - 'talos.zip': {'path': '', 17:14:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:14:08 INFO - Running post-action listener: _resource_record_post_action 17:14:08 INFO - [mozharness: 2017-06-02 00:14:08.731935Z] Finished populate-webroot step (success) 17:14:08 INFO - [mozharness: 2017-06-02 00:14:08.732096Z] Running create-virtualenv step. 17:14:08 INFO - Running pre-action listener: _resource_record_pre_action 17:14:08 INFO - Running main action method: create_virtualenv 17:14:08 INFO - Creating virtualenv /builds/slave/test/build/venv 17:14:08 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 17:14:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:14:08 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 17:14:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:14:08 INFO - Using real prefix '/tools/python27' 17:14:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:14:09 INFO - Installing distribute.............................................................................................................................................................................................done. 17:14:12 INFO - Installing pip.................done. 17:14:12 INFO - Return code: 0 17:14:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:14:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:14:12 INFO - Reading from file tmpfile_stdout 17:14:12 INFO - Output received: 17:14:12 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) 17:14:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:14:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:14:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:14:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101a59c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a5d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3e3598220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019e2c60>, '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 0x101777cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1017a0be8>, '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.JMZNwaFdVQ/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.fy2M4Jznr3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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 17:14:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:14:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:14:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JMZNwaFdVQ/Render', 17:14:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:14:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:14:12 INFO - 'HOME': '/Users/cltbld', 17:14:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:14:12 INFO - 'LOGNAME': 'cltbld', 17:14:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:12 INFO - 'MOZ_NO_REMOTE': '1', 17:14:12 INFO - 'NO_EM_RESTART': '1', 17:14:12 INFO - 'PAGER': '/bin/cat', 17:14:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:14:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:12 INFO - 'PWD': '/builds/slave/test', 17:14:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:14:12 INFO - 'SHELL': '/bin/bash', 17:14:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fy2M4Jznr3/Listeners', 17:14:12 INFO - 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/', 17:14:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:14:12 INFO - 'USER': 'cltbld', 17:14:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:14:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:14:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:12 INFO - 'XPC_FLAGS': '0x0', 17:14:12 INFO - 'XPC_SERVICE_NAME': '0', 17:14:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:14:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:13 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 17:14:13 INFO - Cleaning up... 17:14:13 INFO - Return code: 0 17:14:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:14:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:14:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:14:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:13 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 0x101a59c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a5d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3e3598220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019e2c60>, '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 0x101777cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1017a0be8>, '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.JMZNwaFdVQ/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.fy2M4Jznr3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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 17:14:13 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 17:14:13 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 17:14:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JMZNwaFdVQ/Render', 17:14:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:14:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:14:13 INFO - 'HOME': '/Users/cltbld', 17:14:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:14:13 INFO - 'LOGNAME': 'cltbld', 17:14:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:13 INFO - 'MOZ_NO_REMOTE': '1', 17:14:13 INFO - 'NO_EM_RESTART': '1', 17:14:13 INFO - 'PAGER': '/bin/cat', 17:14:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:14:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:13 INFO - 'PWD': '/builds/slave/test', 17:14:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:14:13 INFO - 'SHELL': '/bin/bash', 17:14:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fy2M4Jznr3/Listeners', 17:14:13 INFO - 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/', 17:14:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:14:13 INFO - 'USER': 'cltbld', 17:14:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:14:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:14:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:13 INFO - 'XPC_FLAGS': '0x0', 17:14:13 INFO - 'XPC_SERVICE_NAME': '0', 17:14:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:14:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:13 INFO - Downloading/unpacking psutil>=3.1.1 17:14:13 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 17:14:13 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 17:14:13 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 17:14:13 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 17:14:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:14:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:14:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:14:15 INFO - Installing collected packages: psutil 17:14:15 INFO - Running setup.py install for psutil 17:14:15 INFO - building 'psutil._psutil_osx' extension 17:14:15 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 17:14:15 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 17:14:15 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 17:14:16 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 17:14:16 INFO - building 'psutil._psutil_posix' extension 17:14:16 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 17:14:16 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:14:16 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:14:16 INFO - ^ 17:14:16 INFO - 1 warning generated. 17:14:16 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 17:14:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:14:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:14:16 INFO - Successfully installed psutil 17:14:16 INFO - Cleaning up... 17:14:16 INFO - Return code: 0 17:14:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17: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')]} 17:14:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17: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')]} 17:14:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:17 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 0x101a59c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a5d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3e3598220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019e2c60>, '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 0x101777cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1017a0be8>, '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.JMZNwaFdVQ/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.fy2M4Jznr3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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 17:14:17 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 17:14:17 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 17:14:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JMZNwaFdVQ/Render', 17:14:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:14:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:14:17 INFO - 'HOME': '/Users/cltbld', 17:14:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:14:17 INFO - 'LOGNAME': 'cltbld', 17:14:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:17 INFO - 'MOZ_NO_REMOTE': '1', 17:14:17 INFO - 'NO_EM_RESTART': '1', 17:14:17 INFO - 'PAGER': '/bin/cat', 17:14:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:14:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:17 INFO - 'PWD': '/builds/slave/test', 17:14:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:14:17 INFO - 'SHELL': '/bin/bash', 17:14:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fy2M4Jznr3/Listeners', 17:14:17 INFO - 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/', 17:14:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:14:17 INFO - 'USER': 'cltbld', 17:14:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:14:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:14:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:17 INFO - 'XPC_FLAGS': '0x0', 17:14:17 INFO - 'XPC_SERVICE_NAME': '0', 17:14:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:14:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:14:17 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 17:14:17 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 17:14:17 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 17:14:17 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 17:14:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:14:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:14:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:14:19 INFO - Installing collected packages: mozsystemmonitor 17:14:19 INFO - Running setup.py install for mozsystemmonitor 17:14:19 INFO - Successfully installed mozsystemmonitor 17:14:19 INFO - Cleaning up... 17:14:19 INFO - Return code: 0 17:14:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17: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')]} 17:14:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17: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')]} 17:14:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:19 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 0x101a59c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a5d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3e3598220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019e2c60>, '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 0x101777cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1017a0be8>, '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.JMZNwaFdVQ/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.fy2M4Jznr3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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 17:14:19 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 17:14:19 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 17:14:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JMZNwaFdVQ/Render', 17:14:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:14:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:14:19 INFO - 'HOME': '/Users/cltbld', 17:14:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:14:19 INFO - 'LOGNAME': 'cltbld', 17:14:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:19 INFO - 'MOZ_NO_REMOTE': '1', 17:14:19 INFO - 'NO_EM_RESTART': '1', 17:14:19 INFO - 'PAGER': '/bin/cat', 17:14:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:14:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:19 INFO - 'PWD': '/builds/slave/test', 17:14:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:14:19 INFO - 'SHELL': '/bin/bash', 17:14:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fy2M4Jznr3/Listeners', 17:14:19 INFO - 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/', 17:14:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:14:19 INFO - 'USER': 'cltbld', 17:14:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:14:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:14:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:19 INFO - 'XPC_FLAGS': '0x0', 17:14:19 INFO - 'XPC_SERVICE_NAME': '0', 17:14:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:14:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:19 INFO - Downloading/unpacking jsonschema==2.5.1 17:14:19 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 17:14:19 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 17:14:19 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 17:14:19 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 17:14:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:14:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17: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 17: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 17: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 17: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 17:14:21 INFO - Downloading functools32-3.2.3-2.tar.gz 17:14:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:14:21 INFO - warning: no files found matching '*.txt' 17:14:21 INFO - no previously-included directories found matching 'build' 17:14:21 INFO - no previously-included directories found matching 'dist' 17:14:21 INFO - no previously-included directories found matching '.git*' 17:14:21 INFO - Installing collected packages: jsonschema, functools32 17:14:21 INFO - Running setup.py install for functools32 17:14:21 INFO - warning: no files found matching '*.txt' 17:14:21 INFO - no previously-included directories found matching 'build' 17:14:21 INFO - no previously-included directories found matching 'dist' 17:14:21 INFO - no previously-included directories found matching '.git*' 17:14:21 INFO - Successfully installed jsonschema functools32 17:14:21 INFO - Cleaning up... 17:14:21 INFO - Return code: 0 17:14:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:14:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:14:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:14:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101a59c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a5d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3e3598220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019e2c60>, '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 0x101777cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1017a0be8>, '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.JMZNwaFdVQ/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.fy2M4Jznr3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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 17:14:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:14:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 17:14:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JMZNwaFdVQ/Render', 17:14:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:14:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:14:21 INFO - 'HOME': '/Users/cltbld', 17:14:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:14:21 INFO - 'LOGNAME': 'cltbld', 17:14:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:21 INFO - 'MOZ_NO_REMOTE': '1', 17:14:21 INFO - 'NO_EM_RESTART': '1', 17:14:21 INFO - 'PAGER': '/bin/cat', 17:14:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:14:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:21 INFO - 'PWD': '/builds/slave/test', 17:14:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:14:21 INFO - 'SHELL': '/bin/bash', 17:14:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fy2M4Jznr3/Listeners', 17:14:21 INFO - 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/', 17:14:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:14:21 INFO - 'USER': 'cltbld', 17:14:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:14:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:14:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:21 INFO - 'XPC_FLAGS': '0x0', 17:14:21 INFO - 'XPC_SERVICE_NAME': '0', 17:14:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:14:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:14:21 INFO - Cleaning up... 17:14:21 INFO - Return code: 0 17:14:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:14:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:14:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:14:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101a59c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a5d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3e3598220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019e2c60>, '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 0x101777cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1017a0be8>, '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.JMZNwaFdVQ/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.fy2M4Jznr3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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 17:14:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:14:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:14:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JMZNwaFdVQ/Render', 17:14:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:14:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:14:21 INFO - 'HOME': '/Users/cltbld', 17:14:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:14:21 INFO - 'LOGNAME': 'cltbld', 17:14:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:21 INFO - 'MOZ_NO_REMOTE': '1', 17:14:21 INFO - 'NO_EM_RESTART': '1', 17:14:21 INFO - 'PAGER': '/bin/cat', 17:14:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:14:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:21 INFO - 'PWD': '/builds/slave/test', 17:14:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:14:21 INFO - 'SHELL': '/bin/bash', 17:14:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fy2M4Jznr3/Listeners', 17:14:21 INFO - 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/', 17:14:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:14:21 INFO - 'USER': 'cltbld', 17:14:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:14:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:14:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:21 INFO - 'XPC_FLAGS': '0x0', 17:14:21 INFO - 'XPC_SERVICE_NAME': '0', 17:14:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:14:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:22 INFO - Downloading/unpacking blobuploader==1.2.4 17:14:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:23 INFO - Downloading blobuploader-1.2.4.tar.gz 17:14:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:14:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17: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 17: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 17: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 17: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 17:14:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:14:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17: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 17: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 17: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 17: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 17:14:24 INFO - Downloading docopt-0.6.1.tar.gz 17:14:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:14:24 INFO - Installing collected packages: blobuploader, requests, docopt 17:14:24 INFO - Running setup.py install for blobuploader 17:14:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:14:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:14:24 INFO - Running setup.py install for requests 17:14:25 INFO - Running setup.py install for docopt 17:14:25 INFO - Successfully installed blobuploader requests docopt 17:14:25 INFO - Cleaning up... 17:14:25 INFO - Return code: 0 17:14:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17: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')]} 17:14:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17: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')]} 17:14:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:25 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 0x101a59c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a5d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3e3598220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019e2c60>, '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 0x101777cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1017a0be8>, '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.JMZNwaFdVQ/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.fy2M4Jznr3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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 17:14:25 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 17:14:25 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 17:14:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JMZNwaFdVQ/Render', 17:14:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:14:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:14:25 INFO - 'HOME': '/Users/cltbld', 17:14:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:14:25 INFO - 'LOGNAME': 'cltbld', 17:14:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:25 INFO - 'MOZ_NO_REMOTE': '1', 17:14:25 INFO - 'NO_EM_RESTART': '1', 17:14:25 INFO - 'PAGER': '/bin/cat', 17:14:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:14:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:25 INFO - 'PWD': '/builds/slave/test', 17:14:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:14:25 INFO - 'SHELL': '/bin/bash', 17:14:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fy2M4Jznr3/Listeners', 17:14:25 INFO - 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/', 17:14:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:14:25 INFO - 'USER': 'cltbld', 17:14:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:14:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:14:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:25 INFO - 'XPC_FLAGS': '0x0', 17:14:25 INFO - 'XPC_SERVICE_NAME': '0', 17:14:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:14:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:14:25 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-RbwJRs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:14:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:14:25 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-ceJ2x5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:14:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:14:25 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-_jGFnF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:14:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:14:25 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-e8svFy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:14:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:14:26 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-EdJUf_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:14:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:14:26 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-96ekWS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:14:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:14:26 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-DGxHdr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:14:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:14:26 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-pmGu4A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:14:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:14:26 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-DJ_mW2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:14:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:14:26 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-rCK1yO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:14:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:14:26 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-ZE_V9d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:14:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:14:26 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-Wkm3lF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:14:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:14:26 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-sm4Zxo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:14:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:14:27 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-jfJhqH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:14:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:14:27 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-pb3QAb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:14:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:14:27 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-nGtMjd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:14:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:14:27 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-PHnI0x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:14:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:14:27 INFO - Running setup.py install for manifestparser 17:14:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:14:27 INFO - Running setup.py install for mozcrash 17:14:27 INFO - Running setup.py install for mozdebug 17:14:27 INFO - Running setup.py install for mozdevice 17:14:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:14:27 INFO - Running setup.py install for mozfile 17:14:28 INFO - Running setup.py install for mozhttpd 17:14:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:14:28 INFO - Running setup.py install for mozinfo 17:14:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:14:28 INFO - Running setup.py install for mozInstall 17:14:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:14:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:14:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:14:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:14:28 INFO - Running setup.py install for mozleak 17:14:28 INFO - Running setup.py install for mozlog 17:14:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:14:28 INFO - Running setup.py install for moznetwork 17:14:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:14:29 INFO - Running setup.py install for mozprocess 17:14:29 INFO - Running setup.py install for mozprofile 17:14:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:14:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:14:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:14:29 INFO - Running setup.py install for mozrunner 17:14:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:14:29 INFO - Running setup.py install for mozscreenshot 17:14:29 INFO - Running setup.py install for moztest 17:14:29 INFO - Running setup.py install for mozversion 17:14:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:14:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:14:29 INFO - Cleaning up... 17:14:29 INFO - Return code: 0 17:14:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17: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')]} 17:14:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17: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')]} 17:14:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101a59c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a5d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3e3598220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019e2c60>, '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 0x101777cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1017a0be8>, '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.JMZNwaFdVQ/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.fy2M4Jznr3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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 17:14:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:14:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:14:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JMZNwaFdVQ/Render', 17:14:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:14:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:14:29 INFO - 'HOME': '/Users/cltbld', 17:14:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:14:29 INFO - 'LOGNAME': 'cltbld', 17:14:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:29 INFO - 'MOZ_NO_REMOTE': '1', 17:14:29 INFO - 'NO_EM_RESTART': '1', 17:14:29 INFO - 'PAGER': '/bin/cat', 17:14:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:14:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:29 INFO - 'PWD': '/builds/slave/test', 17:14:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:14:29 INFO - 'SHELL': '/bin/bash', 17:14:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fy2M4Jznr3/Listeners', 17:14:29 INFO - 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/', 17:14:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:14:29 INFO - 'USER': 'cltbld', 17:14:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:14:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:14:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:29 INFO - 'XPC_FLAGS': '0x0', 17:14:29 INFO - 'XPC_SERVICE_NAME': '0', 17:14:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:14:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:14:30 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-u9lvph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:14:30 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)) 17:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:14:30 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-NsgDgY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:14:30 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)) 17:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:14:30 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-EpvDoY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:14:30 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)) 17:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:14:30 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-b7m7gK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:14:30 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)) 17:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:14:30 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-0w6ySV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:14:30 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)) 17:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:14:30 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-0S5Bb7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:14:30 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)) 17:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:14:30 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-qKqikZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:14:30 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)) 17:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:14:30 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-dFeIS9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:14:31 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)) 17:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:14:31 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-aYZEyw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:14:31 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)) 17:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:14:31 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-BXF_r4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:14:31 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)) 17:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:14:31 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-IIlywO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:14:31 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)) 17:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:14:31 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-P249Ul-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:14:31 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)) 17:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:14:31 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-Gi1F9M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:14:31 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)) 17:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:14:31 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-zJ1ig9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:14:31 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)) 17:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:14:31 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-aMxLMK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:14:31 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)) 17:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:14:31 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-ByXqvC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:14:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 17:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:14:31 INFO - Running setup.py (path:/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/pip-G1AF0B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:14:32 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)) 17:14:32 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)) 17:14:32 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)) 17:14:32 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)) 17:14:32 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)) 17:14:32 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)) 17:14:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:14:32 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 17:14:32 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 17:14:32 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 17:14:32 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 17:14:33 INFO - Downloading blessings-1.6.tar.gz 17:14:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:14:33 INFO - Installing collected packages: blessings 17:14:33 INFO - Running setup.py install for blessings 17:14:33 INFO - Successfully installed blessings 17:14:33 INFO - Cleaning up... 17:14:33 INFO - Return code: 0 17:14:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:14:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:14:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:14:34 INFO - Reading from file tmpfile_stdout 17:14:34 INFO - Current package versions: 17:14:34 INFO - blessings == 1.6 17:14:34 INFO - blobuploader == 1.2.4 17:14:34 INFO - docopt == 0.6.1 17:14:34 INFO - functools32 == 3.2.3-2 17:14:34 INFO - jsonschema == 2.5.1 17:14:34 INFO - manifestparser == 1.1 17:14:34 INFO - mozInstall == 1.12 17:14:34 INFO - mozcrash == 1.0 17:14:34 INFO - mozdebug == 0.1 17:14:34 INFO - mozdevice == 0.51 17:14:34 INFO - mozfile == 1.2 17:14:34 INFO - mozhttpd == 0.7 17:14:34 INFO - mozinfo == 0.9 17:14:34 INFO - mozleak == 0.1 17:14:34 INFO - mozlog == 3.4 17:14:34 INFO - moznetwork == 0.27 17:14:34 INFO - mozprocess == 0.25 17:14:34 INFO - mozprofile == 0.28 17:14:34 INFO - mozrunner == 6.13 17:14:34 INFO - mozscreenshot == 0.1 17:14:34 INFO - mozsystemmonitor == 0.3 17:14:34 INFO - moztest == 0.8 17:14:34 INFO - mozversion == 1.4 17:14:34 INFO - psutil == 3.1.1 17:14:34 INFO - requests == 1.2.3 17:14:34 INFO - wsgiref == 0.1.2 17:14:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:14:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:14:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:14:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:34 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 0x101a59c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a5d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3e3598220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019e2c60>, '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 0x101777cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1017a0be8>, '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.JMZNwaFdVQ/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.fy2M4Jznr3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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 17:14:34 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 17:14:34 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 17:14:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JMZNwaFdVQ/Render', 17:14:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:14:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:14:34 INFO - 'HOME': '/Users/cltbld', 17:14:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:14:34 INFO - 'LOGNAME': 'cltbld', 17:14:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:34 INFO - 'MOZ_NO_REMOTE': '1', 17:14:34 INFO - 'NO_EM_RESTART': '1', 17:14:34 INFO - 'PAGER': '/bin/cat', 17:14:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:14:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:34 INFO - 'PWD': '/builds/slave/test', 17:14:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:14:34 INFO - 'SHELL': '/bin/bash', 17:14:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fy2M4Jznr3/Listeners', 17:14:34 INFO - 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/', 17:14:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:14:34 INFO - 'USER': 'cltbld', 17:14:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:14:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:14:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:34 INFO - 'XPC_FLAGS': '0x0', 17:14:34 INFO - 'XPC_SERVICE_NAME': '0', 17:14:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:14:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:34 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)) 17:14:34 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)) 17:14:34 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)) 17:14:34 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)) 17:14:34 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)) 17:14:34 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)) 17:14:34 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)) 17:14:34 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)) 17:14:34 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)) 17:14:34 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 17:14:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:14:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:14:35 INFO - Downloading marionette_driver-1.4.0.tar.gz 17:14:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 17:14:35 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)) 17: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 mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:14:35 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)) 17:14:35 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)) 17:14:35 INFO - Installing collected packages: marionette-driver 17:14:35 INFO - Running setup.py install for marionette-driver 17:14:36 INFO - Successfully installed marionette-driver 17:14:36 INFO - Cleaning up... 17:14:36 INFO - Return code: 0 17:14:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:14:36 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')]} 17:14:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:14:36 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')]} 17:14:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:14:36 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 0x101a59c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a5d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3e3598220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019e2c60>, '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 0x101777cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1017a0be8>, '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.JMZNwaFdVQ/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.fy2M4Jznr3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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 17:14:36 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 17:14:36 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 17:14:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JMZNwaFdVQ/Render', 17:14:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:14:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:14:36 INFO - 'HOME': '/Users/cltbld', 17:14:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:14:36 INFO - 'LOGNAME': 'cltbld', 17:14:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:36 INFO - 'MOZ_NO_REMOTE': '1', 17:14:36 INFO - 'NO_EM_RESTART': '1', 17:14:36 INFO - 'PAGER': '/bin/cat', 17:14:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:14:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:36 INFO - 'PWD': '/builds/slave/test', 17:14:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:14:36 INFO - 'SHELL': '/bin/bash', 17:14:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fy2M4Jznr3/Listeners', 17:14:36 INFO - 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/', 17:14:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:14:36 INFO - 'USER': 'cltbld', 17:14:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:14:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:14:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:36 INFO - 'XPC_FLAGS': '0x0', 17:14:36 INFO - 'XPC_SERVICE_NAME': '0', 17:14:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:14:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:14:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:14:36 INFO - Cleaning up... 17:14:36 INFO - Return code: 0 17:14:36 INFO - Running post-action listener: _resource_record_post_action 17:14:36 INFO - Running post-action listener: _start_resource_monitoring 17:14:36 INFO - Starting resource monitoring. 17:14:36 INFO - [mozharness: 2017-06-02 00:14:36.430275Z] Finished create-virtualenv step (success) 17:14:36 INFO - [mozharness: 2017-06-02 00:14:36.431298Z] Running install step. 17:14:36 INFO - Running pre-action listener: _resource_record_pre_action 17:14:36 INFO - Running main action method: install 17:14:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:14:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:14:36 INFO - Reading from file tmpfile_stdout 17:14:36 INFO - Detecting whether we're running mozinstall >=1.0... 17:14:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:14:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:14:36 INFO - Reading from file tmpfile_stdout 17:14:36 INFO - Output received: 17:14:36 INFO - Usage: mozinstall [options] installer 17:14:36 INFO - Options: 17:14:36 INFO - -h, --help show this help message and exit 17:14:36 INFO - -d DEST, --destination=DEST 17:14:36 INFO - Directory to install application into. [default: 17:14:36 INFO - "/builds/slave/test"] 17:14:36 INFO - --app=APP Application being installed. [default: firefox] 17:14:36 INFO - mkdir: /builds/slave/test/build/application 17:14:36 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'] 17:14:36 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 17:14:58 INFO - Reading from file tmpfile_stdout 17:14:58 INFO - Output received: 17:14:58 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:14:58 INFO - Running post-action listener: _resource_record_post_action 17:14:58 INFO - [mozharness: 2017-06-02 00:14:58.124862Z] Finished install step (success) 17:14:58 INFO - [mozharness: 2017-06-02 00:14:58.125043Z] Running setup-mitmproxy step. 17:14:58 INFO - Running pre-action listener: _resource_record_pre_action 17:14:58 INFO - Running main action method: setup_mitmproxy 17:14:58 INFO - Skipping: mitmproxy is not required 17:14:58 INFO - Running post-action listener: _resource_record_post_action 17:14:58 INFO - [mozharness: 2017-06-02 00:14:58.125757Z] Finished setup-mitmproxy step (success) 17:14:58 INFO - [mozharness: 2017-06-02 00:14:58.125856Z] Running run-tests step. 17:14:58 INFO - Running pre-action listener: _resource_record_pre_action 17:14:58 INFO - Running main action method: run_tests 17:14:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:14:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:14:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:14:58 INFO - 2017-06-01 17:14:58.139 screenresolution[1626:7402] starting screenresolution argv=screenresolution get 17:14:58 INFO - 2017-06-01 17:14:58.141 screenresolution[1626:7402] Display 0: 1600x1200x32@60 17:14:58 INFO - 2017-06-01 17:14:58.146 screenresolution[1627:7404] starting screenresolution argv=screenresolution list 17:14:58 INFO - Available Modes on Display 0 17:14:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:14:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:14:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:14:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:14:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:14:58 INFO - Intel Iris: 17:14:58 INFO - Chipset Model: Intel Iris 17:14:58 INFO - Type: GPU 17:14:58 INFO - Bus: Built-In 17:14:58 INFO - VRAM (Dynamic, Max): 1536 MB 17:14:58 INFO - Vendor: Intel (0x8086) 17:14:58 INFO - Device ID: 0x0a2e 17:14:58 INFO - Revision ID: 0x0009 17:14:58 INFO - Displays: 17:14:58 INFO - Display: 17:14:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:14:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:14:58 INFO - Display Serial Number: 1600x1200 60 17:14:58 INFO - Main Display: Yes 17:14:58 INFO - Mirror: Off 17:14:58 INFO - Online: Yes 17:14:58 INFO - Rotation: Supported 17:14:58 INFO - Return code: 0 17:14:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:14:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:14:58 INFO - Python 2.7.3 17:14:58 INFO - Return code: 0 17:14:58 INFO - grabbing minidump binary from tooltool 17:14:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:14:58 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019e2c60>, '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 0x101777cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1017a0be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:14:58 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 17:14:58 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 17:14:58 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 17:14:58 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:14:58 INFO - Return code: 0 17:14:58 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:14:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:14:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:14:58 INFO - ENV: RUST_BACKTRACE is now 1 17:14:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:14:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:14:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/B6K62yKLQzGk5raU6dSi9Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0238', '--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 17:14:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Cedar --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/B6K62yKLQzGk5raU6dSi9Q/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0238 --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 17:14:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JMZNwaFdVQ/Render', 17:14:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:14:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:14:58 INFO - 'HOME': '/Users/cltbld', 17:14:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:14:58 INFO - 'LOGNAME': 'cltbld', 17:14:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:14:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:14:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:58 INFO - 'MOZ_NO_REMOTE': '1', 17:14:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:14:58 INFO - 'NO_EM_RESTART': '1', 17:14:58 INFO - 'PAGER': '/bin/cat', 17:14:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:14:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:58 INFO - 'PWD': '/builds/slave/test', 17:14:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:14:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:14:58 INFO - 'RUST_BACKTRACE': '1', 17:14:58 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:14:58 INFO - 'SHELL': '/bin/bash', 17:14:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fy2M4Jznr3/Listeners', 17:14:58 INFO - 'TMPDIR': '/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/', 17:14:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:14:58 INFO - 'USER': 'cltbld', 17:14:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:14:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:14:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:58 INFO - 'XPC_FLAGS': '0x0', 17:14:58 INFO - 'XPC_SERVICE_NAME': '0', 17:14:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:14:58 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/B6K62yKLQzGk5raU6dSi9Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0238', '--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 17:14:58 INFO - mozversion application_buildid: 20170601150322 17:14:58 INFO - mozversion application_changeset: 22feec40b9f57198fbe271a28bc33f6a157b694c 17:14:58 INFO - mozversion application_display_name: Nightly 17:14:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:14:58 INFO - mozversion application_name: Firefox 17:14:58 INFO - mozversion application_remotingname: firefox 17:14:58 INFO - mozversion application_repository: https://hg.mozilla.org/projects/cedar 17:14:58 INFO - mozversion application_vendor: Mozilla 17:14:58 INFO - mozversion application_version: 55.0a1 17:14:58 INFO - mozversion platform_buildid: 20170601150322 17:14:58 INFO - mozversion platform_changeset: 22feec40b9f57198fbe271a28bc33f6a157b694c 17:14:58 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/cedar 17:14:58 INFO - mozversion platform_version: 55.0a1 17:14:58 INFO - using testdate: 1496362498 17:14:58 INFO - actual date: 1496362498 17:14:58 INFO - starting webserver on 'localhost:49228' 17:14:58 INFO - SUITE-START | Running 2 tests 17:14:58 INFO - TEST-START | dromaeo_css 17:14:58 INFO - operating with platform_type : mac_ 17:14:58 INFO - Initialising browser for dromaeo_css test... 17:14:58 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/tmp9qxOCg/profile 17:14:58 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 17:15:32 INFO - TEST-INFO | 1637: killed by SIGTERM 17:15:32 INFO - Browser initialized. 17:15:32 INFO - Running cycle 1/1 for dromaeo_css test... 17:15:32 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/tmp9qxOCg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:15:35 INFO - PID 1647 | 2017-06-01 17:15:35.085 plugin-container[1648:7979] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9943, name = 'com.apple.tsm.portname' 17:15:35 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:15:35 INFO - PID 1647 | 2017-06-01 17:15:35.086 plugin-container[1648:7979] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4907, name = 'com.apple.CFPasteboardClient' 17:15:35 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:15:35 INFO - PID 1647 | 2017-06-01 17:15:35.086 plugin-container[1648:7979] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:15:35 INFO - PID 1647 | 2017-06-01 17:15:35.483 plugin-container[1649:8035] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9643, name = 'com.apple.tsm.portname' 17:15:35 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:15:35 INFO - PID 1647 | 2017-06-01 17:15:35.484 plugin-container[1649:8035] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 17:15:35 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:15:35 INFO - PID 1647 | 2017-06-01 17:15:35.484 plugin-container[1649:8035] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:15:44 INFO - PID 1647 | RSS: Main: 268328960 17:15:44 INFO - PID 1647 | 17:18:47 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49228/tests/dromaeo/cssquery-ext.html) 17:18:49 INFO - PID 1647 | RSS: Main: 278028288 17:18:49 INFO - PID 1647 | 17:21:50 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-ext.html (next: http://localhost:49228/tests/dromaeo/cssquery-jquery.html) 17:21:51 INFO - PID 1647 | RSS: Main: 289406976 17:21:51 INFO - PID 1647 | 17:24:53 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49228/tests/dromaeo/cssquery-mootools.html) 17:24:53 INFO - PID 1647 | RSS: Main: 288161792 17:24:53 INFO - PID 1647 | 17:27:55 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49228/tests/dromaeo/cssquery-prototype.html) 17:27:56 INFO - PID 1647 | RSS: Main: 293294080 17:27:56 INFO - PID 1647 | 17:30:59 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49228/tests/dromaeo/cssquery-yui.html) 17:31:00 INFO - PID 1647 | RSS: Main: 293634048 17:31:00 INFO - PID 1647 | 17:34:01 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-yui.html (next: http://localhost:49228/tests/dromaeo/cssquery-dojo.html) 17:34:02 INFO - PID 1647 | RSS: Main: 293957632 17:34:02 INFO - PID 1647 | 17:34:02 INFO - PID 1647 | __start_tp_report 17:34:02 INFO - PID 1647 | _x_x_mozilla_page_load 17:34:02 INFO - PID 1647 | _x_x_mozilla_page_load_details 17:34:02 INFO - PID 1647 | |i|pagename|runs| 17:34:02 INFO - PID 1647 | |0;dojo.html;3630.3465376232284;3639.344917914775;3659.7072234221264;3676;3683.723720720946;5681.857953551158;6194;6274.905876411857;6296.779612713556;6312;6213.471854892339;6235.438810507054;6266.749330026793;6316.336784637616;6318.115463835055;5302.3637163540425;5485.61600687953;5491.313585801775;5519;5566.693831839248;5538.113901775717;5616.719164041781;5628.212050312959;5647.717614119299;5648;5377.838664840061;5454.754536045887;5510.972445137749;5512.118061110224;5540.307461567304;4182.581741825824;4807.975960120212;5258.973705131488;5403.919216156784;5541.224228607998;4904.950950490485;5393.892122157574;5499.835004949858;5513.20058591506;5525.364583072917;5092;6159.938400615982;6183.81448556544;6302.779402720928;6305.621662700252;6785.253622101565;6787.490938179656;6791.796246112624;6794.796156115275;6797.490188235853;6872.828179295508;6886.965565172142;6897.172339319263;6911.447084233249;6914.827129321757;6238.625682459067;6289.371062893674;6290.182276304051;6345.973282727145;6352.4918006559365;5999.370066143079;6998.16022077354;7020.087388639444;7027.578345299298;7042.7887163385185;5534.723263836872;5981.3719559445635;6551.868962620721;6563.8359041023405;6646.136002319668;6849.726010959505;6851.554648947803;6861.931380686129;6867.931320686829;6867.935470002158;6758.155230596176;6788.355106264897;6794.45644348451;6794.524383293221;6799.252082270947;5454.863628409242;6633.502487313471;6662.83342916431;6743;6775.1531058617675;5397.4062853086125;5408.3509978802795;5417.322834645669;5418.187271909166;5428.4842939920645;4813.205821039489;4830.396200474941;4837.782299796518;4841.394825646794;4855.587275081587;6195.628262304186;6300.684965751786;6315.842103947346;6367.586106903036;6373.203349581302;6875.896861547081;6905.413039891666;6908.896366554506;6913.585184888922;6932.410745086724;6294.8111556653375;6327.683615819191;6355.3009168991375;6357.078223657544;6374;5839.766409343579;5911.261092363455;5917.970410147922;6087.96956015226;6108.908366374508;6912.758053468105;7023.578585284899;7025.121859767427;7037.577745335297;7039.014537964791;5548.334199895877;6627.403533681992;6677.165354330708;6692.933070669425;6712.093867327848;6008.7296071676;6055.061465472859;6291.307956124914;6484.157059582224;6831.180258369028;5253.896681696733;6422.100905873275;6648.069270302259;6689.297623749436;6780.660966951534;6883.931160688529;6903.309669033057;6910.689018994057;6918;6950.687219075053;5203.609729270395;5668.26312579362;6413.935860641333;6577.704003319766;6679.365460281266;6662.900056499059;6685.3983141515555;6695.866082678416;6712.093867327848;6730.360615741497;5230.555402790806;6245.750169993149;6435.710393032418;6473.093766872547;6591.406773390418;6655.800325990228;6673.332666733288;6679.098321726505;6684.6991885364305;6711.832204194934;6542.771003014872;6547.279799221987;6550.672466376757;6553.279139294578;6564.770233041821 17:34:02 INFO - PID 1647 | |1;ext.html;692.8267933016746;791.4341246009102;832;1054.6730513540804;1314.034184874118;38171;38958.805205974;39107;39108.02229944253;39160.41259381112;39514;39948;40016.799916000455;40057.79971100148;40081.59918400815;7868;8014.158513356101;8147.26674599286;8384.538850363228;8410.453320534176;8264.090949995496;8373.204545568162;8377.748667539983;8380.8742868857;8401.3698972577;8334.49993000422;8344.541050242235;8389.244967952884;8398.076211616717;8404.621792019374;8274.2966847818;8303.252707256346;8353.916460835406;8363.790905227357;8397.6221070052;8161.469504482189;8179.304759095484;8198.590070496452;8207.384446166501;8219.260266576037;29668;36598.26803463916;36709.44935825965;36886.26227475463;36930.63069369299;3494.9301013979834;3549.4853246279404;3549.6450354964554;3553.6446355364515;3554.3246783111126;26095.956161753697;30060.24849378761;30266.84866575653;30318.54522182169;30459.543106853416;3050.4051709916575;3062.7549796016265;3072.5391191321255;3094.969050309513;3121.172889184368;230770;234351;234475.48286775712;302446;302925;46203.306950395774;46251.76874115608;46387.76806115948;46433.53566464292;46518.837029074544;48227.517724822304;48272.793180170775;48350;49624;50553.49446505561;14413.85586144125;14471.276436178148;14625.707485850437;14632.341544630517;14632.707345853023;11642.592509262135;11655;11750.942415182675;11761.647150585497;11775.52897884092;10957.90420957898;13759.931200343934;13955.092918960438;13980.370883310276;14000.949928755384;6155.783288447428;6285.465735412449;6296.33888441707;6306.635011870549;6348.4286414222015;910.1490106750209;938.8169306985209;958.4919992404037;974.605284859633;1032.2052019944601;22776;23971.400714981915;24103.638445423334;24207.757922420904;24209;1078.730317420645;1082.3127314155568;1104.116706634705;1110.2339385823918;1116.8771435142128;11709.297442153327;12020.939895300467;12323.938380308042;12329.50681972711;12348.691282718004;334446.3277683596;335090;336873;337846;338419;54320;54559.63600910009;54576.181357279675;54639.45360546423;54770;1432.548747144646;1462.4369617697264;1507.763633820257;1518.764591488321;1530.8239552451387;22372;22960;23708.52582948366;23729.52540949206;23854.88072559626;37137.07157321089;37185.999999998916;37201.81399092987;37221;37237.62762372341;275872;276039;276568;276586;276664.6166769153;34526.7915622952;35356.93929182128;35445.46831797577;35533.466997995565;35629.64370356263;44392.33411498766;46070.07859842717;46198.30702539397;46255.76872115618;46264;25892;28611;30945;31006.68993310038;31130;31165.90919317813;31213.219669508442;31234.531482027334;31386;31513.897013604415;37353.62646373502;37568.81215593904;37731.24537509289;37768.811155944044;37792.62207377891;29176.854115729286;29544.852275738485;31347.216319592193;31518.054458366285;31823.522647159847;18847.623047539248;21806.781932180475;23884.044638214276;23930;24512.387190320384 17:34:02 INFO - PID 1647 | |2;jquery.html;372.96456836600515;429.7206815569879;437.48595400404537;449.78635148304556;449.8223201835275;17620.911895440506;21659.91700414979;22604.43488912776;22978.65532017021;24403;24542.509149817037;25267.62098568523;25302.74697253027;25317.620235696435;25373.365665858364;6193.907091393633;6342.0803983422375;6359.8092057238355;6363.522735794811;6393.936060639404;5914.615549989237;6333.176687030679;6385.265694445128;6386.233651961771;6404.295527491972;6150.507959363263;6195.442410183083;6209.7580483903175;6224.097505861648;6227.813165605039;6205.317415084337;6212.378762123752;6215.005599104122;6220.7822726204595;6227.595206311576;5507.339119305669;5525.889482210374;5541.390447050821;5560.916586251209;5567.777288908439;28984.27539311513;29554.704452955626;29729.851350743324;29847;29901.70098299011;2185.715856938604;2216.445888527868;2229.888505574731;2230.096810791632;2231.386368748591;18523.44429667112;19054.809451905443;19089.331873384366;19119.426417207505;19120.23519059236;2140.7752186020557;2188.5841690078832;2208.6797414374987;2216.5788500185076;2220.1896307847683;141288.58711412858;157652;168290;175445.12277438657;197029;13011.349432528335;13033.02252331079;13190.076694631283;13511.189328640314;13723.313834308245;17047.31810727582;17123.14384280781;17315.740263896052;17316.740248896276;17466.91266543659;9057.864132038025;9207.723768286962;9344.112309330603;9413.294002949806;9417.246620270362;9032.006479287273;9039.81920361589;9059.773505662411;9132.908670913206;9154;8349.29031032357;8452.661893524313;8482.448640838325;8565.929258842645;8576.656933722707;1889.4142815727168;2182.7885523534273;2210.5136869888597;2210.7899749523767;2377.108584280895;839.5718183726343;955;968.8519104860762;973.0366936732584;1018.4958445569342;17537.473875783748;21477.46306342354;22095.779042209695;22183.778162218496;22234.666480002812;874.3005595523556;960.4957397366438;1000.2348203624233;1018.5721996761378;1037.2531777120462;11117.777644447066;11435.656930292105;11479.885201148049;11494.827577586342;11569.074474042056;527980;532410;532563;537544;548006;154280;157532.21233893756;157948;158089;159346;991.7520619845038;1014.5434554450527;1024.5645600619855;1057.4184198690689;1198.376844041111;18381.724274136166;23069.423264418525;24275.514489710462;24356.391090222885;24980.500389991736;67272.66363668346;67417;68010.65994669995;68028.63942721019;68603.3139668597;65403;81238;81611;82065;88600.5569972146;8638.82722345562;10683.03852653265;10686.62596809139;10698.304610200312;10733.087687546333;17760.200790964696;18255.995920224515;18330;18464.261429543207;18572.814271857107;14890.925545372203;14893.702125957638;15031.549053528412;15055;15056.021358611655;11513.481893314989;13277.933610331887;13553.221589573917;13666.385012674255;13686.589402317946;12883.613491595268;13305.800412994007;14984.325705343068;15452.072875627498;15560.92219538895;10483.213758967955;10492.790144197226;10494.317869338469;10520.316179448311;10525.526351314056;12379.504819807467;14709.338079786221;14870.405183792527;14879.92560037193;15086.94391392592 17:34:02 INFO - PID 1647 | |3;mootools.html;1319.267806367466;1415.9079659822105;1429.5354009946764;1429.6989739337205;1434.770436730123;11720.941395293023;11907.10696697749;12101.878981210186;12263.877361226396;12331.445084971176;11954.103442241823;12175.51297948081;12188.451519681636;12214.877851221485;12219.266843989366;2531.0888080291124;2577.5618144915315;2577.8839952202197;2579.7162312145647;2583.702874169466;2493.7007559092935;2530.304166354249;2550.0564791027346;2553.374423266293;2570.9973110486926;2472.678551788274;2481.925542233727;2488.1664642344836;2498.250524842549;2505.5114252720728;2487.614419764939;2495.962560561593;2505.18581461025;2508.535920854648;2511.1211076123395;2398.97601023991;2477.306354220803;2493.5386953413677;2506.373406648338;2508.3227528567186;9435.622575097057;11272.549098036068;11762.882371176263;11886;11975.820362694567;6471.773487927947;6658.667066646648;6732.629705366203;6738.730450781963;6747.05541224229;4027.543198648822;4206.305959516676;4263.211305908417;4272.572742725733;4286.057067445157;1227.7544491101814;1254.6737848159453;1259.80473026681;1275.1711387598043;1286.2539726958341;15311.923440382727;15786.131762753044;16451.506454806375;16614.086225257604;16638.168091595373;4793.640476964241;5515.641483303572;5604.579656525695;5692.8861422771315;5804.100364443518;12359;12369.752604947851;12370;12373.824486673751;12388.132830701945;6453.225612926385;6546.8363290918105;6557.311482294386;6569.934300657028;6575.572587781779;6088.360722124202;6146.170267013896;6228.532860035515;6235.033569796688;6248.218972628421;4362.716423432466;4420.248557745191;4462.977685111554;4497.2129877272;4506.3916371290115;1269.676232560691;1279.923204607708;1282.352412031918;1294.8769866862633;1300.219868079145;774.3379410603965;774.3650206830345;795.316028215734;798.9081255655558;800.0799081056831;6780.627065511346;7039.417949868003;7093.645317734093;7160.925861120873;7162.176349719744;919.3334832246568;931.6180366050022;931.82295363882;946.1059298962552;968.6270785747538;4333.9133217335475;4511.368408422823;4519.909601807945;4675.976620116878;4678.649101317346;14913.92543037278;15016.24918754058;15275.465358712392;15440.768388474182;15467.767983480257;14737.852621473648;15288.923555382153;15366.078035317916;15398.307076181598;15423.768643470357;7227.421806255487;7281.890771638429;8281.254687078192;8725.083866193958;8777.648894044294;6371.522135839923;7275.527090739085;7349.640316541243;7401.629918504161;7409.2590740927635;9950.701478955643;13605.979551533477;13792.379342929391;13910.234937078558;14008.879289657007;13760.724785504033;14160.291985400483;14185.645358866112;14293.28533573338;14329.928350358181;9485.525723713925;9780.85328720084;9788.412695238309;9788.412695238309;9857.507124643882;10957;10984.615538456435;11117.221794474633;11119.221654484432;11130.998210161124;9305.069493050642;9320.021397753138;9322.440653560538;9400.623975040922;9461.195798356941;9116.316276279438;9158.771030724285;9203.125703058198;9251;9253.028432014811;6997.510174287751;8435.873461897954;8436.325093992344;8438.873416898628;8447.8732819009;6041.6375017499095;6089.665068421102;6160.815175544741;6223.315435302204;6292.2134733158355;6153.876922461615;6258.499320054294;6293.276273228637;6388.520860935541;6484.870302594016 17:34:02 INFO - PID 1647 | |4;prototype.html;340.38390513171146;354.74990131956974;393.77948348924605;398.81255809769414;410.6160739708373;5241.973790131049;6146.416090471409;6162.352952939944;6342.207224096994;6463.224413070432;5329.626926115174;6048.879022419549;6165;6202.5037996960245;6244;3821.331267028273;3874.9537624841278;4369;4626.629869610423;4665.533446655341;3790.905227369324;4047.271491131595;4380.912381752363;4508.684392092555;4639.489656137822;3366.5286859839644;4230.498173148519;4610.308453731934;4639.884002899921;4665.2535594304745;3977.9458443512494;4585.633149348044;4639.327297541854;4639.721616702975;4650.302454631832;3459.8270086495827;4019.1358857845703;4021.477207962946;4034.172994536113;4048.0487085534874;6274.780382686629;6288.496920246369;6306.842328941813;6321;6334.2398912130375;5527.419620939824;5532.695701736403;5552.194931734916;5572.1303528682765;5584.5253153482;6730.697118629673;6788.151481064867;6794.422474089708;6802.251752307242;6803.353681400259;3454.775439596418;4320.827166913351;4539.773011349453;4577.107464044512;4598.655100867449;6104.816855494342;6588.571742836701;6805.591664500146;6855.177378714586;7198.496105272633;3904.2581909437117;4189.224993376235;4330.372096046056;4623.214053610895;4649.907001860011;4401.427814384174;4670.579647831712;4705.694129881546;4715.646326525456;4720.763961801965;5320.228566857784;5463.289772329651;5466.535344495683;5487.807926722626;5555.583331250173;5171.948280517222;5544.002079625706;5907.4387933147145;6662.367075127855;6692.386111499735;1343.7301749846301;1590.1492701404925;1602.1748799368102;1607.3570571771386;1616.8059832820136;1026.9178465722725;1046.4401545173448;1060.1465820014962;1064.6220591689994;1071.5767271927546;5121.231815227746;6354.364563543609;6368.936310636835;6419.454346380517;6495.382938620824;7026.610952276251;7032.296770323029;7036.120484939383;7045.436365090781;7056.094854389286;5121.8719532012;6007.519398448114;6258.9374106259265;6347.301796802348;6363.872722545523;3008.518637018067;3608.963910360915;3637.3998290281806;3670.15586415126;3674.210044840372;5909.438603332676;5981.491573216238;6170.82754276686;6723.7646682365885;6990.811562034466;6679.599224046573;7132.786016419515;7182.3535881770895;7186.389156921616;7187.353138217585;7131.215566287704;7156.60638664868;7164.104486939133;7172;7176.354128128493;5574.554035677055;6477.740890364333;6858.58722094843;7015.36861682451;7030.507864449644;6319.526035547445;6877.415419689182;6880.552764070319;6903.723851045902;6932.965335173493;6663.733450661919;7018.508704390643;7172.39034682037;7245.166805817398;7333.230010848997;3897.9805100974313;4606.239970404846;4656.9301460477445;4660.627149828073;4665.650076244227;7145.678444470115;7150.570965742072;7171.49799514029;7186.640667966685;7187.137543494814;6605.834854128685;6842.281560436082;6850.8972365413565;6873.2783057778215;6914;6342.7780027700655;6875.89686154698;6883.208431030295;6893.310668933066;6907.34380233877;5936.050231962865;6719.361660642231;6720.32796720324;6724.957631567115;6733.259341472336;6832.838417469142;6849.349311815369;6852.588844669336;6871.804610855976;6872;5312.389075256395;6642.3025582315095;6659.800205993828;6688.3646053624825;6695.163104611924 17:34:02 INFO - PID 1647 | |5;yui.html;775.662586774753;824.9463784853988;842.0905422144085;868.0624925080913;868.6134670071818;14619.195944223064;15085.472008479705;16636.91681541592;16657.333706651734;17122.1165671776;16328.18359082047;18131.63736725268;18309.542261443472;18345.7248141278;18366.35717749879;956.9856452153223;986.9950650246739;997.3616885193446;1001.654429221921;1003.0721582536125;860.5267103093305;1012.6101450941404;1014.4065721552901;1016.998256717133;1021.9540120694587;935.9344845860793;1044.6709286574705;1045.5399624165382;1049.3127001813825;1055.1137044882298;1036.6475398364591;1039.3244391145815;1039.6101461951769;1040.5525623981684;1043.5877828257833;773.1920143450108;778.2528772378523;778.7235531386333;778.8208711996216;779.4504874063772;16850;17012.744808827876;17143.228554715068;17155.05647189404;17202.741958870625;17251;17305.740413893676;17372.131393430278;17574.38489652856;17709.557261068578;13569.796453053114;13720.245386503739;14598.854011459856;14772.482963096347;14774.556763297118;3605.891823245307;3627.147620309225;3627.9274414511824;3629.5644522657185;3635.3456377851976;133122;136311;137119.31440342835;137308.31345843305;137630.62369376278;13625.727485450236;13967.580972570839;14019.22894240806;14149;14234.644133896736;22779.88610056939;23957.880210598836;27255.318617034336;27272.863635681693;27401.177964661092;6925.411340036053;7552.622368881534;7665.846683066307;7802.297793198526;7851.568163751049;3801.2967600993907;3834.9041273968373;3875.302445559826;3875.922481550353;3893.143508428198;4386;4483.910321793611;4488.640908727294;4496.662750293741;4500.077484115748;911.1844898815523;921.755734730297;935.1350001248818;936.3679516326589;938.2306508662831;609.7804790275497;630.5397060146119;647.7311915555086;655.2922843329193;671.8521925176453;10841.349519028725;11450.713732156762;11683.240589361665;12191.512339506498;12376.319302438453;526.5866294958439;641.7208514296296;644.7259914536397;659.8581305019444;669.4176066821896;5159.690418574897;5704.857378565569;5707.429257074259;5824.767009319579;5850.444207800252;148593;150567;150764;151834.96330073557;153439;113003.30495042581;116429.41785291019;127008;127267;132250.33874830758;97040.05919881562;97553.99999999857;99665;102063.48968255111;103333;11873.287602743863;12018.459169337226;12031.45858436355;12272.325022123512;12505.827852671568;43086.78456607697;45354.77322613366;45502;46029.78597889242;46266.53733462622;48731.07613543199;59812.401875980686;60361.69819150876;60462.69768651129;61416.46458838565;16403.343866245217;16708.916455417646;16782.748258776373;16931.830681693027;16996.235169417654;16318.510444686679;18355.54111147232;18893.622127557097;18899.622007559497;19014.524636883634;17334.39329623457;17381.739273911153;17397.565060873578;17548.91225543864;17633.559161021076;15480;15962.920185398998;15964.12197329158;16063.919680401523;16108.436204732778;16195.75706364429;16222.837771622133;16301.836981630031;16397.344106236094;16442;9881;12018.819717704067;12032.879671203176;12154.878451215374;12255.448504817126;11733.647990560297;11997.700057498283;13186.604401867959;13381.46113196956;13442.991775617063 17:34:02 INFO - PID 1647 | __end_tp_report 17:34:02 INFO - PID 1647 | __start_cc_report 17:34:02 INFO - PID 1647 | _x_x_mozilla_cycle_collect,463 17:34:02 INFO - PID 1647 | __end_cc_report 17:34:02 INFO - PID 1647 | __startTimestamp1496363642172__endTimestamp 17:34:02 INFO - PID 1647 | 17:34:02 INFO - PID 1647 | ------- Summary: start ------- 17:34:02 INFO - PID 1647 | Number of tests: 6 17:34:02 INFO - PID 1647 | 17:34:02 INFO - PID 1647 | [#0] dojo.html Cycles:165 Average:6160.90 Median:6352.49 stddev:767.23 (12.1%) stddev-sans-first:743.45 17:34:02 INFO - PID 1647 | Values: 3630.3 3639.3 3659.7 3676.0 3683.7 5681.9 6194.0 6274.9 6296.8 6312.0 6213.5 6235.4 6266.7 6316.3 6318.1 5302.4 5485.6 5491.3 5519.0 5566.7 5538.1 5616.7 5628.2 5647.7 5648.0 5377.8 5454.8 5511.0 5512.1 5540.3 4182.6 4808.0 5259.0 5403.9 5541.2 4905.0 5393.9 5499.8 5513.2 5525.4 5092.0 6159.9 6183.8 6302.8 6305.6 6785.3 6787.5 6791.8 6794.8 6797.5 6872.8 6887.0 6897.2 6911.4 6914.8 6238.6 6289.4 6290.2 6346.0 6352.5 5999.4 6998.2 7020.1 7027.6 7042.8 5534.7 5981.4 6551.9 6563.8 6646.1 6849.7 6851.6 6861.9 6867.9 6867.9 6758.2 6788.4 6794.5 6794.5 6799.3 5454.9 6633.5 6662.8 6743.0 6775.2 5397.4 5408.4 5417.3 5418.2 5428.5 4813.2 4830.4 4837.8 4841.4 4855.6 6195.6 6300.7 6315.8 6367.6 6373.2 6875.9 6905.4 6908.9 6913.6 6932.4 6294.8 6327.7 6355.3 6357.1 6374.0 5839.8 5911.3 5918.0 6088.0 6108.9 6912.8 7023.6 7025.1 7037.6 7039.0 5548.3 6627.4 6677.2 6692.9 6712.1 6008.7 6055.1 6291.3 6484.2 6831.2 5253.9 6422.1 6648.1 6689.3 6780.7 6883.9 6903.3 6910.7 6918.0 6950.7 5203.6 5668.3 6413.9 6577.7 6679.4 6662.9 6685.4 6695.9 6712.1 6730.4 5230.6 6245.8 6435.7 6473.1 6591.4 6655.8 6673.3 6679.1 6684.7 6711.8 6542.8 6547.3 6550.7 6553.3 6564.8 17:34:02 INFO - PID 1647 | 17:34:02 INFO - PID 1647 | [#1] ext.html Cycles:180 Average:44312.62 Median:19425.93 stddev:77069.11 (396.7%) stddev-sans-first:77215.72 17:34:02 INFO - PID 1647 | Values: 692.8 791.4 832.0 1054.7 1314.0 38171.0 38958.8 39107.0 39108.0 39160.4 39514.0 39948.0 40016.8 40057.8 40081.6 7868.0 8014.2 8147.3 8384.5 8410.5 8264.1 8373.2 8377.7 8380.9 8401.4 8334.5 8344.5 8389.2 8398.1 8404.6 8274.3 8303.3 8353.9 8363.8 8397.6 8161.5 8179.3 8198.6 8207.4 8219.3 29668.0 36598.3 36709.4 36886.3 36930.6 3494.9 3549.5 3549.6 3553.6 3554.3 26096.0 30060.2 30266.8 30318.5 30459.5 3050.4 3062.8 3072.5 3095.0 3121.2 230770.0 234351.0 234475.5 302446.0 302925.0 46203.3 46251.8 46387.8 46433.5 46518.8 48227.5 48272.8 48350.0 49624.0 50553.5 14413.9 14471.3 14625.7 14632.3 14632.7 11642.6 11655.0 11750.9 11761.6 11775.5 10957.9 13759.9 13955.1 13980.4 14000.9 6155.8 6285.5 6296.3 6306.6 6348.4 910.1 938.8 958.5 974.6 1032.2 22776.0 23971.4 24103.6 24207.8 24209.0 1078.7 1082.3 1104.1 1110.2 1116.9 11709.3 12020.9 12323.9 12329.5 12348.7 334446.3 335090.0 336873.0 337846.0 338419.0 54320.0 54559.6 54576.2 54639.5 54770.0 1432.5 1462.4 1507.8 1518.8 1530.8 22372.0 22960.0 23708.5 23729.5 23854.9 37137.1 37186.0 37201.8 37221.0 37237.6 275872.0 276039.0 276568.0 276586.0 276664.6 34526.8 35356.9 35445.5 35533.5 35629.6 44392.3 46070.1 46198.3 46255.8 46264.0 25892.0 28611.0 30945.0 31006.7 31130.0 31165.9 31213.2 31234.5 31386.0 31513.9 37353.6 37568.8 37731.2 37768.8 37792.6 29176.9 29544.9 31347.2 31518.1 31823.5 18847.6 21806.8 23884.0 23930.0 24512.4 17:34:02 INFO - PID 1647 | 17:34:02 INFO - PID 1647 | [#2] jquery.html Cycles:180 Average:37607.33 Median:12232.28 stddev:92487.28 (756.1%) stddev-sans-first:92704.48 17:34:02 INFO - PID 1647 | Values: 373.0 429.7 437.5 449.8 449.8 17620.9 21659.9 22604.4 22978.7 24403.0 24542.5 25267.6 25302.7 25317.6 25373.4 6193.9 6342.1 6359.8 6363.5 6393.9 5914.6 6333.2 6385.3 6386.2 6404.3 6150.5 6195.4 6209.8 6224.1 6227.8 6205.3 6212.4 6215.0 6220.8 6227.6 5507.3 5525.9 5541.4 5560.9 5567.8 28984.3 29554.7 29729.9 29847.0 29901.7 2185.7 2216.4 2229.9 2230.1 2231.4 18523.4 19054.8 19089.3 19119.4 19120.2 2140.8 2188.6 2208.7 2216.6 2220.2 141288.6 157652.0 168290.0 175445.1 197029.0 13011.3 13033.0 13190.1 13511.2 13723.3 17047.3 17123.1 17315.7 17316.7 17466.9 9057.9 9207.7 9344.1 9413.3 9417.2 9032.0 9039.8 9059.8 9132.9 9154.0 8349.3 8452.7 8482.4 8565.9 8576.7 1889.4 2182.8 2210.5 2210.8 2377.1 839.6 955.0 968.9 973.0 1018.5 17537.5 21477.5 22095.8 22183.8 22234.7 874.3 960.5 1000.2 1018.6 1037.3 11117.8 11435.7 11479.9 11494.8 11569.1 527980.0 532410.0 532563.0 537544.0 548006.0 154280.0 157532.2 157948.0 158089.0 159346.0 991.8 1014.5 1024.6 1057.4 1198.4 18381.7 23069.4 24275.5 24356.4 24980.5 67272.7 67417.0 68010.7 68028.6 68603.3 65403.0 81238.0 81611.0 82065.0 88600.6 8638.8 10683.0 10686.6 10698.3 10733.1 17760.2 18256.0 18330.0 18464.3 18572.8 14890.9 14893.7 15031.5 15055.0 15056.0 11513.5 13277.9 13553.2 13666.4 13686.6 12883.6 13305.8 14984.3 15452.1 15560.9 10483.2 10492.8 10494.3 10520.3 10525.5 12379.5 14709.3 14870.4 14879.9 15086.9 17:34:02 INFO - PID 1647 | 17:34:02 INFO - PID 1647 | [#3] mootools.html Cycles:180 Average:7230.02 Median:4267.89 stddev:4558.59 (106.8%) stddev-sans-first:4549.74 17:34:02 INFO - PID 1647 | Values: 1319.3 1415.9 1429.5 1429.7 1434.8 11720.9 11907.1 12101.9 12263.9 12331.4 11954.1 12175.5 12188.5 12214.9 12219.3 2531.1 2577.6 2577.9 2579.7 2583.7 2493.7 2530.3 2550.1 2553.4 2571.0 2472.7 2481.9 2488.2 2498.3 2505.5 2487.6 2496.0 2505.2 2508.5 2511.1 2399.0 2477.3 2493.5 2506.4 2508.3 9435.6 11272.5 11762.9 11886.0 11975.8 6471.8 6658.7 6732.6 6738.7 6747.1 4027.5 4206.3 4263.2 4272.6 4286.1 1227.8 1254.7 1259.8 1275.2 1286.3 15311.9 15786.1 16451.5 16614.1 16638.2 4793.6 5515.6 5604.6 5692.9 5804.1 12359.0 12369.8 12370.0 12373.8 12388.1 6453.2 6546.8 6557.3 6569.9 6575.6 6088.4 6146.2 6228.5 6235.0 6248.2 4362.7 4420.2 4463.0 4497.2 4506.4 1269.7 1279.9 1282.4 1294.9 1300.2 774.3 774.4 795.3 798.9 800.1 6780.6 7039.4 7093.6 7160.9 7162.2 919.3 931.6 931.8 946.1 968.6 4333.9 4511.4 4519.9 4676.0 4678.6 14913.9 15016.2 15275.5 15440.8 15467.8 14737.9 15288.9 15366.1 15398.3 15423.8 7227.4 7281.9 8281.3 8725.1 8777.6 6371.5 7275.5 7349.6 7401.6 7409.3 9950.7 13606.0 13792.4 13910.2 14008.9 13760.7 14160.3 14185.6 14293.3 14329.9 9485.5 9780.9 9788.4 9788.4 9857.5 10957.0 10984.6 11117.2 11119.2 11131.0 9305.1 9320.0 9322.4 9400.6 9461.2 9116.3 9158.8 9203.1 9251.0 9253.0 6997.5 8435.9 8436.3 8438.9 8447.9 6041.6 6089.7 6160.8 6223.3 6292.2 6153.9 6258.5 6293.3 6388.5 6484.9 17:34:02 INFO - PID 1647 | 17:34:02 INFO - PID 1647 | [#4] prototype.html Cycles:180 Average:5424.22 Median:6154.38 stddev:1753.70 (28.5%) stddev-sans-first:1716.61 17:34:02 INFO - PID 1647 | Values: 340.4 354.7 393.8 398.8 410.6 5242.0 6146.4 6162.4 6342.2 6463.2 5329.6 6048.9 6165.0 6202.5 6244.0 3821.3 3875.0 4369.0 4626.6 4665.5 3790.9 4047.3 4380.9 4508.7 4639.5 3366.5 4230.5 4610.3 4639.9 4665.3 3977.9 4585.6 4639.3 4639.7 4650.3 3459.8 4019.1 4021.5 4034.2 4048.0 6274.8 6288.5 6306.8 6321.0 6334.2 5527.4 5532.7 5552.2 5572.1 5584.5 6730.7 6788.2 6794.4 6802.3 6803.4 3454.8 4320.8 4539.8 4577.1 4598.7 6104.8 6588.6 6805.6 6855.2 7198.5 3904.3 4189.2 4330.4 4623.2 4649.9 4401.4 4670.6 4705.7 4715.6 4720.8 5320.2 5463.3 5466.5 5487.8 5555.6 5171.9 5544.0 5907.4 6662.4 6692.4 1343.7 1590.1 1602.2 1607.4 1616.8 1026.9 1046.4 1060.1 1064.6 1071.6 5121.2 6354.4 6368.9 6419.5 6495.4 7026.6 7032.3 7036.1 7045.4 7056.1 5121.9 6007.5 6258.9 6347.3 6363.9 3008.5 3609.0 3637.4 3670.2 3674.2 5909.4 5981.5 6170.8 6723.8 6990.8 6679.6 7132.8 7182.4 7186.4 7187.4 7131.2 7156.6 7164.1 7172.0 7176.4 5574.6 6477.7 6858.6 7015.4 7030.5 6319.5 6877.4 6880.6 6903.7 6933.0 6663.7 7018.5 7172.4 7245.2 7333.2 3898.0 4606.2 4656.9 4660.6 4665.7 7145.7 7150.6 7171.5 7186.6 7187.1 6605.8 6842.3 6850.9 6873.3 6914.0 6342.8 6875.9 6883.2 6893.3 6907.3 5936.1 6719.4 6720.3 6725.0 6733.3 6832.8 6849.3 6852.6 6871.8 6872.0 5312.4 6642.3 6659.8 6688.4 6695.2 17:34:02 INFO - PID 1647 | 17:34:02 INFO - PID 1647 | [#5] yui.html Cycles:180 Average:25065.29 Median:17353.26 stddev:38788.69 (223.5%) stddev-sans-first:38854.63 17:34:02 INFO - PID 1647 | Values: 775.7 824.9 842.1 868.1 868.6 14619.2 15085.5 16636.9 16657.3 17122.1 16328.2 18131.6 18309.5 18345.7 18366.4 957.0 987.0 997.4 1001.7 1003.1 860.5 1012.6 1014.4 1017.0 1022.0 935.9 1044.7 1045.5 1049.3 1055.1 1036.6 1039.3 1039.6 1040.6 1043.6 773.2 778.3 778.7 778.8 779.5 16850.0 17012.7 17143.2 17155.1 17202.7 17251.0 17305.7 17372.1 17574.4 17709.6 13569.8 13720.2 14598.9 14772.5 14774.6 3605.9 3627.1 3627.9 3629.6 3635.3 133122.0 136311.0 137119.3 137308.3 137630.6 13625.7 13967.6 14019.2 14149.0 14234.6 22779.9 23957.9 27255.3 27272.9 27401.2 6925.4 7552.6 7665.8 7802.3 7851.6 3801.3 3834.9 3875.3 3875.9 3893.1 4386.0 4483.9 4488.6 4496.7 4500.1 911.2 921.8 935.1 936.4 938.2 609.8 630.5 647.7 655.3 671.9 10841.3 11450.7 11683.2 12191.5 12376.3 526.6 641.7 644.7 659.9 669.4 5159.7 5704.9 5707.4 5824.8 5850.4 148593.0 150567.0 150764.0 151835.0 153439.0 113003.3 116429.4 127008.0 127267.0 132250.3 97040.1 97554.0 99665.0 102063.5 103333.0 11873.3 12018.5 12031.5 12272.3 12505.8 43086.8 45354.8 45502.0 46029.8 46266.5 48731.1 59812.4 60361.7 60462.7 61416.5 16403.3 16708.9 16782.7 16931.8 16996.2 16318.5 18355.5 18893.6 18899.6 19014.5 17334.4 17381.7 17397.6 17548.9 17633.6 15480.0 15962.9 15964.1 16063.9 16108.4 16195.8 16222.8 16301.8 16397.3 16442.0 9881.0 12018.8 12032.9 12154.9 12255.4 11733.6 11997.7 13186.6 13381.5 13443.0 17:34:02 INFO - PID 1647 | -------- Summary: end -------- 17:34:02 INFO - PID 1647 | 17:34:02 INFO - TEST-INFO | 1647: exit 0 17:34:02 INFO - TEST-OK | dromaeo_css | took 1144025ms 17:34:02 INFO - TEST-START | kraken 17:34:02 INFO - operating with platform_type : mac_ 17:34:02 INFO - Initialising browser for kraken test... 17:34:02 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/tmpy8IWZt/profile 17:34:02 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 17:34:36 INFO - TEST-INFO | 1657: killed by SIGTERM 17:34:36 INFO - Browser initialized. 17:34:36 INFO - Running cycle 1/1 for kraken test... 17:34:36 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/T/tmpy8IWZt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:34:39 INFO - PID 1661 | 2017-06-01 17:34:39.093 plugin-container[1662:10632] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x993b, name = 'com.apple.tsm.portname' 17:34:39 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:34:39 INFO - PID 1661 | 2017-06-01 17:34:39.093 plugin-container[1662:10632] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b1f, name = 'com.apple.CFPasteboardClient' 17:34:39 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:34:39 INFO - PID 1661 | 2017-06-01 17:34:39.093 plugin-container[1662:10632] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:34:39 INFO - PID 1661 | 2017-06-01 17:34:39.619 plugin-container[1663:10680] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 17:34:39 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:34:39 INFO - PID 1661 | 2017-06-01 17:34:39.620 plugin-container[1663:10680] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 17:34:39 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:34:39 INFO - PID 1661 | 2017-06-01 17:34:39.620 plugin-container[1663:10680] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:34:48 INFO - PID 1661 | RSS: Main: 268279808 17:34:48 INFO - PID 1661 | 17:34:51 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection) 17:34:52 INFO - PID 1661 | RSS: Main: 275939328 17:34:52 INFO - PID 1661 | 17:34:55 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-dft) 17:34:55 INFO - PID 1661 | RSS: Main: 283312128 17:34:55 INFO - PID 1661 | 17:34:59 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-fft) 17:35:00 INFO - PID 1661 | RSS: Main: 280981504 17:35:00 INFO - PID 1661 | 17:35:02 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator) 17:35:02 INFO - PID 1661 | RSS: Main: 282783744 17:35:02 INFO - PID 1661 | 17:35:04 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:35:05 INFO - PID 1661 | RSS: Main: 283615232 17:35:05 INFO - PID 1661 | 17:35:08 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom) 17:35:09 INFO - PID 1661 | RSS: Main: 292659200 17:35:09 INFO - PID 1661 | 17:35:12 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate) 17:35:12 INFO - PID 1661 | RSS: Main: 291074048 17:35:12 INFO - PID 1661 | 17:35:15 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial) 17:35:16 INFO - PID 1661 | RSS: Main: 290283520 17:35:16 INFO - PID 1661 | 17:35:17 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:35:18 INFO - PID 1661 | RSS: Main: 290250752 17:35:18 INFO - PID 1661 | 17:35:20 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:35:21 INFO - PID 1661 | RSS: Main: 290111488 17:35:21 INFO - PID 1661 | 17:35:23 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:35:24 INFO - PID 1661 | RSS: Main: 289320960 17:35:24 INFO - PID 1661 | 17:35:26 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:35:27 INFO - PID 1661 | RSS: Main: 289730560 17:35:27 INFO - PID 1661 | 17:35:30 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:35:31 INFO - PID 1661 | RSS: Main: 289566720 17:35:31 INFO - PID 1661 | 17:35:32 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49228/tests/kraken/driver.html?testName=ai-astar) 17:35:33 INFO - PID 1661 | RSS: Main: 291373056 17:35:33 INFO - PID 1661 | 17:35:33 INFO - PID 1661 | __start_tp_report 17:35:33 INFO - PID 1661 | _x_x_mozilla_page_load 17:35:33 INFO - PID 1661 | _x_x_mozilla_page_load_details 17:35:33 INFO - PID 1661 | |i|pagename|runs| 17:35:33 INFO - PID 1661 | |0;ai-astar;104;115;165;169;117;116;116;114;115;120 17:35:33 INFO - PID 1661 | |1;audio-beat-detection;132;110;109;148;111;112;111;118;134;110 17:35:33 INFO - PID 1661 | |2;audio-dft;199;207;202;235;238;289;282;233;196;245 17:35:33 INFO - PID 1661 | |3;audio-fft;82;57;59;108;82;47;69;87;91;47 17:35:33 INFO - PID 1661 | |4;audio-oscillator;85;71;100;92;62;59;102;105;103;120 17:35:33 INFO - PID 1661 | |5;imaging-gaussian-blur;91;91;113;89;91;128;91;90;97;100 17:35:33 INFO - PID 1661 | |6;imaging-darkroom;103;99;97;96;96;106;97;96;97;96 17:35:33 INFO - PID 1661 | |7;imaging-desaturate;92;93;91;91;115;142;83;146;81;147 17:35:33 INFO - PID 1661 | |8;json-parse-financial;51;51;51;53;53;50;52;53;52;51 17:35:33 INFO - PID 1661 | |9;json-stringify-tinderbox;58;67;61;60;65;59;57;56;55;59 17:35:33 INFO - PID 1661 | |10;stanford-crypto-aes;90;91;89;91;90;90;93;91;122;117 17:35:33 INFO - PID 1661 | |11;stanford-crypto-ccm;138;137;140;135;140;135;147;140;136;151 17:35:33 INFO - PID 1661 | |12;stanford-crypto-pbkdf2;181;188;183;189;190;190;187;185;190;190 17:35:33 INFO - PID 1661 | |13;stanford-crypto-sha256-iterative;66;70;69;68;70;67;70;70;66;69 17:35:33 INFO - PID 1661 | __end_tp_report 17:35:33 INFO - PID 1661 | __start_cc_report 17:35:33 INFO - PID 1661 | _x_x_mozilla_cycle_collect,1208 17:35:33 INFO - PID 1661 | __end_cc_report 17:35:33 INFO - PID 1661 | __startTimestamp1496363733061__endTimestamp 17:35:33 INFO - PID 1661 | 17:35:33 INFO - PID 1661 | ------- Summary: start ------- 17:35:33 INFO - PID 1661 | Number of tests: 14 17:35:33 INFO - PID 1661 | 17:35:33 INFO - PID 1661 | [#0] ai-astar Cycles:10 Average:125.10 Median:116.50 stddev:22.48 (19.3%) stddev-sans-first:22.51 17:35:33 INFO - PID 1661 | Values: 104.0 115.0 165.0 169.0 117.0 116.0 116.0 114.0 115.0 120.0 17:35:33 INFO - PID 1661 | 17:35:33 INFO - PID 1661 | [#1] audio-beat-detection Cycles:10 Average:119.50 Median:115.00 stddev:13.63 (11.9%) stddev-sans-first:13.69 17:35:33 INFO - PID 1661 | Values: 132.0 110.0 109.0 148.0 111.0 112.0 111.0 118.0 134.0 110.0 17:35:33 INFO - PID 1661 | 17:35:33 INFO - PID 1661 | [#2] audio-dft Cycles:10 Average:232.60 Median:236.50 stddev:33.12 (14.0%) stddev-sans-first:32.82 17:35:33 INFO - PID 1661 | Values: 199.0 207.0 202.0 235.0 238.0 289.0 282.0 233.0 196.0 245.0 17:35:33 INFO - PID 1661 | 17:35:33 INFO - PID 1661 | [#3] audio-fft Cycles:10 Average:72.90 Median:75.50 stddev:20.35 (27.0%) stddev-sans-first:21.32 17:35:33 INFO - PID 1661 | Values: 82.0 57.0 59.0 108.0 82.0 47.0 69.0 87.0 91.0 47.0 17:35:33 INFO - PID 1661 | 17:35:33 INFO - PID 1661 | [#4] audio-oscillator Cycles:10 Average:89.90 Median:60.50 stddev:20.20 (33.4%) stddev-sans-first:21.35 17:35:33 INFO - PID 1661 | Values: 85.0 71.0 100.0 92.0 62.0 59.0 102.0 105.0 103.0 120.0 17:35:33 INFO - PID 1661 | 17:35:33 INFO - PID 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:98.10 Median:91.00 stddev:12.78 (14.0%) stddev-sans-first:13.30 17:35:33 INFO - PID 1661 | Values: 91.0 91.0 113.0 89.0 91.0 128.0 91.0 90.0 97.0 100.0 17:35:33 INFO - PID 1661 | 17:35:33 INFO - PID 1661 | [#6] imaging-darkroom Cycles:10 Average:98.30 Median:96.50 stddev:3.47 (3.6%) stddev-sans-first:3.23 17:35:33 INFO - PID 1661 | Values: 103.0 99.0 97.0 96.0 96.0 106.0 97.0 96.0 97.0 96.0 17:35:33 INFO - PID 1661 | 17:35:33 INFO - PID 1661 | [#7] imaging-desaturate Cycles:10 Average:108.10 Median:87.00 stddev:27.05 (31.1%) stddev-sans-first:28.05 17:35:33 INFO - PID 1661 | Values: 92.0 93.0 91.0 91.0 115.0 142.0 83.0 146.0 81.0 147.0 17:35:33 INFO - PID 1661 | 17:35:33 INFO - PID 1661 | [#8] json-parse-financial Cycles:10 Average:51.70 Median:52.00 stddev:1.06 (2.0%) stddev-sans-first:1.09 17:35:33 INFO - PID 1661 | Values: 51.0 51.0 51.0 53.0 53.0 50.0 52.0 53.0 52.0 51.0 17:35:33 INFO - PID 1661 | 17:35:33 INFO - PID 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:59.70 Median:59.50 stddev:3.80 (6.4%) stddev-sans-first:3.98 17:35:33 INFO - PID 1661 | Values: 58.0 67.0 61.0 60.0 65.0 59.0 57.0 56.0 55.0 59.0 17:35:33 INFO - PID 1661 | 17:35:33 INFO - PID 1661 | [#10] stanford-crypto-aes Cycles:10 Average:96.40 Median:90.50 stddev:12.28 (13.6%) stddev-sans-first:12.80 17:35:33 INFO - PID 1661 | Values: 90.0 91.0 89.0 91.0 90.0 90.0 93.0 91.0 122.0 117.0 17:35:33 INFO - PID 1661 | 17:35:33 INFO - PID 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:139.90 Median:140.00 stddev:5.26 (3.8%) stddev-sans-first:5.53 17:35:33 INFO - PID 1661 | Values: 138.0 137.0 140.0 135.0 140.0 135.0 147.0 140.0 136.0 151.0 17:35:33 INFO - PID 1661 | 17:35:33 INFO - PID 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.30 Median:189.50 stddev:3.27 (1.7%) stddev-sans-first:2.55 17:35:33 INFO - PID 1661 | Values: 181.0 188.0 183.0 189.0 190.0 190.0 187.0 185.0 190.0 190.0 17:35:33 INFO - PID 1661 | 17:35:33 INFO - PID 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.50 Median:69.50 stddev:1.65 (2.4%) stddev-sans-first:1.48 17:35:33 INFO - PID 1661 | Values: 66.0 70.0 69.0 68.0 70.0 67.0 70.0 70.0 66.0 69.0 17:35:33 INFO - PID 1661 | -------- Summary: end -------- 17:35:33 INFO - PID 1661 | 17:35:33 INFO - TEST-INFO | 1661: exit 0 17:35:33 INFO - TEST-OK | kraken | took 91126ms 17:35:33 INFO - SUITE-END | took 1235s 17:35:34 INFO - Completed test suite (00:20:35) 17:35:34 INFO - javascript benchmark 17:35:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8066.7873034255945, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3630.3465376232284, 3639.344917914775, 3659.7072234221264, 3676.0, 3683.723720720946, 5681.857953551158, 6194.0, 6274.905876411857, 6296.779612713556, 6312.0, 6213.471854892339, 6235.438810507054, 6266.749330026793, 6316.336784637616, 6318.115463835055, 5302.3637163540425, 5485.61600687953, 5491.313585801775, 5519.0, 5566.693831839248, 5538.113901775717, 5616.719164041781, 5628.212050312959, 5647.717614119299, 5648.0, 5377.838664840061, 5454.754536045887, 5510.972445137749, 5512.118061110224, 5540.307461567304, 4182.581741825824, 4807.975960120212, 5258.973705131488, 5403.919216156784, 5541.224228607998, 4904.950950490485, 5393.892122157574, 5499.835004949858, 5513.20058591506, 5525.364583072917, 5092.0, 6159.938400615982, 6183.81448556544, 6302.779402720928, 6305.621662700252, 6785.253622101565, 6787.490938179656, 6791.796246112624, 6794.796156115275, 6797.490188235853, 6872.828179295508, 6886.965565172142, 6897.172339319263, 6911.447084233249, 6914.827129321757, 6238.625682459067, 6289.371062893674, 6290.182276304051, 6345.973282727145, 6352.4918006559365, 5999.370066143079, 6998.16022077354, 7020.087388639444, 7027.578345299298, 7042.7887163385185, 5534.723263836872, 5981.3719559445635, 6551.868962620721, 6563.8359041023405, 6646.136002319668, 6849.726010959505, 6851.554648947803, 6861.931380686129, 6867.931320686829, 6867.935470002158, 6758.155230596176, 6788.355106264897, 6794.45644348451, 6794.524383293221, 6799.252082270947, 5454.863628409242, 6633.502487313471, 6662.83342916431, 6743.0, 6775.1531058617675, 5397.4062853086125, 5408.3509978802795, 5417.322834645669, 5418.187271909166, 5428.4842939920645, 4813.205821039489, 4830.396200474941, 4837.782299796518, 4841.394825646794, 4855.587275081587, 6195.628262304186, 6300.684965751786, 6315.842103947346, 6367.586106903036, 6373.203349581302, 6875.896861547081, 6905.413039891666, 6908.896366554506, 6913.585184888922, 6932.410745086724, 6294.8111556653375, 6327.683615819191, 6355.3009168991375, 6357.078223657544, 6374.0, 5839.766409343579, 5911.261092363455, 5917.970410147922, 6087.96956015226, 6108.908366374508, 6912.758053468105, 7023.578585284899, 7025.121859767427, 7037.577745335297, 7039.014537964791, 5548.334199895877, 6627.403533681992, 6677.165354330708, 6692.933070669425, 6712.093867327848, 6008.7296071676, 6055.061465472859, 6291.307956124914, 6484.157059582224, 6831.180258369028, 5253.896681696733, 6422.100905873275, 6648.069270302259, 6689.297623749436, 6780.660966951534, 6883.931160688529, 6903.309669033057, 6910.689018994057, 6918.0, 6950.687219075053, 5203.609729270395, 5668.26312579362, 6413.935860641333, 6577.704003319766, 6679.365460281266, 6662.900056499059, 6685.3983141515555, 6695.866082678416, 6712.093867327848, 6730.360615741497, 5230.555402790806, 6245.750169993149, 6435.710393032418, 6473.093766872547, 6591.406773390418, 6655.800325990228, 6673.332666733288, 6679.098321726505, 6684.6991885364305, 6711.832204194934, 6542.771003014872, 6547.279799221987, 6550.672466376757, 6553.279139294578, 6564.770233041821], "value": 6112.6126775768325, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [692.8267933016746, 791.4341246009102, 832.0, 1054.6730513540804, 1314.034184874118, 38171.0, 38958.805205974, 39107.0, 39108.02229944253, 39160.41259381112, 39514.0, 39948.0, 40016.799916000455, 40057.79971100148, 40081.59918400815, 7868.0, 8014.158513356101, 8147.26674599286, 8384.538850363228, 8410.453320534176, 8264.090949995496, 8373.204545568162, 8377.748667539983, 8380.8742868857, 8401.3698972577, 8334.49993000422, 8344.541050242235, 8389.244967952884, 8398.076211616717, 8404.621792019374, 8274.2966847818, 8303.252707256346, 8353.916460835406, 8363.790905227357, 8397.6221070052, 8161.469504482189, 8179.304759095484, 8198.590070496452, 8207.384446166501, 8219.260266576037, 29668.0, 36598.26803463916, 36709.44935825965, 36886.26227475463, 36930.63069369299, 3494.9301013979834, 3549.4853246279404, 3549.6450354964554, 3553.6446355364515, 3554.3246783111126, 26095.956161753697, 30060.24849378761, 30266.84866575653, 30318.54522182169, 30459.543106853416, 3050.4051709916575, 3062.7549796016265, 3072.5391191321255, 3094.969050309513, 3121.172889184368, 230770.0, 234351.0, 234475.48286775712, 302446.0, 302925.0, 46203.306950395774, 46251.76874115608, 46387.76806115948, 46433.53566464292, 46518.837029074544, 48227.517724822304, 48272.793180170775, 48350.0, 49624.0, 50553.49446505561, 14413.85586144125, 14471.276436178148, 14625.707485850437, 14632.341544630517, 14632.707345853023, 11642.592509262135, 11655.0, 11750.942415182675, 11761.647150585497, 11775.52897884092, 10957.90420957898, 13759.931200343934, 13955.092918960438, 13980.370883310276, 14000.949928755384, 6155.783288447428, 6285.465735412449, 6296.33888441707, 6306.635011870549, 6348.4286414222015, 910.1490106750209, 938.8169306985209, 958.4919992404037, 974.605284859633, 1032.2052019944601, 22776.0, 23971.400714981915, 24103.638445423334, 24207.757922420904, 24209.0, 1078.730317420645, 1082.3127314155568, 1104.116706634705, 1110.2339385823918, 1116.8771435142128, 11709.297442153327, 12020.939895300467, 12323.938380308042, 12329.50681972711, 12348.691282718004, 334446.3277683596, 335090.0, 336873.0, 337846.0, 338419.0, 54320.0, 54559.63600910009, 54576.181357279675, 54639.45360546423, 54770.0, 1432.548747144646, 1462.4369617697264, 1507.763633820257, 1518.764591488321, 1530.8239552451387, 22372.0, 22960.0, 23708.52582948366, 23729.52540949206, 23854.88072559626, 37137.07157321089, 37185.999999998916, 37201.81399092987, 37221.0, 37237.62762372341, 275872.0, 276039.0, 276568.0, 276586.0, 276664.6166769153, 34526.7915622952, 35356.93929182128, 35445.46831797577, 35533.466997995565, 35629.64370356263, 44392.33411498766, 46070.07859842717, 46198.30702539397, 46255.76872115618, 46264.0, 25892.0, 28611.0, 30945.0, 31006.68993310038, 31130.0, 31165.90919317813, 31213.219669508442, 31234.531482027334, 31386.0, 31513.897013604415, 37353.62646373502, 37568.81215593904, 37731.24537509289, 37768.811155944044, 37792.62207377891, 29176.854115729286, 29544.852275738485, 31347.216319592193, 31518.054458366285, 31823.522647159847, 18847.623047539248, 21806.781932180475, 23884.044638214276, 23930.0, 24512.387190320384], "value": 17370.81948036707, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.96456836600515, 429.7206815569879, 437.48595400404537, 449.78635148304556, 449.8223201835275, 17620.911895440506, 21659.91700414979, 22604.43488912776, 22978.65532017021, 24403.0, 24542.509149817037, 25267.62098568523, 25302.74697253027, 25317.620235696435, 25373.365665858364, 6193.907091393633, 6342.0803983422375, 6359.8092057238355, 6363.522735794811, 6393.936060639404, 5914.615549989237, 6333.176687030679, 6385.265694445128, 6386.233651961771, 6404.295527491972, 6150.507959363263, 6195.442410183083, 6209.7580483903175, 6224.097505861648, 6227.813165605039, 6205.317415084337, 6212.378762123752, 6215.005599104122, 6220.7822726204595, 6227.595206311576, 5507.339119305669, 5525.889482210374, 5541.390447050821, 5560.916586251209, 5567.777288908439, 28984.27539311513, 29554.704452955626, 29729.851350743324, 29847.0, 29901.70098299011, 2185.715856938604, 2216.445888527868, 2229.888505574731, 2230.096810791632, 2231.386368748591, 18523.44429667112, 19054.809451905443, 19089.331873384366, 19119.426417207505, 19120.23519059236, 2140.7752186020557, 2188.5841690078832, 2208.6797414374987, 2216.5788500185076, 2220.1896307847683, 141288.58711412858, 157652.0, 168290.0, 175445.12277438657, 197029.0, 13011.349432528335, 13033.02252331079, 13190.076694631283, 13511.189328640314, 13723.313834308245, 17047.31810727582, 17123.14384280781, 17315.740263896052, 17316.740248896276, 17466.91266543659, 9057.864132038025, 9207.723768286962, 9344.112309330603, 9413.294002949806, 9417.246620270362, 9032.006479287273, 9039.81920361589, 9059.773505662411, 9132.908670913206, 9154.0, 8349.29031032357, 8452.661893524313, 8482.448640838325, 8565.929258842645, 8576.656933722707, 1889.4142815727168, 2182.7885523534273, 2210.5136869888597, 2210.7899749523767, 2377.108584280895, 839.5718183726343, 955.0, 968.8519104860762, 973.0366936732584, 1018.4958445569342, 17537.473875783748, 21477.46306342354, 22095.779042209695, 22183.778162218496, 22234.666480002812, 874.3005595523556, 960.4957397366438, 1000.2348203624233, 1018.5721996761378, 1037.2531777120462, 11117.777644447066, 11435.656930292105, 11479.885201148049, 11494.827577586342, 11569.074474042056, 527980.0, 532410.0, 532563.0, 537544.0, 548006.0, 154280.0, 157532.21233893756, 157948.0, 158089.0, 159346.0, 991.7520619845038, 1014.5434554450527, 1024.5645600619855, 1057.4184198690689, 1198.376844041111, 18381.724274136166, 23069.423264418525, 24275.514489710462, 24356.391090222885, 24980.500389991736, 67272.66363668346, 67417.0, 68010.65994669995, 68028.63942721019, 68603.3139668597, 65403.0, 81238.0, 81611.0, 82065.0, 88600.5569972146, 8638.82722345562, 10683.03852653265, 10686.62596809139, 10698.304610200312, 10733.087687546333, 17760.200790964696, 18255.995920224515, 18330.0, 18464.261429543207, 18572.814271857107, 14890.925545372203, 14893.702125957638, 15031.549053528412, 15055.0, 15056.021358611655, 11513.481893314989, 13277.933610331887, 13553.221589573917, 13666.385012674255, 13686.589402317946, 12883.613491595268, 13305.800412994007, 14984.325705343068, 15452.072875627498, 15560.92219538895, 10483.213758967955, 10492.790144197226, 10494.317869338469, 10520.316179448311, 10525.526351314056, 12379.504819807467, 14709.338079786221, 14870.405183792527, 14879.92560037193, 15086.94391392592], "value": 11236.06182129014, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1319.267806367466, 1415.9079659822105, 1429.5354009946764, 1429.6989739337205, 1434.770436730123, 11720.941395293023, 11907.10696697749, 12101.878981210186, 12263.877361226396, 12331.445084971176, 11954.103442241823, 12175.51297948081, 12188.451519681636, 12214.877851221485, 12219.266843989366, 2531.0888080291124, 2577.5618144915315, 2577.8839952202197, 2579.7162312145647, 2583.702874169466, 2493.7007559092935, 2530.304166354249, 2550.0564791027346, 2553.374423266293, 2570.9973110486926, 2472.678551788274, 2481.925542233727, 2488.1664642344836, 2498.250524842549, 2505.5114252720728, 2487.614419764939, 2495.962560561593, 2505.18581461025, 2508.535920854648, 2511.1211076123395, 2398.97601023991, 2477.306354220803, 2493.5386953413677, 2506.373406648338, 2508.3227528567186, 9435.622575097057, 11272.549098036068, 11762.882371176263, 11886.0, 11975.820362694567, 6471.773487927947, 6658.667066646648, 6732.629705366203, 6738.730450781963, 6747.05541224229, 4027.543198648822, 4206.305959516676, 4263.211305908417, 4272.572742725733, 4286.057067445157, 1227.7544491101814, 1254.6737848159453, 1259.80473026681, 1275.1711387598043, 1286.2539726958341, 15311.923440382727, 15786.131762753044, 16451.506454806375, 16614.086225257604, 16638.168091595373, 4793.640476964241, 5515.641483303572, 5604.579656525695, 5692.8861422771315, 5804.100364443518, 12359.0, 12369.752604947851, 12370.0, 12373.824486673751, 12388.132830701945, 6453.225612926385, 6546.8363290918105, 6557.311482294386, 6569.934300657028, 6575.572587781779, 6088.360722124202, 6146.170267013896, 6228.532860035515, 6235.033569796688, 6248.218972628421, 4362.716423432466, 4420.248557745191, 4462.977685111554, 4497.2129877272, 4506.3916371290115, 1269.676232560691, 1279.923204607708, 1282.352412031918, 1294.8769866862633, 1300.219868079145, 774.3379410603965, 774.3650206830345, 795.316028215734, 798.9081255655558, 800.0799081056831, 6780.627065511346, 7039.417949868003, 7093.645317734093, 7160.925861120873, 7162.176349719744, 919.3334832246568, 931.6180366050022, 931.82295363882, 946.1059298962552, 968.6270785747538, 4333.9133217335475, 4511.368408422823, 4519.909601807945, 4675.976620116878, 4678.649101317346, 14913.92543037278, 15016.24918754058, 15275.465358712392, 15440.768388474182, 15467.767983480257, 14737.852621473648, 15288.923555382153, 15366.078035317916, 15398.307076181598, 15423.768643470357, 7227.421806255487, 7281.890771638429, 8281.254687078192, 8725.083866193958, 8777.648894044294, 6371.522135839923, 7275.527090739085, 7349.640316541243, 7401.629918504161, 7409.2590740927635, 9950.701478955643, 13605.979551533477, 13792.379342929391, 13910.234937078558, 14008.879289657007, 13760.724785504033, 14160.291985400483, 14185.645358866112, 14293.28533573338, 14329.928350358181, 9485.525723713925, 9780.85328720084, 9788.412695238309, 9788.412695238309, 9857.507124643882, 10957.0, 10984.615538456435, 11117.221794474633, 11119.221654484432, 11130.998210161124, 9305.069493050642, 9320.021397753138, 9322.440653560538, 9400.623975040922, 9461.195798356941, 9116.316276279438, 9158.771030724285, 9203.125703058198, 9251.0, 9253.028432014811, 6997.510174287751, 8435.873461897954, 8436.325093992344, 8438.873416898628, 8447.8732819009, 6041.6375017499095, 6089.665068421102, 6160.815175544741, 6223.315435302204, 6292.2134733158355, 6153.876922461615, 6258.499320054294, 6293.276273228637, 6388.520860935541, 6484.870302594016], "value": 5467.6986787286905, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.38390513171146, 354.74990131956974, 393.77948348924605, 398.81255809769414, 410.6160739708373, 5241.973790131049, 6146.416090471409, 6162.352952939944, 6342.207224096994, 6463.224413070432, 5329.626926115174, 6048.879022419549, 6165.0, 6202.5037996960245, 6244.0, 3821.331267028273, 3874.9537624841278, 4369.0, 4626.629869610423, 4665.533446655341, 3790.905227369324, 4047.271491131595, 4380.912381752363, 4508.684392092555, 4639.489656137822, 3366.5286859839644, 4230.498173148519, 4610.308453731934, 4639.884002899921, 4665.2535594304745, 3977.9458443512494, 4585.633149348044, 4639.327297541854, 4639.721616702975, 4650.302454631832, 3459.8270086495827, 4019.1358857845703, 4021.477207962946, 4034.172994536113, 4048.0487085534874, 6274.780382686629, 6288.496920246369, 6306.842328941813, 6321.0, 6334.2398912130375, 5527.419620939824, 5532.695701736403, 5552.194931734916, 5572.1303528682765, 5584.5253153482, 6730.697118629673, 6788.151481064867, 6794.422474089708, 6802.251752307242, 6803.353681400259, 3454.775439596418, 4320.827166913351, 4539.773011349453, 4577.107464044512, 4598.655100867449, 6104.816855494342, 6588.571742836701, 6805.591664500146, 6855.177378714586, 7198.496105272633, 3904.2581909437117, 4189.224993376235, 4330.372096046056, 4623.214053610895, 4649.907001860011, 4401.427814384174, 4670.579647831712, 4705.694129881546, 4715.646326525456, 4720.763961801965, 5320.228566857784, 5463.289772329651, 5466.535344495683, 5487.807926722626, 5555.583331250173, 5171.948280517222, 5544.002079625706, 5907.4387933147145, 6662.367075127855, 6692.386111499735, 1343.7301749846301, 1590.1492701404925, 1602.1748799368102, 1607.3570571771386, 1616.8059832820136, 1026.9178465722725, 1046.4401545173448, 1060.1465820014962, 1064.6220591689994, 1071.5767271927546, 5121.231815227746, 6354.364563543609, 6368.936310636835, 6419.454346380517, 6495.382938620824, 7026.610952276251, 7032.296770323029, 7036.120484939383, 7045.436365090781, 7056.094854389286, 5121.8719532012, 6007.519398448114, 6258.9374106259265, 6347.301796802348, 6363.872722545523, 3008.518637018067, 3608.963910360915, 3637.3998290281806, 3670.15586415126, 3674.210044840372, 5909.438603332676, 5981.491573216238, 6170.82754276686, 6723.7646682365885, 6990.811562034466, 6679.599224046573, 7132.786016419515, 7182.3535881770895, 7186.389156921616, 7187.353138217585, 7131.215566287704, 7156.60638664868, 7164.104486939133, 7172.0, 7176.354128128493, 5574.554035677055, 6477.740890364333, 6858.58722094843, 7015.36861682451, 7030.507864449644, 6319.526035547445, 6877.415419689182, 6880.552764070319, 6903.723851045902, 6932.965335173493, 6663.733450661919, 7018.508704390643, 7172.39034682037, 7245.166805817398, 7333.230010848997, 3897.9805100974313, 4606.239970404846, 4656.9301460477445, 4660.627149828073, 4665.650076244227, 7145.678444470115, 7150.570965742072, 7171.49799514029, 7186.640667966685, 7187.137543494814, 6605.834854128685, 6842.281560436082, 6850.8972365413565, 6873.2783057778215, 6914.0, 6342.7780027700655, 6875.89686154698, 6883.208431030295, 6893.310668933066, 6907.34380233877, 5936.050231962865, 6719.361660642231, 6720.32796720324, 6724.957631567115, 6733.259341472336, 6832.838417469142, 6849.349311815369, 6852.588844669336, 6871.804610855976, 6872.0, 5312.389075256395, 6642.3025582315095, 6659.800205993828, 6688.3646053624825, 6695.163104611924], "value": 4871.413496534419, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [775.662586774753, 824.9463784853988, 842.0905422144085, 868.0624925080913, 868.6134670071818, 14619.195944223064, 15085.472008479705, 16636.91681541592, 16657.333706651734, 17122.1165671776, 16328.18359082047, 18131.63736725268, 18309.542261443472, 18345.7248141278, 18366.35717749879, 956.9856452153223, 986.9950650246739, 997.3616885193446, 1001.654429221921, 1003.0721582536125, 860.5267103093305, 1012.6101450941404, 1014.4065721552901, 1016.998256717133, 1021.9540120694587, 935.9344845860793, 1044.6709286574705, 1045.5399624165382, 1049.3127001813825, 1055.1137044882298, 1036.6475398364591, 1039.3244391145815, 1039.6101461951769, 1040.5525623981684, 1043.5877828257833, 773.1920143450108, 778.2528772378523, 778.7235531386333, 778.8208711996216, 779.4504874063772, 16850.0, 17012.744808827876, 17143.228554715068, 17155.05647189404, 17202.741958870625, 17251.0, 17305.740413893676, 17372.131393430278, 17574.38489652856, 17709.557261068578, 13569.796453053114, 13720.245386503739, 14598.854011459856, 14772.482963096347, 14774.556763297118, 3605.891823245307, 3627.147620309225, 3627.9274414511824, 3629.5644522657185, 3635.3456377851976, 133122.0, 136311.0, 137119.31440342835, 137308.31345843305, 137630.62369376278, 13625.727485450236, 13967.580972570839, 14019.22894240806, 14149.0, 14234.644133896736, 22779.88610056939, 23957.880210598836, 27255.318617034336, 27272.863635681693, 27401.177964661092, 6925.411340036053, 7552.622368881534, 7665.846683066307, 7802.297793198526, 7851.568163751049, 3801.2967600993907, 3834.9041273968373, 3875.302445559826, 3875.922481550353, 3893.143508428198, 4386.0, 4483.910321793611, 4488.640908727294, 4496.662750293741, 4500.077484115748, 911.1844898815523, 921.755734730297, 935.1350001248818, 936.3679516326589, 938.2306508662831, 609.7804790275497, 630.5397060146119, 647.7311915555086, 655.2922843329193, 671.8521925176453, 10841.349519028725, 11450.713732156762, 11683.240589361665, 12191.512339506498, 12376.319302438453, 526.5866294958439, 641.7208514296296, 644.7259914536397, 659.8581305019444, 669.4176066821896, 5159.690418574897, 5704.857378565569, 5707.429257074259, 5824.767009319579, 5850.444207800252, 148593.0, 150567.0, 150764.0, 151834.96330073557, 153439.0, 113003.30495042581, 116429.41785291019, 127008.0, 127267.0, 132250.33874830758, 97040.05919881562, 97553.99999999857, 99665.0, 102063.48968255111, 103333.0, 11873.287602743863, 12018.459169337226, 12031.45858436355, 12272.325022123512, 12505.827852671568, 43086.78456607697, 45354.77322613366, 45502.0, 46029.78597889242, 46266.53733462622, 48731.07613543199, 59812.401875980686, 60361.69819150876, 60462.69768651129, 61416.46458838565, 16403.343866245217, 16708.916455417646, 16782.748258776373, 16931.830681693027, 16996.235169417654, 16318.510444686679, 18355.54111147232, 18893.622127557097, 18899.622007559497, 19014.524636883634, 17334.39329623457, 17381.739273911153, 17397.565060873578, 17548.91225543864, 17633.559161021076, 15480.0, 15962.920185398998, 15964.12197329158, 16063.919680401523, 16108.436204732778, 16195.75706364429, 16222.837771622133, 16301.836981630031, 16397.344106236094, 16442.0, 9881.0, 12018.819717704067, 12032.879671203176, 12154.878451215374, 12255.448504817126, 11733.647990560297, 11997.700057498283, 13186.604401867959, 13381.46113196956, 13442.991775617063], "value": 8670.665348078499, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1548.0000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 115.0, 165.0, 169.0, 117.0, 116.0, 116.0, 114.0, 115.0, 120.0], "value": 125.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 110.0, 109.0, 148.0, 111.0, 112.0, 111.0, 118.0, 134.0, 110.0], "value": 119.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 207.0, 202.0, 235.0, 238.0, 289.0, 282.0, 233.0, 196.0, 245.0], "value": 232.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 57.0, 59.0, 108.0, 82.0, 47.0, 69.0, 87.0, 91.0, 47.0], "value": 72.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 71.0, 100.0, 92.0, 62.0, 59.0, 102.0, 105.0, 103.0, 120.0], "value": 89.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 113.0, 89.0, 91.0, 128.0, 91.0, 90.0, 97.0, 100.0], "value": 98.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 99.0, 97.0, 96.0, 96.0, 106.0, 97.0, 96.0, 97.0, 96.0], "value": 98.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 91.0, 91.0, 115.0, 142.0, 83.0, 146.0, 81.0, 147.0], "value": 108.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 51.0, 53.0, 53.0, 50.0, 52.0, 53.0, 52.0, 51.0], "value": 51.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 67.0, 61.0, 60.0, 65.0, 59.0, 57.0, 56.0, 55.0, 59.0], "value": 59.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 91.0, 89.0, 91.0, 90.0, 90.0, 93.0, 91.0, 122.0, 117.0], "value": 96.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 137.0, 140.0, 135.0, 140.0, 135.0, 147.0, 140.0, 136.0, 151.0], "value": 139.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 188.0, 183.0, 189.0, 190.0, 190.0, 187.0, 185.0, 190.0, 190.0], "value": 187.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 70.0, 69.0, 68.0, 70.0, 67.0, 70.0, 70.0, 66.0, 69.0], "value": 68.5, "unit": "score"}]}]} 17:35:34 INFO - Return code: 0 17:35:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:35:34 INFO - # TBPL SUCCESS # 17:35:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:35:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:35:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:35:34 INFO - 2017-06-01 17:35:34.492 screenresolution[1669:11070] starting screenresolution argv=screenresolution get 17:35:34 INFO - 2017-06-01 17:35:34.494 screenresolution[1669:11070] Display 0: 1600x1200x32@60 17:35:34 INFO - 2017-06-01 17:35:34.500 screenresolution[1670:11073] starting screenresolution argv=screenresolution list 17:35:34 INFO - Available Modes on Display 0 17:35:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:35:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:35:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:35:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:35:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:35:34 INFO - Intel Iris: 17:35:34 INFO - Chipset Model: Intel Iris 17:35:34 INFO - Type: GPU 17:35:34 INFO - Bus: Built-In 17:35:34 INFO - VRAM (Dynamic, Max): 1536 MB 17:35:34 INFO - Vendor: Intel (0x8086) 17:35:34 INFO - Device ID: 0x0a2e 17:35:34 INFO - Revision ID: 0x0009 17:35:34 INFO - Displays: 17:35:34 INFO - Display: 17:35:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:35:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:35:34 INFO - Display Serial Number: 1600x1200 60 17:35:34 INFO - Main Display: Yes 17:35:34 INFO - Mirror: Off 17:35:34 INFO - Online: Yes 17:35:34 INFO - Rotation: Supported 17:35:34 INFO - Return code: 0 17:35:34 INFO - Running post-action listener: _resource_record_post_action 17:35:34 INFO - [mozharness: 2017-06-02 00:35:34.734577Z] Finished run-tests step (success) 17:35:34 INFO - Running post-run listener: _resource_record_post_run 17:35:34 INFO - Total resource usage - Wall time: 1257s; CPU: 53.0%; Read bytes: 40545280; Write bytes: 569082880; Read time: 260; Write time: 1734 17:35:34 INFO - TinderboxPrint: CPU usage
53.3% 17:35:34 INFO - TinderboxPrint: I/O read bytes / time
40,545,280 / 260 17:35:34 INFO - TinderboxPrint: I/O write bytes / time
569,082,880 / 1,734 17:35:34 INFO - TinderboxPrint: CPU idle
2,348.9 (46.7%) 17:35:34 INFO - TinderboxPrint: CPU system
1,013.8 (20.2%) 17:35:34 INFO - TinderboxPrint: CPU user
1,667.1 (33.1%) 17:35:34 INFO - TinderboxPrint: Swap in / out
286,535,680 / 0 17:35:34 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 230965760; Write bytes: 230627328; Read time: 16797; Write time: 481 17:35:34 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 17:35:34 INFO - run-tests - Wall time: 1237s; CPU: 53.0%; Read bytes: 39574528; Write bytes: 319986688; Read time: 248; Write time: 1202 17:35:35 INFO - Running post-run listener: _upload_blobber_files 17:35:35 INFO - Blob upload gear active. 17:35:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:35:35 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 17:35:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Cedar', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:35:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Cedar -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:35:35 INFO - (blobuploader) - INFO - Open directory for files ... 17:35:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:35:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:35:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:35:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:35:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:35:36 INFO - (blobuploader) - INFO - Done attempting. 17:35:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:35:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:35:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:35:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:35:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:35:37 INFO - (blobuploader) - INFO - Done attempting. 17:35:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:35:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:35:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:35:39 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:35:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:35:39 INFO - (blobuploader) - INFO - Done attempting. 17:35:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:35:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:35:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:35:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:35:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:35:41 INFO - (blobuploader) - INFO - Done attempting. 17:35:41 INFO - (blobuploader) - INFO - Iteration through files over. 17:35:41 INFO - Return code: 0 17:35:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:35:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:35:41 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/6f807c39c612b90a7b32d80b871c78d470c675bece8e2192a529fd1e89e65219a9d9e8c08f46738453a86508c15b530ab83cc793c612b44ed7336c9af3121c67", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/ec410fd6dad16d67df5326f696b5b3c3cb3911a5465917f5585823703d63bff6733fff3df2958513b9aa310c64251fe3f5590a189c12ad84d8c535779db85208", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/1f9396cf9ed932df8209459f9c235c1191df638122824c847a87143ead1b86a6f7d3b4ad6156a925166dbda286f88eb59088ee561848def8bff8329a42aef63f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/bb4c6cf8e8127a82db2d874f8a18b3419b02dbe8fdbc995f4ee8ff8fe7dcd94d149fc34923f26d74e8143f3d1435d501882b5fbdc0fcf7cdae07b69ddaa98574"} 17:35:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:35:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:35:41 INFO - Contents: 17:35:41 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/6f807c39c612b90a7b32d80b871c78d470c675bece8e2192a529fd1e89e65219a9d9e8c08f46738453a86508c15b530ab83cc793c612b44ed7336c9af3121c67", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/ec410fd6dad16d67df5326f696b5b3c3cb3911a5465917f5585823703d63bff6733fff3df2958513b9aa310c64251fe3f5590a189c12ad84d8c535779db85208", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/1f9396cf9ed932df8209459f9c235c1191df638122824c847a87143ead1b86a6f7d3b4ad6156a925166dbda286f88eb59088ee561848def8bff8329a42aef63f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/bb4c6cf8e8127a82db2d874f8a18b3419b02dbe8fdbc995f4ee8ff8fe7dcd94d149fc34923f26d74e8143f3d1435d501882b5fbdc0fcf7cdae07b69ddaa98574"} 17:35:41 INFO - Running post-run listener: copy_logs_to_upload_dir 17:35:41 INFO - Copying logs to upload dir... 17:35:41 INFO - mkdir: /builds/slave/test/build/upload/logs 17:35:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1341.126056 ========= master_lag: 1.21 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 22 secs) (at 2017-06-01 17:35:43.014834) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-01 17:35:43.022705) ========= 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.JMZNwaFdVQ/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.fy2M4Jznr3/Listeners TMPDIR=/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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/Cedar/sha512/6f807c39c612b90a7b32d80b871c78d470c675bece8e2192a529fd1e89e65219a9d9e8c08f46738453a86508c15b530ab83cc793c612b44ed7336c9af3121c67", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/ec410fd6dad16d67df5326f696b5b3c3cb3911a5465917f5585823703d63bff6733fff3df2958513b9aa310c64251fe3f5590a189c12ad84d8c535779db85208", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/1f9396cf9ed932df8209459f9c235c1191df638122824c847a87143ead1b86a6f7d3b4ad6156a925166dbda286f88eb59088ee561848def8bff8329a42aef63f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/bb4c6cf8e8127a82db2d874f8a18b3419b02dbe8fdbc995f4ee8ff8fe7dcd94d149fc34923f26d74e8143f3d1435d501882b5fbdc0fcf7cdae07b69ddaa98574"} build_url:https://queue.taskcluster.net/v1/task/B6K62yKLQzGk5raU6dSi9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011143 build_url: 'https://queue.taskcluster.net/v1/task/B6K62yKLQzGk5raU6dSi9Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/6f807c39c612b90a7b32d80b871c78d470c675bece8e2192a529fd1e89e65219a9d9e8c08f46738453a86508c15b530ab83cc793c612b44ed7336c9af3121c67", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/ec410fd6dad16d67df5326f696b5b3c3cb3911a5465917f5585823703d63bff6733fff3df2958513b9aa310c64251fe3f5590a189c12ad84d8c535779db85208", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/1f9396cf9ed932df8209459f9c235c1191df638122824c847a87143ead1b86a6f7d3b4ad6156a925166dbda286f88eb59088ee561848def8bff8329a42aef63f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/bb4c6cf8e8127a82db2d874f8a18b3419b02dbe8fdbc995f4ee8ff8fe7dcd94d149fc34923f26d74e8143f3d1435d501882b5fbdc0fcf7cdae07b69ddaa98574"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-01 17:35:43.062145) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 17:35:43.065551) ========= 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.JMZNwaFdVQ/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.fy2M4Jznr3/Listeners TMPDIR=/var/folders/ry/9wyjbbwd6d76l2z511wzcdbh00000w/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.005114 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 17:35:43.114541) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-01 17:35:43.114888) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-01 17:35:43.118291) ========= ========= Total master_lag: 1.34 =========