builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0086 starttime: 1462470437.26 results: success (0) buildid: 20160505085246 builduid: 441f70eebd0542f4b1254629e64ab8dc revision: 45197bd1bd11fccc5da8efc98629a64b664a387d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-05 10:47:17.259888) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-05 10:47:17.260485) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-05 10:47:17.260795) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-05 10:47:17.280736) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 10:47:17.281032) ========= 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.nHb89xxSIj/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.AWczaWmR3U/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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-05 10:47:17-- 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% 8.18M=0.001s 2016-05-05 10:47:17 (8.18 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.120656 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 10:47:17.418417) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 10:47:17.418730) ========= 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.nHb89xxSIj/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.AWczaWmR3U/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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.020245 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 10:47:17.467063) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-05 10:47:17.467414) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 45197bd1bd11fccc5da8efc98629a64b664a387d --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 45197bd1bd11fccc5da8efc98629a64b664a387d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nHb89xxSIj/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.AWczaWmR3U/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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-05 10:47:17,539 truncating revision to first 12 chars 2016-05-05 10:47:17,539 Setting DEBUG logging. 2016-05-05 10:47:17,539 attempt 1/10 2016-05-05 10:47:17,539 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/45197bd1bd11?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-05 10:47:18,333 unpacking tar archive at: mozilla-aurora-45197bd1bd11/testing/mozharness/ program finished with exit code 0 elapsedTime=1.041858 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-05 10:47:18.526737) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-05 10:47:18.527055) ========= script_repo_revision: 45197bd1bd11fccc5da8efc98629a64b664a387d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-05 10:47:18.528560) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-05 10:47:18.528854) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-05 10:47:18.545451) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-05-05 10:47:18.545825) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.nHb89xxSIj/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.AWczaWmR3U/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 10:47:18 INFO - MultiFileLogger online at 20160505 10:47:18 in /builds/slave/test 10:47:18 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 10:47:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:47:18 INFO - {'append_to_log': False, 10:47:18 INFO - 'base_work_dir': '/builds/slave/test', 10:47:18 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 10:47:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:47:18 INFO - 'branch': 'Mozilla-Aurora', 10:47:18 INFO - 'buildbot_json_path': 'buildprops.json', 10:47:18 INFO - 'config_files': ('talos/mac_config.py',), 10:47:18 INFO - 'default_actions': ('clobber', 10:47:18 INFO - 'read-buildbot-config', 10:47:18 INFO - 'download-and-extract', 10:47:18 INFO - 'populate-webroot', 10:47:18 INFO - 'create-virtualenv', 10:47:18 INFO - 'install', 10:47:18 INFO - 'run-tests'), 10:47:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:47:18 INFO - 'download_minidump_stackwalk': True, 10:47:18 INFO - 'download_symbols': 'ondemand', 10:47:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:47:18 INFO - 'tooltool.py': '/tools/tooltool.py', 10:47:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:47:18 INFO - '/tools/misc-python/virtualenv.py')}, 10:47:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:47:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:47:18 INFO - 'installer_path': 'installer.exe', 10:47:18 INFO - 'log_level': 'info', 10:47:18 INFO - 'log_name': 'talos', 10:47:18 INFO - 'log_to_console': True, 10:47:18 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:47:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:47:18 INFO - 'opt_config_files': (), 10:47:18 INFO - 'pip_index': False, 10:47:18 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:47:18 INFO - 'cmd': ('bash', 10:47:18 INFO - '-c', 10:47:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:47:18 INFO - 'enabled': True, 10:47:18 INFO - 'halt_on_failure': False, 10:47:18 INFO - 'name': 'check_screen_resolution'},), 10:47:18 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:47:18 INFO - 'cmd': ('bash', 10:47:18 INFO - '-c', 10:47:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:47:18 INFO - 'enabled': True, 10:47:18 INFO - 'halt_on_failure': False, 10:47:18 INFO - 'name': 'check_screen_resolution'},), 10:47:18 INFO - 'run_cmd_checks_enabled': True, 10:47:18 INFO - 'sps_profile': False, 10:47:18 INFO - 'sps_profile_interval': 0, 10:47:18 INFO - 'suite': 'dromaeojs', 10:47:18 INFO - 'system_bits': '32', 10:47:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:47:18 INFO - 'title': 't-yosemite-r7-0086', 10:47:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:47:18 INFO - 'use_talos_json': True, 10:47:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:47:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:47:18 INFO - 'work_dir': 'build'} 10:47:18 INFO - ##### 10:47:18 INFO - ##### Running clobber step. 10:47:18 INFO - ##### 10:47:18 INFO - Running pre-action listener: _resource_record_pre_action 10:47:18 INFO - Running main action method: clobber 10:47:18 INFO - rmtree: /builds/slave/test/build 10:47:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:47:18 INFO - Running post-action listener: _resource_record_post_action 10:47:18 INFO - ##### 10:47:18 INFO - ##### Running read-buildbot-config step. 10:47:18 INFO - ##### 10:47:18 INFO - Running pre-action listener: _resource_record_pre_action 10:47:18 INFO - Running main action method: read_buildbot_config 10:47:18 INFO - Using buildbot properties: 10:47:18 INFO - { 10:47:18 INFO - "project": "", 10:47:18 INFO - "product": "firefox", 10:47:18 INFO - "script_repo_revision": "production", 10:47:18 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 10:47:18 INFO - "repository": "", 10:47:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 10:47:18 INFO - "buildid": "20160505085246", 10:47:18 INFO - "pgo_build": "False", 10:47:18 INFO - "basedir": "/builds/slave/test", 10:47:18 INFO - "buildnumber": 0, 10:47:18 INFO - "slavename": "t-yosemite-r7-0086", 10:47:18 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 10:47:18 INFO - "platform": "yosemite_r7", 10:47:18 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 10:47:18 INFO - "branch": "mozilla-aurora", 10:47:18 INFO - "repo_path": "releases/mozilla-aurora", 10:47:18 INFO - "revision": "45197bd1bd11fccc5da8efc98629a64b664a387d", 10:47:18 INFO - "stage_platform": "macosx64", 10:47:18 INFO - "builduid": "441f70eebd0542f4b1254629e64ab8dc", 10:47:18 INFO - "slavebuilddir": "test" 10:47:18 INFO - } 10:47:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/ew0IjzvOTwi3yb4RVPuxRw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 10:47:18 INFO - Running post-action listener: _resource_record_post_action 10:47:18 INFO - ##### 10:47:18 INFO - ##### Running download-and-extract step. 10:47:18 INFO - ##### 10:47:18 INFO - Running pre-action listener: _resource_record_pre_action 10:47:18 INFO - Running main action method: download_and_extract 10:47:18 INFO - mkdir: /builds/slave/test/build/tests 10:47:18 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')]} 10:47:18 INFO - https://queue.taskcluster.net/v1/task/ew0IjzvOTwi3yb4RVPuxRw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 10:47:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ew0IjzvOTwi3yb4RVPuxRw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 10:47:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ew0IjzvOTwi3yb4RVPuxRw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 10:47:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ew0IjzvOTwi3yb4RVPuxRw/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 10:47:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ew0IjzvOTwi3yb4RVPuxRw/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 10:47:31 INFO - Downloaded 1199 bytes. 10:47:31 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 10:47:31 INFO - Using the following test package requirements: 10:47:31 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 10:47:31 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:47:31 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 10:47:31 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:47:31 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 10:47:31 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:47:31 INFO - u'jsshell-mac.zip'], 10:47:31 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:47:31 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 10:47:31 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 10:47:31 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:47:31 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 10:47:31 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:47:31 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 10:47:31 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:47:31 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 10:47:31 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:47:31 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 10:47:31 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 10:47: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')]} 10:47:31 INFO - https://queue.taskcluster.net/v1/task/ew0IjzvOTwi3yb4RVPuxRw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:47:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ew0IjzvOTwi3yb4RVPuxRw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 10:47:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ew0IjzvOTwi3yb4RVPuxRw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 10:47:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ew0IjzvOTwi3yb4RVPuxRw/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 10:47:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ew0IjzvOTwi3yb4RVPuxRw/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 10:47:35 INFO - Downloaded 18114334 bytes. 10:47:35 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'] 10:47:35 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 10:47:38 INFO - Return code: 0 10:47:38 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 10:47:38 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')]} 10:47:38 INFO - https://queue.taskcluster.net/v1/task/ew0IjzvOTwi3yb4RVPuxRw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:47:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ew0IjzvOTwi3yb4RVPuxRw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 10:47:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ew0IjzvOTwi3yb4RVPuxRw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 10:47:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ew0IjzvOTwi3yb4RVPuxRw/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 10:47:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ew0IjzvOTwi3yb4RVPuxRw/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 10:47:42 INFO - Downloaded 18114334 bytes. 10:47:42 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'] 10:47:42 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 10:47:45 INFO - Return code: 0 10:47:45 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')]} 10:47:45 INFO - https://queue.taskcluster.net/v1/task/ew0IjzvOTwi3yb4RVPuxRw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 10:47:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ew0IjzvOTwi3yb4RVPuxRw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 10:47:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ew0IjzvOTwi3yb4RVPuxRw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 10:47:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ew0IjzvOTwi3yb4RVPuxRw/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 10:47:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ew0IjzvOTwi3yb4RVPuxRw/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 10:47:46 INFO - Downloaded 10840145 bytes. 10:47:46 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'] 10:47:46 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 10:47:46 INFO - Return code: 0 10:47:47 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')]} 10:47:47 INFO - https://queue.taskcluster.net/v1/task/ew0IjzvOTwi3yb4RVPuxRw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 10:47:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ew0IjzvOTwi3yb4RVPuxRw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 10:47:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ew0IjzvOTwi3yb4RVPuxRw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 10:47:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ew0IjzvOTwi3yb4RVPuxRw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 10:47:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ew0IjzvOTwi3yb4RVPuxRw/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 10:47:50 INFO - Downloaded 114100256 bytes. 10:47:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ew0IjzvOTwi3yb4RVPuxRw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 10:47:50 INFO - mkdir: /builds/slave/test/properties 10:47:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:47:50 INFO - Writing to file /builds/slave/test/properties/build_url 10:47:50 INFO - Contents: 10:47:50 INFO - build_url:https://queue.taskcluster.net/v1/task/ew0IjzvOTwi3yb4RVPuxRw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 10:47:51 INFO - Running post-action listener: _resource_record_post_action 10:47:51 INFO - Running post-action listener: set_extra_try_arguments 10:47:51 INFO - ##### 10:47:51 INFO - ##### Running populate-webroot step. 10:47:51 INFO - ##### 10:47:51 INFO - Running pre-action listener: _resource_record_pre_action 10:47:51 INFO - Running main action method: populate_webroot 10:47:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:47:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:47:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:47:51 INFO - '--tppagecycles', 10:47:51 INFO - '7'], 10:47:51 INFO - 'tests': ['tsvgm']}}, 10:47:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:47:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:47:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:47:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:47:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:47:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:47:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:47:51 INFO - 'talos_options': ['--disable-e10s'], 10:47:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:47:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:47:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:47:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:47:51 INFO - 'talos_options': ['--disable-e10s'], 10:47:51 INFO - 'tests': ['damp', 'tps']}, 10:47:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:47:51 INFO - 'tests': ['damp', 'tps']}, 10:47:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:47:51 INFO - 'tests': ['dromaeo_dom']}, 10:47:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:47:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:47:51 INFO - 'tests': ['a11yr', 10:47:51 INFO - 'ts_paint', 10:47:51 INFO - 'tpaint', 10:47:51 INFO - 'sessionrestore', 10:47:51 INFO - 'sessionrestore_no_auto_restore', 10:47:51 INFO - 'tabpaint']}, 10:47:51 INFO - 'other-e10s': {'tests': ['a11yr', 10:47:51 INFO - 'ts_paint', 10:47:51 INFO - 'tpaint', 10:47:51 INFO - 'sessionrestore', 10:47:51 INFO - 'sessionrestore_no_auto_restore', 10:47:51 INFO - 'tabpaint']}, 10:47:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:47:51 INFO - 'ts_paint', 10:47:51 INFO - 'tpaint', 10:47:51 INFO - 'sessionrestore', 10:47:51 INFO - 'sessionrestore_no_auto_restore', 10:47:51 INFO - 'tabpaint']}, 10:47:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:47:51 INFO - 'ts_paint', 10:47:51 INFO - 'tpaint', 10:47:51 INFO - 'sessionrestore', 10:47:51 INFO - 'sessionrestore_no_auto_restore', 10:47:51 INFO - 'tabpaint']}, 10:47:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:47:51 INFO - 'tests': ['a11yr', 10:47:51 INFO - 'ts_paint', 10:47:51 INFO - 'tpaint', 10:47:51 INFO - 'sessionrestore', 10:47:51 INFO - 'sessionrestore_no_auto_restore', 10:47:51 INFO - 'tabpaint']}, 10:47:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:47:51 INFO - 'tests': ['a11yr', 10:47:51 INFO - 'ts_paint', 10:47:51 INFO - 'tpaint', 10:47:51 INFO - 'sessionrestore', 10:47:51 INFO - 'sessionrestore_no_auto_restore', 10:47:51 INFO - 'tabpaint']}, 10:47:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:47:51 INFO - 'tests': ['tsvgx', 10:47:51 INFO - 'tsvgr_opacity', 10:47:51 INFO - 'tart', 10:47:51 INFO - 'tscrollx', 10:47:51 INFO - 'cart']}, 10:47:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:47:51 INFO - 'tsvgr_opacity', 10:47:51 INFO - 'tart', 10:47:51 INFO - 'tscrollx', 10:47:51 INFO - 'cart']}, 10:47:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:47:51 INFO - 'talos_options': ['--disable-e10s'], 10:47:51 INFO - 'tests': ['tp5o']}, 10:47:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:47:51 INFO - 'tests': ['tp5o']}, 10:47:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:47:51 INFO - 'talos_options': ['--disable-e10s', 10:47:51 INFO - '--xperf_path', 10:47:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:47:51 INFO - 'tests': ['tp5n']}, 10:47:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:47:51 INFO - 'talos_options': ['--xperf_path', 10:47:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:47:51 INFO - 'tests': ['tp5n']}}, 10:47:51 INFO - 'talos.zip': {'path': '', 10:47:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:47:51 INFO - Running post-action listener: _resource_record_post_action 10:47:51 INFO - ##### 10:47:51 INFO - ##### Running create-virtualenv step. 10:47:51 INFO - ##### 10:47:51 INFO - Running pre-action listener: _resource_record_pre_action 10:47:51 INFO - Running main action method: create_virtualenv 10:47:51 INFO - Creating virtualenv /builds/slave/test/build/venv 10:47:51 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 10:47:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:47:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:47:51 INFO - Using real prefix '/tools/python27' 10:47:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:47:52 INFO - Installing distribute.............................................................................................................................................................................................done. 10:47:54 INFO - Installing pip.................done. 10:47:54 INFO - Return code: 0 10:47:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:47:54 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')]} 10:47:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:47:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:47:54 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')]} 10:47:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:47:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:47:54 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 0x10dc82a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc92420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc779488b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db21e00>, '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 0x10d98adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db1cb20>, '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.nHb89xxSIj/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.AWczaWmR3U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 10:47:54 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 10:47:54 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 10:47:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nHb89xxSIj/Render', 10:47:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:47:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:47:54 INFO - 'HOME': '/Users/cltbld', 10:47:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:47:54 INFO - 'LOGNAME': 'cltbld', 10:47:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:47:54 INFO - 'MOZ_NO_REMOTE': '1', 10:47:54 INFO - 'NO_EM_RESTART': '1', 10:47:54 INFO - 'PAGER': '/bin/cat', 10:47:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:47:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:47:54 INFO - 'PWD': '/builds/slave/test', 10:47:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:47:54 INFO - 'SHELL': '/bin/bash', 10:47:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AWczaWmR3U/Listeners', 10:47:54 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 10:47:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:47:54 INFO - 'USER': 'cltbld', 10:47:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:47:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:47:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:47:54 INFO - 'XPC_FLAGS': '0x0', 10:47:54 INFO - 'XPC_SERVICE_NAME': '0', 10:47:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:47:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:47:55 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 10:47:55 INFO - Cleaning up... 10:47:55 INFO - Return code: 0 10:47:55 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 10:47:55 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')]} 10:47:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:47:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:47:55 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')]} 10:47:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:47:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:47:55 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 0x10dc82a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc92420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc779488b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db21e00>, '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 0x10d98adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db1cb20>, '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.nHb89xxSIj/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.AWczaWmR3U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 10:47:55 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 10:47:55 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 10:47:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nHb89xxSIj/Render', 10:47:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:47:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:47:55 INFO - 'HOME': '/Users/cltbld', 10:47:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:47:55 INFO - 'LOGNAME': 'cltbld', 10:47:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:47:55 INFO - 'MOZ_NO_REMOTE': '1', 10:47:55 INFO - 'NO_EM_RESTART': '1', 10:47:55 INFO - 'PAGER': '/bin/cat', 10:47:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:47:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:47:55 INFO - 'PWD': '/builds/slave/test', 10:47:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:47:55 INFO - 'SHELL': '/bin/bash', 10:47:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AWczaWmR3U/Listeners', 10:47:55 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 10:47:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:47:55 INFO - 'USER': 'cltbld', 10:47:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:47:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:47:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:47:55 INFO - 'XPC_FLAGS': '0x0', 10:47:55 INFO - 'XPC_SERVICE_NAME': '0', 10:47:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:47:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:47:55 INFO - Downloading/unpacking psutil>=0.7.1 10:47:55 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 10:47:55 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 10:47:55 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 10:47:55 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 10:47:55 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 10:47:55 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 10:47:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:47:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:47:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:47:57 INFO - Installing collected packages: psutil 10:47:57 INFO - Running setup.py install for psutil 10:47:57 INFO - building 'psutil._psutil_osx' extension 10:47:57 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 10:47:58 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 10:47:58 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 10:47:58 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 10:47:58 INFO - building 'psutil._psutil_posix' extension 10:47:58 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 10:47:58 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:47:58 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:47:58 INFO - ^ 10:47:58 INFO - 1 warning generated. 10:47:58 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 10:47:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:47:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:47:58 INFO - Successfully installed psutil 10:47:58 INFO - Cleaning up... 10:47:58 INFO - Return code: 0 10:47:58 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 10:47: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')]} 10:47:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:47:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:47: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')]} 10:47:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:47:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:47:58 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 0x10dc82a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc92420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc779488b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db21e00>, '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 0x10d98adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db1cb20>, '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.nHb89xxSIj/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.AWczaWmR3U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 10:47:58 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 10:47:58 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 10:47:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nHb89xxSIj/Render', 10:47:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:47:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:47:58 INFO - 'HOME': '/Users/cltbld', 10:47:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:47:58 INFO - 'LOGNAME': 'cltbld', 10:47:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:47:58 INFO - 'MOZ_NO_REMOTE': '1', 10:47:58 INFO - 'NO_EM_RESTART': '1', 10:47:58 INFO - 'PAGER': '/bin/cat', 10:47:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:47:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:47:58 INFO - 'PWD': '/builds/slave/test', 10:47:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:47:58 INFO - 'SHELL': '/bin/bash', 10:47:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AWczaWmR3U/Listeners', 10:47:58 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 10:47:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:47:58 INFO - 'USER': 'cltbld', 10:47:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:47:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:47:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:47:58 INFO - 'XPC_FLAGS': '0x0', 10:47:58 INFO - 'XPC_SERVICE_NAME': '0', 10:47:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:47:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:47:59 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 10:47:59 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 10:47:59 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 10:47:59 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 10:47:59 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 10:47:59 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 10:47:59 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 10:48:01 INFO - Downloading mozsystemmonitor-0.0.tar.gz 10:48:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:48:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 10:48:01 INFO - Installing collected packages: mozsystemmonitor 10:48:01 INFO - Running setup.py install for mozsystemmonitor 10:48:01 INFO - Successfully installed mozsystemmonitor 10:48:01 INFO - Cleaning up... 10:48:01 INFO - Return code: 0 10:48:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:48:01 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')]} 10:48:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:02 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')]} 10:48:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:02 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 0x10dc82a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc92420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc779488b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db21e00>, '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 0x10d98adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db1cb20>, '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.nHb89xxSIj/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.AWczaWmR3U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 10:48:02 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 10:48:02 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 10:48:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nHb89xxSIj/Render', 10:48:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:02 INFO - 'HOME': '/Users/cltbld', 10:48:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:02 INFO - 'LOGNAME': 'cltbld', 10:48:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:02 INFO - 'MOZ_NO_REMOTE': '1', 10:48:02 INFO - 'NO_EM_RESTART': '1', 10:48:02 INFO - 'PAGER': '/bin/cat', 10:48:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:02 INFO - 'PWD': '/builds/slave/test', 10:48:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:02 INFO - 'SHELL': '/bin/bash', 10:48:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AWczaWmR3U/Listeners', 10:48:02 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 10:48:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:02 INFO - 'USER': 'cltbld', 10:48:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:02 INFO - 'XPC_FLAGS': '0x0', 10:48:02 INFO - 'XPC_SERVICE_NAME': '0', 10:48:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:02 INFO - Downloading/unpacking blobuploader==1.2.4 10:48:02 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 10:48:02 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 10:48:02 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 10:48:02 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 10:48:02 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 10:48:02 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 10:48:05 INFO - Downloading blobuploader-1.2.4.tar.gz 10:48:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:48:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:48:05 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 10:48:05 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 10:48:05 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 10:48:05 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 10:48:05 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 10:48:05 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 10:48:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:48:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:48:05 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 10:48:05 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 10:48:05 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 10:48:05 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 10:48:05 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 10:48:05 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 10:48:06 INFO - Downloading docopt-0.6.1.tar.gz 10:48:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:48:06 INFO - Installing collected packages: blobuploader, requests, docopt 10:48:06 INFO - Running setup.py install for blobuploader 10:48:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:48:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:48:06 INFO - Running setup.py install for requests 10:48:07 INFO - Running setup.py install for docopt 10:48:07 INFO - Successfully installed blobuploader requests docopt 10:48:07 INFO - Cleaning up... 10:48:07 INFO - Return code: 0 10:48:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:48: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')]} 10:48:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48: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')]} 10:48:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:07 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 0x10dc82a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc92420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc779488b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db21e00>, '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 0x10d98adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db1cb20>, '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.nHb89xxSIj/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.AWczaWmR3U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 10:48:07 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 10:48:07 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 10:48:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nHb89xxSIj/Render', 10:48:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:07 INFO - 'HOME': '/Users/cltbld', 10:48:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:07 INFO - 'LOGNAME': 'cltbld', 10:48:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:07 INFO - 'MOZ_NO_REMOTE': '1', 10:48:07 INFO - 'NO_EM_RESTART': '1', 10:48:07 INFO - 'PAGER': '/bin/cat', 10:48:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:07 INFO - 'PWD': '/builds/slave/test', 10:48:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:07 INFO - 'SHELL': '/bin/bash', 10:48:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AWczaWmR3U/Listeners', 10:48:07 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 10:48:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:07 INFO - 'USER': 'cltbld', 10:48:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:07 INFO - 'XPC_FLAGS': '0x0', 10:48:07 INFO - 'XPC_SERVICE_NAME': '0', 10:48:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:48:07 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-9HS0cp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:48:07 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-dQssSr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:48:07 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-P9S9jS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:48:07 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-mUTxMu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:48:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:48:08 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-apUaDH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:48:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:48:08 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-kXMpEl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:48:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:48:08 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-UHbQDV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:48:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:48:08 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-WHwTW0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:48:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:48:08 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-ZTNkHI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:48:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:48:08 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-2NzxQt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:48:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:48:08 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-yRqxFr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:48:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:48:08 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-WK8biL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:48:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:48:08 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-bP5q3C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:48:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:48:09 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-G5fvH2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:48:09 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-d21S_d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:48:09 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-NyzmFU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:48:09 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-YsvH7y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:48:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:48:09 INFO - Running setup.py install for manifestparser 10:48:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:48:09 INFO - Running setup.py install for mozcrash 10:48:09 INFO - Running setup.py install for mozdebug 10:48:09 INFO - Running setup.py install for mozdevice 10:48:10 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:48:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:48:10 INFO - Running setup.py install for mozfile 10:48:10 INFO - Running setup.py install for mozhttpd 10:48:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:48:10 INFO - Running setup.py install for mozinfo 10:48:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:48:10 INFO - Running setup.py install for mozInstall 10:48:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:48:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:48:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:48:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:48:10 INFO - Running setup.py install for mozleak 10:48:10 INFO - Running setup.py install for mozlog 10:48:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:48:10 INFO - Running setup.py install for moznetwork 10:48:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:48:11 INFO - Running setup.py install for mozprocess 10:48:11 INFO - Running setup.py install for mozprofile 10:48:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:48:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:48:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:48:11 INFO - Running setup.py install for mozrunner 10:48:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:48:11 INFO - Running setup.py install for mozscreenshot 10:48:11 INFO - Running setup.py install for moztest 10:48:11 INFO - Running setup.py install for mozversion 10:48:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:48:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:48:11 INFO - Cleaning up... 10:48:12 INFO - Return code: 0 10:48:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:48:12 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')]} 10:48:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:12 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')]} 10:48:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:12 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 0x10dc82a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc92420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc779488b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db21e00>, '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 0x10d98adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db1cb20>, '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.nHb89xxSIj/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.AWczaWmR3U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 10:48:12 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 10:48:12 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 10:48:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nHb89xxSIj/Render', 10:48:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:12 INFO - 'HOME': '/Users/cltbld', 10:48:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:12 INFO - 'LOGNAME': 'cltbld', 10:48:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:12 INFO - 'MOZ_NO_REMOTE': '1', 10:48:12 INFO - 'NO_EM_RESTART': '1', 10:48:12 INFO - 'PAGER': '/bin/cat', 10:48:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:12 INFO - 'PWD': '/builds/slave/test', 10:48:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:12 INFO - 'SHELL': '/bin/bash', 10:48:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AWczaWmR3U/Listeners', 10:48:12 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 10:48:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:12 INFO - 'USER': 'cltbld', 10:48:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:12 INFO - 'XPC_FLAGS': '0x0', 10:48:12 INFO - 'XPC_SERVICE_NAME': '0', 10:48:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:48:12 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-YpPNHi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:48:12 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)) 10:48:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:48:12 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-D7OFli-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:48:12 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)) 10:48:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:48:12 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-oWEKsW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:48:12 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)) 10:48:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:48:12 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-94wiI4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:48:12 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)) 10:48:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:48:12 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-Pj550f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:48:12 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)) 10:48:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:48:12 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-Xb1q1o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:48:12 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)) 10:48:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:48:12 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-OfLMOk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:48:13 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)) 10:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:48:13 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-j4Gp_u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:48:13 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)) 10:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:48:13 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-R0IWg3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:48:13 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)) 10:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:48:13 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-2lfOIs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:48:13 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)) 10:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:48:13 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-mcbZTJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:48:13 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)) 10:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:48:13 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-dHz9pq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:48:13 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)) 10:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:48:13 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-PHKR_W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:48:13 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)) 10:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:48:13 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-IQ__JY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:48:13 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)) 10:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:48:13 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-rkFoDI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:48:13 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)) 10:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:48:13 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-y_mp2_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:48:14 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)) 10:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:48:14 INFO - Running setup.py (path:/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/pip-sOuNDS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:48:14 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)) 10:48:14 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)) 10:48:14 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)) 10:48:14 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)) 10:48:14 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)) 10:48:14 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)) 10:48:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:48: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 10:48: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 10:48: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 10:48: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 10:48: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 10:48: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 10:48:16 INFO - Downloading blessings-1.6.tar.gz 10:48:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:48:16 INFO - Installing collected packages: blessings 10:48:16 INFO - Running setup.py install for blessings 10:48:16 INFO - Successfully installed blessings 10:48:16 INFO - Cleaning up... 10:48:16 INFO - Return code: 0 10:48:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:48:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:48:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:48:17 INFO - Reading from file tmpfile_stdout 10:48:17 INFO - Current package versions: 10:48:17 INFO - blessings == 1.6 10:48:17 INFO - blobuploader == 1.2.4 10:48:17 INFO - docopt == 0.6.1 10:48:17 INFO - manifestparser == 1.1 10:48:17 INFO - mozInstall == 1.12 10:48:17 INFO - mozcrash == 0.17 10:48:17 INFO - mozdebug == 0.1 10:48:17 INFO - mozdevice == 0.48 10:48:17 INFO - mozfile == 1.2 10:48:17 INFO - mozhttpd == 0.7 10:48:17 INFO - mozinfo == 0.9 10:48:17 INFO - mozleak == 0.1 10:48:17 INFO - mozlog == 3.1 10:48:17 INFO - moznetwork == 0.27 10:48:17 INFO - mozprocess == 0.22 10:48:17 INFO - mozprofile == 0.28 10:48:17 INFO - mozrunner == 6.11 10:48:17 INFO - mozscreenshot == 0.1 10:48:17 INFO - mozsystemmonitor == 0.0 10:48:17 INFO - moztest == 0.7 10:48:17 INFO - mozversion == 1.4 10:48:17 INFO - psutil == 3.1.1 10:48:17 INFO - requests == 1.2.3 10:48:17 INFO - wsgiref == 0.1.2 10:48:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:48:17 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')]} 10:48:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:17 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')]} 10:48:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:17 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 0x10dc82a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc92420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc779488b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db21e00>, '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 0x10d98adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db1cb20>, '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.nHb89xxSIj/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.AWczaWmR3U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 10:48:17 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 10:48:17 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 10:48:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nHb89xxSIj/Render', 10:48:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:17 INFO - 'HOME': '/Users/cltbld', 10:48:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:17 INFO - 'LOGNAME': 'cltbld', 10:48:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:17 INFO - 'MOZ_NO_REMOTE': '1', 10:48:17 INFO - 'NO_EM_RESTART': '1', 10:48:17 INFO - 'PAGER': '/bin/cat', 10:48:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:17 INFO - 'PWD': '/builds/slave/test', 10:48:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:17 INFO - 'SHELL': '/bin/bash', 10:48:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AWczaWmR3U/Listeners', 10:48:17 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 10:48:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:17 INFO - 'USER': 'cltbld', 10:48:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:17 INFO - 'XPC_FLAGS': '0x0', 10:48:17 INFO - 'XPC_SERVICE_NAME': '0', 10:48:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:17 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)) 10:48:17 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)) 10:48:17 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)) 10:48:17 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)) 10:48:17 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)) 10:48:17 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)) 10:48:17 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)) 10:48:17 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)) 10:48:17 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)) 10:48:17 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)) 10:48:17 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)) 10:48:17 INFO - Cleaning up... 10:48:17 INFO - Return code: 0 10:48:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:48:17 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')]} 10:48:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:17 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')]} 10:48:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x10dc82a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc92420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc779488b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db21e00>, '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 0x10d98adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db1cb20>, '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.nHb89xxSIj/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.AWczaWmR3U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 10:48:17 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 10:48:17 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 10:48:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nHb89xxSIj/Render', 10:48:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:17 INFO - 'HOME': '/Users/cltbld', 10:48:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:17 INFO - 'LOGNAME': 'cltbld', 10:48:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:17 INFO - 'MOZ_NO_REMOTE': '1', 10:48:17 INFO - 'NO_EM_RESTART': '1', 10:48:17 INFO - 'PAGER': '/bin/cat', 10:48:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:17 INFO - 'PWD': '/builds/slave/test', 10:48:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:17 INFO - 'SHELL': '/bin/bash', 10:48:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AWczaWmR3U/Listeners', 10:48:17 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 10:48:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:17 INFO - 'USER': 'cltbld', 10:48:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:17 INFO - 'XPC_FLAGS': '0x0', 10:48:17 INFO - 'XPC_SERVICE_NAME': '0', 10:48:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:17 INFO - Downloading/unpacking jsonschema 10:48: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 10:48: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 10:48: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 10:48: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 10:48: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 10:48: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 10:48:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:48:20 INFO - Downloading/unpacking functools32 (from jsonschema) 10:48:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:20 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 10:48:20 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 10:48:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:20 INFO - Downloading functools32-3.2.3-2.tar.gz 10:48:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:48:20 INFO - warning: no files found matching '*.txt' 10:48:20 INFO - no previously-included directories found matching 'build' 10:48:20 INFO - no previously-included directories found matching 'dist' 10:48:20 INFO - no previously-included directories found matching '.git*' 10:48:21 INFO - Installing collected packages: jsonschema, functools32 10:48:21 INFO - Running setup.py install for functools32 10:48:21 INFO - warning: no files found matching '*.txt' 10:48:21 INFO - no previously-included directories found matching 'build' 10:48:21 INFO - no previously-included directories found matching 'dist' 10:48:21 INFO - no previously-included directories found matching '.git*' 10:48:21 INFO - Successfully installed jsonschema functools32 10:48:21 INFO - Cleaning up... 10:48:21 INFO - Return code: 0 10:48:21 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 10:48:21 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')]} 10:48:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:21 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')]} 10:48:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x10dc82a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc92420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc779488b40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db21e00>, '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 0x10d98adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db1cb20>, '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.nHb89xxSIj/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.AWczaWmR3U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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 10:48:21 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 10:48:21 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 10:48:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nHb89xxSIj/Render', 10:48:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:21 INFO - 'HOME': '/Users/cltbld', 10:48:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:21 INFO - 'LOGNAME': 'cltbld', 10:48:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:21 INFO - 'MOZ_NO_REMOTE': '1', 10:48:21 INFO - 'NO_EM_RESTART': '1', 10:48:21 INFO - 'PAGER': '/bin/cat', 10:48:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:21 INFO - 'PWD': '/builds/slave/test', 10:48:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:21 INFO - 'SHELL': '/bin/bash', 10:48:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AWczaWmR3U/Listeners', 10:48:21 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 10:48:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:21 INFO - 'USER': 'cltbld', 10:48:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:21 INFO - 'XPC_FLAGS': '0x0', 10:48:21 INFO - 'XPC_SERVICE_NAME': '0', 10:48:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:21 INFO - Downloading/unpacking flake8 10:48:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:21 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 10:48:21 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 10:48:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:23 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 10:48:23 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 10:48:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:23 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 10:48:23 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 10:48:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:24 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 10:48:24 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 10:48:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:24 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 10:48:24 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 10:48:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:24 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 10:48:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:24 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 10:48:24 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 10:48:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:48:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:48:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 10:48:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:48:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:48:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:48:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:48:25 INFO - no previously-included directories found matching 'docs/_build' 10:48:25 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 10:48:25 INFO - Running setup.py install for pep8 10:48:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:48:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:48:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:48:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:48:25 INFO - no previously-included directories found matching 'docs/_build' 10:48:25 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 10:48:25 INFO - Successfully installed flake8 mccabe pyflakes pep8 10:48:25 INFO - Cleaning up... 10:48:25 INFO - Return code: 0 10:48:25 INFO - Running post-action listener: _resource_record_post_action 10:48:25 INFO - Running post-action listener: _start_resource_monitoring 10:48:25 INFO - Starting resource monitoring. 10:48:25 INFO - ##### 10:48:25 INFO - ##### Running install step. 10:48:25 INFO - ##### 10:48:25 INFO - Running pre-action listener: _resource_record_pre_action 10:48:25 INFO - Running main action method: install 10:48:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:48:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:48:25 INFO - Reading from file tmpfile_stdout 10:48:25 INFO - Detecting whether we're running mozinstall >=1.0... 10:48:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:48:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:48:25 INFO - Reading from file tmpfile_stdout 10:48:25 INFO - Output received: 10:48:25 INFO - Usage: mozinstall [options] installer 10:48:25 INFO - Options: 10:48:25 INFO - -h, --help show this help message and exit 10:48:25 INFO - -d DEST, --destination=DEST 10:48:25 INFO - Directory to install application into. [default: 10:48:25 INFO - "/builds/slave/test"] 10:48:25 INFO - --app=APP Application being installed. [default: firefox] 10:48:25 INFO - mkdir: /builds/slave/test/build/application 10:48:25 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'] 10:48:25 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 10:48:57 INFO - Reading from file tmpfile_stdout 10:48:57 INFO - Output received: 10:48:57 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 10:48:57 INFO - Running post-action listener: _resource_record_post_action 10:48:57 INFO - ##### 10:48:57 INFO - ##### Running run-tests step. 10:48:57 INFO - ##### 10:48:57 INFO - Running pre-action listener: _resource_record_pre_action 10:48:57 INFO - Running main action method: run_tests 10:48:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:48:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:48:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:48:57 INFO - 2016-05-05 10:48:57.480 screenresolution[2058:12706] starting screenresolution argv=screenresolution get 10:48:57 INFO - 2016-05-05 10:48:57.483 screenresolution[2058:12706] Display 0: 1600x1200x32@60 10:48:57 INFO - 2016-05-05 10:48:57.489 screenresolution[2059:12709] starting screenresolution argv=screenresolution list 10:48:57 INFO - Available Modes on Display 0 10:48:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:48:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:48:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:48:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:48:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:48:57 INFO - Intel Iris: 10:48:57 INFO - Chipset Model: Intel Iris 10:48:57 INFO - Type: GPU 10:48:57 INFO - Bus: Built-In 10:48:57 INFO - VRAM (Dynamic, Max): 1536 MB 10:48:57 INFO - Vendor: Intel (0x8086) 10:48:57 INFO - Device ID: 0x0a2e 10:48:57 INFO - Revision ID: 0x0009 10:48:57 INFO - Displays: 10:48:57 INFO - Display: 10:48:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:48:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:48:57 INFO - Display Serial Number: 1600x1200 60 10:48:57 INFO - Main Display: Yes 10:48:57 INFO - Mirror: Off 10:48:57 INFO - Online: Yes 10:48:57 INFO - Rotation: Supported 10:48:57 INFO - Return code: 0 10:48:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:48:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:48:57 INFO - Python 2.7.3 10:48:57 INFO - Return code: 0 10:48:57 INFO - grabbing minidump binary from tooltool 10:48:57 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')]} 10:48:57 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db21e00>, '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 0x10d98adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10db1cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:48:57 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 10:48:57 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 10:48:57 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:48:57 INFO - Return code: 0 10:48:57 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:48:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:48:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:48:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:48:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:48:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 10:48:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 10:48:58 INFO - Return code: 0 10:48:58 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', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ew0IjzvOTwi3yb4RVPuxRw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0086', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 10:48:58 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 --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ew0IjzvOTwi3yb4RVPuxRw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0086 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 10:48:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nHb89xxSIj/Render', 10:48:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:58 INFO - 'HOME': '/Users/cltbld', 10:48:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:58 INFO - 'LOGNAME': 'cltbld', 10:48:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:48:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:48:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:58 INFO - 'MOZ_NO_REMOTE': '1', 10:48:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:48:58 INFO - 'NO_EM_RESTART': '1', 10:48:58 INFO - 'PAGER': '/bin/cat', 10:48:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:58 INFO - 'PWD': '/builds/slave/test', 10:48:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:48:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:58 INFO - 'SHELL': '/bin/bash', 10:48:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AWczaWmR3U/Listeners', 10:48:58 INFO - 'TMPDIR': '/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/', 10:48:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:58 INFO - 'USER': 'cltbld', 10:48:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:58 INFO - 'XPC_FLAGS': '0x0', 10:48:58 INFO - 'XPC_SERVICE_NAME': '0', 10:48:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:58 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ew0IjzvOTwi3yb4RVPuxRw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0086', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 10:48:59 INFO - mozversion application_buildid: 20160505085246 10:48:59 INFO - mozversion application_changeset: 45197bd1bd11fccc5da8efc98629a64b664a387d 10:48:59 INFO - mozversion application_display_name: FirefoxDeveloperEdition 10:48:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:48:59 INFO - mozversion application_name: Firefox 10:48:59 INFO - mozversion application_remotingname: firefox-dev 10:48:59 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:48:59 INFO - mozversion application_vendor: Mozilla 10:48:59 INFO - mozversion application_version: 48.0a2 10:48:59 INFO - mozversion platform_buildid: 20160505085246 10:48:59 INFO - mozversion platform_changeset: 45197bd1bd11fccc5da8efc98629a64b664a387d 10:48:59 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:48:59 INFO - mozversion platform_version: 48.0a2 10:48:59 INFO - using testdate: 1462470539 10:48:59 INFO - actual date: 1462470539 10:48:59 INFO - starting webserver on 'localhost:49360' 10:48:59 INFO - SUITE-START | Running 2 tests 10:48:59 INFO - TEST-START | dromaeo_css 10:48:59 INFO - operating with platform_type : mac_ 10:48:59 INFO - Initialising browser for dromaeo_css test... 10:48:59 INFO - TEST-INFO | started process 2077 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/tmpNqod68/profile http://localhost:49360/getInfo.html) 10:49:01 INFO - PROCESS | 2077 | __metrics Screen width/height:1600/1200 10:49:01 INFO - PROCESS | 2077 | colorDepth:24 10:49:01 INFO - PROCESS | 2077 | Browser inner width/height: 1024/689 10:49:01 INFO - PROCESS | 2077 | __metrics 10:49:01 INFO - PROCESS | 2077 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:49:01 ERROR - PROCESS | 2077 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:49:02 INFO - TEST-INFO | 2077: exit 0 10:49:02 INFO - Browser initialized. 10:49:02 INFO - Running cycle 1/1 for dromaeo_css test... 10:49:02 INFO - TEST-INFO | started process 2080 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/tmpNqod68/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:49:14 INFO - PROCESS | 2080 | RSS: Main: 211271680 10:49:14 INFO - PROCESS | 2080 | 10:50:05 INFO - PROCESS | 2080 | 1462470605737 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] 10:52:16 INFO - PROCESS | 2080 | Cycle 1(1): loaded http://localhost:49360/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49360/tests/dromaeo/cssquery-ext.html) 10:52:17 INFO - PROCESS | 2080 | RSS: Main: 337416192 10:52:17 INFO - PROCESS | 2080 | 10:55:20 INFO - PROCESS | 2080 | Cycle 1(1): loaded http://localhost:49360/tests/dromaeo/cssquery-ext.html (next: http://localhost:49360/tests/dromaeo/cssquery-jquery.html) 10:55:21 INFO - PROCESS | 2080 | RSS: Main: 355090432 10:55:21 INFO - PROCESS | 2080 | 10:58:23 INFO - PROCESS | 2080 | Cycle 1(1): loaded http://localhost:49360/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49360/tests/dromaeo/cssquery-mootools.html) 10:58:24 INFO - PROCESS | 2080 | RSS: Main: 356487168 10:58:24 INFO - PROCESS | 2080 | 11:01:26 INFO - PROCESS | 2080 | Cycle 1(1): loaded http://localhost:49360/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49360/tests/dromaeo/cssquery-prototype.html) 11:01:26 INFO - PROCESS | 2080 | RSS: Main: 356691968 11:01:26 INFO - PROCESS | 2080 | 11:04:30 INFO - PROCESS | 2080 | Cycle 1(1): loaded http://localhost:49360/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49360/tests/dromaeo/cssquery-yui.html) 11:04:30 INFO - PROCESS | 2080 | RSS: Main: 320929792 11:04:30 INFO - PROCESS | 2080 | 11:07:33 INFO - PROCESS | 2080 | Cycle 1(1): loaded http://localhost:49360/tests/dromaeo/cssquery-yui.html (next: http://localhost:49360/tests/dromaeo/cssquery-dojo.html) 11:07:33 INFO - PROCESS | 2080 | RSS: Main: 371548160 11:07:33 INFO - PROCESS | 2080 | 11:07:33 INFO - PROCESS | 2080 | __start_tp_report 11:07:33 INFO - PROCESS | 2080 | _x_x_mozilla_page_load 11:07:33 INFO - PROCESS | 2080 | _x_x_mozilla_page_load_details 11:07:33 INFO - PROCESS | 2080 | |i|pagename|runs| 11:07:33 INFO - PROCESS | 2080 | |0;dojo.html;3407.9318413631727;3469.8438570264334;3483.355579217843;3492.249166429217;3522.4364101743727;5813.360530341659;5835.503982161516;5845.210896528967;5850.268716410444;5883.676397798119;5810.360860305363;5837.532997360212;5843.707814609274;5853.122031695238;5864.266966629171;4497.415336006315;5192.273081768555;5255.527002569768;5267.499587539178;5272.578193744511;5331.840044798643;5341.06531357013;5345.893082138355;5347.598930080259;5350.063738845706;5246.632735708502;5312.681239125645;5318.228856815779;5327.387350454708;5334.093204155283;5320.680759154444;5388.030154572176;5398.406175320712;5402.648827826218;5415.918761218585;5087;5215.217717342391;5267.762950667229;5279.6832190068335;5296.311479507639;5650;5705.54355651551;5717.342505611867;5717.857053573653;5741.799037033726;6249.343818898996;6296.118543403927;6298.842528936768;6306.400891915261;6313.337099604521;6208.410201030895;6211.87576248477;6288.213973253343;6289.088082228097;6291.811245662638;5948;5956.106584012433;5977.910331345033;5988.610740301867;6020.548458865559;6327.620342779448;6340.3342649022115;6345.016522439029;6349;6350.396712312323;6063.921309257987;6151.753929842756;6167.414095660905;6175.475084617768;6274.529410294246;6135.054273916394;6215.254169499599;6258.499320054385;6259.561830671901;6266.24805023391;6303.054541818764;6320.399562041599;6326.177596912464;6326.968365158237;6332.746690132343;6126.731429653714;6225.8132256032395;6255.311915689271;6300.527460440394;6313.77901773445;4932.630052746058;4950.727709975986;4958.628102892298;4966.950330496721;4969.850904472872;3866.2847373235477;4414.205443020223;4454.354118652842;4474.955250447454;4520.751358675238;5777.133429985536;5813.709314534256;5828.271466066742;5840.795572155039;5891.823245302648;5093.312402825645;6260.874782504325;6264.310925798159;6267.341929097379;6290.968545157245;5756.539476841843;5796.072628379439;5814.534837213013;5836.67898265591;5858.974679431168;5453.372862120907;5462.3445186577865;5466.34403871538;5476.67139971603;5484.314460692413;5265.473452654704;6260.937390626035;6472.579282346726;6492.642904640195;6493.740250389931;6218.378162183926;6261.186045814015;6275.058741188858;6277.905831412441;6293.150424692608;6436.517261205335;6472.288048314776;6504.38208370204;6507.739690412331;6511.21865376139;6141.815745527641;6262.812115636538;6275.811725648238;6300.558960872695;6312.621242725451;5599.356074051536;6178.104174894525;6226.221722284715;6261.718222680081;6292.402221788922;4728.668993170445;6153.13856060143;6179.62922224668;6225.128482012612;6237.532185086227;6004.609700369462;6109.663968481598;6205.937940620716;6226.937730622636;6228.252609686867;6014.969925150346;6038.5772995889865;6055.576109672281;6088.056351265561;6101.664408457577;6136.969315153574;6202.193714817045;6206.968965155145;6218.5647004709235;6243.219597550306;5892.558058145742;6033.909491357545;6109.083637454418;6111.327753947151;6118.634521738825 11:07:33 INFO - PROCESS | 2080 | |1;ext.html;510.27286117282875;513.3326675322082;518.7691477292607;579.5189992306387;602.4487593851626;28478.715212847866;33033.66966330336;39337.80331098348;39744.00639984002;39779.80110099445;45019.77490112553;45862.77068614661;45933.54066459335;46147.30779038317;46149.76925115378;9530.094641009111;9789.804203915917;9790.902090979105;9795;9795.118439340458;8470.110638382974;8688.000879898798;11265.324080555192;11553.364564948924;11599.710007249843;11543;11687.298762074302;11710.590129345475;11721;11748.41257937104;8526.914730852674;11398.259113157617;11570.884291157065;11763.35301558414;11767.705807354841;9728;11701.765964680659;11712.53149874004;11720.589779357766;11722.706932326675;39603.40594891079;40209.79895100535;40216.396754048714;40227.59772402268;40337.99155021118;3191.824449655291;3278.606567211926;3364.108511244522;3456.4642480415578;3526.9948064801665;28494.00270990526;28937.855310723313;29262.707372926212;29515.70484295151;30080.398392032035;2747.2719729271757;2785.7075007124163;2800.957985630217;2942.396808654221;2944.1461976026926;456682;459207.7039614814;459624;460148;460255;34906.12734681653;35609.82195089008;35616.616421439925;35670;65360;36472.63527364745;36581;36583.4512482313;36603.450948235266;36798.81600592034;15334.0799552025;15554.533363999099;15592.142432166112;15615.843841561666;15618.609534761721;11801.940990294994;12086.758264834654;12801.871981280254;13017.544385946447;13091.934540327427;7282.890756638654;8284.130166332568;8331.750047498585;8332.916670833336;8376.581170941428;3292.42382583047;3408.9488657670154;3439.6904278615043;3444.3283559705615;3485.2118305355616;962.5908988679784;1004.6885465505717;1020.5458570935863;1045.1273186889032;1087.1574529739357;29603.703962960095;30025.69974300229;30175.396492070475;30909.536356954213;30949.535756963665;909.977250568741;949.8860136783492;1050.3907733514543;1182.9826349339562;1187.1749134351714;10993.450327483593;11743.47154378055;13077.149985251119;14340.422791294548;15778.526644200692;679788;687801;689321.5533922398;689510;689707;62398.68800655968;63065.684671576346;63214;63217.05174422387;63257.68371158115;1426.771716525351;1472.3006571878486;1517.7647464642778;1536.5697604670263;1537.7616469447253;26047.609285861105;28962;30039.699603004563;30279;30697.53953690652;52002.73998630134;52451.68870778108;52461.7376913113;52519;52602.21096683476;437567.8121609372;447980.7600961974;448386;449141.75429122645;449174;22107;23070.653940190576;26038.34904127412;26485.470290594465;26904.59643105316;44535.07016023801;45981.77009115067;46364.30453543267;47077.529224707316;47635;34362.82818585891;34767.13082172966;37149.442758358105;37751.81124094472;38508.61491385163;37067.814660926524;37256.81371593125;37521.81239093787;37817.432738508396;38194.22572178478;37107;37837.62162378341;38254.808725956194;38257.617423826516;38407.80796096114;33253.50119748254;33287.83356083204;33294.33411331808;33304.66695333113;33448.83275583606;24605.87697061503;24944.750552494243;25292.36769080714;25546.872265638554;25611.97552097895 11:07:33 INFO - PROCESS | 2080 | |2;jquery.html;352.595300053634;373.3745975491052;406.81489922085456;412.9587041295871;418.21793246628806;14085.57743267701;19842.30551930683;20558.89720551399;21243.15027398906;21270;14051.718965620681;16214.675706485863;20036.69944950827;20924.953752312398;21010.474738131554;5739.454751798583;5944.762209511614;5962.241138864046;5962.522998160137;5972.25346831646;5613.129964855433;5941.435563621449;5963.135345374918;6002.129691194774;6021.729022194012;3917.138229589486;4152.3771434284945;5810.506106980912;6126.172966649512;6134.386561343852;5914.408559144094;6106.572539922207;6115.327313995479;6139.6009259398215;6588.40704336612;5810.796622118248;5832.737526811304;5833.241678581757;5841.182234487175;5844.532437404998;13668;17962.820371796246;25066.498670026678;26070.86964565165;26128;1975.6147551227516;2116.291042500764;2126.851120421571;2287.885605719707;2384.640754769782;9254.398464099879;9404.764880877965;9789.657361992367;9878.950605246999;9906.653267135616;1658.328377007302;1667.7831881855402;1679.5633135384887;1701.4648471409905;1744.1802352894247;82534;105460;123939;126746;128296.35851820775;11823.172377933633;11859.70350741221;11862.695103538605;11903.464344104535;11908.225965312227;15307.92346038285;15392.538223853302;15412.922935385252;15413;15437.614059648598;6508.576942498722;8811.118888111137;8858.64565417376;8929.776755581033;8982.820343593223;8769.079246679134;8774.736757897273;8787.077356877437;8884.555772211364;8953.910460895439;4558.407407037064;4675.836345727883;4675.95324046762;4681.250999840009;4709.764511774398;1686.0979376033763;1701.829817018313;1764.7705798246145;1828.8628352873322;1919.1747548553976;875.1336177184542;889.7908991387019;916.867054277126;936.7611259128879;970.7039352997263;19139.2344306229;20109.899450502948;20111.899440502704;20139.697904531444;20686.379408617766;1001.5342865567546;1003.372891942536;1005.6066561583531;1011.5751384418562;1036.300497164411;10187.439690817077;10784.40685762275;10797.892021079846;10802.891971080346;10877.83683244752;550767;558769;567641;648984;652388;133252;136828;142202.57797421893;142397;152502;958.5255298627363;1012.9696109116738;1046.9581216751396;1059.3749687684149;1066.290916540492;14076.718465630835;18268.543286417946;18429.81570184281;19083;19116.23535058582;53444.390394027105;53873.191902120714;53965.460345396044;54652.726736366065;55040.42859268908;75951;83163.5841820787;86523;86855.56572217098;87188.5640571793;5699.715014249354;8084.191580841869;8112.716054938049;8293.377996650155;8390.244877961013;11488.770224595628;12664.113512054068;12823.294718790556;13045;13143.211407315592;11201.943990279997;11394.145439092266;11494.367809770209;11531.596394126165;11534.59628912984;9503.382280151767;10881.619143329945;10959.06847917936;11155.776884462428;11243.156763242952;11285.153613478858;11536.884631153582;11668.70828229301;11804.527818887493;11810.940945295219;8097.352211823158;8343.249107580346;8404.747857564284;8424.283935865275;8655.17775811297;10107.292489525662;11228;11476.311421314414;11534.423278836193;11573.247738896946 11:07:33 INFO - PROCESS | 2080 | |3;mootools.html;1422.6372275069855;1597.888147829652;1613.9757903631444;1861.404350607805;1970.4482744831444;7916.722914697987;8722.040575536686;8931.955340223307;8942.776430589238;8947.64209431622;8580.570971451418;8781.077986811388;8782.341324400664;8787.868181977276;8789.165029322205;2436.902523899042;2463.1255904154036;2464.149868295435;2467.4324905271797;2474.319562120413;2400.8919598618018;2463.224084413413;2465.6055031195015;2468.259522143359;2473.802095832338;2425.9393515162174;2448.4490989527394;2461.3754921751647;2466.7533246675366;2474.9628755568683;2087.5198704298023;2395.185636883468;2433.6957880264968;2435.0625009371342;2444.828861979662;2396.484755777499;2410.7950824179966;2424.139430502164;2425.357280320716;2426.5632186206476;8752.956235218848;8800.955995220047;8864.822703545893;8872.290216782641;8895.955520222358;5274.182501712241;5446.074167391553;5466;5484.780608775644;5494.80768173116;3473.426884564069;3567.554055743032;3623.2391197848483;3647.1611529348397;3665.816709164531;1014.0721240065332;1024.4519182237468;1029.5161274201114;1029.9073083422527;1043.2123746571367;11446.198766086378;11544.365059921702;11762.353070581115;11795.997340226091;11858.229215101077;4590.540945905383;4599.264117741145;4694.460137084279;4724.740139292372;4730.621550276039;9316.301277404222;9317.90682093184;9329.393589416666;9333.90666093344;9392.966773654893;5242.97378513105;5296.496832800878;5303.36359636846;5346.492083252085;5354.518093371616;4441.689081764245;4604.493505714368;4808.206645903457;4914.262860570871;4962.900741985212;3793.108619474421;3803.030227292023;3857.40210267409;3885.9611403886165;3909.667678247324;1200.4417945655312;1201.2312120243053;1203.0676225924979;1214.732758793064;1216.8235605837103;646.4182235987555;804.1958041958042;903.7153296711515;950.0831697411893;957.5403806173076;5615.157726341082;5777.942220577825;5784.971075144597;5920.082387229986;6019.2175017248355;474.92638641010006;485.1437213318496;486.6228672778554;508.67953189490385;657.9177253418196;3720.069982504374;3902.3951287550476;3924.136689928211;3989.2420440116866;4025.054112283611;10423.322484038514;10817.188710846718;10905.672829815117;11043.779124417466;11069;10868;10894.964978327047;10905.073068789241;11006.284591501526;11020.944895275632;7810.414218933603;8199.836003280021;8277.461964972257;8284.792880178044;8298.875516867252;5615;5714.79998200061;5860.648361098348;5880.913987888637;6056.636601803906;9841.114299713061;10061.295709300279;10115.848262275924;10260.333078349884;10270.486475676036;8680.392372533863;9985.900140998498;10209.38743675382;10247.59009639636;10346.586136554453;6463.838404039937;7560.130584982798;7658.042744656918;7677.8464430709955;7688.923110768821;8212.753617391489;8294.460860044319;8324.875126872981;8387.370947178864;8390;7500.62496875165;7516.060492438445;7532.13380461232;7636.389088872988;7663.846723065619;7312.597807120446;7412.96293518529;7452.217517160837;7482.176960534228;7500;6842.486813489108;7066.823329416806;7126.251743566851;7155.212926577969;7157.606331651809;4800.591949684316;4803.255495398219;4878.390201224847;4914.533119353656;4951;4931.950680493149;4975.676581022222;5024.120778863758;5033.194688849913;5154.768035438489 11:07:33 INFO - PROCESS | 2080 | |4;prototype.html;280.6758194285598;309.0835672231832;318.6287974509696;327.2767184522205;327.394320507062;4260.40234911908;4654.674172807905;5024.799008039683;5310.20346947958;5424.403315635277;4507.842225522099;5161.458046905077;5358.839234822952;5370.516653501184;5417.322834645669;3769.831352280788;3807.0101773538872;4041.8585349512773;4141.544430112685;4149.46057012588;3586.7668601540818;3889.688824894016;4023.275810354135;4132.834686612532;4188.141431006637;3699.408094704848;4139.56534563871;4155.771432571208;4173.56177601352;4180.937285940713;3487.6686714762186;3771.41543060826;4054.493188351456;4141.8964525886795;4154.376843473503;3585.2470981094;3615.837287322077;3624.9093772655633;3629.2922880038404;3639.727020473475;4258.87223383299;4877.489125663432;5102.642815002914;5119.411267704188;5166.715830629314;3998.4801975743258;4393.231184542724;4605.976970115162;4724.5984091352275;4758.738269395182;4855.854324370274;4910.803567857317;5386.649867758583;5611.186377975171;5620.358431537212;3181.5704879841387;3645.927081458356;3890.708196885245;3912.2762288976346;4017.3773065174946;5869.767348856745;5893.764249430018;5909.881802363929;5930.1994230779155;5971.731272092767;3285.720713739311;3530.7881527108457;3964.32606456903;3980.800959951991;3984.442178095069;3826.4260360945523;3990.301697203036;3995.1210733638554;4032.971592243959;4085.4484644572603;3902.414637804295;4630.652701047435;4658.487566367697;4676.392069031072;4705.6470764692785;4811.446683631357;5674.744636491368;5681.403452637496;5682.886342273131;5727.70891643346;1306.6341424401182;1375.5116933488468;1432.641839540115;1460.1531111955042;1484.094702231638;949.8290307744672;969.3650658818485;970.2965350121106;971.3103696375653;978.5205249427871;4803.351547541037;4828.37231159954;5690.174924635905;5696.493542841136;5722.971385143048;5661.46216109469;6009.489193418521;6133.570650054453;6363.236411630634;6386.07401926718;5655.688937108416;5681.857953551194;5690.5163061140265;5706.229658996065;5749.7700091996685;3015.010093338386;3083.5683004379407;3163.975055964185;3299.86800527981;3356.580427446569;5359.812406565751;6097.420745029215;6607.933920660732;6616.569922955089;6639.236487803964;6458;6492.64290464029;6521.217453905561;6559.344065593498;6610.603363798187;5180.870478238075;5442.91835622466;6170.5372097092;6581.643361885377;6582.80251592453;6279.403456671609;6287.748490060347;6292.370762923671;6307.463865571478;6365.204349456318;5266.499682530153;5965.403459654;6195.287541932736;6239.126522286974;6268.686565671607;5194.610404219623;5484.917726234189;6053.75784968614;6548.148740663683;6564.179477565304;3826.617338266151;4009.178118485762;4044.5348784890116;4220.366944958281;4226.281532139482;4857.489963553918;5254.474552544715;5608.523275521627;5733.792489891339;6349.238091429058;4780.545848144421;5586.6033491627095;5588.6646801192055;5633.6056476047925;5648.406917273627;4800.927986080282;5170.146925757358;5474.5894057945015;5598.972005139948;5599.804006859903;4621.752126925644;5135.281060651587;5509.201165830852;5519.31008623922;5566.693831839288;4305.729809706207;5376.951494458543;5557.694326812064;5606.943930560642;5640.887182256414;4485.0229113357345;5483.424240454854;5530.451473587402;5541.085720856014;5550.888982220414 11:07:33 INFO - PROCESS | 2080 | |5;yui.html;656.8653426047659;661.4047357378362;670.1890712238195;683.1221879884348;750.1073722270494;14999.40002399904;15369.462068827594;15442.150681712501;15447.845521544808;15459.61350966225;14036.438542458289;14042.438302467914;14091.295435228249;14094.154350738963;14118.858811411883;737.8782500887347;764.1861417590248;915.0529202275646;928.8235235305305;969.7721035556639;932.4917919733762;948.9193418559431;949.5299826585815;952.0527075559828;955.3264948211529;1002.16319373323;1002.6039714312842;1003.859459675646;1007.5113569918584;1008.3596915958383;776.8523980443725;807.4670717326595;807.9617691266694;808.6967387229789;994.5922171909518;704.1550139832221;743.1156923261302;745.2882497215146;749.4678778067579;750.0624219725344;13883.4446622136;13965.930170349084;14010.649733756636;14061.929690351482;14101.365438555187;12285.508579656804;12370.505179792797;12447.377631118408;12540.937295313464;12643.988480921596;10724.571017159304;10831.241813073088;11093.445327733582;11319.264247823865;11694.532218711242;4090.325096359097;4106.650934670557;4147.502299724052;4189.895252618679;4227.87316380509;80922;80957;81646.18353816445;84462.57768711179;84940;9696.806063878683;9932.155766759906;9952.253580981456;9977.900220997843;10251.28241023136;22159.778402216092;22675.092996279964;22699.773002269765;22716.772832271796;22724;7655.2344765523;7673.923260767432;7756.806079848049;7769.572673502898;7857.567833769198;4037.7375470594907;4058.9797051014557;4096.40602112698;4123.463949686581;4202.810873510699;3539.964600354015;3630.219502806909;3662.5238718966366;3665.578458477309;3669.2478042001326;547.9205515200354;652.1066139389068;675.2268652392983;676.7800464848943;700.4571457120772;524.0305434945396;524.1953601222106;578.1068249554448;595.6396380190009;604.8941435248814;7637.5035622684345;8108.513489190667;8139.267465928095;8160.061592916772;8186.0382074628915;476.6710969431081;564.4383838081136;575.8733078722674;589.7758851636437;593.7387549478302;3769.7738135711943;3775.5469343678933;3938.980305098456;3995.320795464778;4013.7190396671954;100052.49973750231;105204.47397762962;105530;107412;107824.8435031295;61493.077603835976;76283.47433051308;78615.03462413486;87174.69237961435;87803.56098219469;65582.34417655763;65851;66537.33656658526;67777;77965.61017194878;7944.364450843803;8035.749990002809;8056.476329038594;8079.3536517079665;8264;35331.646683533865;38103.04742381463;38626.6137338623;43230.351544727484;44046;59073.81852363014;62062;62903.6854815723;63006.684966574874;63108;10834.891651083388;10929.836052459379;10992.17558683086;11196.832047519456;11411.97292243702;11702.180847340946;11888.583899563473;12072.939635301767;12197.51209951592;12291.69270768238;11232.943835280772;11270.492827822603;11474.254173478697;11541.538338466367;11966;11193.72015699614;11308.321500709984;11436.828447573127;11573.098485149601;12132.75734485323;9064.63741450361;11213.551457941918;11289.887101128883;11330.37682927447;11642.301461912313;9101.089891010846;9217.032211617952;9251;9465.621375144918;9688.612455501701;9201.241042037105;9395.248380129437;9467.905320946702;9477;9655.131038206595 11:07:33 INFO - PROCESS | 2080 | __end_tp_report 11:07:33 INFO - PROCESS | 2080 | __start_cc_report 11:07:33 INFO - PROCESS | 2080 | _x_x_mozilla_cycle_collect,1114 11:07:33 INFO - PROCESS | 2080 | __end_cc_report 11:07:33 INFO - PROCESS | 2080 | __startTimestamp1462471653566__endTimestamp 11:07:33 INFO - PROCESS | 2080 | 11:07:33 INFO - PROCESS | 2080 | ------- Summary: start ------- 11:07:33 INFO - PROCESS | 2080 | Number of tests: 6 11:07:33 INFO - PROCESS | 2080 | 11:07:33 INFO - PROCESS | 2080 | [#0] dojo.html Cycles:165 Average:5794.09 Median:6033.91 stddev:654.73 (10.9%) stddev-sans-first:629.41 11:07:33 INFO - PROCESS | 2080 | Values: 3407.9 3469.8 3483.4 3492.2 3522.4 5813.4 5835.5 5845.2 5850.3 5883.7 5810.4 5837.5 5843.7 5853.1 5864.3 4497.4 5192.3 5255.5 5267.5 5272.6 5331.8 5341.1 5345.9 5347.6 5350.1 5246.6 5312.7 5318.2 5327.4 5334.1 5320.7 5388.0 5398.4 5402.6 5415.9 5087.0 5215.2 5267.8 5279.7 5296.3 5650.0 5705.5 5717.3 5717.9 5741.8 6249.3 6296.1 6298.8 6306.4 6313.3 6208.4 6211.9 6288.2 6289.1 6291.8 5948.0 5956.1 5977.9 5988.6 6020.5 6327.6 6340.3 6345.0 6349.0 6350.4 6063.9 6151.8 6167.4 6175.5 6274.5 6135.1 6215.3 6258.5 6259.6 6266.2 6303.1 6320.4 6326.2 6327.0 6332.7 6126.7 6225.8 6255.3 6300.5 6313.8 4932.6 4950.7 4958.6 4967.0 4969.9 3866.3 4414.2 4454.4 4475.0 4520.8 5777.1 5813.7 5828.3 5840.8 5891.8 5093.3 6260.9 6264.3 6267.3 6291.0 5756.5 5796.1 5814.5 5836.7 5859.0 5453.4 5462.3 5466.3 5476.7 5484.3 5265.5 6260.9 6472.6 6492.6 6493.7 6218.4 6261.2 6275.1 6277.9 6293.2 6436.5 6472.3 6504.4 6507.7 6511.2 6141.8 6262.8 6275.8 6300.6 6312.6 5599.4 6178.1 6226.2 6261.7 6292.4 4728.7 6153.1 6179.6 6225.1 6237.5 6004.6 6109.7 6205.9 6226.9 6228.3 6015.0 6038.6 6055.6 6088.1 6101.7 6137.0 6202.2 6207.0 6218.6 6243.2 5892.6 6033.9 6109.1 6111.3 6118.6 11:07:33 INFO - PROCESS | 2080 | 11:07:33 INFO - PROCESS | 2080 | [#1] ext.html Cycles:180 Average:65449.36 Median:19195.67 stddev:145301.77 (757.0%) stddev-sans-first:145627.57 11:07:33 INFO - PROCESS | 2080 | Values: 510.3 513.3 518.8 579.5 602.4 28478.7 33033.7 39337.8 39744.0 39779.8 45019.8 45862.8 45933.5 46147.3 46149.8 9530.1 9789.8 9790.9 9795.0 9795.1 8470.1 8688.0 11265.3 11553.4 11599.7 11543.0 11687.3 11710.6 11721.0 11748.4 8526.9 11398.3 11570.9 11763.4 11767.7 9728.0 11701.8 11712.5 11720.6 11722.7 39603.4 40209.8 40216.4 40227.6 40338.0 3191.8 3278.6 3364.1 3456.5 3527.0 28494.0 28937.9 29262.7 29515.7 30080.4 2747.3 2785.7 2801.0 2942.4 2944.1 456682.0 459207.7 459624.0 460148.0 460255.0 34906.1 35609.8 35616.6 35670.0 65360.0 36472.6 36581.0 36583.5 36603.5 36798.8 15334.1 15554.5 15592.1 15615.8 15618.6 11801.9 12086.8 12801.9 13017.5 13091.9 7282.9 8284.1 8331.8 8332.9 8376.6 3292.4 3408.9 3439.7 3444.3 3485.2 962.6 1004.7 1020.5 1045.1 1087.2 29603.7 30025.7 30175.4 30909.5 30949.5 910.0 949.9 1050.4 1183.0 1187.2 10993.5 11743.5 13077.1 14340.4 15778.5 679788.0 687801.0 689321.6 689510.0 689707.0 62398.7 63065.7 63214.0 63217.1 63257.7 1426.8 1472.3 1517.8 1536.6 1537.8 26047.6 28962.0 30039.7 30279.0 30697.5 52002.7 52451.7 52461.7 52519.0 52602.2 437567.8 447980.8 448386.0 449141.8 449174.0 22107.0 23070.7 26038.3 26485.5 26904.6 44535.1 45981.8 46364.3 47077.5 47635.0 34362.8 34767.1 37149.4 37751.8 38508.6 37067.8 37256.8 37521.8 37817.4 38194.2 37107.0 37837.6 38254.8 38257.6 38407.8 33253.5 33287.8 33294.3 33304.7 33448.8 24605.9 24944.8 25292.4 25546.9 25612.0 11:07:33 INFO - PROCESS | 2080 | 11:07:33 INFO - PROCESS | 2080 | [#2] jquery.html Cycles:180 Average:35206.17 Median:11698.43 stddev:100167.18 (856.2%) stddev-sans-first:100413.99 11:07:33 INFO - PROCESS | 2080 | Values: 352.6 373.4 406.8 413.0 418.2 14085.6 19842.3 20558.9 21243.2 21270.0 14051.7 16214.7 20036.7 20925.0 21010.5 5739.5 5944.8 5962.2 5962.5 5972.3 5613.1 5941.4 5963.1 6002.1 6021.7 3917.1 4152.4 5810.5 6126.2 6134.4 5914.4 6106.6 6115.3 6139.6 6588.4 5810.8 5832.7 5833.2 5841.2 5844.5 13668.0 17962.8 25066.5 26070.9 26128.0 1975.6 2116.3 2126.9 2287.9 2384.6 9254.4 9404.8 9789.7 9879.0 9906.7 1658.3 1667.8 1679.6 1701.5 1744.2 82534.0 105460.0 123939.0 126746.0 128296.4 11823.2 11859.7 11862.7 11903.5 11908.2 15307.9 15392.5 15412.9 15413.0 15437.6 6508.6 8811.1 8858.6 8929.8 8982.8 8769.1 8774.7 8787.1 8884.6 8953.9 4558.4 4675.8 4676.0 4681.3 4709.8 1686.1 1701.8 1764.8 1828.9 1919.2 875.1 889.8 916.9 936.8 970.7 19139.2 20109.9 20111.9 20139.7 20686.4 1001.5 1003.4 1005.6 1011.6 1036.3 10187.4 10784.4 10797.9 10802.9 10877.8 550767.0 558769.0 567641.0 648984.0 652388.0 133252.0 136828.0 142202.6 142397.0 152502.0 958.5 1013.0 1047.0 1059.4 1066.3 14076.7 18268.5 18429.8 19083.0 19116.2 53444.4 53873.2 53965.5 54652.7 55040.4 75951.0 83163.6 86523.0 86855.6 87188.6 5699.7 8084.2 8112.7 8293.4 8390.2 11488.8 12664.1 12823.3 13045.0 13143.2 11201.9 11394.1 11494.4 11531.6 11534.6 9503.4 10881.6 10959.1 11155.8 11243.2 11285.2 11536.9 11668.7 11804.5 11810.9 8097.4 8343.2 8404.7 8424.3 8655.2 10107.3 11228.0 11476.3 11534.4 11573.2 11:07:33 INFO - PROCESS | 2080 | 11:07:33 INFO - PROCESS | 2080 | [#3] mootools.html Cycles:180 Average:5710.34 Median:4896.33 stddev:3214.04 (65.6%) stddev-sans-first:3206.90 11:07:33 INFO - PROCESS | 2080 | Values: 1422.6 1597.9 1614.0 1861.4 1970.4 7916.7 8722.0 8932.0 8942.8 8947.6 8580.6 8781.1 8782.3 8787.9 8789.2 2436.9 2463.1 2464.1 2467.4 2474.3 2400.9 2463.2 2465.6 2468.3 2473.8 2425.9 2448.4 2461.4 2466.8 2475.0 2087.5 2395.2 2433.7 2435.1 2444.8 2396.5 2410.8 2424.1 2425.4 2426.6 8753.0 8801.0 8864.8 8872.3 8896.0 5274.2 5446.1 5466.0 5484.8 5494.8 3473.4 3567.6 3623.2 3647.2 3665.8 1014.1 1024.5 1029.5 1029.9 1043.2 11446.2 11544.4 11762.4 11796.0 11858.2 4590.5 4599.3 4694.5 4724.7 4730.6 9316.3 9317.9 9329.4 9333.9 9393.0 5243.0 5296.5 5303.4 5346.5 5354.5 4441.7 4604.5 4808.2 4914.3 4962.9 3793.1 3803.0 3857.4 3886.0 3909.7 1200.4 1201.2 1203.1 1214.7 1216.8 646.4 804.2 903.7 950.1 957.5 5615.2 5777.9 5785.0 5920.1 6019.2 474.9 485.1 486.6 508.7 657.9 3720.1 3902.4 3924.1 3989.2 4025.1 10423.3 10817.2 10905.7 11043.8 11069.0 10868.0 10895.0 10905.1 11006.3 11020.9 7810.4 8199.8 8277.5 8284.8 8298.9 5615.0 5714.8 5860.6 5880.9 6056.6 9841.1 10061.3 10115.8 10260.3 10270.5 8680.4 9985.9 10209.4 10247.6 10346.6 6463.8 7560.1 7658.0 7677.8 7688.9 8212.8 8294.5 8324.9 8387.4 8390.0 7500.6 7516.1 7532.1 7636.4 7663.8 7312.6 7413.0 7452.2 7482.2 7500.0 6842.5 7066.8 7126.3 7155.2 7157.6 4800.6 4803.3 4878.4 4914.5 4951.0 4932.0 4975.7 5024.1 5033.2 5154.8 11:07:33 INFO - PROCESS | 2080 | 11:07:33 INFO - PROCESS | 2080 | [#4] prototype.html Cycles:180 Average:4669.75 Median:5164.09 stddev:1477.03 (28.6%) stddev-sans-first:1443.97 11:07:33 INFO - PROCESS | 2080 | Values: 280.7 309.1 318.6 327.3 327.4 4260.4 4654.7 5024.8 5310.2 5424.4 4507.8 5161.5 5358.8 5370.5 5417.3 3769.8 3807.0 4041.9 4141.5 4149.5 3586.8 3889.7 4023.3 4132.8 4188.1 3699.4 4139.6 4155.8 4173.6 4180.9 3487.7 3771.4 4054.5 4141.9 4154.4 3585.2 3615.8 3624.9 3629.3 3639.7 4258.9 4877.5 5102.6 5119.4 5166.7 3998.5 4393.2 4606.0 4724.6 4758.7 4855.9 4910.8 5386.6 5611.2 5620.4 3181.6 3645.9 3890.7 3912.3 4017.4 5869.8 5893.8 5909.9 5930.2 5971.7 3285.7 3530.8 3964.3 3980.8 3984.4 3826.4 3990.3 3995.1 4033.0 4085.4 3902.4 4630.7 4658.5 4676.4 4705.6 4811.4 5674.7 5681.4 5682.9 5727.7 1306.6 1375.5 1432.6 1460.2 1484.1 949.8 969.4 970.3 971.3 978.5 4803.4 4828.4 5690.2 5696.5 5723.0 5661.5 6009.5 6133.6 6363.2 6386.1 5655.7 5681.9 5690.5 5706.2 5749.8 3015.0 3083.6 3164.0 3299.9 3356.6 5359.8 6097.4 6607.9 6616.6 6639.2 6458.0 6492.6 6521.2 6559.3 6610.6 5180.9 5442.9 6170.5 6581.6 6582.8 6279.4 6287.7 6292.4 6307.5 6365.2 5266.5 5965.4 6195.3 6239.1 6268.7 5194.6 5484.9 6053.8 6548.1 6564.2 3826.6 4009.2 4044.5 4220.4 4226.3 4857.5 5254.5 5608.5 5733.8 6349.2 4780.5 5586.6 5588.7 5633.6 5648.4 4800.9 5170.1 5474.6 5599.0 5599.8 4621.8 5135.3 5509.2 5519.3 5566.7 4305.7 5377.0 5557.7 5606.9 5640.9 4485.0 5483.4 5530.5 5541.1 5550.9 11:07:33 INFO - PROCESS | 2080 | 11:07:33 INFO - PROCESS | 2080 | [#5] yui.html Cycles:180 Average:18294.63 Median:500.35 stddev:26357.97 (5267.9%) stddev-sans-first:26398.64 11:07:33 INFO - PROCESS | 2080 | Values: 656.9 661.4 670.2 683.1 750.1 14999.4 15369.5 15442.2 15447.8 15459.6 14036.4 14042.4 14091.3 14094.2 14118.9 737.9 764.2 915.1 928.8 969.8 932.5 948.9 949.5 952.1 955.3 1002.2 1002.6 1003.9 1007.5 1008.4 776.9 807.5 808.0 808.7 994.6 704.2 743.1 745.3 749.5 750.1 13883.4 13965.9 14010.6 14061.9 14101.4 12285.5 12370.5 12447.4 12540.9 12644.0 10724.6 10831.2 11093.4 11319.3 11694.5 4090.3 4106.7 4147.5 4189.9 4227.9 80922.0 80957.0 81646.2 84462.6 84940.0 9696.8 9932.2 9952.3 9977.9 10251.3 22159.8 22675.1 22699.8 22716.8 22724.0 7655.2 7673.9 7756.8 7769.6 7857.6 4037.7 4059.0 4096.4 4123.5 4202.8 3540.0 3630.2 3662.5 3665.6 3669.2 547.9 652.1 675.2 676.8 700.5 524.0 524.2 578.1 595.6 604.9 7637.5 8108.5 8139.3 8160.1 8186.0 476.7 564.4 575.9 589.8 593.7 3769.8 3775.5 3939.0 3995.3 4013.7 100052.5 105204.5 105530.0 107412.0 107824.8 61493.1 76283.5 78615.0 87174.7 87803.6 65582.3 65851.0 66537.3 67777.0 77965.6 7944.4 8035.7 8056.5 8079.4 8264.0 35331.6 38103.0 38626.6 43230.4 44046.0 59073.8 62062.0 62903.7 63006.7 63108.0 10834.9 10929.8 10992.2 11196.8 11412.0 11702.2 11888.6 12072.9 12197.5 12291.7 11232.9 11270.5 11474.3 11541.5 11966.0 11193.7 11308.3 11436.8 11573.1 12132.8 9064.6 11213.6 11289.9 11330.4 11642.3 9101.1 9217.0 9251.0 9465.6 9688.6 9201.2 9395.2 9467.9 9477.0 9655.1 11:07:33 INFO - PROCESS | 2080 | -------- Summary: end -------- 11:07:33 INFO - PROCESS | 2080 | 11:07:34 INFO - TEST-INFO | 2080: exit 0 11:07:34 INFO - TEST-OK | dromaeo_css | took 1115031ms 11:07:34 INFO - TEST-START | kraken 11:07:34 INFO - operating with platform_type : mac_ 11:07:34 INFO - Initialising browser for kraken test... 11:07:34 INFO - TEST-INFO | started process 2085 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/tmp9VjkJY/profile http://localhost:49360/getInfo.html) 11:07:36 INFO - PROCESS | 2085 | __metrics Screen width/height:1600/1200 11:07:36 INFO - PROCESS | 2085 | colorDepth:24 11:07:36 INFO - PROCESS | 2085 | Browser inner width/height: 1024/689 11:07:36 INFO - PROCESS | 2085 | __metrics 11:07:36 INFO - PROCESS | 2085 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:07:36 ERROR - PROCESS | 2085 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 11:07:37 INFO - TEST-INFO | 2085: exit 0 11:07:37 INFO - Browser initialized. 11:07:37 INFO - Running cycle 1/1 for kraken test... 11:07:37 INFO - TEST-INFO | started process 2087 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/T/tmp9VjkJY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:07:49 INFO - PROCESS | 2087 | RSS: Main: 211468288 11:07:49 INFO - PROCESS | 2087 | 11:07:51 INFO - PROCESS | 2087 | Cycle 1(1): loaded http://localhost:49360/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49360/tests/kraken/driver.html?testName=audio-beat-detection) 11:07:52 INFO - PROCESS | 2087 | RSS: Main: 269561856 11:07:52 INFO - PROCESS | 2087 | 11:07:55 INFO - PROCESS | 2087 | Cycle 1(1): loaded http://localhost:49360/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49360/tests/kraken/driver.html?testName=audio-dft) 11:07:56 INFO - PROCESS | 2087 | RSS: Main: 283336704 11:07:56 INFO - PROCESS | 2087 | 11:07:59 INFO - PROCESS | 2087 | Cycle 1(1): loaded http://localhost:49360/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49360/tests/kraken/driver.html?testName=audio-fft) 11:08:00 INFO - PROCESS | 2087 | RSS: Main: 371855360 11:08:00 INFO - PROCESS | 2087 | 11:08:02 INFO - PROCESS | 2087 | Cycle 1(1): loaded http://localhost:49360/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49360/tests/kraken/driver.html?testName=audio-oscillator) 11:08:02 INFO - PROCESS | 2087 | RSS: Main: 278667264 11:08:02 INFO - PROCESS | 2087 | 11:08:04 INFO - PROCESS | 2087 | Cycle 1(1): loaded http://localhost:49360/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49360/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:08:05 INFO - PROCESS | 2087 | RSS: Main: 279707648 11:08:05 INFO - PROCESS | 2087 | 11:08:08 INFO - PROCESS | 2087 | Cycle 1(1): loaded http://localhost:49360/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49360/tests/kraken/driver.html?testName=imaging-darkroom) 11:08:08 INFO - PROCESS | 2087 | RSS: Main: 317870080 11:08:08 INFO - PROCESS | 2087 | 11:08:11 INFO - PROCESS | 2087 | Cycle 1(1): loaded http://localhost:49360/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49360/tests/kraken/driver.html?testName=imaging-desaturate) 11:08:12 INFO - PROCESS | 2087 | RSS: Main: 318676992 11:08:12 INFO - PROCESS | 2087 | 11:08:15 INFO - PROCESS | 2087 | Cycle 1(1): loaded http://localhost:49360/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49360/tests/kraken/driver.html?testName=json-parse-financial) 11:08:15 INFO - PROCESS | 2087 | RSS: Main: 316731392 11:08:15 INFO - PROCESS | 2087 | 11:08:17 INFO - PROCESS | 2087 | Cycle 1(1): loaded http://localhost:49360/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49360/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:08:18 INFO - PROCESS | 2087 | RSS: Main: 307077120 11:08:18 INFO - PROCESS | 2087 | 11:08:20 INFO - PROCESS | 2087 | Cycle 1(1): loaded http://localhost:49360/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49360/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:08:20 INFO - PROCESS | 2087 | RSS: Main: 314179584 11:08:20 INFO - PROCESS | 2087 | 11:08:22 INFO - PROCESS | 2087 | Cycle 1(1): loaded http://localhost:49360/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49360/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:08:23 INFO - PROCESS | 2087 | RSS: Main: 316252160 11:08:23 INFO - PROCESS | 2087 | 11:08:26 INFO - PROCESS | 2087 | Cycle 1(1): loaded http://localhost:49360/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49360/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:08:26 INFO - PROCESS | 2087 | RSS: Main: 322072576 11:08:26 INFO - PROCESS | 2087 | 11:08:29 INFO - PROCESS | 2087 | Cycle 1(1): loaded http://localhost:49360/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49360/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:08:30 INFO - PROCESS | 2087 | RSS: Main: 319483904 11:08:30 INFO - PROCESS | 2087 | 11:08:32 INFO - PROCESS | 2087 | Cycle 1(1): loaded http://localhost:49360/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49360/tests/kraken/driver.html?testName=ai-astar) 11:08:32 INFO - PROCESS | 2087 | RSS: Main: 383557632 11:08:32 INFO - PROCESS | 2087 | 11:08:32 INFO - PROCESS | 2087 | __start_tp_report 11:08:32 INFO - PROCESS | 2087 | _x_x_mozilla_page_load 11:08:32 INFO - PROCESS | 2087 | _x_x_mozilla_page_load_details 11:08:32 INFO - PROCESS | 2087 | |i|pagename|runs| 11:08:32 INFO - PROCESS | 2087 | |0;ai-astar;115;115;108;115;114;116;114;115;105;115 11:08:32 INFO - PROCESS | 2087 | |1;audio-beat-detection;156;123;156;160;148;170;166;121;173;121 11:08:32 INFO - PROCESS | 2087 | |2;audio-dft;251;252;186;188;183;183;187;187;184;186 11:08:32 INFO - PROCESS | 2087 | |3;audio-fft;73;78;74;153;57;55;56;65;134;56 11:08:32 INFO - PROCESS | 2087 | |4;audio-oscillator;87;86;138;77;80;88;123;79;76;121 11:08:32 INFO - PROCESS | 2087 | |5;imaging-gaussian-blur;89;85;91;85;92;130;133;87;89;85 11:08:32 INFO - PROCESS | 2087 | |6;imaging-darkroom;94;95;94;94;94;94;94;93;93;100 11:08:32 INFO - PROCESS | 2087 | |7;imaging-desaturate;85;126;133;122;85;84;84;83;84;85 11:08:32 INFO - PROCESS | 2087 | |8;json-parse-financial;60;63;66;63;65;65;63;81;59;59 11:08:32 INFO - PROCESS | 2087 | |9;json-stringify-tinderbox;65;64;67;72;78;75;70;69;68;68 11:08:32 INFO - PROCESS | 2087 | |10;stanford-crypto-aes;81;81;82;81;82;80;80;79;83;85 11:08:32 INFO - PROCESS | 2087 | |11;stanford-crypto-ccm;137;138;134;135;135;137;136;135;174;139 11:08:32 INFO - PROCESS | 2087 | |12;stanford-crypto-pbkdf2;204;220;189;168;169;169;174;172;166;166 11:08:32 INFO - PROCESS | 2087 | |13;stanford-crypto-sha256-iterative;72;92;95;78;68;68;67;75;69;56 11:08:32 INFO - PROCESS | 2087 | __end_tp_report 11:08:32 INFO - PROCESS | 2087 | __start_cc_report 11:08:32 INFO - PROCESS | 2087 | _x_x_mozilla_cycle_collect,2041 11:08:32 INFO - PROCESS | 2087 | __end_cc_report 11:08:32 INFO - PROCESS | 2087 | __startTimestamp1462471712542__endTimestamp 11:08:32 INFO - PROCESS | 2087 | 11:08:32 INFO - PROCESS | 2087 | ------- Summary: start ------- 11:08:32 INFO - PROCESS | 2087 | Number of tests: 14 11:08:32 INFO - PROCESS | 2087 | 11:08:32 INFO - PROCESS | 2087 | [#0] ai-astar Cycles:10 Average:113.20 Median:115.00 stddev:3.65 (3.2%) stddev-sans-first:3.81 11:08:32 INFO - PROCESS | 2087 | Values: 115.0 115.0 108.0 115.0 114.0 116.0 114.0 115.0 105.0 115.0 11:08:32 INFO - PROCESS | 2087 | 11:08:32 INFO - PROCESS | 2087 | [#1] audio-beat-detection Cycles:10 Average:149.40 Median:158.00 stddev:20.46 (13.0%) stddev-sans-first:21.56 11:08:32 INFO - PROCESS | 2087 | Values: 156.0 123.0 156.0 160.0 148.0 170.0 166.0 121.0 173.0 121.0 11:08:32 INFO - PROCESS | 2087 | 11:08:32 INFO - PROCESS | 2087 | [#2] audio-dft Cycles:10 Average:198.70 Median:187.00 stddev:27.88 (14.9%) stddev-sans-first:22.24 11:08:32 INFO - PROCESS | 2087 | Values: 251.0 252.0 186.0 188.0 183.0 183.0 187.0 187.0 184.0 186.0 11:08:32 INFO - PROCESS | 2087 | 11:08:32 INFO - PROCESS | 2087 | [#3] audio-fft Cycles:10 Average:80.10 Median:61.00 stddev:34.74 (57.0%) stddev-sans-first:36.76 11:08:32 INFO - PROCESS | 2087 | Values: 73.0 78.0 74.0 153.0 57.0 55.0 56.0 65.0 134.0 56.0 11:08:32 INFO - PROCESS | 2087 | 11:08:32 INFO - PROCESS | 2087 | [#4] audio-oscillator Cycles:10 Average:95.50 Median:79.50 stddev:22.77 (28.6%) stddev-sans-first:23.94 11:08:32 INFO - PROCESS | 2087 | Values: 87.0 86.0 138.0 77.0 80.0 88.0 123.0 79.0 76.0 121.0 11:08:32 INFO - PROCESS | 2087 | 11:08:32 INFO - PROCESS | 2087 | [#5] imaging-gaussian-blur Cycles:10 Average:96.60 Median:88.00 stddev:18.57 (21.1%) stddev-sans-first:19.49 11:08:32 INFO - PROCESS | 2087 | Values: 89.0 85.0 91.0 85.0 92.0 130.0 133.0 87.0 89.0 85.0 11:08:32 INFO - PROCESS | 2087 | 11:08:32 INFO - PROCESS | 2087 | [#6] imaging-darkroom Cycles:10 Average:94.50 Median:94.00 stddev:2.01 (2.1%) stddev-sans-first:2.13 11:08:32 INFO - PROCESS | 2087 | Values: 94.0 95.0 94.0 94.0 94.0 94.0 94.0 93.0 93.0 100.0 11:08:32 INFO - PROCESS | 2087 | 11:08:32 INFO - PROCESS | 2087 | [#7] imaging-desaturate Cycles:10 Average:97.10 Median:84.00 stddev:20.81 (24.8%) stddev-sans-first:21.61 11:08:32 INFO - PROCESS | 2087 | Values: 85.0 126.0 133.0 122.0 85.0 84.0 84.0 83.0 84.0 85.0 11:08:32 INFO - PROCESS | 2087 | 11:08:32 INFO - PROCESS | 2087 | [#8] json-parse-financial Cycles:10 Average:64.40 Median:64.00 stddev:6.35 (9.9%) stddev-sans-first:6.53 11:08:32 INFO - PROCESS | 2087 | Values: 60.0 63.0 66.0 63.0 65.0 65.0 63.0 81.0 59.0 59.0 11:08:32 INFO - PROCESS | 2087 | 11:08:32 INFO - PROCESS | 2087 | [#9] json-stringify-tinderbox Cycles:10 Average:69.60 Median:69.50 stddev:4.35 (6.3%) stddev-sans-first:4.28 11:08:32 INFO - PROCESS | 2087 | Values: 65.0 64.0 67.0 72.0 78.0 75.0 70.0 69.0 68.0 68.0 11:08:32 INFO - PROCESS | 2087 | 11:08:32 INFO - PROCESS | 2087 | [#10] stanford-crypto-aes Cycles:10 Average:81.40 Median:81.50 stddev:1.71 (2.1%) stddev-sans-first:1.81 11:08:32 INFO - PROCESS | 2087 | Values: 81.0 81.0 82.0 81.0 82.0 80.0 80.0 79.0 83.0 85.0 11:08:32 INFO - PROCESS | 2087 | 11:08:32 INFO - PROCESS | 2087 | [#11] stanford-crypto-ccm Cycles:10 Average:140.00 Median:137.00 stddev:12.05 (8.8%) stddev-sans-first:12.73 11:08:32 INFO - PROCESS | 2087 | Values: 137.0 138.0 134.0 135.0 135.0 137.0 136.0 135.0 174.0 139.0 11:08:32 INFO - PROCESS | 2087 | 11:08:32 INFO - PROCESS | 2087 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.70 Median:173.00 stddev:18.66 (10.8%) stddev-sans-first:17.60 11:08:32 INFO - PROCESS | 2087 | Values: 204.0 220.0 189.0 168.0 169.0 169.0 174.0 172.0 166.0 166.0 11:08:32 INFO - PROCESS | 2087 | 11:08:32 INFO - PROCESS | 2087 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.00 Median:73.50 stddev:11.81 (16.1%) stddev-sans-first:12.51 11:08:32 INFO - PROCESS | 2087 | Values: 72.0 92.0 95.0 78.0 68.0 68.0 67.0 75.0 69.0 56.0 11:08:32 INFO - PROCESS | 2087 | -------- Summary: end -------- 11:08:32 INFO - PROCESS | 2087 | 11:08:33 INFO - TEST-INFO | 2087: exit 0 11:08:33 INFO - TEST-OK | kraken | took 59311ms 11:08:33 INFO - SUITE-END | took 1174s 11:08:33 INFO - Completed test suite (00:19:34) 11:08:33 INFO - javascript benchmark 11:08:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3407.9318413631727, 3469.8438570264334, 3483.355579217843, 3492.249166429217, 3522.4364101743727, 5813.360530341659, 5835.503982161516, 5845.210896528967, 5850.268716410444, 5883.676397798119, 5810.360860305363, 5837.532997360212, 5843.707814609274, 5853.122031695238, 5864.266966629171, 4497.415336006315, 5192.273081768555, 5255.527002569768, 5267.499587539178, 5272.578193744511, 5331.840044798643, 5341.06531357013, 5345.893082138355, 5347.598930080259, 5350.063738845706, 5246.632735708502, 5312.681239125645, 5318.228856815779, 5327.387350454708, 5334.093204155283, 5320.680759154444, 5388.030154572176, 5398.406175320712, 5402.648827826218, 5415.918761218585, 5087.0, 5215.217717342391, 5267.762950667229, 5279.6832190068335, 5296.311479507639, 5650.0, 5705.54355651551, 5717.342505611867, 5717.857053573653, 5741.799037033726, 6249.343818898996, 6296.118543403927, 6298.842528936768, 6306.400891915261, 6313.337099604521, 6208.410201030895, 6211.87576248477, 6288.213973253343, 6289.088082228097, 6291.811245662638, 5948.0, 5956.106584012433, 5977.910331345033, 5988.610740301867, 6020.548458865559, 6327.620342779448, 6340.3342649022115, 6345.016522439029, 6349.0, 6350.396712312323, 6063.921309257987, 6151.753929842756, 6167.414095660905, 6175.475084617768, 6274.529410294246, 6135.054273916394, 6215.254169499599, 6258.499320054385, 6259.561830671901, 6266.24805023391, 6303.054541818764, 6320.399562041599, 6326.177596912464, 6326.968365158237, 6332.746690132343, 6126.731429653714, 6225.8132256032395, 6255.311915689271, 6300.527460440394, 6313.77901773445, 4932.630052746058, 4950.727709975986, 4958.628102892298, 4966.950330496721, 4969.850904472872, 3866.2847373235477, 4414.205443020223, 4454.354118652842, 4474.955250447454, 4520.751358675238, 5777.133429985536, 5813.709314534256, 5828.271466066742, 5840.795572155039, 5891.823245302648, 5093.312402825645, 6260.874782504325, 6264.310925798159, 6267.341929097379, 6290.968545157245, 5756.539476841843, 5796.072628379439, 5814.534837213013, 5836.67898265591, 5858.974679431168, 5453.372862120907, 5462.3445186577865, 5466.34403871538, 5476.67139971603, 5484.314460692413, 5265.473452654704, 6260.937390626035, 6472.579282346726, 6492.642904640195, 6493.740250389931, 6218.378162183926, 6261.186045814015, 6275.058741188858, 6277.905831412441, 6293.150424692608, 6436.517261205335, 6472.288048314776, 6504.38208370204, 6507.739690412331, 6511.21865376139, 6141.815745527641, 6262.812115636538, 6275.811725648238, 6300.558960872695, 6312.621242725451, 5599.356074051536, 6178.104174894525, 6226.221722284715, 6261.718222680081, 6292.402221788922, 4728.668993170445, 6153.13856060143, 6179.62922224668, 6225.128482012612, 6237.532185086227, 6004.609700369462, 6109.663968481598, 6205.937940620716, 6226.937730622636, 6228.252609686867, 6014.969925150346, 6038.5772995889865, 6055.576109672281, 6088.056351265561, 6101.664408457577, 6136.969315153574, 6202.193714817045, 6206.968965155145, 6218.5647004709235, 6243.219597550306, 5892.558058145742, 6033.909491357545, 6109.083637454418, 6111.327753947151, 6118.634521738825], "value": 5753.665125541931, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [510.27286117282875, 513.3326675322082, 518.7691477292607, 579.5189992306387, 602.4487593851626, 28478.715212847866, 33033.66966330336, 39337.80331098348, 39744.00639984002, 39779.80110099445, 45019.77490112553, 45862.77068614661, 45933.54066459335, 46147.30779038317, 46149.76925115378, 9530.094641009111, 9789.804203915917, 9790.902090979105, 9795.0, 9795.118439340458, 8470.110638382974, 8688.000879898798, 11265.324080555192, 11553.364564948924, 11599.710007249843, 11543.0, 11687.298762074302, 11710.590129345475, 11721.0, 11748.41257937104, 8526.914730852674, 11398.259113157617, 11570.884291157065, 11763.35301558414, 11767.705807354841, 9728.0, 11701.765964680659, 11712.53149874004, 11720.589779357766, 11722.706932326675, 39603.40594891079, 40209.79895100535, 40216.396754048714, 40227.59772402268, 40337.99155021118, 3191.824449655291, 3278.606567211926, 3364.108511244522, 3456.4642480415578, 3526.9948064801665, 28494.00270990526, 28937.855310723313, 29262.707372926212, 29515.70484295151, 30080.398392032035, 2747.2719729271757, 2785.7075007124163, 2800.957985630217, 2942.396808654221, 2944.1461976026926, 456682.0, 459207.7039614814, 459624.0, 460148.0, 460255.0, 34906.12734681653, 35609.82195089008, 35616.616421439925, 35670.0, 65360.0, 36472.63527364745, 36581.0, 36583.4512482313, 36603.450948235266, 36798.81600592034, 15334.0799552025, 15554.533363999099, 15592.142432166112, 15615.843841561666, 15618.609534761721, 11801.940990294994, 12086.758264834654, 12801.871981280254, 13017.544385946447, 13091.934540327427, 7282.890756638654, 8284.130166332568, 8331.750047498585, 8332.916670833336, 8376.581170941428, 3292.42382583047, 3408.9488657670154, 3439.6904278615043, 3444.3283559705615, 3485.2118305355616, 962.5908988679784, 1004.6885465505717, 1020.5458570935863, 1045.1273186889032, 1087.1574529739357, 29603.703962960095, 30025.69974300229, 30175.396492070475, 30909.536356954213, 30949.535756963665, 909.977250568741, 949.8860136783492, 1050.3907733514543, 1182.9826349339562, 1187.1749134351714, 10993.450327483593, 11743.47154378055, 13077.149985251119, 14340.422791294548, 15778.526644200692, 679788.0, 687801.0, 689321.5533922398, 689510.0, 689707.0, 62398.68800655968, 63065.684671576346, 63214.0, 63217.05174422387, 63257.68371158115, 1426.771716525351, 1472.3006571878486, 1517.7647464642778, 1536.5697604670263, 1537.7616469447253, 26047.609285861105, 28962.0, 30039.699603004563, 30279.0, 30697.53953690652, 52002.73998630134, 52451.68870778108, 52461.7376913113, 52519.0, 52602.21096683476, 437567.8121609372, 447980.7600961974, 448386.0, 449141.75429122645, 449174.0, 22107.0, 23070.653940190576, 26038.34904127412, 26485.470290594465, 26904.59643105316, 44535.07016023801, 45981.77009115067, 46364.30453543267, 47077.529224707316, 47635.0, 34362.82818585891, 34767.13082172966, 37149.442758358105, 37751.81124094472, 38508.61491385163, 37067.814660926524, 37256.81371593125, 37521.81239093787, 37817.432738508396, 38194.22572178478, 37107.0, 37837.62162378341, 38254.808725956194, 38257.617423826516, 38407.80796096114, 33253.50119748254, 33287.83356083204, 33294.33411331808, 33304.66695333113, 33448.83275583606, 24605.87697061503, 24944.750552494243, 25292.36769080714, 25546.872265638554, 25611.97552097895], "value": 18637.879579310757, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.595300053634, 373.3745975491052, 406.81489922085456, 412.9587041295871, 418.21793246628806, 14085.57743267701, 19842.30551930683, 20558.89720551399, 21243.15027398906, 21270.0, 14051.718965620681, 16214.675706485863, 20036.69944950827, 20924.953752312398, 21010.474738131554, 5739.454751798583, 5944.762209511614, 5962.241138864046, 5962.522998160137, 5972.25346831646, 5613.129964855433, 5941.435563621449, 5963.135345374918, 6002.129691194774, 6021.729022194012, 3917.138229589486, 4152.3771434284945, 5810.506106980912, 6126.172966649512, 6134.386561343852, 5914.408559144094, 6106.572539922207, 6115.327313995479, 6139.6009259398215, 6588.40704336612, 5810.796622118248, 5832.737526811304, 5833.241678581757, 5841.182234487175, 5844.532437404998, 13668.0, 17962.820371796246, 25066.498670026678, 26070.86964565165, 26128.0, 1975.6147551227516, 2116.291042500764, 2126.851120421571, 2287.885605719707, 2384.640754769782, 9254.398464099879, 9404.764880877965, 9789.657361992367, 9878.950605246999, 9906.653267135616, 1658.328377007302, 1667.7831881855402, 1679.5633135384887, 1701.4648471409905, 1744.1802352894247, 82534.0, 105460.0, 123939.0, 126746.0, 128296.35851820775, 11823.172377933633, 11859.70350741221, 11862.695103538605, 11903.464344104535, 11908.225965312227, 15307.92346038285, 15392.538223853302, 15412.922935385252, 15413.0, 15437.614059648598, 6508.576942498722, 8811.118888111137, 8858.64565417376, 8929.776755581033, 8982.820343593223, 8769.079246679134, 8774.736757897273, 8787.077356877437, 8884.555772211364, 8953.910460895439, 4558.407407037064, 4675.836345727883, 4675.95324046762, 4681.250999840009, 4709.764511774398, 1686.0979376033763, 1701.829817018313, 1764.7705798246145, 1828.8628352873322, 1919.1747548553976, 875.1336177184542, 889.7908991387019, 916.867054277126, 936.7611259128879, 970.7039352997263, 19139.2344306229, 20109.899450502948, 20111.899440502704, 20139.697904531444, 20686.379408617766, 1001.5342865567546, 1003.372891942536, 1005.6066561583531, 1011.5751384418562, 1036.300497164411, 10187.439690817077, 10784.40685762275, 10797.892021079846, 10802.891971080346, 10877.83683244752, 550767.0, 558769.0, 567641.0, 648984.0, 652388.0, 133252.0, 136828.0, 142202.57797421893, 142397.0, 152502.0, 958.5255298627363, 1012.9696109116738, 1046.9581216751396, 1059.3749687684149, 1066.290916540492, 14076.718465630835, 18268.543286417946, 18429.81570184281, 19083.0, 19116.23535058582, 53444.390394027105, 53873.191902120714, 53965.460345396044, 54652.726736366065, 55040.42859268908, 75951.0, 83163.5841820787, 86523.0, 86855.56572217098, 87188.5640571793, 5699.715014249354, 8084.191580841869, 8112.716054938049, 8293.377996650155, 8390.244877961013, 11488.770224595628, 12664.113512054068, 12823.294718790556, 13045.0, 13143.211407315592, 11201.943990279997, 11394.145439092266, 11494.367809770209, 11531.596394126165, 11534.59628912984, 9503.382280151767, 10881.619143329945, 10959.06847917936, 11155.776884462428, 11243.156763242952, 11285.153613478858, 11536.884631153582, 11668.70828229301, 11804.527818887493, 11810.940945295219, 8097.352211823158, 8343.249107580346, 8404.747857564284, 8424.283935865275, 8655.17775811297, 10107.292489525662, 11228.0, 11476.311421314414, 11534.423278836193, 11573.247738896946], "value": 9515.995369008499, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1422.6372275069855, 1597.888147829652, 1613.9757903631444, 1861.404350607805, 1970.4482744831444, 7916.722914697987, 8722.040575536686, 8931.955340223307, 8942.776430589238, 8947.64209431622, 8580.570971451418, 8781.077986811388, 8782.341324400664, 8787.868181977276, 8789.165029322205, 2436.902523899042, 2463.1255904154036, 2464.149868295435, 2467.4324905271797, 2474.319562120413, 2400.8919598618018, 2463.224084413413, 2465.6055031195015, 2468.259522143359, 2473.802095832338, 2425.9393515162174, 2448.4490989527394, 2461.3754921751647, 2466.7533246675366, 2474.9628755568683, 2087.5198704298023, 2395.185636883468, 2433.6957880264968, 2435.0625009371342, 2444.828861979662, 2396.484755777499, 2410.7950824179966, 2424.139430502164, 2425.357280320716, 2426.5632186206476, 8752.956235218848, 8800.955995220047, 8864.822703545893, 8872.290216782641, 8895.955520222358, 5274.182501712241, 5446.074167391553, 5466.0, 5484.780608775644, 5494.80768173116, 3473.426884564069, 3567.554055743032, 3623.2391197848483, 3647.1611529348397, 3665.816709164531, 1014.0721240065332, 1024.4519182237468, 1029.5161274201114, 1029.9073083422527, 1043.2123746571367, 11446.198766086378, 11544.365059921702, 11762.353070581115, 11795.997340226091, 11858.229215101077, 4590.540945905383, 4599.264117741145, 4694.460137084279, 4724.740139292372, 4730.621550276039, 9316.301277404222, 9317.90682093184, 9329.393589416666, 9333.90666093344, 9392.966773654893, 5242.97378513105, 5296.496832800878, 5303.36359636846, 5346.492083252085, 5354.518093371616, 4441.689081764245, 4604.493505714368, 4808.206645903457, 4914.262860570871, 4962.900741985212, 3793.108619474421, 3803.030227292023, 3857.40210267409, 3885.9611403886165, 3909.667678247324, 1200.4417945655312, 1201.2312120243053, 1203.0676225924979, 1214.732758793064, 1216.8235605837103, 646.4182235987555, 804.1958041958042, 903.7153296711515, 950.0831697411893, 957.5403806173076, 5615.157726341082, 5777.942220577825, 5784.971075144597, 5920.082387229986, 6019.2175017248355, 474.92638641010006, 485.1437213318496, 486.6228672778554, 508.67953189490385, 657.9177253418196, 3720.069982504374, 3902.3951287550476, 3924.136689928211, 3989.2420440116866, 4025.054112283611, 10423.322484038514, 10817.188710846718, 10905.672829815117, 11043.779124417466, 11069.0, 10868.0, 10894.964978327047, 10905.073068789241, 11006.284591501526, 11020.944895275632, 7810.414218933603, 8199.836003280021, 8277.461964972257, 8284.792880178044, 8298.875516867252, 5615.0, 5714.79998200061, 5860.648361098348, 5880.913987888637, 6056.636601803906, 9841.114299713061, 10061.295709300279, 10115.848262275924, 10260.333078349884, 10270.486475676036, 8680.392372533863, 9985.900140998498, 10209.38743675382, 10247.59009639636, 10346.586136554453, 6463.838404039937, 7560.130584982798, 7658.042744656918, 7677.8464430709955, 7688.923110768821, 8212.753617391489, 8294.460860044319, 8324.875126872981, 8387.370947178864, 8390.0, 7500.62496875165, 7516.060492438445, 7532.13380461232, 7636.389088872988, 7663.846723065619, 7312.597807120446, 7412.96293518529, 7452.217517160837, 7482.176960534228, 7500.0, 6842.486813489108, 7066.823329416806, 7126.251743566851, 7155.212926577969, 7157.606331651809, 4800.591949684316, 4803.255495398219, 4878.390201224847, 4914.533119353656, 4951.0, 4931.950680493149, 4975.676581022222, 5024.120778863758, 5033.194688849913, 5154.768035438489], "value": 4524.356852408242, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [280.6758194285598, 309.0835672231832, 318.6287974509696, 327.2767184522205, 327.394320507062, 4260.40234911908, 4654.674172807905, 5024.799008039683, 5310.20346947958, 5424.403315635277, 4507.842225522099, 5161.458046905077, 5358.839234822952, 5370.516653501184, 5417.322834645669, 3769.831352280788, 3807.0101773538872, 4041.8585349512773, 4141.544430112685, 4149.46057012588, 3586.7668601540818, 3889.688824894016, 4023.275810354135, 4132.834686612532, 4188.141431006637, 3699.408094704848, 4139.56534563871, 4155.771432571208, 4173.56177601352, 4180.937285940713, 3487.6686714762186, 3771.41543060826, 4054.493188351456, 4141.8964525886795, 4154.376843473503, 3585.2470981094, 3615.837287322077, 3624.9093772655633, 3629.2922880038404, 3639.727020473475, 4258.87223383299, 4877.489125663432, 5102.642815002914, 5119.411267704188, 5166.715830629314, 3998.4801975743258, 4393.231184542724, 4605.976970115162, 4724.5984091352275, 4758.738269395182, 4855.854324370274, 4910.803567857317, 5386.649867758583, 5611.186377975171, 5620.358431537212, 3181.5704879841387, 3645.927081458356, 3890.708196885245, 3912.2762288976346, 4017.3773065174946, 5869.767348856745, 5893.764249430018, 5909.881802363929, 5930.1994230779155, 5971.731272092767, 3285.720713739311, 3530.7881527108457, 3964.32606456903, 3980.800959951991, 3984.442178095069, 3826.4260360945523, 3990.301697203036, 3995.1210733638554, 4032.971592243959, 4085.4484644572603, 3902.414637804295, 4630.652701047435, 4658.487566367697, 4676.392069031072, 4705.6470764692785, 4811.446683631357, 5674.744636491368, 5681.403452637496, 5682.886342273131, 5727.70891643346, 1306.6341424401182, 1375.5116933488468, 1432.641839540115, 1460.1531111955042, 1484.094702231638, 949.8290307744672, 969.3650658818485, 970.2965350121106, 971.3103696375653, 978.5205249427871, 4803.351547541037, 4828.37231159954, 5690.174924635905, 5696.493542841136, 5722.971385143048, 5661.46216109469, 6009.489193418521, 6133.570650054453, 6363.236411630634, 6386.07401926718, 5655.688937108416, 5681.857953551194, 5690.5163061140265, 5706.229658996065, 5749.7700091996685, 3015.010093338386, 3083.5683004379407, 3163.975055964185, 3299.86800527981, 3356.580427446569, 5359.812406565751, 6097.420745029215, 6607.933920660732, 6616.569922955089, 6639.236487803964, 6458.0, 6492.64290464029, 6521.217453905561, 6559.344065593498, 6610.603363798187, 5180.870478238075, 5442.91835622466, 6170.5372097092, 6581.643361885377, 6582.80251592453, 6279.403456671609, 6287.748490060347, 6292.370762923671, 6307.463865571478, 6365.204349456318, 5266.499682530153, 5965.403459654, 6195.287541932736, 6239.126522286974, 6268.686565671607, 5194.610404219623, 5484.917726234189, 6053.75784968614, 6548.148740663683, 6564.179477565304, 3826.617338266151, 4009.178118485762, 4044.5348784890116, 4220.366944958281, 4226.281532139482, 4857.489963553918, 5254.474552544715, 5608.523275521627, 5733.792489891339, 6349.238091429058, 4780.545848144421, 5586.6033491627095, 5588.6646801192055, 5633.6056476047925, 5648.406917273627, 4800.927986080282, 5170.146925757358, 5474.5894057945015, 5598.972005139948, 5599.804006859903, 4621.752126925644, 5135.281060651587, 5509.201165830852, 5519.31008623922, 5566.693831839288, 4305.729809706207, 5376.951494458543, 5557.694326812064, 5606.943930560642, 5640.887182256414, 4485.0229113357345, 5483.424240454854, 5530.451473587402, 5541.085720856014, 5550.888982220414], "value": 4212.135255672437, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [656.8653426047659, 661.4047357378362, 670.1890712238195, 683.1221879884348, 750.1073722270494, 14999.40002399904, 15369.462068827594, 15442.150681712501, 15447.845521544808, 15459.61350966225, 14036.438542458289, 14042.438302467914, 14091.295435228249, 14094.154350738963, 14118.858811411883, 737.8782500887347, 764.1861417590248, 915.0529202275646, 928.8235235305305, 969.7721035556639, 932.4917919733762, 948.9193418559431, 949.5299826585815, 952.0527075559828, 955.3264948211529, 1002.16319373323, 1002.6039714312842, 1003.859459675646, 1007.5113569918584, 1008.3596915958383, 776.8523980443725, 807.4670717326595, 807.9617691266694, 808.6967387229789, 994.5922171909518, 704.1550139832221, 743.1156923261302, 745.2882497215146, 749.4678778067579, 750.0624219725344, 13883.4446622136, 13965.930170349084, 14010.649733756636, 14061.929690351482, 14101.365438555187, 12285.508579656804, 12370.505179792797, 12447.377631118408, 12540.937295313464, 12643.988480921596, 10724.571017159304, 10831.241813073088, 11093.445327733582, 11319.264247823865, 11694.532218711242, 4090.325096359097, 4106.650934670557, 4147.502299724052, 4189.895252618679, 4227.87316380509, 80922.0, 80957.0, 81646.18353816445, 84462.57768711179, 84940.0, 9696.806063878683, 9932.155766759906, 9952.253580981456, 9977.900220997843, 10251.28241023136, 22159.778402216092, 22675.092996279964, 22699.773002269765, 22716.772832271796, 22724.0, 7655.2344765523, 7673.923260767432, 7756.806079848049, 7769.572673502898, 7857.567833769198, 4037.7375470594907, 4058.9797051014557, 4096.40602112698, 4123.463949686581, 4202.810873510699, 3539.964600354015, 3630.219502806909, 3662.5238718966366, 3665.578458477309, 3669.2478042001326, 547.9205515200354, 652.1066139389068, 675.2268652392983, 676.7800464848943, 700.4571457120772, 524.0305434945396, 524.1953601222106, 578.1068249554448, 595.6396380190009, 604.8941435248814, 7637.5035622684345, 8108.513489190667, 8139.267465928095, 8160.061592916772, 8186.0382074628915, 476.6710969431081, 564.4383838081136, 575.8733078722674, 589.7758851636437, 593.7387549478302, 3769.7738135711943, 3775.5469343678933, 3938.980305098456, 3995.320795464778, 4013.7190396671954, 100052.49973750231, 105204.47397762962, 105530.0, 107412.0, 107824.8435031295, 61493.077603835976, 76283.47433051308, 78615.03462413486, 87174.69237961435, 87803.56098219469, 65582.34417655763, 65851.0, 66537.33656658526, 67777.0, 77965.61017194878, 7944.364450843803, 8035.749990002809, 8056.476329038594, 8079.3536517079665, 8264.0, 35331.646683533865, 38103.04742381463, 38626.6137338623, 43230.351544727484, 44046.0, 59073.81852363014, 62062.0, 62903.6854815723, 63006.684966574874, 63108.0, 10834.891651083388, 10929.836052459379, 10992.17558683086, 11196.832047519456, 11411.97292243702, 11702.180847340946, 11888.583899563473, 12072.939635301767, 12197.51209951592, 12291.69270768238, 11232.943835280772, 11270.492827822603, 11474.254173478697, 11541.538338466367, 11966.0, 11193.72015699614, 11308.321500709984, 11436.828447573127, 11573.098485149601, 12132.75734485323, 9064.63741450361, 11213.551457941918, 11289.887101128883, 11330.37682927447, 11642.301461912313, 9101.089891010846, 9217.032211617952, 9251.0, 9465.621375144918, 9688.612455501701, 9201.241042037105, 9395.248380129437, 9467.905320946702, 9477.0, 9655.131038206595], "value": 6906.394339411602, "unit": "score"}], "name": "dromaeo_css", "value": 7156.346346976836, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 108.0, 115.0, 114.0, 116.0, 114.0, 115.0, 105.0, 115.0], "value": 113.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 123.0, 156.0, 160.0, 148.0, 170.0, 166.0, 121.0, 173.0, 121.0], "value": 149.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [251.0, 252.0, 186.0, 188.0, 183.0, 183.0, 187.0, 187.0, 184.0, 186.0], "value": 198.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 78.0, 74.0, 153.0, 57.0, 55.0, 56.0, 65.0, 134.0, 56.0], "value": 80.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 138.0, 77.0, 80.0, 88.0, 123.0, 79.0, 76.0, 121.0], "value": 95.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 91.0, 85.0, 92.0, 130.0, 133.0, 87.0, 89.0, 85.0], "value": 96.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0, 93.0, 100.0], "value": 94.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 126.0, 133.0, 122.0, 85.0, 84.0, 84.0, 83.0, 84.0, 85.0], "value": 97.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 63.0, 66.0, 63.0, 65.0, 65.0, 63.0, 81.0, 59.0, 59.0], "value": 64.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 67.0, 72.0, 78.0, 75.0, 70.0, 69.0, 68.0, 68.0], "value": 69.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 82.0, 81.0, 82.0, 80.0, 80.0, 79.0, 83.0, 85.0], "value": 81.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 138.0, 134.0, 135.0, 135.0, 137.0, 136.0, 135.0, 174.0, 139.0], "value": 140.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [204.0, 220.0, 189.0, 168.0, 169.0, 169.0, 174.0, 172.0, 166.0, 166.0], "value": 179.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 92.0, 95.0, 78.0, 68.0, 68.0, 67.0, 75.0, 69.0, 56.0], "value": 74.0, "unit": "score"}], "name": "kraken", "value": 1534.2, "alertThreshold": 2.0}]} 11:08:33 INFO - Return code: 0 11:08:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 11:08:33 ERROR - # TBPL SUCCESS # 11:08:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:08:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:08:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:08:33 INFO - 2016-05-05 11:08:33.901 screenresolution[2091:14913] starting screenresolution argv=screenresolution get 11:08:33 INFO - 2016-05-05 11:08:33.904 screenresolution[2091:14913] Display 0: 1600x1200x32@60 11:08:33 INFO - 2016-05-05 11:08:33.910 screenresolution[2092:14916] starting screenresolution argv=screenresolution list 11:08:33 INFO - Available Modes on Display 0 11:08:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:08:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:08:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:08:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:08:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:08:34 INFO - Intel Iris: 11:08:34 INFO - Chipset Model: Intel Iris 11:08:34 INFO - Type: GPU 11:08:34 INFO - Bus: Built-In 11:08:34 INFO - VRAM (Dynamic, Max): 1536 MB 11:08:34 INFO - Vendor: Intel (0x8086) 11:08:34 INFO - Device ID: 0x0a2e 11:08:34 INFO - Revision ID: 0x0009 11:08:34 INFO - Displays: 11:08:34 INFO - Display: 11:08:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:08:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:08:34 INFO - Display Serial Number: 1600x1200 60 11:08:34 INFO - Main Display: Yes 11:08:34 INFO - Mirror: Off 11:08:34 INFO - Online: Yes 11:08:34 INFO - Rotation: Supported 11:08:34 INFO - Return code: 0 11:08:34 INFO - Running post-action listener: _resource_record_post_action 11:08:34 INFO - Running post-run listener: _resource_record_post_run 11:08:35 INFO - Total resource usage - Wall time: 1208s; CPU: 53.0%; Read bytes: 30690816; Write bytes: 653816832; Read time: 308; Write time: 1892 11:08:35 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 344570368; Write bytes: 345495552; Read time: 26480; Write time: 716 11:08:35 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 30690816; Write bytes: 297376768; Read time: 308; Write time: 1134 11:08:35 INFO - Running post-run listener: _upload_blobber_files 11:08:35 INFO - Blob upload gear active. 11:08:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:08:35 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 11:08:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:08:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:08:35 INFO - (blobuploader) - INFO - Open directory for files ... 11:08:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:08:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:08:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:08:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:08:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:08:36 INFO - (blobuploader) - INFO - Done attempting. 11:08:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:08:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:08:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:08:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:08:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:08:37 INFO - (blobuploader) - INFO - Done attempting. 11:08:37 INFO - (blobuploader) - INFO - Iteration through files over. 11:08:37 INFO - Return code: 0 11:08:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:08:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:08:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/22545599e8acbf50e4d54e3586169bf60290db613ca0e90d4366539ea31309f3f67a7ed4e94464a5c14eafc3572ef751f4f09022655bf40f698d18ce46bad5a5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/eb8aff2efa138bd7d7104cdeb778f31aa1c993658adb0d1be226a4e32b19ca9633a4385d025625d92fe1499f489db14be956d2bf4a13d62a1f5e77088a4ba665"} 11:08:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:08:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:08:37 INFO - Contents: 11:08:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/22545599e8acbf50e4d54e3586169bf60290db613ca0e90d4366539ea31309f3f67a7ed4e94464a5c14eafc3572ef751f4f09022655bf40f698d18ce46bad5a5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/eb8aff2efa138bd7d7104cdeb778f31aa1c993658adb0d1be226a4e32b19ca9633a4385d025625d92fe1499f489db14be956d2bf4a13d62a1f5e77088a4ba665"} 11:08:37 INFO - Running post-run listener: copy_logs_to_upload_dir 11:08:37 INFO - Copying logs to upload dir... 11:08:37 INFO - mkdir: /builds/slave/test/build/upload/logs 11:08:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1279.240552 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-05-05 11:08:37.818917) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-05 11:08:37.822782) ========= 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.nHb89xxSIj/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.AWczaWmR3U/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/22545599e8acbf50e4d54e3586169bf60290db613ca0e90d4366539ea31309f3f67a7ed4e94464a5c14eafc3572ef751f4f09022655bf40f698d18ce46bad5a5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/eb8aff2efa138bd7d7104cdeb778f31aa1c993658adb0d1be226a4e32b19ca9633a4385d025625d92fe1499f489db14be956d2bf4a13d62a1f5e77088a4ba665"} build_url:https://queue.taskcluster.net/v1/task/ew0IjzvOTwi3yb4RVPuxRw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011308 build_url: 'https://queue.taskcluster.net/v1/task/ew0IjzvOTwi3yb4RVPuxRw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/22545599e8acbf50e4d54e3586169bf60290db613ca0e90d4366539ea31309f3f67a7ed4e94464a5c14eafc3572ef751f4f09022655bf40f698d18ce46bad5a5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/eb8aff2efa138bd7d7104cdeb778f31aa1c993658adb0d1be226a4e32b19ca9633a4385d025625d92fe1499f489db14be956d2bf4a13d62a1f5e77088a4ba665"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-05 11:08:37.851674) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 11:08:37.852021) ========= 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.nHb89xxSIj/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.AWczaWmR3U/Listeners TMPDIR=/var/folders/6x/fp43dmc567bb9pghsl0yp6g400000w/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.005168 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 11:08:37.902768) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-05 11:08:37.903079) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-05 11:08:37.903391) ========= ========= Total master_lag: 0.16 =========