builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0066 starttime: 1464229888.44 results: success (0) buildid: 20160525183450 builduid: 63f91898e679451db7d589c242ad058b revision: 33693d8bdec17f07fc460ca7cc908ade88f97b86 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-25 19:31:28.446449) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-25 19:31:28.447048) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-25 19:31:28.447331) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-25 19:31:28.469336) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 19:31:28.470180) ========= 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.Opc4I1CoGu/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.bzSAxFz7v1/Listeners TMPDIR=/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 --2016-05-25 19:31:28-- 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: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 6.32M=0.002s 2016-05-25 19:31:28 (6.32 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.493193 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 19:31:28.983564) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 19:31:28.983843) ========= 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.Opc4I1CoGu/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.bzSAxFz7v1/Listeners TMPDIR=/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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.019268 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 19:31:29.032263) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 19:31:29.032646) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 33693d8bdec17f07fc460ca7cc908ade88f97b86 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 33693d8bdec17f07fc460ca7cc908ade88f97b86 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Opc4I1CoGu/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.bzSAxFz7v1/Listeners TMPDIR=/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 2016-05-25 19:31:29,104 truncating revision to first 12 chars 2016-05-25 19:31:29,104 Setting DEBUG logging. 2016-05-25 19:31:29,104 attempt 1/10 2016-05-25 19:31:29,104 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/33693d8bdec1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-25 19:31:29,788 unpacking tar archive at: mozilla-aurora-33693d8bdec1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.933043 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 19:31:29.985803) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-25 19:31:29.986146) ========= script_repo_revision: 33693d8bdec17f07fc460ca7cc908ade88f97b86 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-25 19:31:29.986559) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-25 19:31:29.986844) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-25 19:31:30.004271) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-05-25 19:31:30.004613) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Opc4I1CoGu/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.bzSAxFz7v1/Listeners TMPDIR=/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 19:31:30 INFO - MultiFileLogger online at 20160525 19:31:30 in /builds/slave/test 19:31:30 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 19:31:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:31:30 INFO - {'append_to_log': False, 19:31:30 INFO - 'base_work_dir': '/builds/slave/test', 19:31:30 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 19:31:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:31:30 INFO - 'branch': 'Mozilla-Aurora', 19:31:30 INFO - 'buildbot_json_path': 'buildprops.json', 19:31:30 INFO - 'config_files': ('talos/mac_config.py',), 19:31:30 INFO - 'default_actions': ('clobber', 19:31:30 INFO - 'read-buildbot-config', 19:31:30 INFO - 'download-and-extract', 19:31:30 INFO - 'populate-webroot', 19:31:30 INFO - 'create-virtualenv', 19:31:30 INFO - 'install', 19:31:30 INFO - 'run-tests'), 19:31:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:31:30 INFO - 'download_minidump_stackwalk': True, 19:31:30 INFO - 'download_symbols': 'ondemand', 19:31:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:31:30 INFO - 'tooltool.py': '/tools/tooltool.py', 19:31:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:31:30 INFO - '/tools/misc-python/virtualenv.py')}, 19:31:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:31:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:31:30 INFO - 'installer_path': 'installer.exe', 19:31:30 INFO - 'log_level': 'info', 19:31:30 INFO - 'log_name': 'talos', 19:31:30 INFO - 'log_to_console': True, 19:31:30 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:31:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:31:30 INFO - 'opt_config_files': (), 19:31:30 INFO - 'pip_index': False, 19:31:30 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:31:30 INFO - 'cmd': ('bash', 19:31:30 INFO - '-c', 19:31:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:31:30 INFO - 'enabled': True, 19:31:30 INFO - 'halt_on_failure': False, 19:31:30 INFO - 'name': 'check_screen_resolution'},), 19:31:30 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:31:30 INFO - 'cmd': ('bash', 19:31:30 INFO - '-c', 19:31:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:31:30 INFO - 'enabled': True, 19:31:30 INFO - 'halt_on_failure': False, 19:31:30 INFO - 'name': 'check_screen_resolution'},), 19:31:30 INFO - 'run_cmd_checks_enabled': True, 19:31:30 INFO - 'sps_profile': False, 19:31:30 INFO - 'sps_profile_interval': 0, 19:31:30 INFO - 'suite': 'dromaeojs-e10s', 19:31:30 INFO - 'system_bits': '32', 19:31:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:31:30 INFO - 'title': 't-yosemite-r7-0066', 19:31:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:31:30 INFO - 'use_talos_json': True, 19:31:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:31:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:31:30 INFO - 'work_dir': 'build'} 19:31:30 INFO - ##### 19:31:30 INFO - ##### Running clobber step. 19:31:30 INFO - ##### 19:31:30 INFO - Running pre-action listener: _resource_record_pre_action 19:31:30 INFO - Running main action method: clobber 19:31:30 INFO - rmtree: /builds/slave/test/build 19:31:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:31:31 INFO - Running post-action listener: _resource_record_post_action 19:31:31 INFO - ##### 19:31:31 INFO - ##### Running read-buildbot-config step. 19:31:31 INFO - ##### 19:31:31 INFO - Running pre-action listener: _resource_record_pre_action 19:31:31 INFO - Running main action method: read_buildbot_config 19:31:31 INFO - Using buildbot properties: 19:31:31 INFO - { 19:31:31 INFO - "project": "", 19:31:31 INFO - "product": "firefox", 19:31:31 INFO - "script_repo_revision": "production", 19:31:31 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 19:31:31 INFO - "repository": "", 19:31:31 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 19:31:31 INFO - "buildid": "20160525183450", 19:31:31 INFO - "pgo_build": "False", 19:31:31 INFO - "basedir": "/builds/slave/test", 19:31:31 INFO - "buildnumber": 338, 19:31:31 INFO - "slavename": "t-yosemite-r7-0066", 19:31:31 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 19:31:31 INFO - "platform": "yosemite_r7", 19:31:31 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 19:31:31 INFO - "branch": "mozilla-aurora", 19:31:31 INFO - "repo_path": "releases/mozilla-aurora", 19:31:31 INFO - "revision": "33693d8bdec17f07fc460ca7cc908ade88f97b86", 19:31:31 INFO - "stage_platform": "macosx64", 19:31:31 INFO - "builduid": "63f91898e679451db7d589c242ad058b", 19:31:31 INFO - "slavebuilddir": "test" 19:31:31 INFO - } 19:31:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 19:31:31 INFO - Running post-action listener: _resource_record_post_action 19:31:31 INFO - ##### 19:31:31 INFO - ##### Running download-and-extract step. 19:31:31 INFO - ##### 19:31:31 INFO - Running pre-action listener: _resource_record_pre_action 19:31:31 INFO - Running main action method: download_and_extract 19:31:31 INFO - mkdir: /builds/slave/test/build/tests 19:31:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:31:31 INFO - https://queue.taskcluster.net/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 19:31:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 19:31:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 19:31:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 19:31:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 19:31:39 INFO - Downloaded 1199 bytes. 19:31:39 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 19:31:39 INFO - Using the following test package requirements: 19:31:39 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 19:31:39 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 19:31:39 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 19:31:39 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 19:31:39 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 19:31:39 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 19:31:39 INFO - u'jsshell-mac.zip'], 19:31:39 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 19:31:39 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 19:31:39 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 19:31:39 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 19:31:39 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 19:31:39 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 19:31:39 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 19:31:39 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 19:31:39 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 19:31:39 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 19:31:39 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 19:31:39 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 19:31:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:31:39 INFO - https://queue.taskcluster.net/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 19:31:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 19:31:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 19:31:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 19:31:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 19:31:43 INFO - Downloaded 18425354 bytes. 19:31:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:31:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 19:31:46 INFO - Return code: 0 19:31:46 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 19:31:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:31:46 INFO - https://queue.taskcluster.net/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 19:31:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 19:31:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 19:31:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 19:31:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 19:31:54 INFO - Downloaded 18425354 bytes. 19:31:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:31:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 19:31:58 INFO - Return code: 0 19:31:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:31:58 INFO - https://queue.taskcluster.net/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 19:31:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 19:31:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 19:31:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 19:31:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 19:31:59 INFO - Downloaded 10840145 bytes. 19:31:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:31:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 19:31:59 INFO - Return code: 0 19:31:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:31:59 INFO - https://queue.taskcluster.net/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 19:31:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 19:31:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 19:31:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 19:31:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 19:32:02 INFO - Downloaded 114134973 bytes. 19:32:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 19:32:02 INFO - mkdir: /builds/slave/test/properties 19:32:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:32:02 INFO - Writing to file /builds/slave/test/properties/build_url 19:32:02 INFO - Contents: 19:32:02 INFO - build_url:https://queue.taskcluster.net/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 19:32:03 INFO - Running post-action listener: _resource_record_post_action 19:32:03 INFO - Running post-action listener: set_extra_try_arguments 19:32:03 INFO - ##### 19:32:03 INFO - ##### Running populate-webroot step. 19:32:03 INFO - ##### 19:32:03 INFO - Running pre-action listener: _resource_record_pre_action 19:32:03 INFO - Running main action method: populate_webroot 19:32:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:32:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:32:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:32:03 INFO - '--tppagecycles', 19:32:03 INFO - '7'], 19:32:03 INFO - 'tests': ['tsvgm']}}, 19:32:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:32:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:32:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:32:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:32:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:32:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:32:03 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:32:03 INFO - 'talos_options': ['--disable-e10s'], 19:32:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:32:03 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:32:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:32:03 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:32:03 INFO - 'talos_options': ['--disable-e10s'], 19:32:03 INFO - 'tests': ['damp', 'tps']}, 19:32:03 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:32:03 INFO - 'tests': ['damp', 'tps']}, 19:32:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:32:03 INFO - 'tests': ['dromaeo_dom']}, 19:32:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:32:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:32:03 INFO - 'tests': ['a11yr', 19:32:03 INFO - 'ts_paint', 19:32:03 INFO - 'tpaint', 19:32:03 INFO - 'sessionrestore', 19:32:03 INFO - 'sessionrestore_no_auto_restore', 19:32:03 INFO - 'tabpaint']}, 19:32:03 INFO - 'other-e10s': {'tests': ['a11yr', 19:32:03 INFO - 'ts_paint', 19:32:03 INFO - 'tpaint', 19:32:03 INFO - 'sessionrestore', 19:32:03 INFO - 'sessionrestore_no_auto_restore', 19:32:03 INFO - 'tabpaint']}, 19:32:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:32:03 INFO - 'ts_paint', 19:32:03 INFO - 'tpaint', 19:32:03 INFO - 'sessionrestore', 19:32:03 INFO - 'sessionrestore_no_auto_restore', 19:32:03 INFO - 'tabpaint']}, 19:32:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:32:03 INFO - 'ts_paint', 19:32:03 INFO - 'tpaint', 19:32:03 INFO - 'sessionrestore', 19:32:03 INFO - 'sessionrestore_no_auto_restore', 19:32:03 INFO - 'tabpaint']}, 19:32:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:32:03 INFO - 'tests': ['a11yr', 19:32:03 INFO - 'ts_paint', 19:32:03 INFO - 'tpaint', 19:32:03 INFO - 'sessionrestore', 19:32:03 INFO - 'sessionrestore_no_auto_restore', 19:32:03 INFO - 'tabpaint']}, 19:32:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:32:03 INFO - 'tests': ['a11yr', 19:32:03 INFO - 'ts_paint', 19:32:03 INFO - 'tpaint', 19:32:03 INFO - 'sessionrestore', 19:32:03 INFO - 'sessionrestore_no_auto_restore', 19:32:03 INFO - 'tabpaint']}, 19:32:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:32:03 INFO - 'tests': ['tsvgx', 19:32:03 INFO - 'tsvgr_opacity', 19:32:03 INFO - 'tart', 19:32:03 INFO - 'tscrollx', 19:32:03 INFO - 'cart']}, 19:32:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:32:03 INFO - 'tsvgr_opacity', 19:32:03 INFO - 'tart', 19:32:03 INFO - 'tscrollx', 19:32:03 INFO - 'cart']}, 19:32:03 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:32:03 INFO - 'talos_options': ['--disable-e10s'], 19:32:03 INFO - 'tests': ['tp5o']}, 19:32:03 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:32:03 INFO - 'tests': ['tp5o']}, 19:32:03 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:32:03 INFO - 'talos_options': ['--disable-e10s', 19:32:03 INFO - '--xperf_path', 19:32:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:32:03 INFO - 'tests': ['tp5n']}, 19:32:03 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:32:03 INFO - 'talos_options': ['--xperf_path', 19:32:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:32:03 INFO - 'tests': ['tp5n']}}, 19:32:03 INFO - 'talos.zip': {'path': '', 19:32:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:32:03 INFO - Running post-action listener: _resource_record_post_action 19:32:03 INFO - ##### 19:32:03 INFO - ##### Running create-virtualenv step. 19:32:03 INFO - ##### 19:32:03 INFO - Running pre-action listener: _resource_record_pre_action 19:32:03 INFO - Running main action method: create_virtualenv 19:32:03 INFO - Creating virtualenv /builds/slave/test/build/venv 19:32:03 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 19:32:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:32:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:32:03 INFO - Using real prefix '/tools/python27' 19:32:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:32:03 INFO - Installing distribute.............................................................................................................................................................................................done. 19:32:06 INFO - Installing pip.................done. 19:32:06 INFO - Return code: 0 19:32:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:32:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:32:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:32:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:32:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:32:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x101d2fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d3f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e6255f480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bcee00>, '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 0x101a37db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101bc9b20>, '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.Opc4I1CoGu/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.bzSAxFz7v1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 19:32:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 19:32:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:32:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Opc4I1CoGu/Render', 19:32:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:32:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:32:06 INFO - 'HOME': '/Users/cltbld', 19:32:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:32:06 INFO - 'LOGNAME': 'cltbld', 19:32:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:32:06 INFO - 'MOZ_NO_REMOTE': '1', 19:32:06 INFO - 'NO_EM_RESTART': '1', 19:32:06 INFO - 'PAGER': '/bin/cat', 19:32:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:32:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:32:06 INFO - 'PWD': '/builds/slave/test', 19:32:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:32:06 INFO - 'SHELL': '/bin/bash', 19:32:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bzSAxFz7v1/Listeners', 19:32:06 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 19:32:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:32:06 INFO - 'USER': 'cltbld', 19:32:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:32:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:32:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:32:06 INFO - 'XPC_FLAGS': '0x0', 19:32:06 INFO - 'XPC_SERVICE_NAME': '0', 19:32:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:32:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:32:07 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 19:32:07 INFO - Cleaning up... 19:32:07 INFO - Return code: 0 19:32:07 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 19:32:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:32:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:32:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:32:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:32:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:07 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101d2fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d3f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e6255f480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bcee00>, '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 0x101a37db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101bc9b20>, '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.Opc4I1CoGu/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.bzSAxFz7v1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 19:32:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 19:32:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 19:32:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Opc4I1CoGu/Render', 19:32:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:32:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:32:07 INFO - 'HOME': '/Users/cltbld', 19:32:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:32:07 INFO - 'LOGNAME': 'cltbld', 19:32:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:32:07 INFO - 'MOZ_NO_REMOTE': '1', 19:32:07 INFO - 'NO_EM_RESTART': '1', 19:32:07 INFO - 'PAGER': '/bin/cat', 19:32:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:32:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:32:07 INFO - 'PWD': '/builds/slave/test', 19:32:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:32:07 INFO - 'SHELL': '/bin/bash', 19:32:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bzSAxFz7v1/Listeners', 19:32:07 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 19:32:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:32:07 INFO - 'USER': 'cltbld', 19:32:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:32:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:32:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:32:07 INFO - 'XPC_FLAGS': '0x0', 19:32:07 INFO - 'XPC_SERVICE_NAME': '0', 19:32:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:32:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:32:07 INFO - Downloading/unpacking psutil>=0.7.1 19:32:07 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 19:32:07 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 19:32:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:32:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:32:07 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 19:32:07 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 19:32:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:32:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:32:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:32:10 INFO - Installing collected packages: psutil 19:32:10 INFO - Running setup.py install for psutil 19:32:10 INFO - building 'psutil._psutil_osx' extension 19:32:10 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 19:32:11 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 19:32:11 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 19:32:11 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 19:32:11 INFO - building 'psutil._psutil_posix' extension 19:32:11 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 19:32:11 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:32:11 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:32:11 INFO - ^ 19:32:11 INFO - 1 warning generated. 19:32:11 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 19:32:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:32:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:32:11 INFO - Successfully installed psutil 19:32:11 INFO - Cleaning up... 19:32:11 INFO - Return code: 0 19:32:11 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 19:32:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:32:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:32:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:32:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:32:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101d2fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d3f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e6255f480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bcee00>, '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 0x101a37db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101bc9b20>, '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.Opc4I1CoGu/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.bzSAxFz7v1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 19:32:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 19:32:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 19:32:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Opc4I1CoGu/Render', 19:32:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:32:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:32:11 INFO - 'HOME': '/Users/cltbld', 19:32:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:32:11 INFO - 'LOGNAME': 'cltbld', 19:32:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:32:11 INFO - 'MOZ_NO_REMOTE': '1', 19:32:11 INFO - 'NO_EM_RESTART': '1', 19:32:11 INFO - 'PAGER': '/bin/cat', 19:32:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:32:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:32:11 INFO - 'PWD': '/builds/slave/test', 19:32:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:32:11 INFO - 'SHELL': '/bin/bash', 19:32:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bzSAxFz7v1/Listeners', 19:32:11 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 19:32:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:32:11 INFO - 'USER': 'cltbld', 19:32:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:32:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:32:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:32:11 INFO - 'XPC_FLAGS': '0x0', 19:32:11 INFO - 'XPC_SERVICE_NAME': '0', 19:32:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:32:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:32:11 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 19:32:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:32:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:32:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:32:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:32:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:32:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:32:14 INFO - Downloading mozsystemmonitor-0.0.tar.gz 19:32:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:32:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 19:32:14 INFO - Installing collected packages: mozsystemmonitor 19:32:14 INFO - Running setup.py install for mozsystemmonitor 19:32:14 INFO - Successfully installed mozsystemmonitor 19:32:14 INFO - Cleaning up... 19:32:14 INFO - Return code: 0 19:32:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:32:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:32:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:32:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:32:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:32:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:14 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x101d2fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d3f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e6255f480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bcee00>, '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 0x101a37db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101bc9b20>, '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.Opc4I1CoGu/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.bzSAxFz7v1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 19:32:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 19:32:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:32:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Opc4I1CoGu/Render', 19:32:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:32:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:32:14 INFO - 'HOME': '/Users/cltbld', 19:32:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:32:14 INFO - 'LOGNAME': 'cltbld', 19:32:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:32:14 INFO - 'MOZ_NO_REMOTE': '1', 19:32:14 INFO - 'NO_EM_RESTART': '1', 19:32:14 INFO - 'PAGER': '/bin/cat', 19:32:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:32:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:32:14 INFO - 'PWD': '/builds/slave/test', 19:32:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:32:14 INFO - 'SHELL': '/bin/bash', 19:32:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bzSAxFz7v1/Listeners', 19:32:14 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 19:32:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:32:14 INFO - 'USER': 'cltbld', 19:32:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:32:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:32:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:32:14 INFO - 'XPC_FLAGS': '0x0', 19:32:14 INFO - 'XPC_SERVICE_NAME': '0', 19:32:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:32:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:32:14 INFO - Downloading/unpacking blobuploader==1.2.4 19:32:14 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 19:32:14 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 19:32:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:32:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:32:14 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 19:32:14 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 19:32:17 INFO - Downloading blobuploader-1.2.4.tar.gz 19:32:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:32:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:32: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 19:32: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 19:32:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:32:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:32: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 19:32: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 19:32:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:32:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:32:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:32:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:32:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:32:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:32:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:32:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:32:18 INFO - Downloading docopt-0.6.1.tar.gz 19:32:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:32:18 INFO - Installing collected packages: blobuploader, requests, docopt 19:32:18 INFO - Running setup.py install for blobuploader 19:32:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:32:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:32:18 INFO - Running setup.py install for requests 19:32:19 INFO - Running setup.py install for docopt 19:32:19 INFO - Successfully installed blobuploader requests docopt 19:32:19 INFO - Cleaning up... 19:32:19 INFO - Return code: 0 19:32:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:32:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:32:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:32:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:32:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:32:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:19 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x101d2fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d3f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e6255f480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bcee00>, '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 0x101a37db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101bc9b20>, '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.Opc4I1CoGu/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.bzSAxFz7v1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 19:32:19 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 19:32:19 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:32:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Opc4I1CoGu/Render', 19:32:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:32:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:32:19 INFO - 'HOME': '/Users/cltbld', 19:32:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:32:19 INFO - 'LOGNAME': 'cltbld', 19:32:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:32:19 INFO - 'MOZ_NO_REMOTE': '1', 19:32:19 INFO - 'NO_EM_RESTART': '1', 19:32:19 INFO - 'PAGER': '/bin/cat', 19:32:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:32:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:32:19 INFO - 'PWD': '/builds/slave/test', 19:32:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:32:19 INFO - 'SHELL': '/bin/bash', 19:32:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bzSAxFz7v1/Listeners', 19:32:19 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 19:32:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:32:19 INFO - 'USER': 'cltbld', 19:32:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:32:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:32:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:32:19 INFO - 'XPC_FLAGS': '0x0', 19:32:19 INFO - 'XPC_SERVICE_NAME': '0', 19:32:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:32:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:32:19 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-qBvPif-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:32:19 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-JqeTd0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:32:20 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-fMdYd1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:32:20 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-V7iydD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:32:20 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-SsB4_6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:32:20 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-nVCggY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:32:20 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-6UW719-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:32:20 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-P6o9am-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:32:20 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-veapTw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:32:20 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-u5AjjJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:32:20 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-S7eS3j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:32:20 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-um2cpa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:32:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:32:21 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-QF8TdH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:32:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:32:21 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-mNiDbX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:32:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:32:21 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-ptDsqb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:32:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:32:21 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-riKlDU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:32:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:32:21 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-04EaMX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:32:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:32:21 INFO - Running setup.py install for manifestparser 19:32:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:32:21 INFO - Running setup.py install for mozcrash 19:32:21 INFO - Running setup.py install for mozdebug 19:32:21 INFO - Running setup.py install for mozdevice 19:32:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:32:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:32:22 INFO - Running setup.py install for mozfile 19:32:22 INFO - Running setup.py install for mozhttpd 19:32:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:32:22 INFO - Running setup.py install for mozinfo 19:32:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:32:22 INFO - Running setup.py install for mozInstall 19:32:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:32:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:32:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:32:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:32:22 INFO - Running setup.py install for mozleak 19:32:22 INFO - Running setup.py install for mozlog 19:32:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:32:23 INFO - Running setup.py install for moznetwork 19:32:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:32:23 INFO - Running setup.py install for mozprocess 19:32:23 INFO - Running setup.py install for mozprofile 19:32:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:32:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:32:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:32:23 INFO - Running setup.py install for mozrunner 19:32:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:32:23 INFO - Running setup.py install for mozscreenshot 19:32:23 INFO - Running setup.py install for moztest 19:32:24 INFO - Running setup.py install for mozversion 19:32:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:32:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:32:24 INFO - Cleaning up... 19:32:24 INFO - Return code: 0 19:32:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:32:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:32:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:32:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:32:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:32:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:24 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x101d2fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d3f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e6255f480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bcee00>, '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 0x101a37db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101bc9b20>, '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.Opc4I1CoGu/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.bzSAxFz7v1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 19:32:24 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 19:32:24 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:32:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Opc4I1CoGu/Render', 19:32:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:32:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:32:24 INFO - 'HOME': '/Users/cltbld', 19:32:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:32:24 INFO - 'LOGNAME': 'cltbld', 19:32:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:32:24 INFO - 'MOZ_NO_REMOTE': '1', 19:32:24 INFO - 'NO_EM_RESTART': '1', 19:32:24 INFO - 'PAGER': '/bin/cat', 19:32:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:32:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:32:24 INFO - 'PWD': '/builds/slave/test', 19:32:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:32:24 INFO - 'SHELL': '/bin/bash', 19:32:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bzSAxFz7v1/Listeners', 19:32:24 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 19:32:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:32:24 INFO - 'USER': 'cltbld', 19:32:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:32:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:32:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:32:24 INFO - 'XPC_FLAGS': '0x0', 19:32:24 INFO - 'XPC_SERVICE_NAME': '0', 19:32:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:32:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:32:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:32:24 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-iUF0RH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:32:24 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)) 19:32:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:32:24 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-tG9MVV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:32:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 19:32:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:32:24 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-UPemMu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:32:24 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)) 19:32:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:32:24 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-QBbLb8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:32:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 19:32:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:32:24 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-it9Sib-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:32:24 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)) 19:32:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:32:24 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-YfjLz2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:32:25 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)) 19:32:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:32:25 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-QBPklD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:32:25 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)) 19:32:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:32:25 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-Z9VhXu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:32:25 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)) 19:32:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:32:25 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-rJKD9g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:32:25 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)) 19:32:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:32:25 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-AFyEeI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:32:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 19:32:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:32:25 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-JLV17_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:32:25 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)) 19:32:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:32:25 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-cp3mP8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:32:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 19:32:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:32:25 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-RWeRmO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:32:25 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)) 19:32:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:32:25 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-aGDVXe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:32:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 19:32:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:32:25 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-3ZhEi8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:32:26 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)) 19:32:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:32:26 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-arKtbK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:32:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 19:32:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:32:26 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-agGOkM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:32:26 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)) 19:32:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:32:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:32:26 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)) 19:32:26 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:32:26 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:32:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:32:26 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 19:32:26 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 19:32:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:32:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:32:26 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 19:32:26 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 19:32:28 INFO - Downloading blessings-1.6.tar.gz 19:32:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:32:28 INFO - Installing collected packages: blessings 19:32:28 INFO - Running setup.py install for blessings 19:32:29 INFO - Successfully installed blessings 19:32:29 INFO - Cleaning up... 19:32:29 INFO - Return code: 0 19:32:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:32:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:32:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:32:29 INFO - Reading from file tmpfile_stdout 19:32:29 INFO - Current package versions: 19:32:29 INFO - blessings == 1.6 19:32:29 INFO - blobuploader == 1.2.4 19:32:29 INFO - docopt == 0.6.1 19:32:29 INFO - manifestparser == 1.1 19:32:29 INFO - mozInstall == 1.12 19:32:29 INFO - mozcrash == 0.17 19:32:29 INFO - mozdebug == 0.1 19:32:29 INFO - mozdevice == 0.48 19:32:29 INFO - mozfile == 1.2 19:32:29 INFO - mozhttpd == 0.7 19:32:29 INFO - mozinfo == 0.9 19:32:29 INFO - mozleak == 0.1 19:32:29 INFO - mozlog == 3.1 19:32:29 INFO - moznetwork == 0.27 19:32:29 INFO - mozprocess == 0.22 19:32:29 INFO - mozprofile == 0.28 19:32:29 INFO - mozrunner == 6.11 19:32:29 INFO - mozscreenshot == 0.1 19:32:29 INFO - mozsystemmonitor == 0.0 19:32:29 INFO - moztest == 0.7 19:32:29 INFO - mozversion == 1.4 19:32:29 INFO - psutil == 3.1.1 19:32:29 INFO - requests == 1.2.3 19:32:29 INFO - wsgiref == 0.1.2 19:32:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:32:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:32:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:32:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:32:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:32:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:29 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x101d2fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d3f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e6255f480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bcee00>, '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 0x101a37db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101bc9b20>, '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.Opc4I1CoGu/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.bzSAxFz7v1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 19:32:29 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 19:32:29 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:32:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Opc4I1CoGu/Render', 19:32:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:32:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:32:29 INFO - 'HOME': '/Users/cltbld', 19:32:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:32:29 INFO - 'LOGNAME': 'cltbld', 19:32:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:32:29 INFO - 'MOZ_NO_REMOTE': '1', 19:32:29 INFO - 'NO_EM_RESTART': '1', 19:32:29 INFO - 'PAGER': '/bin/cat', 19:32:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:32:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:32:29 INFO - 'PWD': '/builds/slave/test', 19:32:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:32:29 INFO - 'SHELL': '/bin/bash', 19:32:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bzSAxFz7v1/Listeners', 19:32:29 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 19:32:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:32:29 INFO - 'USER': 'cltbld', 19:32:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:32:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:32:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:32:29 INFO - 'XPC_FLAGS': '0x0', 19:32:29 INFO - 'XPC_SERVICE_NAME': '0', 19:32:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:32:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:32:29 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)) 19:32:29 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)) 19:32:29 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)) 19:32:29 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)) 19:32:29 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)) 19:32:29 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)) 19:32:29 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)) 19:32:29 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)) 19:32:29 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)) 19:32:29 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)) 19:32:29 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)) 19:32:29 INFO - Cleaning up... 19:32:29 INFO - Return code: 0 19:32:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:32:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:32:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:32:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:32:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:32:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:29 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x101d2fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d3f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e6255f480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bcee00>, '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 0x101a37db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101bc9b20>, '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.Opc4I1CoGu/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.bzSAxFz7v1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 19:32:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:32:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:32:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Opc4I1CoGu/Render', 19:32:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:32:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:32:29 INFO - 'HOME': '/Users/cltbld', 19:32:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:32:29 INFO - 'LOGNAME': 'cltbld', 19:32:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:32:29 INFO - 'MOZ_NO_REMOTE': '1', 19:32:29 INFO - 'NO_EM_RESTART': '1', 19:32:29 INFO - 'PAGER': '/bin/cat', 19:32:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:32:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:32:29 INFO - 'PWD': '/builds/slave/test', 19:32:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:32:29 INFO - 'SHELL': '/bin/bash', 19:32:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bzSAxFz7v1/Listeners', 19:32:29 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 19:32:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:32:29 INFO - 'USER': 'cltbld', 19:32:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:32:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:32:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:32:29 INFO - 'XPC_FLAGS': '0x0', 19:32:29 INFO - 'XPC_SERVICE_NAME': '0', 19:32:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:32:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:32:29 INFO - Downloading/unpacking jsonschema 19:32:29 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 19:32:29 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 19:32:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:32:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:32:29 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 19:32:29 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 19:32:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:32:32 INFO - Downloading/unpacking functools32 (from jsonschema) 19:32: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 19:32: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 19:32:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:32:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:32: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 19:32: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 19:32:33 INFO - Downloading functools32-3.2.3-2.tar.gz 19:32:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:32:33 INFO - warning: no files found matching '*.txt' 19:32:33 INFO - no previously-included directories found matching 'build' 19:32:33 INFO - no previously-included directories found matching 'dist' 19:32:33 INFO - no previously-included directories found matching '.git*' 19:32:33 INFO - Installing collected packages: jsonschema, functools32 19:32:33 INFO - Running setup.py install for functools32 19:32:33 INFO - warning: no files found matching '*.txt' 19:32:33 INFO - no previously-included directories found matching 'build' 19:32:33 INFO - no previously-included directories found matching 'dist' 19:32:33 INFO - no previously-included directories found matching '.git*' 19:32:33 INFO - Successfully installed jsonschema functools32 19:32:33 INFO - Cleaning up... 19:32:33 INFO - Return code: 0 19:32:33 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 19:32:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:32:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:32:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:32:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:32:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:32:33 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101d2fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d3f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e6255f480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bcee00>, '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 0x101a37db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101bc9b20>, '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.Opc4I1CoGu/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.bzSAxFz7v1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 19:32:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 19:32:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 19:32:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Opc4I1CoGu/Render', 19:32:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:32:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:32:33 INFO - 'HOME': '/Users/cltbld', 19:32:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:32:33 INFO - 'LOGNAME': 'cltbld', 19:32:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:32:33 INFO - 'MOZ_NO_REMOTE': '1', 19:32:33 INFO - 'NO_EM_RESTART': '1', 19:32:33 INFO - 'PAGER': '/bin/cat', 19:32:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:32:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:32:33 INFO - 'PWD': '/builds/slave/test', 19:32:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:32:33 INFO - 'SHELL': '/bin/bash', 19:32:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bzSAxFz7v1/Listeners', 19:32:33 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 19:32:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:32:33 INFO - 'USER': 'cltbld', 19:32:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:32:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:32:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:32:33 INFO - 'XPC_FLAGS': '0x0', 19:32:33 INFO - 'XPC_SERVICE_NAME': '0', 19:32:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:32:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:32:34 INFO - Downloading/unpacking flake8 19:32: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 19:32: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 19:32:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:32:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:32: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 19:32: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 19:32:36 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 19:32:36 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 19:32:36 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 19:32:36 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 19:32:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:32:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:32:36 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 19:32:36 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 19:32:37 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 19:32:37 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 19:32:37 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 19:32:37 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 19:32:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:32:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:32:37 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 19:32:37 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 19:32:37 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 19:32:37 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 19:32:37 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 19:32:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:32:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:32:37 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 19:32:37 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 19:32:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 19:32:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:32:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:32:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:32:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:32:38 INFO - no previously-included directories found matching 'docs/_build' 19:32:38 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 19:32:38 INFO - Running setup.py install for pep8 19:32:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:32:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:32:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:32:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:32:38 INFO - no previously-included directories found matching 'docs/_build' 19:32:38 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 19:32:38 INFO - Successfully installed flake8 mccabe pyflakes pep8 19:32:38 INFO - Cleaning up... 19:32:38 INFO - Return code: 0 19:32:38 INFO - Running post-action listener: _resource_record_post_action 19:32:38 INFO - Running post-action listener: _start_resource_monitoring 19:32:38 INFO - Starting resource monitoring. 19:32:38 INFO - ##### 19:32:38 INFO - ##### Running install step. 19:32:38 INFO - ##### 19:32:38 INFO - Running pre-action listener: _resource_record_pre_action 19:32:38 INFO - Running main action method: install 19:32:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:32:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:32:38 INFO - Reading from file tmpfile_stdout 19:32:38 INFO - Detecting whether we're running mozinstall >=1.0... 19:32:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:32:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:32:38 INFO - Reading from file tmpfile_stdout 19:32:38 INFO - Output received: 19:32:38 INFO - Usage: mozinstall [options] installer 19:32:38 INFO - Options: 19:32:38 INFO - -h, --help show this help message and exit 19:32:38 INFO - -d DEST, --destination=DEST 19:32:38 INFO - Directory to install application into. [default: 19:32:38 INFO - "/builds/slave/test"] 19:32:38 INFO - --app=APP Application being installed. [default: firefox] 19:32:38 INFO - mkdir: /builds/slave/test/build/application 19:32:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:32:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 19:33:10 INFO - Reading from file tmpfile_stdout 19:33:10 INFO - Output received: 19:33:10 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 19:33:10 INFO - Running post-action listener: _resource_record_post_action 19:33:10 INFO - ##### 19:33:10 INFO - ##### Running run-tests step. 19:33:10 INFO - ##### 19:33:10 INFO - Running pre-action listener: _resource_record_pre_action 19:33:10 INFO - Running main action method: run_tests 19:33:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:33:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:33:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:33:10 INFO - 2016-05-25 19:33:10.406 screenresolution[1926:19845] starting screenresolution argv=screenresolution get 19:33:10 INFO - 2016-05-25 19:33:10.408 screenresolution[1926:19845] Display 0: 1600x1200x32@60 19:33:10 INFO - 2016-05-25 19:33:10.414 screenresolution[1927:19847] starting screenresolution argv=screenresolution list 19:33:10 INFO - Available Modes on Display 0 19:33:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:33:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:33:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:33:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:33:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:33:10 INFO - Intel Iris: 19:33:10 INFO - Chipset Model: Intel Iris 19:33:10 INFO - Type: GPU 19:33:10 INFO - Bus: Built-In 19:33:10 INFO - VRAM (Dynamic, Max): 1536 MB 19:33:10 INFO - Vendor: Intel (0x8086) 19:33:10 INFO - Device ID: 0x0a2e 19:33:10 INFO - Revision ID: 0x0009 19:33:10 INFO - Displays: 19:33:10 INFO - Display: 19:33:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:33:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:33:10 INFO - Display Serial Number: 1600x1200 60 19:33:10 INFO - Main Display: Yes 19:33:10 INFO - Mirror: Off 19:33:10 INFO - Online: Yes 19:33:10 INFO - Rotation: Supported 19:33:10 INFO - Return code: 0 19:33:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:33:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:33:10 INFO - Python 2.7.3 19:33:10 INFO - Return code: 0 19:33:10 INFO - grabbing minidump binary from tooltool 19:33:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:33:10 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bcee00>, '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 0x101a37db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101bc9b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:33:10 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 19:33:10 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 19:33:10 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:33:10 INFO - Return code: 0 19:33:10 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:33:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:33:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:33:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:33:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:33:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 19:33:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 19:33:11 INFO - Return code: 0 19:33:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0066', '--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 19:33:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0066 --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 19:33:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Opc4I1CoGu/Render', 19:33:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:33:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:33:11 INFO - 'HOME': '/Users/cltbld', 19:33:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:33:11 INFO - 'LOGNAME': 'cltbld', 19:33:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:33:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:33:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:33:11 INFO - 'MOZ_NO_REMOTE': '1', 19:33:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:33:11 INFO - 'NO_EM_RESTART': '1', 19:33:11 INFO - 'PAGER': '/bin/cat', 19:33:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:33:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:33:11 INFO - 'PWD': '/builds/slave/test', 19:33:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:33:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:33:11 INFO - 'SHELL': '/bin/bash', 19:33:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bzSAxFz7v1/Listeners', 19:33:11 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 19:33:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:33:11 INFO - 'USER': 'cltbld', 19:33:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:33:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:33:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:33:11 INFO - 'XPC_FLAGS': '0x0', 19:33:11 INFO - 'XPC_SERVICE_NAME': '0', 19:33:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:33:11 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0066', '--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 19:33:12 INFO - mozversion application_buildid: 20160525183450 19:33:12 INFO - mozversion application_changeset: 33693d8bdec17f07fc460ca7cc908ade88f97b86 19:33:12 INFO - mozversion application_display_name: FirefoxDeveloperEdition 19:33:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:33:12 INFO - mozversion application_name: Firefox 19:33:12 INFO - mozversion application_remotingname: firefox-dev 19:33:12 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 19:33:12 INFO - mozversion application_vendor: Mozilla 19:33:12 INFO - mozversion application_version: 48.0a2 19:33:12 INFO - mozversion platform_buildid: 20160525183450 19:33:12 INFO - mozversion platform_changeset: 33693d8bdec17f07fc460ca7cc908ade88f97b86 19:33:12 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 19:33:12 INFO - mozversion platform_version: 48.0a2 19:33:12 INFO - using testdate: 1464229992 19:33:12 INFO - actual date: 1464229992 19:33:12 INFO - starting webserver on 'localhost:54448' 19:33:12 INFO - SUITE-START | Running 2 tests 19:33:12 INFO - TEST-START | dromaeo_css 19:33:12 INFO - operating with platform_type : mac_ 19:33:12 INFO - Initialising browser for dromaeo_css test... 19:33:12 INFO - TEST-INFO | started process 1945 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/tmplwpxPz/profile http://localhost:54448/getInfo.html) 19:33:14 INFO - PROCESS | 1945 | __metrics Screen width/height:1600/1200 19:33:14 INFO - PROCESS | 1945 | colorDepth:24 19:33:14 INFO - PROCESS | 1945 | Browser inner width/height: 1024/689 19:33:14 INFO - PROCESS | 1945 | __metrics 19:33:14 ERROR - PROCESS | 1945 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 19:33:15 INFO - TEST-INFO | 1945: exit 0 19:33:15 INFO - Browser initialized. 19:33:15 INFO - Running cycle 1/1 for dromaeo_css test... 19:33:15 INFO - TEST-INFO | started process 1948 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/tmplwpxPz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:33:27 INFO - PROCESS | 1948 | RSS: Main: 221483008 19:33:27 INFO - PROCESS | 1948 | 19:34:17 INFO - PROCESS | 1948 | 1464230057022 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 19:36:28 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:54448/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54448/tests/dromaeo/cssquery-ext.html) 19:36:29 INFO - PROCESS | 1948 | RSS: Main: 234782720 19:36:29 INFO - PROCESS | 1948 | 19:39:31 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:54448/tests/dromaeo/cssquery-ext.html (next: http://localhost:54448/tests/dromaeo/cssquery-jquery.html) 19:39:32 INFO - PROCESS | 1948 | RSS: Main: 246259712 19:39:32 INFO - PROCESS | 1948 | 19:42:33 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:54448/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54448/tests/dromaeo/cssquery-mootools.html) 19:42:34 INFO - PROCESS | 1948 | RSS: Main: 248061952 19:42:34 INFO - PROCESS | 1948 | 19:45:36 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:54448/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54448/tests/dromaeo/cssquery-prototype.html) 19:45:36 INFO - PROCESS | 1948 | RSS: Main: 249765888 19:45:36 INFO - PROCESS | 1948 | 19:48:39 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:54448/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54448/tests/dromaeo/cssquery-yui.html) 19:48:40 INFO - PROCESS | 1948 | RSS: Main: 252575744 19:48:40 INFO - PROCESS | 1948 | 19:51:42 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:54448/tests/dromaeo/cssquery-yui.html (next: http://localhost:54448/tests/dromaeo/cssquery-dojo.html) 19:51:42 INFO - PROCESS | 1948 | RSS: Main: 253632512 19:51:42 INFO - PROCESS | 1948 | 19:51:42 INFO - PROCESS | 1948 | __start_tp_report 19:51:42 INFO - PROCESS | 1948 | _x_x_mozilla_page_load 19:51:42 INFO - PROCESS | 1948 | _x_x_mozilla_page_load_details 19:51:42 INFO - PROCESS | 1948 | |i|pagename|runs| 19:51:42 INFO - PROCESS | 1948 | |0;dojo.html;3027.091872438268;3401.3197360527893;3430.0738800523845;3453.4301840196367;3499.3176330615524;5571.080771672674;5628.015097357961;5642.746076426561;5650.3784583695815;5652.491275785179;5561.249231353766;5598.1322894951345;5605.747741351639;5618.522425593829;5652.293463317085;5133.743312834371;5182.922256166161;5185.455527169649;5221.425643179247;5227.555657769094;5108.259302401149;5164.715940623264;5239.895202095956;5241.10901146804;5275.208718692188;5189.351331083614;5223.347081614798;5223.791048358061;5229.633925625208;5244.187150991603;5204.245384419238;5204.869878253036;5206.193040078794;5233;5237.47625237477;5053.84838454847;5085.542301192911;5091.847244582631;5169.043726910533;5195.2986346843445;5324.653897496651;5469.480399362054;5490.780368785244;5501.669899806025;5537.086380747212;6080.543959203077;6148.108524264001;6152.5693201475915;6171.84570385744;6202.534809889276;6021.789237376671;6110.694465276719;6125.479643195305;6159.538034647419;6283.968580157116;5729.140628905614;5744.224529688522;5746.281714785652;5749.770009199585;5756.625819321731;5125.743712814345;6204.348543402968;6238.688065596702;6275.811725648238;6280.842978925472;6098.69506524672;6110.175126357974;6157.907631385533;6159.3224745277985;6160.045192995003;6210.4100110489435;6228.439440450381;6241.906371404432;6256.812295631138;6268.937310626835;6020;6117.327094019654;6151.015837465984;6168.506519478521;6208.4722798561725;6156.445919867234;6175.69121543921;6176.938230617726;6187.84530386744;6191.752329906843;4834.347363105936;4903.803847846046;4907.877303067452;4914.70511769295;4922.852314430573;4308.784560771949;4316.525182229952;4387.034852332482;4395.472543294761;4430.8449204277695;5523.088690366125;5567.944320556742;5636.943630563642;5646.407127251661;5646.463585959327;6198.504119670506;6240.375962403814;6242.500599951993;6244.094606282064;6246.562740608114;5509.724513774296;5536.52939500147;5612.663240205601;5622.859428514238;5625.212470254168;5340.946590534123;5345.866353341197;5372.221027950925;5382.623216374895;5382.784688612412;6300.2754683212015;6335.176427064452;6338.873222535616;6366.872662546631;6372.745090196433;6096.512279017579;6166.4758495526585;6231.40801623845;6256.812295631138;6262.718177681924;6302.180716506825;6305.905411418924;6311.684415779101;6315.715792789428;6327.114204011534;5873.500752436091;6124.5712800103565;6129.2032035835055;6131.325554189125;6135.815925522242;6118.80192544137;6170.10533472635;6212.285587157535;6238.033104868752;6253.687315634109;5683.914741278802;6018.548608854266;6042.274927008787;6068.423499767515;6080.331163572092;5729.914051289151;5954.940450595439;6059.545534085049;6071.544634152545;6110.908336374869;4813.975930120327;4912.852614421573;5743;5946.048632218997;5979.431953964367;6023.5181185505935;6059.212302400836;6070.089486576872;6074.271087469532;6075.513958883191;5722.656640601577;5782.190493331022;5883.764649413975;5907.350191478848;5917 19:51:42 INFO - PROCESS | 1948 | |1;ext.html;488.4724497542652;535.5447869311084;572.1617829879226;592.7066102279371;613.1262950295827;31207.68792312076;39849.80075099624;39887.80336589903;39911.401328980086;40025.59974400255;41636;42076;42196;42364;42459.150816983645;9536.2371010319;9779.168770654504;9796.412215267072;9808.46053467059;9851.65519206828;9569.186619137381;9836.016398360178;9850.852237216448;9853.50732463374;9878.703638890809;9820.705378838646;9873.703788886345;9886.456244906527;9886.961869003759;9894;9801.705948821547;9856.50717464124;9858.359206651616;9873.555689993967;9877.06167914047;9767.413955162712;9832.213422926148;9833.655822046265;9865.654702085465;9899.752506187331;40774;40875.795621022;40954.59045409538;40966.9758256046;41883.581164188276;2823.5764635304663;3120.687931206884;3203.615566132056;3343.648916863743;3396.28677977625;26208.082717105;28379.290517737016;29466.41067178666;30426;31417.900373487046;2756.338478765082;2789.0377819652185;2815.3806162644187;2837.886484540616;2893.2043687985974;417728;421365;421991;422720.9999999969;423816;37900;65837.67081164563;66124.33875661182;66338.33661663419;66426;31752.682473174973;37043.814780925924;37657.62342376596;37840.24319513595;37896.86309410722;15806.051636901822;15824.44614438489;16120.435784747477;16198.919005405134;16207.837921620632;13383.263920484467;13396.531121410704;13471.595852124452;13575.660608484866;13595.45618175262;8376.41365104449;8384.077751447336;8444.493330400195;8486.533240671823;8523.977122745186;3375.8818441354924;3423.640517745601;3434.347473979936;3436.5876094868777;3472.4791281308007;962.432165022626;976.3360914578013;1027.9822975254444;1044.9738756530996;1094.5020015892749;26240.475190496083;30820.383592328475;31262.843685781427;31559;31616.209594759854;909.5179554835947;1081.4700796609604;1133.7562424078862;1138.920275580718;1167.9124065694937;14971.132789169733;15707.293171807296;15760.684786304439;15818.76271855923;15974.041557506587;608989;612348;614687;623283;623350.8832455899;61918;62686;62686;63071.684641576496;63235;1483.8664520193233;1505.1871989125968;1515.8256800467866;1519.7568389057699;1585.8255591884883;30606.693933060385;30946.84526577353;31002.379952401276;31274.21814454566;31345;56052.71973640106;56853.431465684815;56910.43089569217;56928;57037.71481142734;467313;468128;469366;472856;473062;27744.72255277502;27786.305342366602;27910.860445697643;28112.578311325757;28474.43051139007;45381;45495.545044550454;47773.76113119412;47873.76063119662;49457.01085978188;38092.80953595214;39490.01274968034;39567;40187.598124019554;40255.396169058076;38158.42762358623;38328.2334353317;38583;39063.41404878872;39425.014374640865;38405.4239186418;38734.80632596819;38773.612263877;39110;39206.80396597999;25439.36401589975;27815.02647407331;32926.506102408006;33885.830570846985;34187.658123419445;25576.360590985376;25662.486750265263;25665.973361066095;25874.611880821427;26337.4732505345 19:51:42 INFO - PROCESS | 1948 | |2;jquery.html;358.2244440785699;395.96238357356043;405.32108717897523;406.1714103229412;406.82193868965703;15454.140983917954;16170.2723377448;20042.198312067518;20794.272200472988;20943.581128377442;19635.214591416327;20162.294319698813;20528.794712052873;20673.379798606067;20872.582548349023;4985.252212168168;6036.185115009462;6095.573309868311;6101.145839582462;6111.877762444748;5888.116782482619;6015.308239552463;6132.632042077467;6146.6004709693725;6153.7846175383875;6037.818865434044;6065.211522502069;6078.665673387962;6125.326214116443;6127.417895299939;5917.940820591782;6064.9090263646085;6070.57505974582;6084.5132389408745;6097.5426842986735;5099.46455622158;5285.180796976475;5297.920531192035;5353.250544923714;5372.650777699437;17570.20934057958;24000.15999440029;25567;26106.34734131643;26633.334166645796;2040.785717499671;2260.672202530624;2326.3835083702834;2558.4243545202366;2560.359910022494;9209.355345125843;9227.492487913161;9934.403935763878;10231.846522302172;10249.795004099951;1724.7495565714883;1734.384293575775;1779.0482092080674;1788.1774383783475;1791.4625612316383;90952.54523727339;97393.5390969136;109853.45073274663;109989.35015974767;119327.99999999913;11772.411379430994;11974.70695558711;12006.579769708018;12008.219465734699;12065.552133743831;15123.16822574747;15191.088534687955;15221.391144354102;15250.389984400499;15288.159151246566;8878.28973682104;8890.955545222232;8927;8975.28197744179;9008.234300084567;8855.64577416909;8969.237614802814;8972.506512141765;9025.548722563846;9056.184943355129;4588.931166032512;4655.4180727409075;4718.8584342469785;4762.833300834454;4766.237402015661;1959.5786905815319;1996.4709352021725;2004.8572313781006;2015.9294424695358;2018.6669199581904;814.8085199978001;876.202655583416;910.8588168833842;1051.3271506236015;1062.654637242899;18008.549786255186;19389.8061019388;19692.40922772319;21567.78432215689;21620.56758864814;952.1716106986966;953.1374106433689;1001.5092604623827;1045.2004216774174;1049.8635177427038;9903.950480247553;10821.188410869216;11151.553937842395;11200.103991680646;11426.771464570827;532249;543193;544289;546759;577535;91178.54410728037;100428.49785751024;104790.42814357791;115263.84736152699;117565;972.7276362618478;983.9114479696862;996.267743208741;996.5565323431003;1003.3929472669076;18043.909780451013;18076.276948922423;18185.45443636693;18253.361132360304;18880.905595471933;50887;51453.7427312876;51691.483085168664;51868.74065629799;52364.73817630888;72300.63849680718;75161.62419187868;77656;78282;78678;6791.694373753292;7881.881771773543;8238.629261683118;8299.502029878227;8323.16768323187;12293.56972505958;12612.747745045232;12619.38265321399;12733.426995785027;13193.274369909363;11041.282316649393;11114.44427778624;11198.38408887519;11291.604793832177;11397.658070257905;10656.89343106559;10890.455477225949;11024;11099.5560177592;11170.553177872795;11110.611128610783;11206.99137077667;11309.886901130883;11449.771004580029;11832.408379581158;8154.510729356258;8337.333013359039;8371.581420928807;8400.075991641037;8422.410431269753;8486.74539763808;10729.980651838063;11187.944060279646;11372.488238029475;11402.771944560896 19:51:42 INFO - PROCESS | 1948 | |3;mootools.html;1556.5875043113576;1568.4040064775388;1781.5189898727344;1870.6352261309041;1921.5100149461884;7964.681412743484;8608.956955215222;8632.352573556993;8799.648014079437;8809.955950220248;8655.783605409868;8664.480131192116;8728.389012769108;8759.036505984337;8776.956115219415;2065.39070974063;2413.9275821725287;2426.4419183587784;2445.7798798108165;2454.6686197363397;2122.5754849030177;2426.3327584914114;2453.411181316489;2460.6432067350224;2468.2471846086937;1951.736515570394;2067.9379618611465;2426.6238732996326;2438.9756102439014;2486.29140694902;1988.2146552111979;2420.624803155505;2425.417899704067;2426.5146970605774;2429.9392515187087;2438;2464.4578192797558;2467.247489515697;2468.506298740241;2474.7277799442045;8906.465612063297;8931.598078086501;8949.955250223773;8990.775230619221;9003.954980225122;5392;5464.754086066137;5514.172874068881;5560.304961879765;5633.492985631272;3391.6608339166132;3459.619441861393;3516.4197907345515;3535.169235229719;3539.557555305587;884.2307192742355;905.3436258712446;910.2035718746097;911.5260064766285;1002.4185972136144;12057.216280941711;12163.756724865541;12191.695207619881;12227.082968777288;12238.265704057696;4748.525147485293;4825.662203645781;4883.8290659826735;4887.29134275528;4897.681650692693;10368.637097701545;10389.688309350731;10429.843552346721;10430.739231519121;10463.94768026155;5292.603054770908;5470.398256191816;5484.067708489566;5485.0949593317455;5514.5864060194845;4381.6932814703305;4816.109019831342;4921.50784921505;4928.211486162197;4936.308916751657;4098.979505102456;4100.5899410059355;4166.791660416967;4202.8739137825305;4219.092895027522;1093.824988001916;1228.9185516745385;1276.5978716704208;1352.8027695489563;1361.5567498451674;848.9915100849036;879.560219890055;934.8267923755643;945.9952700236455;960.7454024683465;6004.789832355934;6014.917314883363;6043.758249670052;6101.938980610226;6178.474829639531;898.3890954151109;995.8556009378744;1018.8522664213796;1035.29082578434;1071.5820829876354;3969.325214713506;4017.4375587417785;4032;4043.292423825819;4078.3270760324212;10616.416097114732;11118.833217501744;11124.721881953015;11228.663140105646;11248.156388270749;10728.624498142684;10880.347179169275;10957.83563246552;11075.446227688582;11118.444077796239;8531.06158322584;8535.445196062361;8668.263197628146;8674.262687671619;8806.383553151347;5736.082226843685;5849.736761845812;5897.292324921037;5915.526757859447;5971.611845230046;7967.681292748225;9795.804083918425;9838.852417213604;9858.753531161778;9890.456024918698;8149.470284431493;8183.3862460316905;9880.950595247265;9977.351472154287;10002.049805268487;6476.805695829133;6833.760818371333;6995.720171193096;7134.857302854018;7274.090738657668;8184;8216.383771217063;8318.209770071833;8327.167283271625;8472.3645726572;5658.519025882846;6526.347365263626;7069.893951590628;7377.889331659922;7449.962750186214;7252.709891604277;7293.635318234174;7322.96338518304;7383.556986580822;7389.482736208413;6998.160220773336;7083.787486375417;7092.893606596009;7099.82250443743;7116.35952764233;4907.386576677915;4964.925526117039;5018.247262910592;5049.798008079636;5082.847514574569;4921.975390123026;4993;5004.074246264453;5058.140116180185;5249.632525723316 19:51:42 INFO - PROCESS | 1948 | |4;prototype.html;272.51900395801414;324.86031006667133;325.8142858570614;330.2075019952115;333.24519962285444;6715.966420167899;6735.562188457753;6743.898841517369;6747.156605424322;6790.151231096113;6655.467562594993;6693.364130407604;6729.29342419045;6731.629760363178;6748.02153687715;4784.78468468918;4847.369841920546;4991.276264941581;4993.1262029144755;4993.201087825949;4922.581580565657;4955.554000139987;4963.826266080688;4986.975065124688;5004.974975125119;4908.435529914052;4908.852734417973;4912.975435122819;4939.061578300129;4942.406911170665;4935.358403407552;4940.901181976358;4948.604111671094;4950.851474455736;4985.376827896513;4261.04126571522;4267.701260911737;4273.465816772903;4279.251131052053;4289;6249.437550620421;6460.321666225026;6499.122618446544;6512.641804700739;6518.543701940866;5850.414958504158;5884.823455296305;5886.440788125121;5895.702945352015;5901.0558310670085;7096.716131354739;7150.499465037449;7157.856842863166;7206.49554531183;7207;3925.411188321746;4078.4290199372113;4746.335513028179;4753.073150735608;4759.904801903977;7286.271372862724;7586.393088552903;7586.772396828104;7609.048868891388;7643.656035478417;4829.420469543677;4838.467768545457;4855.902881942412;4857.9028419431415;4870.196417591058;4484.506704262529;4879.121758083508;4930.506949305112;4932.334134891816;4947.356843610308;5809.593328467051;5844.736986835603;5857.062869940789;5859.824205273849;5874.324452687995;7211.67547460355;7215.927840721631;7218.205997340288;7228.927710722931;7235.674394652253;1438.5252866554013;1490.411287541415;1699.0400423760536;1717.5620216844625;1721.7159168737269;1094.9890501095047;1109.6670998700358;1111.3220935229504;1116.5198964445367;1127.5546159267044;6698.229703584053;6729.596224226562;6734.393904548614;6755.662216889136;6785.864282714417;7270.873014682733;7479.551226926402;7480.738174163878;7490.850182996419;7503.512271702322;6625.006249062582;6675.23234828001;6799.388055075067;6801.1158549388265;6825.897611535831;2992.2968102495897;3997.6601988831276;4008.2785098682516;4018.6383225509844;4020.0954785173103;6377.521685873486;7996.480228785111;8003.759417290329;8006.559639219899;8048.396370272253;7453.850922981618;7746.883796743054;7810.3751699865015;7857.057153141545;7858.724944626911;6591.868162636624;7123.358897699232;7125.002499650053;8031;8035.437519373588;7538.434617403607;7572.318491335586;7575.507592006502;7587.127480339832;7589;7392.371648409945;7414.184439711736;7420.925790742023;7425.108986921604;7454.850902982019;7635.198304178203;7940.960295198487;7945.960270198612;7968.601569921365;8037.517748935083;4661.580457758682;4708.387909571871;4721;4723.7401942893475;4740.976295118502;5803.564732644984;6812.841816891239;7575.6969721210535;7582.431317651088;7611.086669599684;7239.203687594466;7304.6347682616715;7305.525140865827;7308.744193953186;7315.30504602062;6070.725147719028;7038.401735852305;7243.275672432715;7274.636268186464;7294.270572942663;5854.858302630946;6858.3827455529245;6982.406495447944;7012.859742805013;7056.682449289897;5820.883582328414;7249.963750181215;7251.311125443074;7277.890831637424;7282.198958114497;6441.581297215666;7027.824304392431;7059;7123.786286411416;7132.643367831692 19:51:42 INFO - PROCESS | 1948 | |5;yui.html;688.2291833146876;734.1703874621677;755.3088923634874;760.6805141840429;768.1588660416842;14906.031107977988;14953;15158.62103447413;15190.31643576039;15205.087694738324;13881.30593470325;13904.443822247124;13912.51306204283;13921.164730116176;13989.440422383092;696.7213114754113;805.5942380545955;906.8911730592338;929.5631053404923;931.4411353188101;911.544227886057;918.0727465260102;922.0042354257386;924.9722508324727;925.6436272035247;968.7578105473632;989.2036910287216;989.3717489394251;989.995154917759;990.509697699636;959.1319855530721;966.9226461883032;971.3006635222629;974.5224839828504;980.5734505490099;722.6206241723085;724.0117239967459;730.4266151071383;730.62738003618;731.2650785960129;14352.712945741132;14478.493252736103;14606.780898286428;14675.926620366936;14706.926465367605;12675.74648507034;12747.553835615801;12910.096293259476;12918.935405323007;13057;9863.556139973647;10057.396556206651;10064;10250.282480226388;10339.069483746423;3469.8785542505893;3485.860565577374;3526.523919270917;3550.1124718820297;3730.776153430803;81873;82286;82437.58781206116;82958;83688;9282.907170928203;9474.715758527254;9485.90514094864;9536.713898583053;9979.750506237402;21510.92445377725;21609.35171944844;21686.24098156557;21856.45358866041;21881.452963675718;7336.119665640157;7372.668229929666;7384.372328352043;7597.012388389585;7609.695612175562;4057;4127.4015267786;4134.91730165395;4185.079282557833;4186.141840922664;3455.0844026333043;3516.9472457913153;3528.4883691864534;3531.8940431786573;3534.540509733718;632.1939527102888;640.3276559612389;653.1802587752435;669.2370697404962;676.3709749932511;426.5989969428727;548.8792465657549;580.4862696513532;588.2911092133968;592.7954855574819;7888.053433588007;7985.241402066795;7999.480033797785;8009.239122283374;8275.379346549033;567.1549391406775;587.9323877754113;604.0697326117729;606.2421972534332;609.1715267236555;3642.7814331140216;3816.332141875216;3868.460358771749;3877.9030524236555;3900.1809619979554;87253;87538;87727.24545509054;87891;88040.5597972006;51128;76373.23626763772;77495.22504774993;77640;77883.61058194785;68578.31421685818;69566;70080.29919700738;70297.64851175813;70493.4753262345;7599.962000189964;7659.195784442776;7685.5004424712215;7771.466123676319;7782.533048017137;36413.817930910176;48875.778105546226;49031.754841225564;49875;50222.47552769266;52629.947401051;63041.05438418336;65653;66229.66885165543;67948;10069.093781559695;10143;10838.078763305208;11169.329840209613;11225.887741122484;11778.234414763014;12045.939770301093;12108.818367724314;12142.332171730286;12495.937520312704;10908.400037997986;11401.942990284995;11464.656060318204;11565.132615054074;11640.708982275512;10275.45868119758;10711.785764284516;11563.942180289045;11645.6506304811;11858.644240672793;10642.680719578426;11548.191626586395;11573.363465009505;11584.884151158381;12183.878161218629;9478.905210948078;9492.382995105296;9540.379875308083;9818.116369526777;10017.949910250401;7889.566073865993;9364.344495885223;9781.559829807818;9793.46135962529;9836.754081148028 19:51:42 INFO - PROCESS | 1948 | __end_tp_report 19:51:42 INFO - PROCESS | 1948 | __start_cc_report 19:51:42 INFO - PROCESS | 1948 | _x_x_mozilla_cycle_collect,417 19:51:42 INFO - PROCESS | 1948 | __end_cc_report 19:51:42 INFO - PROCESS | 1948 | __startTimestamp1464231102735__endTimestamp 19:51:42 INFO - PROCESS | 1948 | 19:51:42 INFO - PROCESS | 1948 | ------- Summary: start ------- 19:51:42 INFO - PROCESS | 1948 | Number of tests: 6 19:51:42 INFO - PROCESS | 1948 | 19:51:42 INFO - PROCESS | 1948 | [#0] dojo.html Cycles:165 Average:5675.75 Median:5782.19 stddev:640.40 (11.1%) stddev-sans-first:607.72 19:51:42 INFO - PROCESS | 1948 | Values: 3027.1 3401.3 3430.1 3453.4 3499.3 5571.1 5628.0 5642.7 5650.4 5652.5 5561.2 5598.1 5605.7 5618.5 5652.3 5133.7 5182.9 5185.5 5221.4 5227.6 5108.3 5164.7 5239.9 5241.1 5275.2 5189.4 5223.3 5223.8 5229.6 5244.2 5204.2 5204.9 5206.2 5233.0 5237.5 5053.8 5085.5 5091.8 5169.0 5195.3 5324.7 5469.5 5490.8 5501.7 5537.1 6080.5 6148.1 6152.6 6171.8 6202.5 6021.8 6110.7 6125.5 6159.5 6284.0 5729.1 5744.2 5746.3 5749.8 5756.6 5125.7 6204.3 6238.7 6275.8 6280.8 6098.7 6110.2 6157.9 6159.3 6160.0 6210.4 6228.4 6241.9 6256.8 6268.9 6020.0 6117.3 6151.0 6168.5 6208.5 6156.4 6175.7 6176.9 6187.8 6191.8 4834.3 4903.8 4907.9 4914.7 4922.9 4308.8 4316.5 4387.0 4395.5 4430.8 5523.1 5567.9 5636.9 5646.4 5646.5 6198.5 6240.4 6242.5 6244.1 6246.6 5509.7 5536.5 5612.7 5622.9 5625.2 5340.9 5345.9 5372.2 5382.6 5382.8 6300.3 6335.2 6338.9 6366.9 6372.7 6096.5 6166.5 6231.4 6256.8 6262.7 6302.2 6305.9 6311.7 6315.7 6327.1 5873.5 6124.6 6129.2 6131.3 6135.8 6118.8 6170.1 6212.3 6238.0 6253.7 5683.9 6018.5 6042.3 6068.4 6080.3 5729.9 5954.9 6059.5 6071.5 6110.9 4814.0 4912.9 5743.0 5946.0 5979.4 6023.5 6059.2 6070.1 6074.3 6075.5 5722.7 5782.2 5883.8 5907.4 5917.0 19:51:42 INFO - PROCESS | 1948 | 19:51:42 INFO - PROCESS | 1948 | [#1] ext.html Cycles:180 Average:63940.51 Median:39528.51 stddev:135997.77 (344.0%) stddev-sans-first:136295.84 19:51:42 INFO - PROCESS | 1948 | Values: 488.5 535.5 572.2 592.7 613.1 31207.7 39849.8 39887.8 39911.4 40025.6 41636.0 42076.0 42196.0 42364.0 42459.2 9536.2 9779.2 9796.4 9808.5 9851.7 9569.2 9836.0 9850.9 9853.5 9878.7 9820.7 9873.7 9886.5 9887.0 9894.0 9801.7 9856.5 9858.4 9873.6 9877.1 9767.4 9832.2 9833.7 9865.7 9899.8 40774.0 40875.8 40954.6 40967.0 41883.6 2823.6 3120.7 3203.6 3343.6 3396.3 26208.1 28379.3 29466.4 30426.0 31417.9 2756.3 2789.0 2815.4 2837.9 2893.2 417728.0 421365.0 421991.0 422721.0 423816.0 37900.0 65837.7 66124.3 66338.3 66426.0 31752.7 37043.8 37657.6 37840.2 37896.9 15806.1 15824.4 16120.4 16198.9 16207.8 13383.3 13396.5 13471.6 13575.7 13595.5 8376.4 8384.1 8444.5 8486.5 8524.0 3375.9 3423.6 3434.3 3436.6 3472.5 962.4 976.3 1028.0 1045.0 1094.5 26240.5 30820.4 31262.8 31559.0 31616.2 909.5 1081.5 1133.8 1138.9 1167.9 14971.1 15707.3 15760.7 15818.8 15974.0 608989.0 612348.0 614687.0 623283.0 623350.9 61918.0 62686.0 62686.0 63071.7 63235.0 1483.9 1505.2 1515.8 1519.8 1585.8 30606.7 30946.8 31002.4 31274.2 31345.0 56052.7 56853.4 56910.4 56928.0 57037.7 467313.0 468128.0 469366.0 472856.0 473062.0 27744.7 27786.3 27910.9 28112.6 28474.4 45381.0 45495.5 47773.8 47873.8 49457.0 38092.8 39490.0 39567.0 40187.6 40255.4 38158.4 38328.2 38583.0 39063.4 39425.0 38405.4 38734.8 38773.6 39110.0 39206.8 25439.4 27815.0 32926.5 33885.8 34187.7 25576.4 25662.5 25666.0 25874.6 26337.5 19:51:42 INFO - PROCESS | 1948 | 19:51:42 INFO - PROCESS | 1948 | [#2] jquery.html Cycles:180 Average:32554.94 Median:14333.35 stddev:91258.47 (636.7%) stddev-sans-first:91482.46 19:51:42 INFO - PROCESS | 1948 | Values: 358.2 396.0 405.3 406.2 406.8 15454.1 16170.3 20042.2 20794.3 20943.6 19635.2 20162.3 20528.8 20673.4 20872.6 4985.3 6036.2 6095.6 6101.1 6111.9 5888.1 6015.3 6132.6 6146.6 6153.8 6037.8 6065.2 6078.7 6125.3 6127.4 5917.9 6064.9 6070.6 6084.5 6097.5 5099.5 5285.2 5297.9 5353.3 5372.7 17570.2 24000.2 25567.0 26106.3 26633.3 2040.8 2260.7 2326.4 2558.4 2560.4 9209.4 9227.5 9934.4 10231.8 10249.8 1724.7 1734.4 1779.0 1788.2 1791.5 90952.5 97393.5 109853.5 109989.4 119328.0 11772.4 11974.7 12006.6 12008.2 12065.6 15123.2 15191.1 15221.4 15250.4 15288.2 8878.3 8891.0 8927.0 8975.3 9008.2 8855.6 8969.2 8972.5 9025.5 9056.2 4588.9 4655.4 4718.9 4762.8 4766.2 1959.6 1996.5 2004.9 2015.9 2018.7 814.8 876.2 910.9 1051.3 1062.7 18008.5 19389.8 19692.4 21567.8 21620.6 952.2 953.1 1001.5 1045.2 1049.9 9904.0 10821.2 11151.6 11200.1 11426.8 532249.0 543193.0 544289.0 546759.0 577535.0 91178.5 100428.5 104790.4 115263.8 117565.0 972.7 983.9 996.3 996.6 1003.4 18043.9 18076.3 18185.5 18253.4 18880.9 50887.0 51453.7 51691.5 51868.7 52364.7 72300.6 75161.6 77656.0 78282.0 78678.0 6791.7 7881.9 8238.6 8299.5 8323.2 12293.6 12612.7 12619.4 12733.4 13193.3 11041.3 11114.4 11198.4 11291.6 11397.7 10656.9 10890.5 11024.0 11099.6 11170.6 11110.6 11207.0 11309.9 11449.8 11832.4 8154.5 8337.3 8371.6 8400.1 8422.4 8486.7 10730.0 11187.9 11372.5 11402.8 19:51:42 INFO - PROCESS | 1948 | 19:51:42 INFO - PROCESS | 1948 | [#3] mootools.html Cycles:180 Average:5739.18 Median:5034.02 stddev:3222.79 (64.0%) stddev-sans-first:3216.50 19:51:42 INFO - PROCESS | 1948 | Values: 1556.6 1568.4 1781.5 1870.6 1921.5 7964.7 8609.0 8632.4 8799.6 8810.0 8655.8 8664.5 8728.4 8759.0 8777.0 2065.4 2413.9 2426.4 2445.8 2454.7 2122.6 2426.3 2453.4 2460.6 2468.2 1951.7 2067.9 2426.6 2439.0 2486.3 1988.2 2420.6 2425.4 2426.5 2429.9 2438.0 2464.5 2467.2 2468.5 2474.7 8906.5 8931.6 8950.0 8990.8 9004.0 5392.0 5464.8 5514.2 5560.3 5633.5 3391.7 3459.6 3516.4 3535.2 3539.6 884.2 905.3 910.2 911.5 1002.4 12057.2 12163.8 12191.7 12227.1 12238.3 4748.5 4825.7 4883.8 4887.3 4897.7 10368.6 10389.7 10429.8 10430.7 10463.9 5292.6 5470.4 5484.1 5485.1 5514.6 4381.7 4816.1 4921.5 4928.2 4936.3 4099.0 4100.6 4166.8 4202.9 4219.1 1093.8 1228.9 1276.6 1352.8 1361.6 849.0 879.6 934.8 946.0 960.7 6004.8 6014.9 6043.8 6101.9 6178.5 898.4 995.9 1018.9 1035.3 1071.6 3969.3 4017.4 4032.0 4043.3 4078.3 10616.4 11118.8 11124.7 11228.7 11248.2 10728.6 10880.3 10957.8 11075.4 11118.4 8531.1 8535.4 8668.3 8674.3 8806.4 5736.1 5849.7 5897.3 5915.5 5971.6 7967.7 9795.8 9838.9 9858.8 9890.5 8149.5 8183.4 9881.0 9977.4 10002.0 6476.8 6833.8 6995.7 7134.9 7274.1 8184.0 8216.4 8318.2 8327.2 8472.4 5658.5 6526.3 7069.9 7377.9 7450.0 7252.7 7293.6 7323.0 7383.6 7389.5 6998.2 7083.8 7092.9 7099.8 7116.4 4907.4 4964.9 5018.2 5049.8 5082.8 4922.0 4993.0 5004.1 5058.1 5249.6 19:51:42 INFO - PROCESS | 1948 | 19:51:42 INFO - PROCESS | 1948 | [#4] prototype.html Cycles:180 Average:5888.62 Median:6695.80 stddev:1893.81 (28.3%) stddev-sans-first:1851.62 19:51:42 INFO - PROCESS | 1948 | Values: 272.5 324.9 325.8 330.2 333.2 6716.0 6735.6 6743.9 6747.2 6790.2 6655.5 6693.4 6729.3 6731.6 6748.0 4784.8 4847.4 4991.3 4993.1 4993.2 4922.6 4955.6 4963.8 4987.0 5005.0 4908.4 4908.9 4913.0 4939.1 4942.4 4935.4 4940.9 4948.6 4950.9 4985.4 4261.0 4267.7 4273.5 4279.3 4289.0 6249.4 6460.3 6499.1 6512.6 6518.5 5850.4 5884.8 5886.4 5895.7 5901.1 7096.7 7150.5 7157.9 7206.5 7207.0 3925.4 4078.4 4746.3 4753.1 4759.9 7286.3 7586.4 7586.8 7609.0 7643.7 4829.4 4838.5 4855.9 4857.9 4870.2 4484.5 4879.1 4930.5 4932.3 4947.4 5809.6 5844.7 5857.1 5859.8 5874.3 7211.7 7215.9 7218.2 7228.9 7235.7 1438.5 1490.4 1699.0 1717.6 1721.7 1095.0 1109.7 1111.3 1116.5 1127.6 6698.2 6729.6 6734.4 6755.7 6785.9 7270.9 7479.6 7480.7 7490.9 7503.5 6625.0 6675.2 6799.4 6801.1 6825.9 2992.3 3997.7 4008.3 4018.6 4020.1 6377.5 7996.5 8003.8 8006.6 8048.4 7453.9 7746.9 7810.4 7857.1 7858.7 6591.9 7123.4 7125.0 8031.0 8035.4 7538.4 7572.3 7575.5 7587.1 7589.0 7392.4 7414.2 7420.9 7425.1 7454.9 7635.2 7941.0 7946.0 7968.6 8037.5 4661.6 4708.4 4721.0 4723.7 4741.0 5803.6 6812.8 7575.7 7582.4 7611.1 7239.2 7304.6 7305.5 7308.7 7315.3 6070.7 7038.4 7243.3 7274.6 7294.3 5854.9 6858.4 6982.4 7012.9 7056.7 5820.9 7250.0 7251.3 7277.9 7282.2 6441.6 7027.8 7059.0 7123.8 7132.6 19:51:42 INFO - PROCESS | 1948 | 19:51:42 INFO - PROCESS | 1948 | [#5] yui.html Cycles:180 Average:17810.24 Median:607.71 stddev:24822.13 (4084.6%) stddev-sans-first:24858.46 19:51:42 INFO - PROCESS | 1948 | Values: 688.2 734.2 755.3 760.7 768.2 14906.0 14953.0 15158.6 15190.3 15205.1 13881.3 13904.4 13912.5 13921.2 13989.4 696.7 805.6 906.9 929.6 931.4 911.5 918.1 922.0 925.0 925.6 968.8 989.2 989.4 990.0 990.5 959.1 966.9 971.3 974.5 980.6 722.6 724.0 730.4 730.6 731.3 14352.7 14478.5 14606.8 14675.9 14706.9 12675.7 12747.6 12910.1 12918.9 13057.0 9863.6 10057.4 10064.0 10250.3 10339.1 3469.9 3485.9 3526.5 3550.1 3730.8 81873.0 82286.0 82437.6 82958.0 83688.0 9282.9 9474.7 9485.9 9536.7 9979.8 21510.9 21609.4 21686.2 21856.5 21881.5 7336.1 7372.7 7384.4 7597.0 7609.7 4057.0 4127.4 4134.9 4185.1 4186.1 3455.1 3516.9 3528.5 3531.9 3534.5 632.2 640.3 653.2 669.2 676.4 426.6 548.9 580.5 588.3 592.8 7888.1 7985.2 7999.5 8009.2 8275.4 567.2 587.9 604.1 606.2 609.2 3642.8 3816.3 3868.5 3877.9 3900.2 87253.0 87538.0 87727.2 87891.0 88040.6 51128.0 76373.2 77495.2 77640.0 77883.6 68578.3 69566.0 70080.3 70297.6 70493.5 7600.0 7659.2 7685.5 7771.5 7782.5 36413.8 48875.8 49031.8 49875.0 50222.5 52629.9 63041.1 65653.0 66229.7 67948.0 10069.1 10143.0 10838.1 11169.3 11225.9 11778.2 12045.9 12108.8 12142.3 12495.9 10908.4 11401.9 11464.7 11565.1 11640.7 10275.5 10711.8 11563.9 11645.7 11858.6 10642.7 11548.2 11573.4 11584.9 12183.9 9478.9 9492.4 9540.4 9818.1 10017.9 7889.6 9364.3 9781.6 9793.5 9836.8 19:51:42 INFO - PROCESS | 1948 | -------- Summary: end -------- 19:51:42 INFO - PROCESS | 1948 | 19:51:43 INFO - TEST-INFO | 1948: exit 0 19:51:43 INFO - TEST-OK | dromaeo_css | took 1111188ms 19:51:43 INFO - TEST-START | kraken 19:51:43 INFO - operating with platform_type : mac_ 19:51:43 INFO - Initialising browser for kraken test... 19:51:43 INFO - TEST-INFO | started process 1953 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/tmpTg3cPG/profile http://localhost:54448/getInfo.html) 19:51:46 INFO - PROCESS | 1953 | __metrics Screen width/height:1600/1200 19:51:46 INFO - PROCESS | 1953 | colorDepth:24 19:51:46 INFO - PROCESS | 1953 | Browser inner width/height: 1024/689 19:51:46 INFO - PROCESS | 1953 | __metrics 19:51:46 ERROR - PROCESS | 1953 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 19:51:46 INFO - TEST-INFO | 1953: exit 0 19:51:46 INFO - Browser initialized. 19:51:46 INFO - Running cycle 1/1 for kraken test... 19:51:46 INFO - TEST-INFO | started process 1956 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/tmpTg3cPG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:51:58 INFO - PROCESS | 1956 | RSS: Main: 221511680 19:51:58 INFO - PROCESS | 1956 | 19:52:01 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:54448/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54448/tests/kraken/driver.html?testName=audio-beat-detection) 19:52:02 INFO - PROCESS | 1956 | RSS: Main: 233099264 19:52:02 INFO - PROCESS | 1956 | 19:52:04 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:54448/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54448/tests/kraken/driver.html?testName=audio-dft) 19:52:05 INFO - PROCESS | 1956 | RSS: Main: 241967104 19:52:05 INFO - PROCESS | 1956 | 19:52:08 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:54448/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54448/tests/kraken/driver.html?testName=audio-fft) 19:52:09 INFO - PROCESS | 1956 | RSS: Main: 245555200 19:52:09 INFO - PROCESS | 1956 | 19:52:11 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:54448/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54448/tests/kraken/driver.html?testName=audio-oscillator) 19:52:12 INFO - PROCESS | 1956 | RSS: Main: 246321152 19:52:12 INFO - PROCESS | 1956 | 19:52:14 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:54448/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54448/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:52:15 INFO - PROCESS | 1956 | RSS: Main: 247390208 19:52:15 INFO - PROCESS | 1956 | 19:52:17 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:54448/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54448/tests/kraken/driver.html?testName=imaging-darkroom) 19:52:18 INFO - PROCESS | 1956 | RSS: Main: 247799808 19:52:18 INFO - PROCESS | 1956 | 19:52:21 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:54448/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54448/tests/kraken/driver.html?testName=imaging-desaturate) 19:52:22 INFO - PROCESS | 1956 | RSS: Main: 247910400 19:52:22 INFO - PROCESS | 1956 | 19:52:24 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:54448/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54448/tests/kraken/driver.html?testName=json-parse-financial) 19:52:25 INFO - PROCESS | 1956 | RSS: Main: 248004608 19:52:25 INFO - PROCESS | 1956 | 19:52:27 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:54448/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54448/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:52:27 INFO - PROCESS | 1956 | RSS: Main: 247373824 19:52:27 INFO - PROCESS | 1956 | 19:52:29 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:54448/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54448/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:52:30 INFO - PROCESS | 1956 | RSS: Main: 247533568 19:52:30 INFO - PROCESS | 1956 | 19:52:32 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:54448/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54448/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:52:33 INFO - PROCESS | 1956 | RSS: Main: 247771136 19:52:33 INFO - PROCESS | 1956 | 19:52:35 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:54448/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54448/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:52:36 INFO - PROCESS | 1956 | RSS: Main: 247824384 19:52:36 INFO - PROCESS | 1956 | 19:52:39 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:54448/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54448/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:52:40 INFO - PROCESS | 1956 | RSS: Main: 248590336 19:52:40 INFO - PROCESS | 1956 | 19:52:41 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:54448/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54448/tests/kraken/driver.html?testName=ai-astar) 19:52:42 INFO - PROCESS | 1956 | RSS: Main: 249708544 19:52:42 INFO - PROCESS | 1956 | 19:52:42 INFO - PROCESS | 1956 | __start_tp_report 19:52:42 INFO - PROCESS | 1956 | _x_x_mozilla_page_load 19:52:42 INFO - PROCESS | 1956 | _x_x_mozilla_page_load_details 19:52:42 INFO - PROCESS | 1956 | |i|pagename|runs| 19:52:42 INFO - PROCESS | 1956 | |0;ai-astar;116;119;108;117;119;119;108;119;117;118 19:52:42 INFO - PROCESS | 1956 | |1;audio-beat-detection;154;121;154;160;121;144;121;153;157;121 19:52:42 INFO - PROCESS | 1956 | |2;audio-dft;195;197;196;195;202;195;203;199;205;199 19:52:42 INFO - PROCESS | 1956 | |3;audio-fft;64;67;81;149;83;83;85;55;156;83 19:52:42 INFO - PROCESS | 1956 | |4;audio-oscillator;68;124;159;119;120;85;121;79;103;164 19:52:42 INFO - PROCESS | 1956 | |5;imaging-gaussian-blur;136;126;89;87;90;86;89;86;89;87 19:52:42 INFO - PROCESS | 1956 | |6;imaging-darkroom;95;96;96;97;95;96;95;141;100;95 19:52:42 INFO - PROCESS | 1956 | |7;imaging-desaturate;91;113;138;91;89;89;89;89;94;90 19:52:42 INFO - PROCESS | 1956 | |8;json-parse-financial;61;65;65;64;66;66;66;81;63;61 19:52:42 INFO - PROCESS | 1956 | |9;json-stringify-tinderbox;63;67;90;109;76;77;67;67;69;73 19:52:42 INFO - PROCESS | 1956 | |10;stanford-crypto-aes;83;82;87;94;80;80;83;85;114;111 19:52:42 INFO - PROCESS | 1956 | |11;stanford-crypto-ccm;136;134;145;151;128;153;119;120;147;142 19:52:42 INFO - PROCESS | 1956 | |12;stanford-crypto-pbkdf2;168;164;177;173;170;163;167;172;169;166 19:52:42 INFO - PROCESS | 1956 | |13;stanford-crypto-sha256-iterative;61;101;91;66;68;67;69;71;69;67 19:52:42 INFO - PROCESS | 1956 | __end_tp_report 19:52:42 INFO - PROCESS | 1956 | __start_cc_report 19:52:42 INFO - PROCESS | 1956 | _x_x_mozilla_cycle_collect,1118 19:52:42 INFO - PROCESS | 1956 | __end_cc_report 19:52:42 INFO - PROCESS | 1956 | __startTimestamp1464231162012__endTimestamp 19:52:42 INFO - PROCESS | 1956 | 19:52:42 INFO - PROCESS | 1956 | ------- Summary: start ------- 19:52:42 INFO - PROCESS | 1956 | Number of tests: 14 19:52:42 INFO - PROCESS | 1956 | 19:52:42 INFO - PROCESS | 1956 | [#0] ai-astar Cycles:10 Average:116.00 Median:118.50 stddev:4.35 (3.7%) stddev-sans-first:4.61 19:52:42 INFO - PROCESS | 1956 | Values: 116.0 119.0 108.0 117.0 119.0 119.0 108.0 119.0 117.0 118.0 19:52:42 INFO - PROCESS | 1956 | 19:52:42 INFO - PROCESS | 1956 | [#1] audio-beat-detection Cycles:10 Average:140.60 Median:153.50 stddev:17.34 (11.3%) stddev-sans-first:17.70 19:52:42 INFO - PROCESS | 1956 | Values: 154.0 121.0 154.0 160.0 121.0 144.0 121.0 153.0 157.0 121.0 19:52:42 INFO - PROCESS | 1956 | 19:52:42 INFO - PROCESS | 1956 | [#2] audio-dft Cycles:10 Average:198.60 Median:199.00 stddev:3.66 (1.8%) stddev-sans-first:3.64 19:52:42 INFO - PROCESS | 1956 | Values: 195.0 197.0 196.0 195.0 202.0 195.0 203.0 199.0 205.0 199.0 19:52:42 INFO - PROCESS | 1956 | 19:52:42 INFO - PROCESS | 1956 | [#3] audio-fft Cycles:10 Average:90.60 Median:82.00 stddev:34.18 (41.7%) stddev-sans-first:34.88 19:52:42 INFO - PROCESS | 1956 | Values: 64.0 67.0 81.0 149.0 83.0 83.0 85.0 55.0 156.0 83.0 19:52:42 INFO - PROCESS | 1956 | 19:52:42 INFO - PROCESS | 1956 | [#4] audio-oscillator Cycles:10 Average:114.20 Median:161.50 stddev:31.69 (19.6%) stddev-sans-first:28.87 19:52:42 INFO - PROCESS | 1956 | Values: 68.0 124.0 159.0 119.0 120.0 85.0 121.0 79.0 103.0 164.0 19:52:42 INFO - PROCESS | 1956 | 19:52:42 INFO - PROCESS | 1956 | [#5] imaging-gaussian-blur Cycles:10 Average:96.50 Median:88.00 stddev:18.39 (20.9%) stddev-sans-first:12.79 19:52:42 INFO - PROCESS | 1956 | Values: 136.0 126.0 89.0 87.0 90.0 86.0 89.0 86.0 89.0 87.0 19:52:42 INFO - PROCESS | 1956 | 19:52:42 INFO - PROCESS | 1956 | [#6] imaging-darkroom Cycles:10 Average:100.60 Median:95.50 stddev:14.28 (14.9%) stddev-sans-first:15.00 19:52:42 INFO - PROCESS | 1956 | Values: 95.0 96.0 96.0 97.0 95.0 96.0 95.0 141.0 100.0 95.0 19:52:42 INFO - PROCESS | 1956 | 19:52:42 INFO - PROCESS | 1956 | [#7] imaging-desaturate Cycles:10 Average:97.30 Median:89.50 stddev:16.06 (17.9%) stddev-sans-first:16.87 19:52:42 INFO - PROCESS | 1956 | Values: 91.0 113.0 138.0 91.0 89.0 89.0 89.0 89.0 94.0 90.0 19:52:42 INFO - PROCESS | 1956 | 19:52:42 INFO - PROCESS | 1956 | [#8] json-parse-financial Cycles:10 Average:65.80 Median:65.50 stddev:5.67 (8.7%) stddev-sans-first:5.74 19:52:42 INFO - PROCESS | 1956 | Values: 61.0 65.0 65.0 64.0 66.0 66.0 66.0 81.0 63.0 61.0 19:52:42 INFO - PROCESS | 1956 | 19:52:42 INFO - PROCESS | 1956 | [#9] json-stringify-tinderbox Cycles:10 Average:75.80 Median:71.00 stddev:13.97 (19.7%) stddev-sans-first:14.02 19:52:42 INFO - PROCESS | 1956 | Values: 63.0 67.0 90.0 109.0 76.0 77.0 67.0 67.0 69.0 73.0 19:52:42 INFO - PROCESS | 1956 | 19:52:42 INFO - PROCESS | 1956 | [#10] stanford-crypto-aes Cycles:10 Average:89.90 Median:83.00 stddev:12.60 (15.2%) stddev-sans-first:13.11 19:52:42 INFO - PROCESS | 1956 | Values: 83.0 82.0 87.0 94.0 80.0 80.0 83.0 85.0 114.0 111.0 19:52:42 INFO - PROCESS | 1956 | 19:52:42 INFO - PROCESS | 1956 | [#11] stanford-crypto-ccm Cycles:10 Average:137.50 Median:143.50 stddev:12.21 (8.5%) stddev-sans-first:12.94 19:52:42 INFO - PROCESS | 1956 | Values: 136.0 134.0 145.0 151.0 128.0 153.0 119.0 120.0 147.0 142.0 19:52:42 INFO - PROCESS | 1956 | 19:52:42 INFO - PROCESS | 1956 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.90 Median:169.50 stddev:4.28 (2.5%) stddev-sans-first:4.53 19:52:42 INFO - PROCESS | 1956 | Values: 168.0 164.0 177.0 173.0 170.0 163.0 167.0 172.0 169.0 166.0 19:52:42 INFO - PROCESS | 1956 | 19:52:42 INFO - PROCESS | 1956 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.00 Median:68.50 stddev:12.62 (18.4%) stddev-sans-first:12.62 19:52:42 INFO - PROCESS | 1956 | Values: 61.0 101.0 91.0 66.0 68.0 67.0 69.0 71.0 69.0 67.0 19:52:42 INFO - PROCESS | 1956 | -------- Summary: end -------- 19:52:42 INFO - PROCESS | 1956 | 19:52:42 INFO - TEST-INFO | 1956: exit 0 19:52:42 INFO - TEST-OK | kraken | took 59540ms 19:52:42 INFO - SUITE-END | took 1170s 19:52:43 INFO - Completed test suite (00:19:31) 19:52:43 INFO - javascript benchmark 19:52:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3027.091872438268, 3401.3197360527893, 3430.0738800523845, 3453.4301840196367, 3499.3176330615524, 5571.080771672674, 5628.015097357961, 5642.746076426561, 5650.3784583695815, 5652.491275785179, 5561.249231353766, 5598.1322894951345, 5605.747741351639, 5618.522425593829, 5652.293463317085, 5133.743312834371, 5182.922256166161, 5185.455527169649, 5221.425643179247, 5227.555657769094, 5108.259302401149, 5164.715940623264, 5239.895202095956, 5241.10901146804, 5275.208718692188, 5189.351331083614, 5223.347081614798, 5223.791048358061, 5229.633925625208, 5244.187150991603, 5204.245384419238, 5204.869878253036, 5206.193040078794, 5233.0, 5237.47625237477, 5053.84838454847, 5085.542301192911, 5091.847244582631, 5169.043726910533, 5195.2986346843445, 5324.653897496651, 5469.480399362054, 5490.780368785244, 5501.669899806025, 5537.086380747212, 6080.543959203077, 6148.108524264001, 6152.5693201475915, 6171.84570385744, 6202.534809889276, 6021.789237376671, 6110.694465276719, 6125.479643195305, 6159.538034647419, 6283.968580157116, 5729.140628905614, 5744.224529688522, 5746.281714785652, 5749.770009199585, 5756.625819321731, 5125.743712814345, 6204.348543402968, 6238.688065596702, 6275.811725648238, 6280.842978925472, 6098.69506524672, 6110.175126357974, 6157.907631385533, 6159.3224745277985, 6160.045192995003, 6210.4100110489435, 6228.439440450381, 6241.906371404432, 6256.812295631138, 6268.937310626835, 6020.0, 6117.327094019654, 6151.015837465984, 6168.506519478521, 6208.4722798561725, 6156.445919867234, 6175.69121543921, 6176.938230617726, 6187.84530386744, 6191.752329906843, 4834.347363105936, 4903.803847846046, 4907.877303067452, 4914.70511769295, 4922.852314430573, 4308.784560771949, 4316.525182229952, 4387.034852332482, 4395.472543294761, 4430.8449204277695, 5523.088690366125, 5567.944320556742, 5636.943630563642, 5646.407127251661, 5646.463585959327, 6198.504119670506, 6240.375962403814, 6242.500599951993, 6244.094606282064, 6246.562740608114, 5509.724513774296, 5536.52939500147, 5612.663240205601, 5622.859428514238, 5625.212470254168, 5340.946590534123, 5345.866353341197, 5372.221027950925, 5382.623216374895, 5382.784688612412, 6300.2754683212015, 6335.176427064452, 6338.873222535616, 6366.872662546631, 6372.745090196433, 6096.512279017579, 6166.4758495526585, 6231.40801623845, 6256.812295631138, 6262.718177681924, 6302.180716506825, 6305.905411418924, 6311.684415779101, 6315.715792789428, 6327.114204011534, 5873.500752436091, 6124.5712800103565, 6129.2032035835055, 6131.325554189125, 6135.815925522242, 6118.80192544137, 6170.10533472635, 6212.285587157535, 6238.033104868752, 6253.687315634109, 5683.914741278802, 6018.548608854266, 6042.274927008787, 6068.423499767515, 6080.331163572092, 5729.914051289151, 5954.940450595439, 6059.545534085049, 6071.544634152545, 6110.908336374869, 4813.975930120327, 4912.852614421573, 5743.0, 5946.048632218997, 5979.431953964367, 6023.5181185505935, 6059.212302400836, 6070.089486576872, 6074.271087469532, 6075.513958883191, 5722.656640601577, 5782.190493331022, 5883.764649413975, 5907.350191478848, 5917.0], "value": 5635.268958416646, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [488.4724497542652, 535.5447869311084, 572.1617829879226, 592.7066102279371, 613.1262950295827, 31207.68792312076, 39849.80075099624, 39887.80336589903, 39911.401328980086, 40025.59974400255, 41636.0, 42076.0, 42196.0, 42364.0, 42459.150816983645, 9536.2371010319, 9779.168770654504, 9796.412215267072, 9808.46053467059, 9851.65519206828, 9569.186619137381, 9836.016398360178, 9850.852237216448, 9853.50732463374, 9878.703638890809, 9820.705378838646, 9873.703788886345, 9886.456244906527, 9886.961869003759, 9894.0, 9801.705948821547, 9856.50717464124, 9858.359206651616, 9873.555689993967, 9877.06167914047, 9767.413955162712, 9832.213422926148, 9833.655822046265, 9865.654702085465, 9899.752506187331, 40774.0, 40875.795621022, 40954.59045409538, 40966.9758256046, 41883.581164188276, 2823.5764635304663, 3120.687931206884, 3203.615566132056, 3343.648916863743, 3396.28677977625, 26208.082717105, 28379.290517737016, 29466.41067178666, 30426.0, 31417.900373487046, 2756.338478765082, 2789.0377819652185, 2815.3806162644187, 2837.886484540616, 2893.2043687985974, 417728.0, 421365.0, 421991.0, 422720.9999999969, 423816.0, 37900.0, 65837.67081164563, 66124.33875661182, 66338.33661663419, 66426.0, 31752.682473174973, 37043.814780925924, 37657.62342376596, 37840.24319513595, 37896.86309410722, 15806.051636901822, 15824.44614438489, 16120.435784747477, 16198.919005405134, 16207.837921620632, 13383.263920484467, 13396.531121410704, 13471.595852124452, 13575.660608484866, 13595.45618175262, 8376.41365104449, 8384.077751447336, 8444.493330400195, 8486.533240671823, 8523.977122745186, 3375.8818441354924, 3423.640517745601, 3434.347473979936, 3436.5876094868777, 3472.4791281308007, 962.432165022626, 976.3360914578013, 1027.9822975254444, 1044.9738756530996, 1094.5020015892749, 26240.475190496083, 30820.383592328475, 31262.843685781427, 31559.0, 31616.209594759854, 909.5179554835947, 1081.4700796609604, 1133.7562424078862, 1138.920275580718, 1167.9124065694937, 14971.132789169733, 15707.293171807296, 15760.684786304439, 15818.76271855923, 15974.041557506587, 608989.0, 612348.0, 614687.0, 623283.0, 623350.8832455899, 61918.0, 62686.0, 62686.0, 63071.684641576496, 63235.0, 1483.8664520193233, 1505.1871989125968, 1515.8256800467866, 1519.7568389057699, 1585.8255591884883, 30606.693933060385, 30946.84526577353, 31002.379952401276, 31274.21814454566, 31345.0, 56052.71973640106, 56853.431465684815, 56910.43089569217, 56928.0, 57037.71481142734, 467313.0, 468128.0, 469366.0, 472856.0, 473062.0, 27744.72255277502, 27786.305342366602, 27910.860445697643, 28112.578311325757, 28474.43051139007, 45381.0, 45495.545044550454, 47773.76113119412, 47873.76063119662, 49457.01085978188, 38092.80953595214, 39490.01274968034, 39567.0, 40187.598124019554, 40255.396169058076, 38158.42762358623, 38328.2334353317, 38583.0, 39063.41404878872, 39425.014374640865, 38405.4239186418, 38734.80632596819, 38773.612263877, 39110.0, 39206.80396597999, 25439.36401589975, 27815.02647407331, 32926.506102408006, 33885.830570846985, 34187.658123419445, 25576.360590985376, 25662.486750265263, 25665.973361066095, 25874.611880821427, 26337.4732505345], "value": 18859.78793448786, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.2244440785699, 395.96238357356043, 405.32108717897523, 406.1714103229412, 406.82193868965703, 15454.140983917954, 16170.2723377448, 20042.198312067518, 20794.272200472988, 20943.581128377442, 19635.214591416327, 20162.294319698813, 20528.794712052873, 20673.379798606067, 20872.582548349023, 4985.252212168168, 6036.185115009462, 6095.573309868311, 6101.145839582462, 6111.877762444748, 5888.116782482619, 6015.308239552463, 6132.632042077467, 6146.6004709693725, 6153.7846175383875, 6037.818865434044, 6065.211522502069, 6078.665673387962, 6125.326214116443, 6127.417895299939, 5917.940820591782, 6064.9090263646085, 6070.57505974582, 6084.5132389408745, 6097.5426842986735, 5099.46455622158, 5285.180796976475, 5297.920531192035, 5353.250544923714, 5372.650777699437, 17570.20934057958, 24000.15999440029, 25567.0, 26106.34734131643, 26633.334166645796, 2040.785717499671, 2260.672202530624, 2326.3835083702834, 2558.4243545202366, 2560.359910022494, 9209.355345125843, 9227.492487913161, 9934.403935763878, 10231.846522302172, 10249.795004099951, 1724.7495565714883, 1734.384293575775, 1779.0482092080674, 1788.1774383783475, 1791.4625612316383, 90952.54523727339, 97393.5390969136, 109853.45073274663, 109989.35015974767, 119327.99999999913, 11772.411379430994, 11974.70695558711, 12006.579769708018, 12008.219465734699, 12065.552133743831, 15123.16822574747, 15191.088534687955, 15221.391144354102, 15250.389984400499, 15288.159151246566, 8878.28973682104, 8890.955545222232, 8927.0, 8975.28197744179, 9008.234300084567, 8855.64577416909, 8969.237614802814, 8972.506512141765, 9025.548722563846, 9056.184943355129, 4588.931166032512, 4655.4180727409075, 4718.8584342469785, 4762.833300834454, 4766.237402015661, 1959.5786905815319, 1996.4709352021725, 2004.8572313781006, 2015.9294424695358, 2018.6669199581904, 814.8085199978001, 876.202655583416, 910.8588168833842, 1051.3271506236015, 1062.654637242899, 18008.549786255186, 19389.8061019388, 19692.40922772319, 21567.78432215689, 21620.56758864814, 952.1716106986966, 953.1374106433689, 1001.5092604623827, 1045.2004216774174, 1049.8635177427038, 9903.950480247553, 10821.188410869216, 11151.553937842395, 11200.103991680646, 11426.771464570827, 532249.0, 543193.0, 544289.0, 546759.0, 577535.0, 91178.54410728037, 100428.49785751024, 104790.42814357791, 115263.84736152699, 117565.0, 972.7276362618478, 983.9114479696862, 996.267743208741, 996.5565323431003, 1003.3929472669076, 18043.909780451013, 18076.276948922423, 18185.45443636693, 18253.361132360304, 18880.905595471933, 50887.0, 51453.7427312876, 51691.483085168664, 51868.74065629799, 52364.73817630888, 72300.63849680718, 75161.62419187868, 77656.0, 78282.0, 78678.0, 6791.694373753292, 7881.881771773543, 8238.629261683118, 8299.502029878227, 8323.16768323187, 12293.56972505958, 12612.747745045232, 12619.38265321399, 12733.426995785027, 13193.274369909363, 11041.282316649393, 11114.44427778624, 11198.38408887519, 11291.604793832177, 11397.658070257905, 10656.89343106559, 10890.455477225949, 11024.0, 11099.5560177592, 11170.553177872795, 11110.611128610783, 11206.99137077667, 11309.886901130883, 11449.771004580029, 11832.408379581158, 8154.510729356258, 8337.333013359039, 8371.581420928807, 8400.075991641037, 8422.410431269753, 8486.74539763808, 10729.980651838063, 11187.944060279646, 11372.488238029475, 11402.771944560896], "value": 9497.971313226992, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1556.5875043113576, 1568.4040064775388, 1781.5189898727344, 1870.6352261309041, 1921.5100149461884, 7964.681412743484, 8608.956955215222, 8632.352573556993, 8799.648014079437, 8809.955950220248, 8655.783605409868, 8664.480131192116, 8728.389012769108, 8759.036505984337, 8776.956115219415, 2065.39070974063, 2413.9275821725287, 2426.4419183587784, 2445.7798798108165, 2454.6686197363397, 2122.5754849030177, 2426.3327584914114, 2453.411181316489, 2460.6432067350224, 2468.2471846086937, 1951.736515570394, 2067.9379618611465, 2426.6238732996326, 2438.9756102439014, 2486.29140694902, 1988.2146552111979, 2420.624803155505, 2425.417899704067, 2426.5146970605774, 2429.9392515187087, 2438.0, 2464.4578192797558, 2467.247489515697, 2468.506298740241, 2474.7277799442045, 8906.465612063297, 8931.598078086501, 8949.955250223773, 8990.775230619221, 9003.954980225122, 5392.0, 5464.754086066137, 5514.172874068881, 5560.304961879765, 5633.492985631272, 3391.6608339166132, 3459.619441861393, 3516.4197907345515, 3535.169235229719, 3539.557555305587, 884.2307192742355, 905.3436258712446, 910.2035718746097, 911.5260064766285, 1002.4185972136144, 12057.216280941711, 12163.756724865541, 12191.695207619881, 12227.082968777288, 12238.265704057696, 4748.525147485293, 4825.662203645781, 4883.8290659826735, 4887.29134275528, 4897.681650692693, 10368.637097701545, 10389.688309350731, 10429.843552346721, 10430.739231519121, 10463.94768026155, 5292.603054770908, 5470.398256191816, 5484.067708489566, 5485.0949593317455, 5514.5864060194845, 4381.6932814703305, 4816.109019831342, 4921.50784921505, 4928.211486162197, 4936.308916751657, 4098.979505102456, 4100.5899410059355, 4166.791660416967, 4202.8739137825305, 4219.092895027522, 1093.824988001916, 1228.9185516745385, 1276.5978716704208, 1352.8027695489563, 1361.5567498451674, 848.9915100849036, 879.560219890055, 934.8267923755643, 945.9952700236455, 960.7454024683465, 6004.789832355934, 6014.917314883363, 6043.758249670052, 6101.938980610226, 6178.474829639531, 898.3890954151109, 995.8556009378744, 1018.8522664213796, 1035.29082578434, 1071.5820829876354, 3969.325214713506, 4017.4375587417785, 4032.0, 4043.292423825819, 4078.3270760324212, 10616.416097114732, 11118.833217501744, 11124.721881953015, 11228.663140105646, 11248.156388270749, 10728.624498142684, 10880.347179169275, 10957.83563246552, 11075.446227688582, 11118.444077796239, 8531.06158322584, 8535.445196062361, 8668.263197628146, 8674.262687671619, 8806.383553151347, 5736.082226843685, 5849.736761845812, 5897.292324921037, 5915.526757859447, 5971.611845230046, 7967.681292748225, 9795.804083918425, 9838.852417213604, 9858.753531161778, 9890.456024918698, 8149.470284431493, 8183.3862460316905, 9880.950595247265, 9977.351472154287, 10002.049805268487, 6476.805695829133, 6833.760818371333, 6995.720171193096, 7134.857302854018, 7274.090738657668, 8184.0, 8216.383771217063, 8318.209770071833, 8327.167283271625, 8472.3645726572, 5658.519025882846, 6526.347365263626, 7069.893951590628, 7377.889331659922, 7449.962750186214, 7252.709891604277, 7293.635318234174, 7322.96338518304, 7383.556986580822, 7389.482736208413, 6998.160220773336, 7083.787486375417, 7092.893606596009, 7099.82250443743, 7116.35952764233, 4907.386576677915, 4964.925526117039, 5018.247262910592, 5049.798008079636, 5082.847514574569, 4921.975390123026, 4993.0, 5004.074246264453, 5058.140116180185, 5249.632525723316], "value": 4613.11974616816, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [272.51900395801414, 324.86031006667133, 325.8142858570614, 330.2075019952115, 333.24519962285444, 6715.966420167899, 6735.562188457753, 6743.898841517369, 6747.156605424322, 6790.151231096113, 6655.467562594993, 6693.364130407604, 6729.29342419045, 6731.629760363178, 6748.02153687715, 4784.78468468918, 4847.369841920546, 4991.276264941581, 4993.1262029144755, 4993.201087825949, 4922.581580565657, 4955.554000139987, 4963.826266080688, 4986.975065124688, 5004.974975125119, 4908.435529914052, 4908.852734417973, 4912.975435122819, 4939.061578300129, 4942.406911170665, 4935.358403407552, 4940.901181976358, 4948.604111671094, 4950.851474455736, 4985.376827896513, 4261.04126571522, 4267.701260911737, 4273.465816772903, 4279.251131052053, 4289.0, 6249.437550620421, 6460.321666225026, 6499.122618446544, 6512.641804700739, 6518.543701940866, 5850.414958504158, 5884.823455296305, 5886.440788125121, 5895.702945352015, 5901.0558310670085, 7096.716131354739, 7150.499465037449, 7157.856842863166, 7206.49554531183, 7207.0, 3925.411188321746, 4078.4290199372113, 4746.335513028179, 4753.073150735608, 4759.904801903977, 7286.271372862724, 7586.393088552903, 7586.772396828104, 7609.048868891388, 7643.656035478417, 4829.420469543677, 4838.467768545457, 4855.902881942412, 4857.9028419431415, 4870.196417591058, 4484.506704262529, 4879.121758083508, 4930.506949305112, 4932.334134891816, 4947.356843610308, 5809.593328467051, 5844.736986835603, 5857.062869940789, 5859.824205273849, 5874.324452687995, 7211.67547460355, 7215.927840721631, 7218.205997340288, 7228.927710722931, 7235.674394652253, 1438.5252866554013, 1490.411287541415, 1699.0400423760536, 1717.5620216844625, 1721.7159168737269, 1094.9890501095047, 1109.6670998700358, 1111.3220935229504, 1116.5198964445367, 1127.5546159267044, 6698.229703584053, 6729.596224226562, 6734.393904548614, 6755.662216889136, 6785.864282714417, 7270.873014682733, 7479.551226926402, 7480.738174163878, 7490.850182996419, 7503.512271702322, 6625.006249062582, 6675.23234828001, 6799.388055075067, 6801.1158549388265, 6825.897611535831, 2992.2968102495897, 3997.6601988831276, 4008.2785098682516, 4018.6383225509844, 4020.0954785173103, 6377.521685873486, 7996.480228785111, 8003.759417290329, 8006.559639219899, 8048.396370272253, 7453.850922981618, 7746.883796743054, 7810.3751699865015, 7857.057153141545, 7858.724944626911, 6591.868162636624, 7123.358897699232, 7125.002499650053, 8031.0, 8035.437519373588, 7538.434617403607, 7572.318491335586, 7575.507592006502, 7587.127480339832, 7589.0, 7392.371648409945, 7414.184439711736, 7420.925790742023, 7425.108986921604, 7454.850902982019, 7635.198304178203, 7940.960295198487, 7945.960270198612, 7968.601569921365, 8037.517748935083, 4661.580457758682, 4708.387909571871, 4721.0, 4723.7401942893475, 4740.976295118502, 5803.564732644984, 6812.841816891239, 7575.6969721210535, 7582.431317651088, 7611.086669599684, 7239.203687594466, 7304.6347682616715, 7305.525140865827, 7308.744193953186, 7315.30504602062, 6070.725147719028, 7038.401735852305, 7243.275672432715, 7274.636268186464, 7294.270572942663, 5854.858302630946, 6858.3827455529245, 6982.406495447944, 7012.859742805013, 7056.682449289897, 5820.883582328414, 7249.963750181215, 7251.311125443074, 7277.890831637424, 7282.198958114497, 6441.581297215666, 7027.824304392431, 7059.0, 7123.786286411416, 7132.643367831692], "value": 5248.547331998814, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [688.2291833146876, 734.1703874621677, 755.3088923634874, 760.6805141840429, 768.1588660416842, 14906.031107977988, 14953.0, 15158.62103447413, 15190.31643576039, 15205.087694738324, 13881.30593470325, 13904.443822247124, 13912.51306204283, 13921.164730116176, 13989.440422383092, 696.7213114754113, 805.5942380545955, 906.8911730592338, 929.5631053404923, 931.4411353188101, 911.544227886057, 918.0727465260102, 922.0042354257386, 924.9722508324727, 925.6436272035247, 968.7578105473632, 989.2036910287216, 989.3717489394251, 989.995154917759, 990.509697699636, 959.1319855530721, 966.9226461883032, 971.3006635222629, 974.5224839828504, 980.5734505490099, 722.6206241723085, 724.0117239967459, 730.4266151071383, 730.62738003618, 731.2650785960129, 14352.712945741132, 14478.493252736103, 14606.780898286428, 14675.926620366936, 14706.926465367605, 12675.74648507034, 12747.553835615801, 12910.096293259476, 12918.935405323007, 13057.0, 9863.556139973647, 10057.396556206651, 10064.0, 10250.282480226388, 10339.069483746423, 3469.8785542505893, 3485.860565577374, 3526.523919270917, 3550.1124718820297, 3730.776153430803, 81873.0, 82286.0, 82437.58781206116, 82958.0, 83688.0, 9282.907170928203, 9474.715758527254, 9485.90514094864, 9536.713898583053, 9979.750506237402, 21510.92445377725, 21609.35171944844, 21686.24098156557, 21856.45358866041, 21881.452963675718, 7336.119665640157, 7372.668229929666, 7384.372328352043, 7597.012388389585, 7609.695612175562, 4057.0, 4127.4015267786, 4134.91730165395, 4185.079282557833, 4186.141840922664, 3455.0844026333043, 3516.9472457913153, 3528.4883691864534, 3531.8940431786573, 3534.540509733718, 632.1939527102888, 640.3276559612389, 653.1802587752435, 669.2370697404962, 676.3709749932511, 426.5989969428727, 548.8792465657549, 580.4862696513532, 588.2911092133968, 592.7954855574819, 7888.053433588007, 7985.241402066795, 7999.480033797785, 8009.239122283374, 8275.379346549033, 567.1549391406775, 587.9323877754113, 604.0697326117729, 606.2421972534332, 609.1715267236555, 3642.7814331140216, 3816.332141875216, 3868.460358771749, 3877.9030524236555, 3900.1809619979554, 87253.0, 87538.0, 87727.24545509054, 87891.0, 88040.5597972006, 51128.0, 76373.23626763772, 77495.22504774993, 77640.0, 77883.61058194785, 68578.31421685818, 69566.0, 70080.29919700738, 70297.64851175813, 70493.4753262345, 7599.962000189964, 7659.195784442776, 7685.5004424712215, 7771.466123676319, 7782.533048017137, 36413.817930910176, 48875.778105546226, 49031.754841225564, 49875.0, 50222.47552769266, 52629.947401051, 63041.05438418336, 65653.0, 66229.66885165543, 67948.0, 10069.093781559695, 10143.0, 10838.078763305208, 11169.329840209613, 11225.887741122484, 11778.234414763014, 12045.939770301093, 12108.818367724314, 12142.332171730286, 12495.937520312704, 10908.400037997986, 11401.942990284995, 11464.656060318204, 11565.132615054074, 11640.708982275512, 10275.45868119758, 10711.785764284516, 11563.942180289045, 11645.6506304811, 11858.644240672793, 10642.680719578426, 11548.191626586395, 11573.363465009505, 11584.884151158381, 12183.878161218629, 9478.905210948078, 9492.382995105296, 9540.379875308083, 9818.116369526777, 10017.949910250401, 7889.566073865993, 9364.344495885223, 9781.559829807818, 9793.46135962529, 9836.754081148028], "value": 6849.907030816834, "unit": "score"}], "name": "dromaeo_css", "value": 7424.02325264288, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 119.0, 108.0, 117.0, 119.0, 119.0, 108.0, 119.0, 117.0, 118.0], "value": 116.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 121.0, 154.0, 160.0, 121.0, 144.0, 121.0, 153.0, 157.0, 121.0], "value": 140.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 197.0, 196.0, 195.0, 202.0, 195.0, 203.0, 199.0, 205.0, 199.0], "value": 198.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 67.0, 81.0, 149.0, 83.0, 83.0, 85.0, 55.0, 156.0, 83.0], "value": 90.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 124.0, 159.0, 119.0, 120.0, 85.0, 121.0, 79.0, 103.0, 164.0], "value": 114.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 126.0, 89.0, 87.0, 90.0, 86.0, 89.0, 86.0, 89.0, 87.0], "value": 96.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 97.0, 95.0, 96.0, 95.0, 141.0, 100.0, 95.0], "value": 100.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 113.0, 138.0, 91.0, 89.0, 89.0, 89.0, 89.0, 94.0, 90.0], "value": 97.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 65.0, 65.0, 64.0, 66.0, 66.0, 66.0, 81.0, 63.0, 61.0], "value": 65.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 67.0, 90.0, 109.0, 76.0, 77.0, 67.0, 67.0, 69.0, 73.0], "value": 75.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 87.0, 94.0, 80.0, 80.0, 83.0, 85.0, 114.0, 111.0], "value": 89.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 134.0, 145.0, 151.0, 128.0, 153.0, 119.0, 120.0, 147.0, 142.0], "value": 137.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 164.0, 177.0, 173.0, 170.0, 163.0, 167.0, 172.0, 169.0, 166.0], "value": 168.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 101.0, 91.0, 66.0, 68.0, 67.0, 69.0, 71.0, 69.0, 67.0], "value": 73.0, "unit": "score"}], "name": "kraken", "value": 1565.3000000000002, "alertThreshold": 2.0}]} 19:52:43 INFO - Return code: 0 19:52:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 19:52:43 ERROR - # TBPL SUCCESS # 19:52:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:52:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:52:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:52:43 INFO - 2016-05-25 19:52:43.309 screenresolution[1961:22438] starting screenresolution argv=screenresolution get 19:52:43 INFO - 2016-05-25 19:52:43.312 screenresolution[1961:22438] Display 0: 1600x1200x32@60 19:52:43 INFO - 2016-05-25 19:52:43.318 screenresolution[1962:22441] starting screenresolution argv=screenresolution list 19:52:43 INFO - Available Modes on Display 0 19:52:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:52:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:52:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:52:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:52:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:52:43 INFO - Intel Iris: 19:52:43 INFO - Chipset Model: Intel Iris 19:52:43 INFO - Type: GPU 19:52:43 INFO - Bus: Built-In 19:52:43 INFO - VRAM (Dynamic, Max): 1536 MB 19:52:43 INFO - Vendor: Intel (0x8086) 19:52:43 INFO - Device ID: 0x0a2e 19:52:43 INFO - Revision ID: 0x0009 19:52:43 INFO - Displays: 19:52:43 INFO - Display: 19:52:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:52:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:52:43 INFO - Display Serial Number: 1600x1200 60 19:52:43 INFO - Main Display: Yes 19:52:43 INFO - Mirror: Off 19:52:43 INFO - Online: Yes 19:52:43 INFO - Rotation: Supported 19:52:43 INFO - Return code: 0 19:52:43 INFO - Running post-action listener: _resource_record_post_action 19:52:43 INFO - Running post-run listener: _resource_record_post_run 19:52:44 INFO - Total resource usage - Wall time: 1204s; CPU: 53.0%; Read bytes: 880640; Write bytes: 654728704; Read time: 9; Write time: 4693 19:52:44 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 344777216; Write bytes: 346281472; Read time: 26579; Write time: 673 19:52:44 INFO - run-tests - Wall time: 1173s; CPU: 53.0%; Read bytes: 880640; Write bytes: 298440704; Read time: 9; Write time: 3990 19:52:44 INFO - Running post-run listener: _upload_blobber_files 19:52:44 INFO - Blob upload gear active. 19:52:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:52:44 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 19:52:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:52:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:52:45 INFO - (blobuploader) - INFO - Open directory for files ... 19:52:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:52:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:52:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:52:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:52:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:52:45 INFO - (blobuploader) - INFO - Done attempting. 19:52:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:52:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:52:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:52:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:52:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:52:46 INFO - (blobuploader) - INFO - Done attempting. 19:52:46 INFO - (blobuploader) - INFO - Iteration through files over. 19:52:46 INFO - Return code: 0 19:52:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:52:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:52:46 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1e8e099217909cc299d11bc443373bb9745e6596ce810f43938bc3254bdafd1d3e19f6487bf30d05567f7f6eb58bef1b8c2819ef8f4187182551be76810e966a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ea297e9b070d248a2c8a426717dcc771818964fca85c3992640edaf956a189dea7c7288ec82175546a03d391acd045205a410ef7cdff8e8a466b10fc932a0bbb"} 19:52:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:52:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:52:46 INFO - Contents: 19:52:46 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1e8e099217909cc299d11bc443373bb9745e6596ce810f43938bc3254bdafd1d3e19f6487bf30d05567f7f6eb58bef1b8c2819ef8f4187182551be76810e966a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ea297e9b070d248a2c8a426717dcc771818964fca85c3992640edaf956a189dea7c7288ec82175546a03d391acd045205a410ef7cdff8e8a466b10fc932a0bbb"} 19:52:46 INFO - Running post-run listener: copy_logs_to_upload_dir 19:52:46 INFO - Copying logs to upload dir... 19:52:46 INFO - mkdir: /builds/slave/test/build/upload/logs 19:52:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.823278 ========= master_lag: 1.43 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-05-25 19:52:48.253419) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-25 19:52:48.256847) ========= 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.Opc4I1CoGu/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.bzSAxFz7v1/Listeners TMPDIR=/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1e8e099217909cc299d11bc443373bb9745e6596ce810f43938bc3254bdafd1d3e19f6487bf30d05567f7f6eb58bef1b8c2819ef8f4187182551be76810e966a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ea297e9b070d248a2c8a426717dcc771818964fca85c3992640edaf956a189dea7c7288ec82175546a03d391acd045205a410ef7cdff8e8a466b10fc932a0bbb"} build_url:https://queue.taskcluster.net/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010819 build_url: 'https://queue.taskcluster.net/v1/task/VMwW5X-0T7OWqS8rPjT9Yg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1e8e099217909cc299d11bc443373bb9745e6596ce810f43938bc3254bdafd1d3e19f6487bf30d05567f7f6eb58bef1b8c2819ef8f4187182551be76810e966a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ea297e9b070d248a2c8a426717dcc771818964fca85c3992640edaf956a189dea7c7288ec82175546a03d391acd045205a410ef7cdff8e8a466b10fc932a0bbb"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-25 19:52:48.290014) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 19:52:48.290410) ========= 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.Opc4I1CoGu/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.bzSAxFz7v1/Listeners TMPDIR=/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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.005088 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 19:52:48.339411) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-25 19:52:48.339720) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-25 19:52:48.340174) ========= ========= Total master_lag: 1.56 =========