builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0084 starttime: 1461365256.62 results: success (0) buildid: 20160422142650 builduid: 0e0a78d064b14b7f8537b3303394d2df revision: 922334c70324e4295dcfcfafe6da4ae2e1154834 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-22 15:47:36.616545) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-22 15:47:36.616942) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-22 15:47:36.617233) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-22 15:47:36.640259) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 15:47:36.640610) ========= 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.oIyrKS3VOT/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.Me1zZKH9gk/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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-04-22 15:47:36-- 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% 16.4M=0.001s 2016-04-22 15:47:36 (16.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.133824 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 15:47:36.792345) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 15:47:36.792712) ========= 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.oIyrKS3VOT/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.Me1zZKH9gk/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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.031144 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 15:47:36.847397) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 15:47:36.847776) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 922334c70324e4295dcfcfafe6da4ae2e1154834 --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 922334c70324e4295dcfcfafe6da4ae2e1154834 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.oIyrKS3VOT/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.Me1zZKH9gk/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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-04-22 15:47:36,923 truncating revision to first 12 chars 2016-04-22 15:47:36,923 Setting DEBUG logging. 2016-04-22 15:47:36,923 attempt 1/10 2016-04-22 15:47:36,923 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/922334c70324?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-22 15:47:37,163 unpacking tar archive at: mozilla-aurora-922334c70324/testing/mozharness/ program finished with exit code 0 elapsedTime=0.489524 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 15:47:37.360134) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-22 15:47:37.360530) ========= script_repo_revision: 922334c70324e4295dcfcfafe6da4ae2e1154834 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-22 15:47:37.360951) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-22 15:47:37.361315) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-22 15:47:37.377218) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-04-22 15:47:37.377628) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.oIyrKS3VOT/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.Me1zZKH9gk/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 15:47:37 INFO - MultiFileLogger online at 20160422 15:47:37 in /builds/slave/test 15:47:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 15:47:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:47:37 INFO - {'append_to_log': False, 15:47:37 INFO - 'base_work_dir': '/builds/slave/test', 15:47:37 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 15:47:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:47:37 INFO - 'branch': 'Mozilla-Aurora', 15:47:37 INFO - 'buildbot_json_path': 'buildprops.json', 15:47:37 INFO - 'config_files': ('talos/mac_config.py',), 15:47:37 INFO - 'default_actions': ('clobber', 15:47:37 INFO - 'read-buildbot-config', 15:47:37 INFO - 'download-and-extract', 15:47:37 INFO - 'populate-webroot', 15:47:37 INFO - 'create-virtualenv', 15:47:37 INFO - 'install', 15:47:37 INFO - 'run-tests'), 15:47:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:47:37 INFO - 'download_minidump_stackwalk': True, 15:47:37 INFO - 'download_symbols': 'ondemand', 15:47:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:47:37 INFO - 'tooltool.py': '/tools/tooltool.py', 15:47:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:47:37 INFO - '/tools/misc-python/virtualenv.py')}, 15:47:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:47:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:47:37 INFO - 'installer_path': 'installer.exe', 15:47:37 INFO - 'log_level': 'info', 15:47:37 INFO - 'log_name': 'talos', 15:47:37 INFO - 'log_to_console': True, 15:47:37 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:47:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:47:37 INFO - 'opt_config_files': (), 15:47:37 INFO - 'pip_index': False, 15:47:37 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:47:37 INFO - 'cmd': ('bash', 15:47:37 INFO - '-c', 15:47:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:47:37 INFO - 'enabled': True, 15:47:37 INFO - 'halt_on_failure': False, 15:47:37 INFO - 'name': 'check_screen_resolution'},), 15:47:37 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:47:37 INFO - 'cmd': ('bash', 15:47:37 INFO - '-c', 15:47:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:47:37 INFO - 'enabled': True, 15:47:37 INFO - 'halt_on_failure': False, 15:47:37 INFO - 'name': 'check_screen_resolution'},), 15:47:37 INFO - 'run_cmd_checks_enabled': True, 15:47:37 INFO - 'sps_profile': False, 15:47:37 INFO - 'sps_profile_interval': 0, 15:47:37 INFO - 'suite': 'dromaeojs-e10s', 15:47:37 INFO - 'system_bits': '32', 15:47:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:47:37 INFO - 'title': 't-yosemite-r7-0084', 15:47:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:47:37 INFO - 'use_talos_json': True, 15:47:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:47:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:47:37 INFO - 'work_dir': 'build'} 15:47:37 INFO - ##### 15:47:37 INFO - ##### Running clobber step. 15:47:37 INFO - ##### 15:47:37 INFO - Running pre-action listener: _resource_record_pre_action 15:47:37 INFO - Running main action method: clobber 15:47:37 INFO - rmtree: /builds/slave/test/build 15:47:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:47:39 INFO - Running post-action listener: _resource_record_post_action 15:47:39 INFO - ##### 15:47:39 INFO - ##### Running read-buildbot-config step. 15:47:39 INFO - ##### 15:47:39 INFO - Running pre-action listener: _resource_record_pre_action 15:47:39 INFO - Running main action method: read_buildbot_config 15:47:39 INFO - Using buildbot properties: 15:47:39 INFO - { 15:47:39 INFO - "project": "", 15:47:39 INFO - "product": "firefox", 15:47:39 INFO - "script_repo_revision": "production", 15:47:39 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 15:47:39 INFO - "repository": "", 15:47:39 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 15:47:39 INFO - "buildid": "20160422142650", 15:47:39 INFO - "pgo_build": "False", 15:47:39 INFO - "basedir": "/builds/slave/test", 15:47:39 INFO - "buildnumber": 286, 15:47:39 INFO - "slavename": "t-yosemite-r7-0084", 15:47:39 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 15:47:39 INFO - "platform": "yosemite_r7", 15:47:39 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 15:47:39 INFO - "branch": "mozilla-aurora", 15:47:39 INFO - "repo_path": "releases/mozilla-aurora", 15:47:39 INFO - "revision": "922334c70324e4295dcfcfafe6da4ae2e1154834", 15:47:39 INFO - "stage_platform": "macosx64", 15:47:39 INFO - "builduid": "0e0a78d064b14b7f8537b3303394d2df", 15:47:39 INFO - "slavebuilddir": "test" 15:47:39 INFO - } 15:47:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/WMriI2IhRtGQ_iEYFHgolw/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg. 15:47:39 INFO - Running post-action listener: _resource_record_post_action 15:47:39 INFO - ##### 15:47:39 INFO - ##### Running download-and-extract step. 15:47:39 INFO - ##### 15:47:39 INFO - Running pre-action listener: _resource_record_pre_action 15:47:39 INFO - Running main action method: download_and_extract 15:47:39 INFO - mkdir: /builds/slave/test/build/tests 15:47:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:47:39 INFO - https://queue.taskcluster.net/v1/task/WMriI2IhRtGQ_iEYFHgolw/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 15:47:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WMriI2IhRtGQ_iEYFHgolw/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 15:47:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WMriI2IhRtGQ_iEYFHgolw/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 15:47:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WMriI2IhRtGQ_iEYFHgolw/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 15:47:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WMriI2IhRtGQ_iEYFHgolw/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json'}, attempt #1 15:47:41 INFO - Downloaded 1277 bytes. 15:47:41 INFO - Reading from file /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 15:47:41 INFO - Using the following test package requirements: 15:47:41 INFO - {u'common': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 15:47:41 INFO - u'cppunittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 15:47:41 INFO - u'firefox-47.0a2.en-US.mac.cppunittest.tests.zip'], 15:47:41 INFO - u'gtest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 15:47:41 INFO - u'firefox-47.0a2.en-US.mac.gtest.tests.zip'], 15:47:41 INFO - u'jittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 15:47:41 INFO - u'jsshell-mac.zip'], 15:47:41 INFO - u'mochitest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 15:47:41 INFO - u'firefox-47.0a2.en-US.mac.mochitest.tests.zip'], 15:47:41 INFO - u'mozbase': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 15:47:41 INFO - u'reftest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 15:47:41 INFO - u'firefox-47.0a2.en-US.mac.reftest.tests.zip'], 15:47:41 INFO - u'talos': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 15:47:41 INFO - u'firefox-47.0a2.en-US.mac.talos.tests.zip'], 15:47:41 INFO - u'web-platform': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 15:47:41 INFO - u'firefox-47.0a2.en-US.mac.web-platform.tests.zip'], 15:47:41 INFO - u'webapprt': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 15:47:41 INFO - u'xpcshell': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 15:47:41 INFO - u'firefox-47.0a2.en-US.mac.xpcshell.tests.zip']} 15:47:41 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip'] for test suite category: common 15:47:41 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')]} 15:47:41 INFO - https://queue.taskcluster.net/v1/task/WMriI2IhRtGQ_iEYFHgolw/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:47:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WMriI2IhRtGQ_iEYFHgolw/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 15:47:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WMriI2IhRtGQ_iEYFHgolw/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 15:47:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WMriI2IhRtGQ_iEYFHgolw/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 15:47:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WMriI2IhRtGQ_iEYFHgolw/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 15:47:43 INFO - Downloaded 17917533 bytes. 15:47:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:47:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 15:47:46 INFO - Return code: 0 15:47:46 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip', u'firefox-47.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 15:47:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:47:46 INFO - https://queue.taskcluster.net/v1/task/WMriI2IhRtGQ_iEYFHgolw/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:47:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WMriI2IhRtGQ_iEYFHgolw/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 15:47:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WMriI2IhRtGQ_iEYFHgolw/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 15:47:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WMriI2IhRtGQ_iEYFHgolw/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 15:47:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WMriI2IhRtGQ_iEYFHgolw/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 15:47:48 INFO - Downloaded 17917533 bytes. 15:47:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:47:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 15:47:52 INFO - Return code: 0 15:47:52 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')]} 15:47:52 INFO - https://queue.taskcluster.net/v1/task/WMriI2IhRtGQ_iEYFHgolw/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 15:47:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WMriI2IhRtGQ_iEYFHgolw/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 15:47:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WMriI2IhRtGQ_iEYFHgolw/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 15:47:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WMriI2IhRtGQ_iEYFHgolw/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip 15:47:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WMriI2IhRtGQ_iEYFHgolw/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip'}, attempt #1 15:47:53 INFO - Downloaded 10845198 bytes. 15:47:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:47:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 15:47:53 INFO - Return code: 0 15:47:53 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')]} 15:47:53 INFO - https://queue.taskcluster.net/v1/task/WMriI2IhRtGQ_iEYFHgolw/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 15:47:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WMriI2IhRtGQ_iEYFHgolw/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 15:47:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WMriI2IhRtGQ_iEYFHgolw/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 15:47:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WMriI2IhRtGQ_iEYFHgolw/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg 15:47:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WMriI2IhRtGQ_iEYFHgolw/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg'}, attempt #1 15:48:00 INFO - Downloaded 115767620 bytes. 15:48:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WMriI2IhRtGQ_iEYFHgolw/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 15:48:00 INFO - mkdir: /builds/slave/test/properties 15:48:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:48:00 INFO - Writing to file /builds/slave/test/properties/build_url 15:48:00 INFO - Contents: 15:48:00 INFO - build_url:https://queue.taskcluster.net/v1/task/WMriI2IhRtGQ_iEYFHgolw/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 15:48:01 INFO - Running post-action listener: _resource_record_post_action 15:48:01 INFO - Running post-action listener: set_extra_try_arguments 15:48:01 INFO - ##### 15:48:01 INFO - ##### Running populate-webroot step. 15:48:01 INFO - ##### 15:48:01 INFO - Running pre-action listener: _resource_record_pre_action 15:48:01 INFO - Running main action method: populate_webroot 15:48:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:48:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:48:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:48:01 INFO - '--tppagecycles', 15:48:01 INFO - '7'], 15:48:01 INFO - 'tests': ['tsvgm']}}, 15:48:01 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 15:48:01 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 15:48:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:48:01 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 15:48:01 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 15:48:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:48:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:48:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:48:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:48:01 INFO - 'talos_options': ['--e10s'], 15:48:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:48:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:48:01 INFO - 'tests': ['damp', 'tps']}, 15:48:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:48:01 INFO - 'talos_options': ['--e10s'], 15:48:01 INFO - 'tests': ['damp', 'tps']}, 15:48:01 INFO - 'g3': {'tests': ['dromaeo_dom']}, 15:48:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:48:01 INFO - 'other': {'tests': ['a11yr', 15:48:01 INFO - 'ts_paint', 15:48:01 INFO - 'tpaint', 15:48:01 INFO - 'sessionrestore', 15:48:01 INFO - 'sessionrestore_no_auto_restore', 15:48:01 INFO - 'tabpaint']}, 15:48:01 INFO - 'other-e10s': {'talos_options': ['--e10s'], 15:48:01 INFO - 'tests': ['a11yr', 15:48:01 INFO - 'ts_paint', 15:48:01 INFO - 'tpaint', 15:48:01 INFO - 'sessionrestore', 15:48:01 INFO - 'sessionrestore_no_auto_restore', 15:48:01 INFO - 'tabpaint']}, 15:48:01 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 15:48:01 INFO - 'tests': ['a11yr', 15:48:01 INFO - 'ts_paint', 15:48:01 INFO - 'tpaint', 15:48:01 INFO - 'sessionrestore', 15:48:01 INFO - 'sessionrestore_no_auto_restore', 15:48:01 INFO - 'tabpaint']}, 15:48:01 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 15:48:01 INFO - 'tests': ['a11yr', 15:48:01 INFO - 'ts_paint', 15:48:01 INFO - 'tpaint', 15:48:01 INFO - 'sessionrestore', 15:48:01 INFO - 'sessionrestore_no_auto_restore', 15:48:01 INFO - 'tabpaint']}, 15:48:01 INFO - 'other_l64': {'tests': ['a11yr', 15:48:01 INFO - 'ts_paint', 15:48:01 INFO - 'tpaint', 15:48:01 INFO - 'sessionrestore', 15:48:01 INFO - 'sessionrestore_no_auto_restore', 15:48:01 INFO - 'tabpaint']}, 15:48:01 INFO - 'other_nol64': {'tests': ['a11yr', 15:48:01 INFO - 'ts_paint', 15:48:01 INFO - 'tpaint', 15:48:01 INFO - 'sessionrestore', 15:48:01 INFO - 'sessionrestore_no_auto_restore', 15:48:01 INFO - 'tabpaint']}, 15:48:01 INFO - 'svgr': {'tests': ['tsvgx', 15:48:01 INFO - 'tsvgr_opacity', 15:48:01 INFO - 'tart', 15:48:01 INFO - 'tscrollx', 15:48:01 INFO - 'cart']}, 15:48:01 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 15:48:01 INFO - 'tests': ['tsvgx', 15:48:01 INFO - 'tsvgr_opacity', 15:48:01 INFO - 'tart', 15:48:01 INFO - 'tscrollx', 15:48:01 INFO - 'cart']}, 15:48:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:48:01 INFO - 'tests': ['tp5o']}, 15:48:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:48:01 INFO - 'talos_options': ['--e10s'], 15:48:01 INFO - 'tests': ['tp5o']}, 15:48:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:48:01 INFO - 'talos_options': ['--xperf_path', 15:48:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:48:01 INFO - 'tests': ['tp5n']}, 15:48:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:48:01 INFO - 'talos_options': ['--e10s', 15:48:01 INFO - '--xperf_path', 15:48:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:48:01 INFO - 'tests': ['tp5n']}}, 15:48:01 INFO - 'talos.zip': {'path': '', 15:48:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:48:01 INFO - Running post-action listener: _resource_record_post_action 15:48:01 INFO - ##### 15:48:01 INFO - ##### Running create-virtualenv step. 15:48:01 INFO - ##### 15:48:01 INFO - Running pre-action listener: _resource_record_pre_action 15:48:01 INFO - Running main action method: create_virtualenv 15:48:01 INFO - Creating virtualenv /builds/slave/test/build/venv 15:48:01 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:48:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:48:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:48:01 INFO - Using real prefix '/tools/python27' 15:48:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:48:02 INFO - Installing distribute.............................................................................................................................................................................................done. 15:48:05 INFO - Installing pip.................done. 15:48:05 INFO - Return code: 0 15:48:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:48:05 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')]} 15:48:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:05 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')]} 15:48:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:05 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 0x10f2cba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2d24c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6c85596d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f16fd30>, '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 0x10efd3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f166b20>, '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.oIyrKS3VOT/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.Me1zZKH9gk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:48:05 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 15:48:05 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 15:48:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oIyrKS3VOT/Render', 15:48:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:48:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:48:05 INFO - 'HOME': '/Users/cltbld', 15:48:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:48:05 INFO - 'LOGNAME': 'cltbld', 15:48:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:05 INFO - 'MOZ_NO_REMOTE': '1', 15:48:05 INFO - 'NO_EM_RESTART': '1', 15:48:05 INFO - 'PAGER': '/bin/cat', 15:48:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:48:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:05 INFO - 'PWD': '/builds/slave/test', 15:48:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:48:05 INFO - 'SHELL': '/bin/bash', 15:48:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Me1zZKH9gk/Listeners', 15:48:05 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 15:48:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:48:05 INFO - 'USER': 'cltbld', 15:48:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:48:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:48:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:05 INFO - 'XPC_FLAGS': '0x0', 15:48:05 INFO - 'XPC_SERVICE_NAME': '0', 15:48:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:48:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:05 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 15:48:05 INFO - Cleaning up... 15:48:05 INFO - Return code: 0 15:48:05 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 15:48:05 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')]} 15:48:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:05 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')]} 15:48:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:05 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 0x10f2cba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2d24c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6c85596d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f16fd30>, '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 0x10efd3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f166b20>, '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.oIyrKS3VOT/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.Me1zZKH9gk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:48:05 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 15:48:05 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 15:48:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oIyrKS3VOT/Render', 15:48:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:48:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:48:05 INFO - 'HOME': '/Users/cltbld', 15:48:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:48:05 INFO - 'LOGNAME': 'cltbld', 15:48:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:05 INFO - 'MOZ_NO_REMOTE': '1', 15:48:05 INFO - 'NO_EM_RESTART': '1', 15:48:05 INFO - 'PAGER': '/bin/cat', 15:48:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:48:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:05 INFO - 'PWD': '/builds/slave/test', 15:48:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:48:05 INFO - 'SHELL': '/bin/bash', 15:48:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Me1zZKH9gk/Listeners', 15:48:05 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 15:48:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:48:05 INFO - 'USER': 'cltbld', 15:48:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:48:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:48:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:05 INFO - 'XPC_FLAGS': '0x0', 15:48:05 INFO - 'XPC_SERVICE_NAME': '0', 15:48:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:48:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:05 INFO - Downloading/unpacking psutil>=0.7.1 15: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 15: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 15: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 15: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 15: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 15: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 15:48:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:48:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:48:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:48:08 INFO - Installing collected packages: psutil 15:48:08 INFO - Running setup.py install for psutil 15:48:08 INFO - building 'psutil._psutil_osx' extension 15:48:08 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 15:48:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 15:48:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 15:48:09 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 15:48:09 INFO - building 'psutil._psutil_posix' extension 15:48:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 15:48:09 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:48:09 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:48:09 INFO - ^ 15:48:09 INFO - 1 warning generated. 15:48:09 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 15:48:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:48:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:48:09 INFO - Successfully installed psutil 15:48:09 INFO - Cleaning up... 15:48:09 INFO - Return code: 0 15:48:09 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 15:48:09 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')]} 15:48:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:09 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')]} 15:48:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:09 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 0x10f2cba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2d24c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6c85596d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f16fd30>, '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 0x10efd3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f166b20>, '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.oIyrKS3VOT/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.Me1zZKH9gk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:48:09 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 15:48:09 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 15:48:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oIyrKS3VOT/Render', 15:48:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:48:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:48:09 INFO - 'HOME': '/Users/cltbld', 15:48:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:48:09 INFO - 'LOGNAME': 'cltbld', 15:48:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:09 INFO - 'MOZ_NO_REMOTE': '1', 15:48:09 INFO - 'NO_EM_RESTART': '1', 15:48:09 INFO - 'PAGER': '/bin/cat', 15:48:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:48:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:09 INFO - 'PWD': '/builds/slave/test', 15:48:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:48:09 INFO - 'SHELL': '/bin/bash', 15:48:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Me1zZKH9gk/Listeners', 15:48:09 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 15:48:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:48:09 INFO - 'USER': 'cltbld', 15:48:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:48:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:48:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:09 INFO - 'XPC_FLAGS': '0x0', 15:48:09 INFO - 'XPC_SERVICE_NAME': '0', 15:48:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:48:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:10 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 15:48:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:10 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 15:48:10 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 15:48:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:12 INFO - Downloading mozsystemmonitor-0.0.tar.gz 15:48:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:48:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 15:48:12 INFO - Installing collected packages: mozsystemmonitor 15:48:12 INFO - Running setup.py install for mozsystemmonitor 15:48:12 INFO - Successfully installed mozsystemmonitor 15:48:12 INFO - Cleaning up... 15:48:12 INFO - Return code: 0 15:48:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15: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')]} 15:48:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15: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')]} 15:48:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x10f2cba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2d24c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6c85596d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f16fd30>, '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 0x10efd3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f166b20>, '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.oIyrKS3VOT/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.Me1zZKH9gk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:48:12 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 15:48:12 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 15:48:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oIyrKS3VOT/Render', 15:48:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:48:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:48:12 INFO - 'HOME': '/Users/cltbld', 15:48:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:48:12 INFO - 'LOGNAME': 'cltbld', 15:48:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:12 INFO - 'MOZ_NO_REMOTE': '1', 15:48:12 INFO - 'NO_EM_RESTART': '1', 15:48:12 INFO - 'PAGER': '/bin/cat', 15:48:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:48:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:12 INFO - 'PWD': '/builds/slave/test', 15:48:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:48:12 INFO - 'SHELL': '/bin/bash', 15:48:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Me1zZKH9gk/Listeners', 15:48:12 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 15:48:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:48:12 INFO - 'USER': 'cltbld', 15:48:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:48:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:48:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:12 INFO - 'XPC_FLAGS': '0x0', 15:48:12 INFO - 'XPC_SERVICE_NAME': '0', 15:48:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:48:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:13 INFO - Downloading/unpacking blobuploader==1.2.4 15:48:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:13 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 15:48:13 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 15:48:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:15 INFO - Downloading blobuploader-1.2.4.tar.gz 15:48:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:48:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:48:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:15 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 15:48:15 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 15:48:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:48:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:48:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:16 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 15:48:16 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 15:48:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:16 INFO - Downloading docopt-0.6.1.tar.gz 15:48:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:48:16 INFO - Installing collected packages: blobuploader, requests, docopt 15:48:16 INFO - Running setup.py install for blobuploader 15:48:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:48:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:48:17 INFO - Running setup.py install for requests 15:48:17 INFO - Running setup.py install for docopt 15:48:17 INFO - Successfully installed blobuploader requests docopt 15:48:17 INFO - Cleaning up... 15:48:17 INFO - Return code: 0 15:48:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15: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')]} 15:48:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15: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')]} 15:48:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:17 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 0x10f2cba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2d24c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6c85596d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f16fd30>, '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 0x10efd3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f166b20>, '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.oIyrKS3VOT/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.Me1zZKH9gk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:48:17 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 15:48:17 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 15:48:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oIyrKS3VOT/Render', 15:48:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:48:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:48:17 INFO - 'HOME': '/Users/cltbld', 15:48:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:48:17 INFO - 'LOGNAME': 'cltbld', 15:48:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:17 INFO - 'MOZ_NO_REMOTE': '1', 15:48:17 INFO - 'NO_EM_RESTART': '1', 15:48:17 INFO - 'PAGER': '/bin/cat', 15:48:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:48:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:17 INFO - 'PWD': '/builds/slave/test', 15:48:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:48:17 INFO - 'SHELL': '/bin/bash', 15:48:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Me1zZKH9gk/Listeners', 15:48:17 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 15:48:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:48:17 INFO - 'USER': 'cltbld', 15:48:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:48:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:48:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:17 INFO - 'XPC_FLAGS': '0x0', 15:48:17 INFO - 'XPC_SERVICE_NAME': '0', 15:48:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:48:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:48:17 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-MlU32r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:48:18 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-Y4yPiJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:48:18 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-4JoFvn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:48:18 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-FmQeWT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:48:18 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-A7yQT2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:48:18 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-d46Gbq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:48:18 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-o906Ib-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:48:18 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-YaBIuI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:48:18 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-13UFjr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:48:18 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-JYK9z4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:48:19 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-JZgYQ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:48:19 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-p6SK_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:48:19 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-EJ5tFX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:48:19 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-1xn300-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:48:19 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-MfWx7J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:48:19 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-yYKcgh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:48:19 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-SzQewH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:48:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:48:19 INFO - Running setup.py install for manifestparser 15:48:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:48:19 INFO - Running setup.py install for mozcrash 15:48:20 INFO - Running setup.py install for mozdebug 15:48:20 INFO - Running setup.py install for mozdevice 15:48:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:48:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:48:20 INFO - Running setup.py install for mozfile 15:48:20 INFO - Running setup.py install for mozhttpd 15:48:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:48:20 INFO - Running setup.py install for mozinfo 15:48:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:48:20 INFO - Running setup.py install for mozInstall 15:48:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:48:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:48:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:48:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:48:20 INFO - Running setup.py install for mozleak 15:48:21 INFO - Running setup.py install for mozlog 15:48:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:48:21 INFO - Running setup.py install for moznetwork 15:48:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:48:21 INFO - Running setup.py install for mozprocess 15:48:21 INFO - Running setup.py install for mozprofile 15:48:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:48:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:48:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:48:21 INFO - Running setup.py install for mozrunner 15:48:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:48:21 INFO - Running setup.py install for mozscreenshot 15:48:21 INFO - Running setup.py install for moztest 15:48:22 INFO - Running setup.py install for mozversion 15:48:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:48:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:48:22 INFO - Cleaning up... 15:48:22 INFO - Return code: 0 15:48:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:48:22 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')]} 15:48:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:22 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')]} 15:48:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:22 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 0x10f2cba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2d24c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6c85596d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f16fd30>, '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 0x10efd3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f166b20>, '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.oIyrKS3VOT/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.Me1zZKH9gk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:48:22 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 15:48:22 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 15:48:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oIyrKS3VOT/Render', 15:48:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:48:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:48:22 INFO - 'HOME': '/Users/cltbld', 15:48:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:48:22 INFO - 'LOGNAME': 'cltbld', 15:48:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:22 INFO - 'MOZ_NO_REMOTE': '1', 15:48:22 INFO - 'NO_EM_RESTART': '1', 15:48:22 INFO - 'PAGER': '/bin/cat', 15:48:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:48:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:22 INFO - 'PWD': '/builds/slave/test', 15:48:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:48:22 INFO - 'SHELL': '/bin/bash', 15:48:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Me1zZKH9gk/Listeners', 15:48:22 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 15:48:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:48:22 INFO - 'USER': 'cltbld', 15:48:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:48:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:48:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:22 INFO - 'XPC_FLAGS': '0x0', 15:48:22 INFO - 'XPC_SERVICE_NAME': '0', 15:48:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:48:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:48:22 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-lf2Rqa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:48:22 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:48:22 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-MYADsA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:48:22 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)) 15:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:48:22 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-9EN2Sp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:48:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:48:22 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-T4Jd39-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:48:23 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)) 15:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:48:23 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-pbDH08-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:48:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:48:23 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-OWwVKB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:48:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:48:23 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-LujH5r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:48:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:48:23 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-dcCB4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:48:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:48:23 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-I4EyhP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:48:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:48:23 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-SeCErs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:48:23 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)) 15:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:48:23 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-DnwWRY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:48:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:48:23 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-nlwUn1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:48:23 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)) 15:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:48:23 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-T68ee4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:48:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:48:23 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-6EcG49-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:48:24 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)) 15:48:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:48:24 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-OKJRQp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:48:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:48:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:48:24 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-hkyBvb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:48:24 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)) 15:48:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:48:24 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-GD4Cx6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:48:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:48:24 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)) 15:48:24 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)) 15:48:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:48:24 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)) 15:48:24 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)) 15:48:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15: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 15: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 15: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 15: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 15: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 15: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 15:48:26 INFO - Downloading blessings-1.6.tar.gz 15:48:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:48:26 INFO - Installing collected packages: blessings 15:48:26 INFO - Running setup.py install for blessings 15:48:27 INFO - Successfully installed blessings 15:48:27 INFO - Cleaning up... 15:48:27 INFO - Return code: 0 15:48:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:48:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:48:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:48:27 INFO - Reading from file tmpfile_stdout 15:48:27 INFO - Current package versions: 15:48:27 INFO - blessings == 1.6 15:48:27 INFO - blobuploader == 1.2.4 15:48:27 INFO - docopt == 0.6.1 15:48:27 INFO - manifestparser == 1.1 15:48:27 INFO - mozInstall == 1.12 15:48:27 INFO - mozcrash == 0.17 15:48:27 INFO - mozdebug == 0.1 15:48:27 INFO - mozdevice == 0.48 15:48:27 INFO - mozfile == 1.2 15:48:27 INFO - mozhttpd == 0.7 15:48:27 INFO - mozinfo == 0.9 15:48:27 INFO - mozleak == 0.1 15:48:27 INFO - mozlog == 3.1 15:48:27 INFO - moznetwork == 0.27 15:48:27 INFO - mozprocess == 0.22 15:48:27 INFO - mozprofile == 0.28 15:48:27 INFO - mozrunner == 6.11 15:48:27 INFO - mozscreenshot == 0.1 15:48:27 INFO - mozsystemmonitor == 0.0 15:48:27 INFO - moztest == 0.7 15:48:27 INFO - mozversion == 1.4 15:48:27 INFO - psutil == 3.1.1 15:48:27 INFO - requests == 1.2.3 15:48:27 INFO - wsgiref == 0.1.2 15:48:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:48:27 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')]} 15:48:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:27 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')]} 15:48:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:27 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 0x10f2cba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2d24c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6c85596d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f16fd30>, '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 0x10efd3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f166b20>, '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.oIyrKS3VOT/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.Me1zZKH9gk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:48:27 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 15:48:27 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 15:48:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oIyrKS3VOT/Render', 15:48:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:48:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:48:27 INFO - 'HOME': '/Users/cltbld', 15:48:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:48:27 INFO - 'LOGNAME': 'cltbld', 15:48:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:27 INFO - 'MOZ_NO_REMOTE': '1', 15:48:27 INFO - 'NO_EM_RESTART': '1', 15:48:27 INFO - 'PAGER': '/bin/cat', 15:48:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:48:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:27 INFO - 'PWD': '/builds/slave/test', 15:48:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:48:27 INFO - 'SHELL': '/bin/bash', 15:48:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Me1zZKH9gk/Listeners', 15:48:27 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 15:48:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:48:27 INFO - 'USER': 'cltbld', 15:48:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:48:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:48:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:27 INFO - 'XPC_FLAGS': '0x0', 15:48:27 INFO - 'XPC_SERVICE_NAME': '0', 15:48:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:48:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:48:27 INFO - Cleaning up... 15:48:27 INFO - Return code: 0 15:48:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:48:27 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')]} 15:48:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:27 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')]} 15:48:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:27 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 0x10f2cba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2d24c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6c85596d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f16fd30>, '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 0x10efd3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f166b20>, '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.oIyrKS3VOT/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.Me1zZKH9gk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:48:27 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 15:48:27 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 15:48:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oIyrKS3VOT/Render', 15:48:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:48:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:48:27 INFO - 'HOME': '/Users/cltbld', 15:48:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:48:27 INFO - 'LOGNAME': 'cltbld', 15:48:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:27 INFO - 'MOZ_NO_REMOTE': '1', 15:48:27 INFO - 'NO_EM_RESTART': '1', 15:48:27 INFO - 'PAGER': '/bin/cat', 15:48:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:48:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:27 INFO - 'PWD': '/builds/slave/test', 15:48:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:48:27 INFO - 'SHELL': '/bin/bash', 15:48:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Me1zZKH9gk/Listeners', 15:48:27 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 15:48:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:48:27 INFO - 'USER': 'cltbld', 15:48:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:48:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:48:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:27 INFO - 'XPC_FLAGS': '0x0', 15:48:27 INFO - 'XPC_SERVICE_NAME': '0', 15:48:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:48:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:27 INFO - Downloading/unpacking jsonschema 15:48:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:27 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 15:48:27 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 15:48:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:48:30 INFO - Downloading/unpacking functools32 (from jsonschema) 15:48:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:30 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 15:48:30 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 15:48:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:31 INFO - Downloading functools32-3.2.3-2.tar.gz 15:48:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:48:31 INFO - warning: no files found matching '*.txt' 15:48:31 INFO - no previously-included directories found matching 'build' 15:48:31 INFO - no previously-included directories found matching 'dist' 15:48:31 INFO - no previously-included directories found matching '.git*' 15:48:31 INFO - Installing collected packages: jsonschema, functools32 15:48:31 INFO - Running setup.py install for functools32 15:48:31 INFO - warning: no files found matching '*.txt' 15:48:31 INFO - no previously-included directories found matching 'build' 15:48:31 INFO - no previously-included directories found matching 'dist' 15:48:31 INFO - no previously-included directories found matching '.git*' 15:48:31 INFO - Successfully installed jsonschema functools32 15:48:31 INFO - Cleaning up... 15:48:31 INFO - Return code: 0 15:48:31 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:48: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')]} 15:48:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48: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')]} 15:48:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:31 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 0x10f2cba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f2d24c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb6c85596d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f16fd30>, '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 0x10efd3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f166b20>, '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.oIyrKS3VOT/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.Me1zZKH9gk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:48:31 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 15:48:31 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 15:48:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oIyrKS3VOT/Render', 15:48:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:48:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:48:31 INFO - 'HOME': '/Users/cltbld', 15:48:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:48:31 INFO - 'LOGNAME': 'cltbld', 15:48:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:31 INFO - 'MOZ_NO_REMOTE': '1', 15:48:31 INFO - 'NO_EM_RESTART': '1', 15:48:31 INFO - 'PAGER': '/bin/cat', 15:48:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:48:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:31 INFO - 'PWD': '/builds/slave/test', 15:48:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:48:31 INFO - 'SHELL': '/bin/bash', 15:48:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Me1zZKH9gk/Listeners', 15:48:31 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 15:48:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:48:31 INFO - 'USER': 'cltbld', 15:48:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:48:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:48:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:31 INFO - 'XPC_FLAGS': '0x0', 15:48:31 INFO - 'XPC_SERVICE_NAME': '0', 15:48:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:48:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:31 INFO - Downloading/unpacking flake8 15:48:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:31 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 15:48:31 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 15:48:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:34 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:48:34 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:48:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:48:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:48:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:34 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:48:34 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:48:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:48:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:48:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:34 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:48:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:48:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:48:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:48:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:48:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:48:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:48:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:48:35 INFO - no previously-included directories found matching 'docs/_build' 15:48:35 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:48:35 INFO - Running setup.py install for pep8 15:48:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:48:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:48:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:48:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:48:35 INFO - no previously-included directories found matching 'docs/_build' 15:48:35 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:48:35 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:48:35 INFO - Cleaning up... 15:48:35 INFO - Return code: 0 15:48:35 INFO - Running post-action listener: _resource_record_post_action 15:48:35 INFO - Running post-action listener: _start_resource_monitoring 15:48:35 INFO - Starting resource monitoring. 15:48:35 INFO - ##### 15:48:35 INFO - ##### Running install step. 15:48:35 INFO - ##### 15:48:35 INFO - Running pre-action listener: _resource_record_pre_action 15:48:35 INFO - Running main action method: install 15:48:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:48:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:48:36 INFO - Reading from file tmpfile_stdout 15:48:36 INFO - Detecting whether we're running mozinstall >=1.0... 15:48:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:48:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:48:36 INFO - Reading from file tmpfile_stdout 15:48:36 INFO - Output received: 15:48:36 INFO - Usage: mozinstall [options] installer 15:48:36 INFO - Options: 15:48:36 INFO - -h, --help show this help message and exit 15:48:36 INFO - -d DEST, --destination=DEST 15:48:36 INFO - Directory to install application into. [default: 15:48:36 INFO - "/builds/slave/test"] 15:48:36 INFO - --app=APP Application being installed. [default: firefox] 15:48:36 INFO - mkdir: /builds/slave/test/build/application 15:48:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:48:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 15:49:08 INFO - Reading from file tmpfile_stdout 15:49:08 INFO - Output received: 15:49:08 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 15:49:08 INFO - Running post-action listener: _resource_record_post_action 15:49:08 INFO - ##### 15:49:08 INFO - ##### Running run-tests step. 15:49:08 INFO - ##### 15:49:08 INFO - Running pre-action listener: _resource_record_pre_action 15:49:08 INFO - Running main action method: run_tests 15:49:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:49:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:49:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:49:08 INFO - 2016-04-22 15:49:08.186 screenresolution[1643:7708] starting screenresolution argv=screenresolution get 15:49:08 INFO - 2016-04-22 15:49:08.188 screenresolution[1643:7708] Display 0: 1600x1200x32@60 15:49:08 INFO - 2016-04-22 15:49:08.194 screenresolution[1644:7711] starting screenresolution argv=screenresolution list 15:49:08 INFO - Available Modes on Display 0 15:49:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:49:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:49:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:49:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:49:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:49:08 INFO - Intel Iris: 15:49:08 INFO - Chipset Model: Intel Iris 15:49:08 INFO - Type: GPU 15:49:08 INFO - Bus: Built-In 15:49:08 INFO - VRAM (Dynamic, Max): 1536 MB 15:49:08 INFO - Vendor: Intel (0x8086) 15:49:08 INFO - Device ID: 0x0a2e 15:49:08 INFO - Revision ID: 0x0009 15:49:08 INFO - Displays: 15:49:08 INFO - Display: 15:49:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:49:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:49:08 INFO - Display Serial Number: 1600x1200 60 15:49:08 INFO - Main Display: Yes 15:49:08 INFO - Mirror: Off 15:49:08 INFO - Online: Yes 15:49:08 INFO - Rotation: Supported 15:49:08 INFO - Return code: 0 15:49:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:49:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:49:08 INFO - Python 2.7.3 15:49:08 INFO - Return code: 0 15:49:08 INFO - grabbing minidump binary from tooltool 15:49:08 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')]} 15:49:08 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 0x10f16fd30>, '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 0x10efd3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f166b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:49:08 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:49:08 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 15:49:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:49:08 INFO - Return code: 0 15:49:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:49:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:49:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:49:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:49:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:49:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:49:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:49:09 INFO - Return code: 0 15:49:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WMriI2IhRtGQ_iEYFHgolw/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0084', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 15:49:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WMriI2IhRtGQ_iEYFHgolw/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0084 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 15:49:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oIyrKS3VOT/Render', 15:49:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:49:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:49:09 INFO - 'HOME': '/Users/cltbld', 15:49:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:49:09 INFO - 'LOGNAME': 'cltbld', 15:49:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:49:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:49:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:09 INFO - 'MOZ_NO_REMOTE': '1', 15:49:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:49:09 INFO - 'NO_EM_RESTART': '1', 15:49:09 INFO - 'PAGER': '/bin/cat', 15:49:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:49:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:09 INFO - 'PWD': '/builds/slave/test', 15:49:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:49:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:49:09 INFO - 'SHELL': '/bin/bash', 15:49:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Me1zZKH9gk/Listeners', 15:49:09 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 15:49:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:49:09 INFO - 'USER': 'cltbld', 15:49:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:49:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:49:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:09 INFO - 'XPC_FLAGS': '0x0', 15:49:09 INFO - 'XPC_SERVICE_NAME': '0', 15:49:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:49:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WMriI2IhRtGQ_iEYFHgolw/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0084', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 15:49:09 INFO - mozversion application_buildid: 20160422142650 15:49:09 INFO - mozversion application_changeset: 922334c70324e4295dcfcfafe6da4ae2e1154834 15:49:09 INFO - mozversion application_display_name: FirefoxDeveloperEdition 15:49:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:49:09 INFO - mozversion application_name: Firefox 15:49:09 INFO - mozversion application_remotingname: firefox-dev 15:49:09 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:49:09 INFO - mozversion application_vendor: Mozilla 15:49:09 INFO - mozversion application_version: 47.0a2 15:49:09 INFO - mozversion platform_buildid: 20160422142650 15:49:09 INFO - mozversion platform_changeset: 922334c70324e4295dcfcfafe6da4ae2e1154834 15:49:09 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:49:09 INFO - mozversion platform_version: 47.0a2 15:49:09 INFO - using testdate: 1461365349 15:49:09 INFO - actual date: 1461365349 15:49:09 INFO - starting webserver on 'localhost:49275' 15:49:09 INFO - SUITE-START | Running 2 tests 15:49:09 INFO - TEST-START | dromaeo_css 15:49:09 INFO - operating with platform_type : mac_ 15:49:09 INFO - Initialising browser for dromaeo_css test... 15:49:09 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/tmp5Ln9MY/profile http://localhost:49275/getInfo.html) 15:49:12 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 15:49:12 INFO - PROCESS | 1662 | colorDepth:24 15:49:12 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 15:49:12 INFO - PROCESS | 1662 | __metrics 15:49:12 ERROR - PROCESS | 1662 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2849: TypeError: this.tabs is undefined 15:49:13 INFO - TEST-INFO | 1662: exit 0 15:49:13 INFO - Browser initialized. 15:49:13 INFO - Running cycle 1/1 for dromaeo_css test... 15:49:13 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/tmp5Ln9MY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:49:25 INFO - PROCESS | 1665 | RSS: Main: 217931776 15:49:25 INFO - PROCESS | 1665 | 15:50:15 INFO - PROCESS | 1665 | 1461365415257 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] 15:52:26 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 15:52:27 INFO - PROCESS | 1665 | RSS: Main: 229912576 15:52:27 INFO - PROCESS | 1665 | 15:55:29 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 15:55:30 INFO - PROCESS | 1665 | RSS: Main: 240308224 15:55:30 INFO - PROCESS | 1665 | 15:58:31 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 15:58:32 INFO - PROCESS | 1665 | RSS: Main: 242700288 15:58:32 INFO - PROCESS | 1665 | 16:01:34 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 16:01:34 INFO - PROCESS | 1665 | RSS: Main: 244719616 16:01:34 INFO - PROCESS | 1665 | 16:04:37 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 16:04:38 INFO - PROCESS | 1665 | RSS: Main: 247697408 16:04:38 INFO - PROCESS | 1665 | 16:07:40 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 16:07:40 INFO - PROCESS | 1665 | RSS: Main: 247980032 16:07:40 INFO - PROCESS | 1665 | 16:07:40 INFO - PROCESS | 1665 | __start_tp_report 16:07:40 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 16:07:40 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 16:07:40 INFO - PROCESS | 1665 | |i|pagename|runs| 16:07:40 INFO - PROCESS | 1665 | |0;dojo.html;3125.3905488429764;3186.1556687477823;3539.2213712983144;3586.569611646603;3658.158623516591;5803.361630220677;5824.504917082042;5848.7660493580315;5865.970670146648;5866.970665146674;4674.3923289972345;5856.9750293698535;5880.764769409218;5888.5877988540815;5935.258092738408;4824.469308376076;4912.508749125077;5249.16013437851;5287.576993840503;5384.757685904144;5265.578753699714;5399.325084364455;5408.702521361323;5415.620906536544;5417.187421886709;4958.752062396884;4964.776585053663;5386.003589335965;5390.865228369303;5392.110301800199;5311;5343.465653434664;5372.623916325859;5375.274337964363;5398.6220964532495;5334.626576139672;5346.358436987586;5401.405845357008;5423.972880135574;5428;5830.3586605473365;5841.240638716982;5847.678377689225;5867.031939729939;5888.29340479141;5901.321348044944;6202.255729312466;6203.317635060185;6280.811575652738;6330.81007569769;6280.905786413207;6308.2430108386825;6308.905366419507;6319.968400158015;6321.589096708699;5957.1362152487645;5978.790742324085;5983.222181116471;5997.5201983841625;6002.939970600325;6287.339829317856;6320.747170113144;6336.461400780893;6350.714217860207;6380.55336126476;5304.363476382781;6268.686565671698;6278.874422511615;6287.654179020198;6293.559450838489;6249.125122482856;6271.529635277372;6291.811245662638;6294.842628934313;6304.590201636879;6168.8766224675255;6216.875662486816;6246.094316324107;6246.219222597176;6280.2777680566405;6195.411435913581;6234.75060997555;6270.623762574261;6292.528060395489;6334.429901308812;4886.853394398174;4937.35814344133;4965.155923493015;4984.352034235526;4995.400551933792;4341.370501277297;4415.757133357696;4417.668674849399;4448.532904045093;4531.750753708578;5966.433188847052;6011.008183649649;6031.9396806032255;6035.969820150959;6072.483838873745;6204.5967012144065;6238.500919926395;6315.115883776275;6323.241211054611;6327;5893.675847828325;5953.910691339547;5981.342052374235;6042.6978651067275;6051.636901785907;5474.479924407175;5481.780728770885;5491.533219676372;5501.092319767274;5515.4760297771645;6333.113364128933;6424.775132870233;6429.099926010363;6432.581882177644;6447.484201263887;6255.749770009331;6263.96868015657;6277.529185311211;6313.116163736989;6322.683865806599;6427.357264273535;6446.806595801946;6447.838804029937;6450.322716114928;6468.191476065492;5704.743286552198;6225.190725205694;6288.5598008138995;6324.6521441321165;6353.078803573549;6280.246370435577;6308.810735677937;6318.178636777189;6340.239171299474;6346.111544383883;5144.717040562805;6251.874962500816;6265.404786545271;6275.184226050585;6294.087357333253;5369.248305237192;5837.6497410155525;6129.835331287041;6149.86227547905;6201.10084037803;5958.255218097738;6034.63792172471;6039.879202416015;6051.848703782441;6100.908486372619;5443.911217756386;6104.11490333908;6197.163382943245;6200.3489633589625;6240.906386404116;5898.262717160355;6049.818505444844;6125.173101631222;6138.480361557057;6196.628202307876 16:07:40 INFO - PROCESS | 1665 | |1;ext.html;568.8037627018678;582.9157766554208;594.459042271533;665.1386454541368;677.9830254618073;38856.41715374268;39290.41064384037;39352;39470.80264598677;39485;40927.38608920869;41523.169536609246;41539.16921661565;41584.75245742625;41600;9580.520973951292;9870.309078364517;9880.258980576486;9889.703308900744;9901.158401535879;9727.805443891119;9886.802263954716;9891.802163956716;9892.15916647086;9896.901030989706;9836.606535738598;9874.506274686273;9900.306978511508;9918.900810991907;9943.254255930799;8652.697155599524;9832.754181145492;9866.358686685378;9874.308798384116;9893.15908147812;9491.288153388454;9830.21358291335;9834;9839.803203935953;9854.359466634645;34638.82680586581;39938.40246390141;39965.20069598592;40199.397009044886;40211.39682904759;3095.767817413703;3402.2344972381266;3554.377983852841;3669.9633003669887;3926.7447615904875;27350.726492735015;27947.161585152477;27964.561109113332;28072.43855122886;28242.858785706147;2749.986250068757;2803.1450407625666;2826.5336219523756;2835.4896118698625;2871.870765815543;430531;435361.8231908852;435552;438102;439677;67639;67916.320836791;68149.65925170343;68229.97655035178;68270.97593536101;78812.60593696995;79472.80790788143;80530;80624;80749;18125.36561220351;18158;18360.357387491375;18368.908155459136;18424.35514756977;13949.372278247503;13993.580192594238;14046.508372206923;14090.577282681332;14130.293485325898;22066.779332206792;22265.220717274817;22305;22429.43926401853;22996.85015749206;9241.768955776159;9497;9513.336265845574;9536.2371010319;9546;938.5260443476001;1002.5789168549227;1007.3452256033637;1112.8887111288968;1127.0476447401932;24727.752722472545;27939.58090628642;29248.122556323346;29515.409691806042;29922;1129.949152288149;1153.896149346546;1170.8302296167178;1177.2112684501406;1216.7566486702694;14739.55781326562;15616;15794.36822527109;16158.838411615969;16979;710628;711767;712964.740705183;722539;723189;65606.67196663986;67347.66326168338;67505.66247168733;67710.66144669344;68143.65928170328;1555.4866893924977;1565.6633823727952;1568.3256199833952;1572.3946280682019;1683.8316168383308;31145;31427;31773.841130794197;31903;31948.44466476639;53502.73248633732;54178.729106354214;54228;54304.72847635736;54388.18417723658;523362;527603;535101.973470406;535996;539357;33440.832795835864;33442.16394590155;33667.663323366454;34763.957081287605;35219.11952201215;49769.502304976486;51575.226371605204;51688;52582;53145;38303.23393532058;38894;39633.41466341314;39692.427681507645;40803.1839363217;37370.43944340783;38220.42669359906;38552;38841.6115838838;38918.61081389263;34159.4876076864;37808.810955945046;38267;41485.58514414817;42042;32262.838685806422;33579.3284134311;33612.66387336095;33633.15917102092;34155;24967.500649986534;25360.365990850376;25597.744022559535;25834.095806646677;26408.8679556601 16:07:40 INFO - PROCESS | 1665 | |2;jquery.html;379.57677189933213;385.6567654787239;392.63877232945686;394.5856850307177;394.8420631747301;18059;19951.700724489125;19979.200831966722;21754.585951913155;22346.77653223467;20975.16099356028;20989.055492502837;21238.150473981063;21245.99999999996;21334.35996920095;4311.611954924057;5642.322921249445;5944.7324870380935;5945.732442040097;5948.613340132899;4726.8581942541605;6428.582142160744;6499.415052645261;6524.771632992846;6528.2166140063255;5533.22534845122;5738.31140263169;6200.379962003809;6453.967730161343;6466.805995820133;6364.109024736517;6371.044343348489;6398.2322121345505;6398.7760428385245;6420.229572451312;5504.422035686275;5543.113101903676;5550.777968881281;5582.91625625616;5613.943860561424;25467.61798573023;25728.485430291475;25890.22329330123;25921.22236332913;26448.471030579472;1627.9755803662954;2170.8371872109656;2273.022600281878;2433.9269821905373;2655.4556315955183;12261.509539618404;14665.706685866224;14794;15282.388704451809;15545.689086218325;1750.3611181918636;1782.4741701198047;1796.7125259958343;1810.2668419290208;1884.547708549938;108573.45713271463;109283.26791830188;110771;111865.32202016977;113431.43284283526;10772.407517586607;10899.1280697544;11021.779564408827;11232.213745037769;11317.320960742381;13530;13674.794878076837;13700.314984250748;13703.725925481434;13740.312984350743;8953.149450802164;8984.820303594022;9002.189802917637;9021.50381728998;9115.863262051074;9367.250619950388;9398.530073496298;9404.576794044284;9487.430754154773;9491.335606507479;5297.65565238266;5377.865553361119;6271.216097987752;6327.272363678236;6343.841403964845;1612.7097122517825;1868.0379604503692;1899.5156235160223;1905.3140869287045;1908.866379353432;986.3933680786265;1058.4684912364264;1067.8291473364225;1068.3803394031443;1079.5735684404617;19166.041697915047;19866.404007879497;21218;21436.892815535823;21684.132634694437;1051.1590727418036;1063.1388575254068;1073.4525392049954;1093.5188517052472;1119.4906317625623;10378.169746420268;11661.941690291495;11886.346250956107;11899.226550274378;11910.46402911871;642258.7887060628;649991;656319;681925;701596;69950.30049699539;77603;86557.26885462255;109171;109294.45352773185;1069.272894431794;1078.1213311151544;1105.5798796457295;1115.4088333183427;1150.1431433581868;18121.003344816163;18318.81681183171;18555.443336699922;18699.532511687317;18944.431667050012;51119.2332115026;51458.48541514537;52642;53333.46666533285;54789;78015;80818.19181808106;82944.58527707323;83885.16114839018;84298.73551896604;7248.420126389981;8278.006639203335;8285.171482851667;8401.99176098872;8430.915690843012;12484.750304993668;12486.37677101971;13292.136011159244;13353.265570393722;13462.990275729555;9939.006099390002;10223.079922806983;10500.579976800842;11534.884651153381;11683.299002059903;11180.608678696206;11310.717232069264;11382.658520244406;11627.18609697347;11654.18420710576;11521.078313735048;11560.075193984629;11958.342291174069;12017.218880772722;12022;7773.766786996399;7973.681052757825;8227.42408031456;8341.624626891684;8573.74278771638;9261.861072084059;10069.69790906274;11774;11797.410129493663;11803.527858885893 16:07:40 INFO - PROCESS | 1665 | |3;mootools.html;1644.457329081403;1664.5102633143333;2021.0804084141716;2060.7321048263716;2112.8098471137596;8501.872471912926;9095.408798428098;9256.814863702723;9466.76333091673;9469.810603787928;9196.21832144267;9208.355415120945;9310.860337094931;9316.394434361762;9333.859992100124;2599.662043934286;2626.3565426470445;2628.763411292983;2628.789696824259;2658.9335266618295;2560.4367039251333;2637.222019504251;2644.3256969472855;2648.245250103721;2650.08572042645;2544.465662210938;2584.121398724433;2651.4564514274625;2657.787377009844;2663.986680066607;2605.179368498926;2619.0571394298036;2645.2989957661234;2650.628911952338;2655.8273712208643;2296.6210575255063;2522.574745268917;2634.749698778613;2640.749128832758;2643.7792254506307;9265.490398028105;9323.06769323069;9342;9353.76615584609;9376.249900007982;5671.829845104653;5790.855228619276;5831.620944638585;5837.737301821428;5864.648121112704;3636.4545318202217;3726.1988672435555;3786;3791.6208379161862;3791.620837916214;1064.5582083435365;1074.4359211413991;1083.2958576924982;1114.8940850619178;1121.5233525751605;11826;12375.628731138171;12428.813567796491;12445.62663120108;12461.127721059529;5002.57478114357;5004.824831130893;5114.565261952701;5131.947950670104;5139.974300128475;10630.734231644115;10642.521086551124;10686;10689.786204275872;10692;5633.507120613041;5700.9429905701245;5753.539716822645;5759.683217423082;5760.510356619734;4700.976495117503;5084.618653600995;5178.016176926372;5186.273921650972;5195.922061169085;4143.233501802176;4181.996320883027;4204.831806727697;4206.179794939957;4217.36739489073;1385.072001758824;1398.077269002474;1401.5515035188846;1402.726468338664;1406.7819487979175;1368.2679766324968;1394.9302534873216;1416.7379034878375;1421.5166843273337;1428.0717533603033;8495.872561911576;8620.698275560451;8631.654733810577;8679.566021698889;8721.65113395457;521.6583138044587;546.5764032874474;546.9808556700497;547.3185883574974;557.8465921871518;5307.946920530745;5931.792387266425;6336.081268216083;6391.456726178234;6426.903596446057;8963;11176.552937882556;11234.550617975354;11274.379909105079;11290.661280161608;10729.034386905216;11026.062784663394;11133.220674552704;11255;11779.526299477035;8589.44168629037;8819.470831750115;8883.511406872683;8885.466871987572;8926.955365223263;8167.001651708272;8279.50322980623;8319.708810191856;8347.54088525137;8437.156284371515;9760.414375137514;10045.397276163454;10113.69658910234;10173.033561811617;10253.179745620482;10225.795484090128;10514.053735163872;10526.473676316;10672.30630009047;10712.410817405118;7806.9850919380115;7893;7976.840463190588;7978.880316795136;7983.8802417964935;8678.783030424038;8726.214640682369;8744.606492707971;8748.606312715816;8759.16787905148;7471.589062601392;7692.692292308245;7705.6147192641265;7779.611019449118;7794.2985131338455;7350.70597176107;7434.851302974019;7481.551106933602;7548.8490230196185;7646.885296720442;8074.1522140176785;8100.918990810253;8106.229908158715;8116.83766324682;8154.266116049584;7220.855582888418;7326.267373262631;7370.447216458894;7398.260173982774;7432.92567074344;7490.812729681802;7570.545767253982;7590.468667193243;7595.5442673439775;7601.315881570686 16:07:40 INFO - PROCESS | 1665 | |4;prototype.html;327.84099711639834;342.2316898562727;349.75691894133587;353.63928792631526;359.72121605755535;6046.3349031606485;6773.424258937984;6781.186257649077;6824.952514245725;6833.829154271146;6650.900236496444;6681.799546013615;6703.095082163906;6713.899291510631;6721.697523611437;4737.218358970783;4851.781669824866;4934.037862616791;4934.605231581465;4996.950640365528;4895.265710143472;4923.729194894279;4957.25641153828;4957.355543779304;4963.057019166365;4016.4376987221813;4881.585065269456;4886;4898.485659005806;4934.111859865223;4068.2677118118727;4895.192293271606;4906.828261010865;4911.5088491150955;4917.655764096533;4240.681948853849;4253.29820579604;4258.190943720714;4263.850765223233;4273.978630106861;6213.161223234851;6377.362263773632;6420.903686444707;6434.227892652865;6485.77299794505;5200.271961925334;5478.890422191534;5900.763969441217;5939.287285525721;5945.048792193272;7181.964090179516;7185.425165986709;7213.134423869117;7232.674529646179;7250.383717384028;3736.981315093434;3879.5926427724967;3952.4268980997595;4777.307290442866;4792.137415265251;7608.80977975555;7628.580428076453;7686.385089192907;7692.730754423568;7708.190639982777;4299.441072660534;4437.444360747763;4438.800253988578;4532.5694059064335;4888.144574699413;4941.505849415102;4968.577670898014;4970.701757894538;4977.701337919736;4993.725345105981;5865.266841644794;5881.147233651182;5885;5892.1161825726485;5919.792807251725;6036.939630603638;6053.7881174159575;6914.162639734895;7274.054372931485;7302.817429564303;1689.560714214313;1712.3664244229715;1717.957051073733;1719.75063615775;1724.2585688154213;1088.6081010836251;1123.775244951013;1131.9773604527863;1135.9488823002985;1148.3339662995443;5554.888902221933;6820.249772525019;6834.4874134440115;6847.246802851682;6881.759138430231;7461.365783908429;7484.513506622052;7491.700331986768;7511.737089201852;7511.774646760606;5268.947310526845;6712.127423434922;6932.757353492704;6938.895916561256;6951.478639102088;3980.124372638015;3999.900002499961;4032.9111139992624;4040.676745860383;4049.91900162001;8014.078380986145;8048.718294859769;8064.435489515795;8066.435349525595;8096.5546894921345;6862.759803406857;7140;7883.132855385903;7897.091834439113;7912.525248485109;7836.490628109269;7854.646540905559;7857.685692572348;7862.213778622092;7885;6328.14570033058;7172.103487064117;7521.285477879593;7574.356179724565;7633.351165151024;5494.340679118532;7227.855442891007;7472.327490525661;7483.289087536676;7507.662155203138;7622.428317876293;7968.362530997622;7971.282584567417;8001.359891208573;8040.839183216186;4272.4445822042935;4338.7830608470085;4683.812647494062;4764.285357196448;4782.545658162469;6154.3845615438095;6872.862542749217;7545.660445279866;7575.0909890813455;7627.847443051219;7273.709051637875;7273.890891636735;7295.635218238961;7311.159216690149;7311.817204569716;6479.967600161969;6969.128858892638;7049.118860142482;7258.927410725825;7289.416846652149;7039.380942383178;7058.823529411806;7066.187388450188;7072.893906591303;7081.964590177016;7272.381847543018;7288.599127048064;7299.708011679686;7315.30504602062;7333.266673332624;6130.264368275835;6777.017332486663;7071.939209118674;7134.536255143399;7144.749668807834 16:07:40 INFO - PROCESS | 1665 | |5;yui.html;677.8305423644089;693.4209934704521;727.4289682599159;739.7743688175104;798.6605692580654;15332.310046047927;15403.84596154038;15565;15565.766513502307;15607.453739119133;11996.160268781188;14321.71356572868;14389.424423023067;14413.351399187035;14419.63950901228;815.5066184958104;991.0634450444333;1005.3967619428339;1013.7617659849966;1016.872890888639;993.5479356892597;1004.6734811186357;1007.9748006299828;1010.1312870930993;1012.2762225009117;1053.152212468966;1066.4987455895755;1070.1973519860105;1072.4316112460408;1076.2412499188054;1038.2991480750502;1045.033344156652;1058.2327812336073;1060.194252368202;1065.3607835298835;787.3425689549176;789.9960500197519;791.9111222069654;799.4124318625805;800.1958032177682;15433.5369938902;15454.304556294994;15835;15927.601809954727;15966.680666386723;13156.736865262737;13199.406026728724;13265.336733163304;13307.933460332733;13312.267825269606;10294.536745846455;10367.844482332772;10375.636852710195;10376;10747.946260268649;3520.471929210639;3549.804760738158;3581.5164952731307;3654.086478380405;3821.1975485147873;81053.18946810575;83024;83121;83479;83889.74165387524;8279.95860020696;8689.435186712843;9785.951070244746;9980.251481138943;10121.595136194617;23576.64635030476;23594.410139746644;23600.409989750395;23608.527829443316;23623.409414764425;7507.512011719221;7527.8118047048165;7642.847143057108;7670.041244844395;7676.57778822159;4075.370522082395;4083.816228269735;4123.9381409278885;4152.065785198306;4188.979055104766;3746.4942232798767;3787.962120378816;3804.2201348723447;3806.4861243731743;3807.5621303550447;672.653583404547;678.5385937562505;684.7432212920155;695.4158506854267;709.836737550356;577.6216578141324;600.2347007565389;621.8880601491685;625.4746013348717;639.3063526074169;8223.136570660114;8433.409661323649;8458.703945361884;8564.48613083217;8633.33664645669;615.7844754335947;618.2704408797662;620.2123303404651;628.7516431009725;643.427349658804;3870.4000879863665;3880.8059597020037;3971.5829837867186;3973.2848087343978;4082.6937979651643;94354;95258;95664;101162;101692;62676;76162.61918690371;76984;77512.61243693746;77759;71771.92342114872;72257.2774272261;75947.24052759512;76742.84885726719;76805.46389072188;8568.228859402683;8670.436421632574;8683.782905427415;8757.693480728143;8804.867926980973;49121.50878491169;49237.50762492473;51495.48504514907;53668.73165634147;53814.73092634668;68419.65790171018;70334.64832675803;70636;72988.63505682438;73403.63298183474;9457.763555911157;11431.085513159094;11599.130065244972;11601.477933493183;11716.824247636463;12037.78004834629;12111.212771169845;12284.631461056182;12287.508499659914;12604.49582016709;11581.247218930743;11644.53421863116;11864;11869.76260474803;11899.94050029779;11709.824352634887;11753.353565553976;11899.940500297444;11956;12282.955948744457;11620.883791162318;11668.70828229301;11682.299062056303;11975.28148311104;12065.215760975509;8340;9788.314817962675;9870.358426702242;9951.104400603981;9993.600255989679;9765.707028789147;10024.694320306224;10030.749231269278;10036.297459177787;10142.695719128438 16:07:40 INFO - PROCESS | 1665 | __end_tp_report 16:07:40 INFO - PROCESS | 1665 | __start_cc_report 16:07:40 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,361 16:07:40 INFO - PROCESS | 1665 | __end_cc_report 16:07:40 INFO - PROCESS | 1665 | __startTimestamp1461366460414__endTimestamp 16:07:40 INFO - PROCESS | 1665 | 16:07:40 INFO - PROCESS | 1665 | ------- Summary: start ------- 16:07:40 INFO - PROCESS | 1665 | Number of tests: 6 16:07:40 INFO - PROCESS | 1665 | 16:07:40 INFO - PROCESS | 1665 | [#0] dojo.html Cycles:165 Average:5814.31 Median:6039.88 stddev:658.40 (10.9%) stddev-sans-first:625.71 16:07:40 INFO - PROCESS | 1665 | Values: 3125.4 3186.2 3539.2 3586.6 3658.2 5803.4 5824.5 5848.8 5866.0 5867.0 4674.4 5857.0 5880.8 5888.6 5935.3 4824.5 4912.5 5249.2 5287.6 5384.8 5265.6 5399.3 5408.7 5415.6 5417.2 4958.8 4964.8 5386.0 5390.9 5392.1 5311.0 5343.5 5372.6 5375.3 5398.6 5334.6 5346.4 5401.4 5424.0 5428.0 5830.4 5841.2 5847.7 5867.0 5888.3 5901.3 6202.3 6203.3 6280.8 6330.8 6280.9 6308.2 6308.9 6320.0 6321.6 5957.1 5978.8 5983.2 5997.5 6002.9 6287.3 6320.7 6336.5 6350.7 6380.6 5304.4 6268.7 6278.9 6287.7 6293.6 6249.1 6271.5 6291.8 6294.8 6304.6 6168.9 6216.9 6246.1 6246.2 6280.3 6195.4 6234.8 6270.6 6292.5 6334.4 4886.9 4937.4 4965.2 4984.4 4995.4 4341.4 4415.8 4417.7 4448.5 4531.8 5966.4 6011.0 6031.9 6036.0 6072.5 6204.6 6238.5 6315.1 6323.2 6327.0 5893.7 5953.9 5981.3 6042.7 6051.6 5474.5 5481.8 5491.5 5501.1 5515.5 6333.1 6424.8 6429.1 6432.6 6447.5 6255.7 6264.0 6277.5 6313.1 6322.7 6427.4 6446.8 6447.8 6450.3 6468.2 5704.7 6225.2 6288.6 6324.7 6353.1 6280.2 6308.8 6318.2 6340.2 6346.1 5144.7 6251.9 6265.4 6275.2 6294.1 5369.2 5837.6 6129.8 6149.9 6201.1 5958.3 6034.6 6039.9 6051.8 6100.9 5443.9 6104.1 6197.2 6200.3 6240.9 5898.3 6049.8 6125.2 6138.5 6196.6 16:07:40 INFO - PROCESS | 1665 | 16:07:40 INFO - PROCESS | 1665 | [#1] ext.html Cycles:180 Average:71185.84 Median:39662.92 stddev:153343.01 (386.6%) stddev-sans-first:153681.52 16:07:40 INFO - PROCESS | 1665 | Values: 568.8 582.9 594.5 665.1 678.0 38856.4 39290.4 39352.0 39470.8 39485.0 40927.4 41523.2 41539.2 41584.8 41600.0 9580.5 9870.3 9880.3 9889.7 9901.2 9727.8 9886.8 9891.8 9892.2 9896.9 9836.6 9874.5 9900.3 9918.9 9943.3 8652.7 9832.8 9866.4 9874.3 9893.2 9491.3 9830.2 9834.0 9839.8 9854.4 34638.8 39938.4 39965.2 40199.4 40211.4 3095.8 3402.2 3554.4 3670.0 3926.7 27350.7 27947.2 27964.6 28072.4 28242.9 2750.0 2803.1 2826.5 2835.5 2871.9 430531.0 435361.8 435552.0 438102.0 439677.0 67639.0 67916.3 68149.7 68230.0 68271.0 78812.6 79472.8 80530.0 80624.0 80749.0 18125.4 18158.0 18360.4 18368.9 18424.4 13949.4 13993.6 14046.5 14090.6 14130.3 22066.8 22265.2 22305.0 22429.4 22996.9 9241.8 9497.0 9513.3 9536.2 9546.0 938.5 1002.6 1007.3 1112.9 1127.0 24727.8 27939.6 29248.1 29515.4 29922.0 1129.9 1153.9 1170.8 1177.2 1216.8 14739.6 15616.0 15794.4 16158.8 16979.0 710628.0 711767.0 712964.7 722539.0 723189.0 65606.7 67347.7 67505.7 67710.7 68143.7 1555.5 1565.7 1568.3 1572.4 1683.8 31145.0 31427.0 31773.8 31903.0 31948.4 53502.7 54178.7 54228.0 54304.7 54388.2 523362.0 527603.0 535102.0 535996.0 539357.0 33440.8 33442.2 33667.7 34764.0 35219.1 49769.5 51575.2 51688.0 52582.0 53145.0 38303.2 38894.0 39633.4 39692.4 40803.2 37370.4 38220.4 38552.0 38841.6 38918.6 34159.5 37808.8 38267.0 41485.6 42042.0 32262.8 33579.3 33612.7 33633.2 34155.0 24967.5 25360.4 25597.7 25834.1 26408.9 16:07:40 INFO - PROCESS | 1665 | 16:07:40 INFO - PROCESS | 1665 | [#2] jquery.html Cycles:180 Average:36047.45 Median:21385.63 stddev:109879.13 (513.8%) stddev-sans-first:110154.73 16:07:40 INFO - PROCESS | 1665 | Values: 379.6 385.7 392.6 394.6 394.8 18059.0 19951.7 19979.2 21754.6 22346.8 20975.2 20989.1 21238.2 21246.0 21334.4 4311.6 5642.3 5944.7 5945.7 5948.6 4726.9 6428.6 6499.4 6524.8 6528.2 5533.2 5738.3 6200.4 6454.0 6466.8 6364.1 6371.0 6398.2 6398.8 6420.2 5504.4 5543.1 5550.8 5582.9 5613.9 25467.6 25728.5 25890.2 25921.2 26448.5 1628.0 2170.8 2273.0 2433.9 2655.5 12261.5 14665.7 14794.0 15282.4 15545.7 1750.4 1782.5 1796.7 1810.3 1884.5 108573.5 109283.3 110771.0 111865.3 113431.4 10772.4 10899.1 11021.8 11232.2 11317.3 13530.0 13674.8 13700.3 13703.7 13740.3 8953.1 8984.8 9002.2 9021.5 9115.9 9367.3 9398.5 9404.6 9487.4 9491.3 5297.7 5377.9 6271.2 6327.3 6343.8 1612.7 1868.0 1899.5 1905.3 1908.9 986.4 1058.5 1067.8 1068.4 1079.6 19166.0 19866.4 21218.0 21436.9 21684.1 1051.2 1063.1 1073.5 1093.5 1119.5 10378.2 11661.9 11886.3 11899.2 11910.5 642258.8 649991.0 656319.0 681925.0 701596.0 69950.3 77603.0 86557.3 109171.0 109294.5 1069.3 1078.1 1105.6 1115.4 1150.1 18121.0 18318.8 18555.4 18699.5 18944.4 51119.2 51458.5 52642.0 53333.5 54789.0 78015.0 80818.2 82944.6 83885.2 84298.7 7248.4 8278.0 8285.2 8402.0 8430.9 12484.8 12486.4 13292.1 13353.3 13463.0 9939.0 10223.1 10500.6 11534.9 11683.3 11180.6 11310.7 11382.7 11627.2 11654.2 11521.1 11560.1 11958.3 12017.2 12022.0 7773.8 7973.7 8227.4 8341.6 8573.7 9261.9 10069.7 11774.0 11797.4 11803.5 16:07:40 INFO - PROCESS | 1665 | 16:07:40 INFO - PROCESS | 1665 | [#3] mootools.html Cycles:180 Average:6361.90 Median:5182.15 stddev:3354.46 (64.7%) stddev-sans-first:3345.13 16:07:40 INFO - PROCESS | 1665 | Values: 1644.5 1664.5 2021.1 2060.7 2112.8 8501.9 9095.4 9256.8 9466.8 9469.8 9196.2 9208.4 9310.9 9316.4 9333.9 2599.7 2626.4 2628.8 2628.8 2658.9 2560.4 2637.2 2644.3 2648.2 2650.1 2544.5 2584.1 2651.5 2657.8 2664.0 2605.2 2619.1 2645.3 2650.6 2655.8 2296.6 2522.6 2634.7 2640.7 2643.8 9265.5 9323.1 9342.0 9353.8 9376.2 5671.8 5790.9 5831.6 5837.7 5864.6 3636.5 3726.2 3786.0 3791.6 3791.6 1064.6 1074.4 1083.3 1114.9 1121.5 11826.0 12375.6 12428.8 12445.6 12461.1 5002.6 5004.8 5114.6 5131.9 5140.0 10630.7 10642.5 10686.0 10689.8 10692.0 5633.5 5700.9 5753.5 5759.7 5760.5 4701.0 5084.6 5178.0 5186.3 5195.9 4143.2 4182.0 4204.8 4206.2 4217.4 1385.1 1398.1 1401.6 1402.7 1406.8 1368.3 1394.9 1416.7 1421.5 1428.1 8495.9 8620.7 8631.7 8679.6 8721.7 521.7 546.6 547.0 547.3 557.8 5307.9 5931.8 6336.1 6391.5 6426.9 8963.0 11176.6 11234.6 11274.4 11290.7 10729.0 11026.1 11133.2 11255.0 11779.5 8589.4 8819.5 8883.5 8885.5 8927.0 8167.0 8279.5 8319.7 8347.5 8437.2 9760.4 10045.4 10113.7 10173.0 10253.2 10225.8 10514.1 10526.5 10672.3 10712.4 7807.0 7893.0 7976.8 7978.9 7983.9 8678.8 8726.2 8744.6 8748.6 8759.2 7471.6 7692.7 7705.6 7779.6 7794.3 7350.7 7434.9 7481.6 7548.8 7646.9 8074.2 8100.9 8106.2 8116.8 8154.3 7220.9 7326.3 7370.4 7398.3 7432.9 7490.8 7570.5 7590.5 7595.5 7601.3 16:07:40 INFO - PROCESS | 1665 | 16:07:40 INFO - PROCESS | 1665 | [#4] prototype.html Cycles:180 Average:5859.09 Median:6666.35 stddev:1909.22 (28.6%) stddev-sans-first:1868.89 16:07:40 INFO - PROCESS | 1665 | Values: 327.8 342.2 349.8 353.6 359.7 6046.3 6773.4 6781.2 6825.0 6833.8 6650.9 6681.8 6703.1 6713.9 6721.7 4737.2 4851.8 4934.0 4934.6 4997.0 4895.3 4923.7 4957.3 4957.4 4963.1 4016.4 4881.6 4886.0 4898.5 4934.1 4068.3 4895.2 4906.8 4911.5 4917.7 4240.7 4253.3 4258.2 4263.9 4274.0 6213.2 6377.4 6420.9 6434.2 6485.8 5200.3 5478.9 5900.8 5939.3 5945.0 7182.0 7185.4 7213.1 7232.7 7250.4 3737.0 3879.6 3952.4 4777.3 4792.1 7608.8 7628.6 7686.4 7692.7 7708.2 4299.4 4437.4 4438.8 4532.6 4888.1 4941.5 4968.6 4970.7 4977.7 4993.7 5865.3 5881.1 5885.0 5892.1 5919.8 6036.9 6053.8 6914.2 7274.1 7302.8 1689.6 1712.4 1718.0 1719.8 1724.3 1088.6 1123.8 1132.0 1135.9 1148.3 5554.9 6820.2 6834.5 6847.2 6881.8 7461.4 7484.5 7491.7 7511.7 7511.8 5268.9 6712.1 6932.8 6938.9 6951.5 3980.1 3999.9 4032.9 4040.7 4049.9 8014.1 8048.7 8064.4 8066.4 8096.6 6862.8 7140.0 7883.1 7897.1 7912.5 7836.5 7854.6 7857.7 7862.2 7885.0 6328.1 7172.1 7521.3 7574.4 7633.4 5494.3 7227.9 7472.3 7483.3 7507.7 7622.4 7968.4 7971.3 8001.4 8040.8 4272.4 4338.8 4683.8 4764.3 4782.5 6154.4 6872.9 7545.7 7575.1 7627.8 7273.7 7273.9 7295.6 7311.2 7311.8 6480.0 6969.1 7049.1 7258.9 7289.4 7039.4 7058.8 7066.2 7072.9 7082.0 7272.4 7288.6 7299.7 7315.3 7333.3 6130.3 6777.0 7071.9 7134.5 7144.7 16:07:40 INFO - PROCESS | 1665 | 16:07:40 INFO - PROCESS | 1665 | [#5] yui.html Cycles:180 Average:18893.90 Median:3972.43 stddev:26522.95 (667.7%) stddev-sans-first:26562.08 16:07:40 INFO - PROCESS | 1665 | Values: 677.8 693.4 727.4 739.8 798.7 15332.3 15403.8 15565.0 15565.8 15607.5 11996.2 14321.7 14389.4 14413.4 14419.6 815.5 991.1 1005.4 1013.8 1016.9 993.5 1004.7 1008.0 1010.1 1012.3 1053.2 1066.5 1070.2 1072.4 1076.2 1038.3 1045.0 1058.2 1060.2 1065.4 787.3 790.0 791.9 799.4 800.2 15433.5 15454.3 15835.0 15927.6 15966.7 13156.7 13199.4 13265.3 13307.9 13312.3 10294.5 10367.8 10375.6 10376.0 10747.9 3520.5 3549.8 3581.5 3654.1 3821.2 81053.2 83024.0 83121.0 83479.0 83889.7 8280.0 8689.4 9786.0 9980.3 10121.6 23576.6 23594.4 23600.4 23608.5 23623.4 7507.5 7527.8 7642.8 7670.0 7676.6 4075.4 4083.8 4123.9 4152.1 4189.0 3746.5 3788.0 3804.2 3806.5 3807.6 672.7 678.5 684.7 695.4 709.8 577.6 600.2 621.9 625.5 639.3 8223.1 8433.4 8458.7 8564.5 8633.3 615.8 618.3 620.2 628.8 643.4 3870.4 3880.8 3971.6 3973.3 4082.7 94354.0 95258.0 95664.0 101162.0 101692.0 62676.0 76162.6 76984.0 77512.6 77759.0 71771.9 72257.3 75947.2 76742.8 76805.5 8568.2 8670.4 8683.8 8757.7 8804.9 49121.5 49237.5 51495.5 53668.7 53814.7 68419.7 70334.6 70636.0 72988.6 73403.6 9457.8 11431.1 11599.1 11601.5 11716.8 12037.8 12111.2 12284.6 12287.5 12604.5 11581.2 11644.5 11864.0 11869.8 11899.9 11709.8 11753.4 11899.9 11956.0 12283.0 11620.9 11668.7 11682.3 11975.3 12065.2 8340.0 9788.3 9870.4 9951.1 9993.6 9765.7 10024.7 10030.7 10036.3 10142.7 16:07:40 INFO - PROCESS | 1665 | -------- Summary: end -------- 16:07:40 INFO - PROCESS | 1665 | 16:07:40 INFO - TEST-INFO | 1665: exit 0 16:07:40 INFO - TEST-OK | dromaeo_css | took 1110964ms 16:07:40 INFO - TEST-START | kraken 16:07:40 INFO - operating with platform_type : mac_ 16:07:40 INFO - Initialising browser for kraken test... 16:07:40 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/tmpTaLFsP/profile http://localhost:49275/getInfo.html) 16:07:43 INFO - PROCESS | 1677 | __metrics Screen width/height:1600/1200 16:07:43 INFO - PROCESS | 1677 | colorDepth:24 16:07:43 INFO - PROCESS | 1677 | Browser inner width/height: 1024/689 16:07:43 INFO - PROCESS | 1677 | __metrics 16:07:43 ERROR - PROCESS | 1677 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2849: TypeError: this.tabs is undefined 16:07:44 INFO - TEST-INFO | 1677: exit 0 16:07:44 INFO - Browser initialized. 16:07:44 INFO - Running cycle 1/1 for kraken test... 16:07:44 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/tmpTaLFsP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:07:56 INFO - PROCESS | 1680 | RSS: Main: 218402816 16:07:56 INFO - PROCESS | 1680 | 16:07:58 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 16:07:59 INFO - PROCESS | 1680 | RSS: Main: 230240256 16:07:59 INFO - PROCESS | 1680 | 16:08:01 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 16:08:02 INFO - PROCESS | 1680 | RSS: Main: 239087616 16:08:02 INFO - PROCESS | 1680 | 16:08:05 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 16:08:06 INFO - PROCESS | 1680 | RSS: Main: 242622464 16:08:06 INFO - PROCESS | 1680 | 16:08:08 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 16:08:08 INFO - PROCESS | 1680 | RSS: Main: 243154944 16:08:08 INFO - PROCESS | 1680 | 16:08:10 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:08:11 INFO - PROCESS | 1680 | RSS: Main: 244760576 16:08:11 INFO - PROCESS | 1680 | 16:08:14 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 16:08:15 INFO - PROCESS | 1680 | RSS: Main: 245256192 16:08:15 INFO - PROCESS | 1680 | 16:08:17 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 16:08:18 INFO - PROCESS | 1680 | RSS: Main: 244842496 16:08:18 INFO - PROCESS | 1680 | 16:08:21 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 16:08:21 INFO - PROCESS | 1680 | RSS: Main: 244826112 16:08:21 INFO - PROCESS | 1680 | 16:08:23 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:08:24 INFO - PROCESS | 1680 | RSS: Main: 244654080 16:08:24 INFO - PROCESS | 1680 | 16:08:25 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:08:26 INFO - PROCESS | 1680 | RSS: Main: 244899840 16:08:26 INFO - PROCESS | 1680 | 16:08:28 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:08:29 INFO - PROCESS | 1680 | RSS: Main: 245694464 16:08:29 INFO - PROCESS | 1680 | 16:08:31 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:08:32 INFO - PROCESS | 1680 | RSS: Main: 245075968 16:08:32 INFO - PROCESS | 1680 | 16:08:34 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:08:35 INFO - PROCESS | 1680 | RSS: Main: 245215232 16:08:35 INFO - PROCESS | 1680 | 16:08:37 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 16:08:37 INFO - PROCESS | 1680 | RSS: Main: 246964224 16:08:37 INFO - PROCESS | 1680 | 16:08:37 INFO - PROCESS | 1680 | __start_tp_report 16:08:37 INFO - PROCESS | 1680 | _x_x_mozilla_page_load 16:08:37 INFO - PROCESS | 1680 | _x_x_mozilla_page_load_details 16:08:37 INFO - PROCESS | 1680 | |i|pagename|runs| 16:08:37 INFO - PROCESS | 1680 | |0;ai-astar;116;107;113;114;116;130;116;115;114;115 16:08:37 INFO - PROCESS | 1680 | |1;audio-beat-detection;148;120;152;148;122;137;134;119;152;121 16:08:37 INFO - PROCESS | 1680 | |2;audio-dft;192;198;195;196;195;192;196;197;193;196 16:08:37 INFO - PROCESS | 1680 | |3;audio-fft;65;74;70;115;56;55;56;58;116;56 16:08:37 INFO - PROCESS | 1680 | |4;audio-oscillator;82;84;117;88;99;107;158;114;94;158 16:08:37 INFO - PROCESS | 1680 | |5;imaging-gaussian-blur;88;85;107;110;88;108;88;93;110;129 16:08:37 INFO - PROCESS | 1680 | |6;imaging-darkroom;94;94;103;130;94;116;147;93;93;94 16:08:37 INFO - PROCESS | 1680 | |7;imaging-desaturate;91;93;91;93;92;92;92;122;107;144 16:08:37 INFO - PROCESS | 1680 | |8;json-parse-financial;52;59;59;59;61;57;58;68;55;57 16:08:37 INFO - PROCESS | 1680 | |9;json-stringify-tinderbox;64;65;73;75;73;71;69;65;66;68 16:08:37 INFO - PROCESS | 1680 | |10;stanford-crypto-aes;80;81;99;109;111;82;81;82;81;86 16:08:37 INFO - PROCESS | 1680 | |11;stanford-crypto-ccm;136;128;120;132;133;123;120;129;146;139 16:08:37 INFO - PROCESS | 1680 | |12;stanford-crypto-pbkdf2;123;136;137;137;135;125;133;134;136;173 16:08:37 INFO - PROCESS | 1680 | |13;stanford-crypto-sha256-iterative;71;68;66;65;68;67;63;67;73;68 16:08:37 INFO - PROCESS | 1680 | __end_tp_report 16:08:37 INFO - PROCESS | 1680 | __start_cc_report 16:08:37 INFO - PROCESS | 1680 | _x_x_mozilla_cycle_collect,915 16:08:37 INFO - PROCESS | 1680 | __end_cc_report 16:08:37 INFO - PROCESS | 1680 | __startTimestamp1461366517375__endTimestamp 16:08:37 INFO - PROCESS | 1680 | 16:08:37 INFO - PROCESS | 1680 | ------- Summary: start ------- 16:08:37 INFO - PROCESS | 1680 | Number of tests: 14 16:08:37 INFO - PROCESS | 1680 | 16:08:37 INFO - PROCESS | 1680 | [#0] ai-astar Cycles:10 Average:115.60 Median:115.50 stddev:5.72 (5.0%) stddev-sans-first:6.06 16:08:37 INFO - PROCESS | 1680 | Values: 116.0 107.0 113.0 114.0 116.0 130.0 116.0 115.0 114.0 115.0 16:08:37 INFO - PROCESS | 1680 | 16:08:37 INFO - PROCESS | 1680 | [#1] audio-beat-detection Cycles:10 Average:135.30 Median:142.50 stddev:14.01 (9.8%) stddev-sans-first:14.08 16:08:37 INFO - PROCESS | 1680 | Values: 148.0 120.0 152.0 148.0 122.0 137.0 134.0 119.0 152.0 121.0 16:08:37 INFO - PROCESS | 1680 | 16:08:37 INFO - PROCESS | 1680 | [#2] audio-dft Cycles:10 Average:195.00 Median:196.00 stddev:2.05 (1.0%) stddev-sans-first:1.87 16:08:37 INFO - PROCESS | 1680 | Values: 192.0 198.0 195.0 196.0 195.0 192.0 196.0 197.0 193.0 196.0 16:08:37 INFO - PROCESS | 1680 | 16:08:37 INFO - PROCESS | 1680 | [#3] audio-fft Cycles:10 Average:72.10 Median:57.00 stddev:23.79 (41.7%) stddev-sans-first:25.10 16:08:37 INFO - PROCESS | 1680 | Values: 65.0 74.0 70.0 115.0 56.0 55.0 56.0 58.0 116.0 56.0 16:08:37 INFO - PROCESS | 1680 | 16:08:37 INFO - PROCESS | 1680 | [#4] audio-oscillator Cycles:10 Average:110.10 Median:83.00 stddev:27.89 (33.6%) stddev-sans-first:27.67 16:08:37 INFO - PROCESS | 1680 | Values: 82.0 84.0 117.0 88.0 99.0 107.0 158.0 114.0 94.0 158.0 16:08:37 INFO - PROCESS | 1680 | 16:08:37 INFO - PROCESS | 1680 | [#5] imaging-gaussian-blur Cycles:10 Average:100.60 Median:86.50 stddev:14.36 (16.6%) stddev-sans-first:14.49 16:08:37 INFO - PROCESS | 1680 | Values: 88.0 85.0 107.0 110.0 88.0 108.0 88.0 93.0 110.0 129.0 16:08:37 INFO - PROCESS | 1680 | 16:08:37 INFO - PROCESS | 1680 | [#6] imaging-darkroom Cycles:10 Average:105.80 Median:93.50 stddev:19.09 (20.4%) stddev-sans-first:19.76 16:08:37 INFO - PROCESS | 1680 | Values: 94.0 94.0 103.0 130.0 94.0 116.0 147.0 93.0 93.0 94.0 16:08:37 INFO - PROCESS | 1680 | 16:08:37 INFO - PROCESS | 1680 | [#7] imaging-desaturate Cycles:10 Average:101.70 Median:92.00 stddev:17.93 (19.5%) stddev-sans-first:18.59 16:08:37 INFO - PROCESS | 1680 | Values: 91.0 93.0 91.0 93.0 92.0 92.0 92.0 122.0 107.0 144.0 16:08:37 INFO - PROCESS | 1680 | 16:08:37 INFO - PROCESS | 1680 | [#8] json-parse-financial Cycles:10 Average:58.50 Median:59.00 stddev:4.17 (7.1%) stddev-sans-first:3.70 16:08:37 INFO - PROCESS | 1680 | Values: 52.0 59.0 59.0 59.0 61.0 57.0 58.0 68.0 55.0 57.0 16:08:37 INFO - PROCESS | 1680 | 16:08:37 INFO - PROCESS | 1680 | [#9] json-stringify-tinderbox Cycles:10 Average:68.90 Median:70.00 stddev:3.93 (5.6%) stddev-sans-first:3.75 16:08:37 INFO - PROCESS | 1680 | Values: 64.0 65.0 73.0 75.0 73.0 71.0 69.0 65.0 66.0 68.0 16:08:37 INFO - PROCESS | 1680 | 16:08:37 INFO - PROCESS | 1680 | [#10] stanford-crypto-aes Cycles:10 Average:89.20 Median:81.50 stddev:12.31 (15.1%) stddev-sans-first:12.60 16:08:37 INFO - PROCESS | 1680 | Values: 80.0 81.0 99.0 109.0 111.0 82.0 81.0 82.0 81.0 86.0 16:08:37 INFO - PROCESS | 1680 | 16:08:37 INFO - PROCESS | 1680 | [#11] stanford-crypto-ccm Cycles:10 Average:130.60 Median:132.50 stddev:8.41 (6.3%) stddev-sans-first:8.69 16:08:37 INFO - PROCESS | 1680 | Values: 136.0 128.0 120.0 132.0 133.0 123.0 120.0 129.0 146.0 139.0 16:08:37 INFO - PROCESS | 1680 | 16:08:37 INFO - PROCESS | 1680 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:136.90 Median:136.00 stddev:13.61 (10.0%) stddev-sans-first:13.47 16:08:37 INFO - PROCESS | 1680 | Values: 123.0 136.0 137.0 137.0 135.0 125.0 133.0 134.0 136.0 173.0 16:08:37 INFO - PROCESS | 1680 | 16:08:37 INFO - PROCESS | 1680 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.60 Median:68.00 stddev:2.84 (4.2%) stddev-sans-first:2.73 16:08:37 INFO - PROCESS | 1680 | Values: 71.0 68.0 66.0 65.0 68.0 67.0 63.0 67.0 73.0 68.0 16:08:37 INFO - PROCESS | 1680 | -------- Summary: end -------- 16:08:37 INFO - PROCESS | 1680 | 16:08:38 INFO - TEST-INFO | 1680: exit 0 16:08:38 INFO - TEST-OK | kraken | took 57200ms 16:08:38 INFO - SUITE-END | took 1168s 16:08:38 INFO - Completed test suite (00:19:28) 16:08:38 INFO - javascript benchmark 16:08:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3125.3905488429764, 3186.1556687477823, 3539.2213712983144, 3586.569611646603, 3658.158623516591, 5803.361630220677, 5824.504917082042, 5848.7660493580315, 5865.970670146648, 5866.970665146674, 4674.3923289972345, 5856.9750293698535, 5880.764769409218, 5888.5877988540815, 5935.258092738408, 4824.469308376076, 4912.508749125077, 5249.16013437851, 5287.576993840503, 5384.757685904144, 5265.578753699714, 5399.325084364455, 5408.702521361323, 5415.620906536544, 5417.187421886709, 4958.752062396884, 4964.776585053663, 5386.003589335965, 5390.865228369303, 5392.110301800199, 5311.0, 5343.465653434664, 5372.623916325859, 5375.274337964363, 5398.6220964532495, 5334.626576139672, 5346.358436987586, 5401.405845357008, 5423.972880135574, 5428.0, 5830.3586605473365, 5841.240638716982, 5847.678377689225, 5867.031939729939, 5888.29340479141, 5901.321348044944, 6202.255729312466, 6203.317635060185, 6280.811575652738, 6330.81007569769, 6280.905786413207, 6308.2430108386825, 6308.905366419507, 6319.968400158015, 6321.589096708699, 5957.1362152487645, 5978.790742324085, 5983.222181116471, 5997.5201983841625, 6002.939970600325, 6287.339829317856, 6320.747170113144, 6336.461400780893, 6350.714217860207, 6380.55336126476, 5304.363476382781, 6268.686565671698, 6278.874422511615, 6287.654179020198, 6293.559450838489, 6249.125122482856, 6271.529635277372, 6291.811245662638, 6294.842628934313, 6304.590201636879, 6168.8766224675255, 6216.875662486816, 6246.094316324107, 6246.219222597176, 6280.2777680566405, 6195.411435913581, 6234.75060997555, 6270.623762574261, 6292.528060395489, 6334.429901308812, 4886.853394398174, 4937.35814344133, 4965.155923493015, 4984.352034235526, 4995.400551933792, 4341.370501277297, 4415.757133357696, 4417.668674849399, 4448.532904045093, 4531.750753708578, 5966.433188847052, 6011.008183649649, 6031.9396806032255, 6035.969820150959, 6072.483838873745, 6204.5967012144065, 6238.500919926395, 6315.115883776275, 6323.241211054611, 6327.0, 5893.675847828325, 5953.910691339547, 5981.342052374235, 6042.6978651067275, 6051.636901785907, 5474.479924407175, 5481.780728770885, 5491.533219676372, 5501.092319767274, 5515.4760297771645, 6333.113364128933, 6424.775132870233, 6429.099926010363, 6432.581882177644, 6447.484201263887, 6255.749770009331, 6263.96868015657, 6277.529185311211, 6313.116163736989, 6322.683865806599, 6427.357264273535, 6446.806595801946, 6447.838804029937, 6450.322716114928, 6468.191476065492, 5704.743286552198, 6225.190725205694, 6288.5598008138995, 6324.6521441321165, 6353.078803573549, 6280.246370435577, 6308.810735677937, 6318.178636777189, 6340.239171299474, 6346.111544383883, 5144.717040562805, 6251.874962500816, 6265.404786545271, 6275.184226050585, 6294.087357333253, 5369.248305237192, 5837.6497410155525, 6129.835331287041, 6149.86227547905, 6201.10084037803, 5958.255218097738, 6034.63792172471, 6039.879202416015, 6051.848703782441, 6100.908486372619, 5443.911217756386, 6104.11490333908, 6197.163382943245, 6200.3489633589625, 6240.906386404116, 5898.262717160355, 6049.818505444844, 6125.173101631222, 6138.480361557057, 6196.628202307876], "unit": "score", "name": "dojo.html", "value": 5773.098841193193}, {"lowerIsBetter": false, "replicates": [568.8037627018678, 582.9157766554208, 594.459042271533, 665.1386454541368, 677.9830254618073, 38856.41715374268, 39290.41064384037, 39352.0, 39470.80264598677, 39485.0, 40927.38608920869, 41523.169536609246, 41539.16921661565, 41584.75245742625, 41600.0, 9580.520973951292, 9870.309078364517, 9880.258980576486, 9889.703308900744, 9901.158401535879, 9727.805443891119, 9886.802263954716, 9891.802163956716, 9892.15916647086, 9896.901030989706, 9836.606535738598, 9874.506274686273, 9900.306978511508, 9918.900810991907, 9943.254255930799, 8652.697155599524, 9832.754181145492, 9866.358686685378, 9874.308798384116, 9893.15908147812, 9491.288153388454, 9830.21358291335, 9834.0, 9839.803203935953, 9854.359466634645, 34638.82680586581, 39938.40246390141, 39965.20069598592, 40199.397009044886, 40211.39682904759, 3095.767817413703, 3402.2344972381266, 3554.377983852841, 3669.9633003669887, 3926.7447615904875, 27350.726492735015, 27947.161585152477, 27964.561109113332, 28072.43855122886, 28242.858785706147, 2749.986250068757, 2803.1450407625666, 2826.5336219523756, 2835.4896118698625, 2871.870765815543, 430531.0, 435361.8231908852, 435552.0, 438102.0, 439677.0, 67639.0, 67916.320836791, 68149.65925170343, 68229.97655035178, 68270.97593536101, 78812.60593696995, 79472.80790788143, 80530.0, 80624.0, 80749.0, 18125.36561220351, 18158.0, 18360.357387491375, 18368.908155459136, 18424.35514756977, 13949.372278247503, 13993.580192594238, 14046.508372206923, 14090.577282681332, 14130.293485325898, 22066.779332206792, 22265.220717274817, 22305.0, 22429.43926401853, 22996.85015749206, 9241.768955776159, 9497.0, 9513.336265845574, 9536.2371010319, 9546.0, 938.5260443476001, 1002.5789168549227, 1007.3452256033637, 1112.8887111288968, 1127.0476447401932, 24727.752722472545, 27939.58090628642, 29248.122556323346, 29515.409691806042, 29922.0, 1129.949152288149, 1153.896149346546, 1170.8302296167178, 1177.2112684501406, 1216.7566486702694, 14739.55781326562, 15616.0, 15794.36822527109, 16158.838411615969, 16979.0, 710628.0, 711767.0, 712964.740705183, 722539.0, 723189.0, 65606.67196663986, 67347.66326168338, 67505.66247168733, 67710.66144669344, 68143.65928170328, 1555.4866893924977, 1565.6633823727952, 1568.3256199833952, 1572.3946280682019, 1683.8316168383308, 31145.0, 31427.0, 31773.841130794197, 31903.0, 31948.44466476639, 53502.73248633732, 54178.729106354214, 54228.0, 54304.72847635736, 54388.18417723658, 523362.0, 527603.0, 535101.973470406, 535996.0, 539357.0, 33440.832795835864, 33442.16394590155, 33667.663323366454, 34763.957081287605, 35219.11952201215, 49769.502304976486, 51575.226371605204, 51688.0, 52582.0, 53145.0, 38303.23393532058, 38894.0, 39633.41466341314, 39692.427681507645, 40803.1839363217, 37370.43944340783, 38220.42669359906, 38552.0, 38841.6115838838, 38918.61081389263, 34159.4876076864, 37808.810955945046, 38267.0, 41485.58514414817, 42042.0, 32262.838685806422, 33579.3284134311, 33612.66387336095, 33633.15917102092, 34155.0, 24967.500649986534, 25360.365990850376, 25597.744022559535, 25834.095806646677, 26408.8679556601], "unit": "score", "name": "ext.html", "value": 21044.91964107799}, {"lowerIsBetter": false, "replicates": [379.57677189933213, 385.6567654787239, 392.63877232945686, 394.5856850307177, 394.8420631747301, 18059.0, 19951.700724489125, 19979.200831966722, 21754.585951913155, 22346.77653223467, 20975.16099356028, 20989.055492502837, 21238.150473981063, 21245.99999999996, 21334.35996920095, 4311.611954924057, 5642.322921249445, 5944.7324870380935, 5945.732442040097, 5948.613340132899, 4726.8581942541605, 6428.582142160744, 6499.415052645261, 6524.771632992846, 6528.2166140063255, 5533.22534845122, 5738.31140263169, 6200.379962003809, 6453.967730161343, 6466.805995820133, 6364.109024736517, 6371.044343348489, 6398.2322121345505, 6398.7760428385245, 6420.229572451312, 5504.422035686275, 5543.113101903676, 5550.777968881281, 5582.91625625616, 5613.943860561424, 25467.61798573023, 25728.485430291475, 25890.22329330123, 25921.22236332913, 26448.471030579472, 1627.9755803662954, 2170.8371872109656, 2273.022600281878, 2433.9269821905373, 2655.4556315955183, 12261.509539618404, 14665.706685866224, 14794.0, 15282.388704451809, 15545.689086218325, 1750.3611181918636, 1782.4741701198047, 1796.7125259958343, 1810.2668419290208, 1884.547708549938, 108573.45713271463, 109283.26791830188, 110771.0, 111865.32202016977, 113431.43284283526, 10772.407517586607, 10899.1280697544, 11021.779564408827, 11232.213745037769, 11317.320960742381, 13530.0, 13674.794878076837, 13700.314984250748, 13703.725925481434, 13740.312984350743, 8953.149450802164, 8984.820303594022, 9002.189802917637, 9021.50381728998, 9115.863262051074, 9367.250619950388, 9398.530073496298, 9404.576794044284, 9487.430754154773, 9491.335606507479, 5297.65565238266, 5377.865553361119, 6271.216097987752, 6327.272363678236, 6343.841403964845, 1612.7097122517825, 1868.0379604503692, 1899.5156235160223, 1905.3140869287045, 1908.866379353432, 986.3933680786265, 1058.4684912364264, 1067.8291473364225, 1068.3803394031443, 1079.5735684404617, 19166.041697915047, 19866.404007879497, 21218.0, 21436.892815535823, 21684.132634694437, 1051.1590727418036, 1063.1388575254068, 1073.4525392049954, 1093.5188517052472, 1119.4906317625623, 10378.169746420268, 11661.941690291495, 11886.346250956107, 11899.226550274378, 11910.46402911871, 642258.7887060628, 649991.0, 656319.0, 681925.0, 701596.0, 69950.30049699539, 77603.0, 86557.26885462255, 109171.0, 109294.45352773185, 1069.272894431794, 1078.1213311151544, 1105.5798796457295, 1115.4088333183427, 1150.1431433581868, 18121.003344816163, 18318.81681183171, 18555.443336699922, 18699.532511687317, 18944.431667050012, 51119.2332115026, 51458.48541514537, 52642.0, 53333.46666533285, 54789.0, 78015.0, 80818.19181808106, 82944.58527707323, 83885.16114839018, 84298.73551896604, 7248.420126389981, 8278.006639203335, 8285.171482851667, 8401.99176098872, 8430.915690843012, 12484.750304993668, 12486.37677101971, 13292.136011159244, 13353.265570393722, 13462.990275729555, 9939.006099390002, 10223.079922806983, 10500.579976800842, 11534.884651153381, 11683.299002059903, 11180.608678696206, 11310.717232069264, 11382.658520244406, 11627.18609697347, 11654.18420710576, 11521.078313735048, 11560.075193984629, 11958.342291174069, 12017.218880772722, 12022.0, 7773.766786996399, 7973.681052757825, 8227.42408031456, 8341.624626891684, 8573.74278771638, 9261.861072084059, 10069.69790906274, 11774.0, 11797.410129493663, 11803.527858885893], "unit": "score", "name": "jquery.html", "value": 9851.041306449508}, {"lowerIsBetter": false, "replicates": [1644.457329081403, 1664.5102633143333, 2021.0804084141716, 2060.7321048263716, 2112.8098471137596, 8501.872471912926, 9095.408798428098, 9256.814863702723, 9466.76333091673, 9469.810603787928, 9196.21832144267, 9208.355415120945, 9310.860337094931, 9316.394434361762, 9333.859992100124, 2599.662043934286, 2626.3565426470445, 2628.763411292983, 2628.789696824259, 2658.9335266618295, 2560.4367039251333, 2637.222019504251, 2644.3256969472855, 2648.245250103721, 2650.08572042645, 2544.465662210938, 2584.121398724433, 2651.4564514274625, 2657.787377009844, 2663.986680066607, 2605.179368498926, 2619.0571394298036, 2645.2989957661234, 2650.628911952338, 2655.8273712208643, 2296.6210575255063, 2522.574745268917, 2634.749698778613, 2640.749128832758, 2643.7792254506307, 9265.490398028105, 9323.06769323069, 9342.0, 9353.76615584609, 9376.249900007982, 5671.829845104653, 5790.855228619276, 5831.620944638585, 5837.737301821428, 5864.648121112704, 3636.4545318202217, 3726.1988672435555, 3786.0, 3791.6208379161862, 3791.620837916214, 1064.5582083435365, 1074.4359211413991, 1083.2958576924982, 1114.8940850619178, 1121.5233525751605, 11826.0, 12375.628731138171, 12428.813567796491, 12445.62663120108, 12461.127721059529, 5002.57478114357, 5004.824831130893, 5114.565261952701, 5131.947950670104, 5139.974300128475, 10630.734231644115, 10642.521086551124, 10686.0, 10689.786204275872, 10692.0, 5633.507120613041, 5700.9429905701245, 5753.539716822645, 5759.683217423082, 5760.510356619734, 4700.976495117503, 5084.618653600995, 5178.016176926372, 5186.273921650972, 5195.922061169085, 4143.233501802176, 4181.996320883027, 4204.831806727697, 4206.179794939957, 4217.36739489073, 1385.072001758824, 1398.077269002474, 1401.5515035188846, 1402.726468338664, 1406.7819487979175, 1368.2679766324968, 1394.9302534873216, 1416.7379034878375, 1421.5166843273337, 1428.0717533603033, 8495.872561911576, 8620.698275560451, 8631.654733810577, 8679.566021698889, 8721.65113395457, 521.6583138044587, 546.5764032874474, 546.9808556700497, 547.3185883574974, 557.8465921871518, 5307.946920530745, 5931.792387266425, 6336.081268216083, 6391.456726178234, 6426.903596446057, 8963.0, 11176.552937882556, 11234.550617975354, 11274.379909105079, 11290.661280161608, 10729.034386905216, 11026.062784663394, 11133.220674552704, 11255.0, 11779.526299477035, 8589.44168629037, 8819.470831750115, 8883.511406872683, 8885.466871987572, 8926.955365223263, 8167.001651708272, 8279.50322980623, 8319.708810191856, 8347.54088525137, 8437.156284371515, 9760.414375137514, 10045.397276163454, 10113.69658910234, 10173.033561811617, 10253.179745620482, 10225.795484090128, 10514.053735163872, 10526.473676316, 10672.30630009047, 10712.410817405118, 7806.9850919380115, 7893.0, 7976.840463190588, 7978.880316795136, 7983.8802417964935, 8678.783030424038, 8726.214640682369, 8744.606492707971, 8748.606312715816, 8759.16787905148, 7471.589062601392, 7692.692292308245, 7705.6147192641265, 7779.611019449118, 7794.2985131338455, 7350.70597176107, 7434.851302974019, 7481.551106933602, 7548.8490230196185, 7646.885296720442, 8074.1522140176785, 8100.918990810253, 8106.229908158715, 8116.83766324682, 8154.266116049584, 7220.855582888418, 7326.267373262631, 7370.447216458894, 7398.260173982774, 7432.92567074344, 7490.812729681802, 7570.545767253982, 7590.468667193243, 7595.5442673439775, 7601.315881570686], "unit": "score", "name": "mootools.html", "value": 5116.909150195418}, {"lowerIsBetter": false, "replicates": [327.84099711639834, 342.2316898562727, 349.75691894133587, 353.63928792631526, 359.72121605755535, 6046.3349031606485, 6773.424258937984, 6781.186257649077, 6824.952514245725, 6833.829154271146, 6650.900236496444, 6681.799546013615, 6703.095082163906, 6713.899291510631, 6721.697523611437, 4737.218358970783, 4851.781669824866, 4934.037862616791, 4934.605231581465, 4996.950640365528, 4895.265710143472, 4923.729194894279, 4957.25641153828, 4957.355543779304, 4963.057019166365, 4016.4376987221813, 4881.585065269456, 4886.0, 4898.485659005806, 4934.111859865223, 4068.2677118118727, 4895.192293271606, 4906.828261010865, 4911.5088491150955, 4917.655764096533, 4240.681948853849, 4253.29820579604, 4258.190943720714, 4263.850765223233, 4273.978630106861, 6213.161223234851, 6377.362263773632, 6420.903686444707, 6434.227892652865, 6485.77299794505, 5200.271961925334, 5478.890422191534, 5900.763969441217, 5939.287285525721, 5945.048792193272, 7181.964090179516, 7185.425165986709, 7213.134423869117, 7232.674529646179, 7250.383717384028, 3736.981315093434, 3879.5926427724967, 3952.4268980997595, 4777.307290442866, 4792.137415265251, 7608.80977975555, 7628.580428076453, 7686.385089192907, 7692.730754423568, 7708.190639982777, 4299.441072660534, 4437.444360747763, 4438.800253988578, 4532.5694059064335, 4888.144574699413, 4941.505849415102, 4968.577670898014, 4970.701757894538, 4977.701337919736, 4993.725345105981, 5865.266841644794, 5881.147233651182, 5885.0, 5892.1161825726485, 5919.792807251725, 6036.939630603638, 6053.7881174159575, 6914.162639734895, 7274.054372931485, 7302.817429564303, 1689.560714214313, 1712.3664244229715, 1717.957051073733, 1719.75063615775, 1724.2585688154213, 1088.6081010836251, 1123.775244951013, 1131.9773604527863, 1135.9488823002985, 1148.3339662995443, 5554.888902221933, 6820.249772525019, 6834.4874134440115, 6847.246802851682, 6881.759138430231, 7461.365783908429, 7484.513506622052, 7491.700331986768, 7511.737089201852, 7511.774646760606, 5268.947310526845, 6712.127423434922, 6932.757353492704, 6938.895916561256, 6951.478639102088, 3980.124372638015, 3999.900002499961, 4032.9111139992624, 4040.676745860383, 4049.91900162001, 8014.078380986145, 8048.718294859769, 8064.435489515795, 8066.435349525595, 8096.5546894921345, 6862.759803406857, 7140.0, 7883.132855385903, 7897.091834439113, 7912.525248485109, 7836.490628109269, 7854.646540905559, 7857.685692572348, 7862.213778622092, 7885.0, 6328.14570033058, 7172.103487064117, 7521.285477879593, 7574.356179724565, 7633.351165151024, 5494.340679118532, 7227.855442891007, 7472.327490525661, 7483.289087536676, 7507.662155203138, 7622.428317876293, 7968.362530997622, 7971.282584567417, 8001.359891208573, 8040.839183216186, 4272.4445822042935, 4338.7830608470085, 4683.812647494062, 4764.285357196448, 4782.545658162469, 6154.3845615438095, 6872.862542749217, 7545.660445279866, 7575.0909890813455, 7627.847443051219, 7273.709051637875, 7273.890891636735, 7295.635218238961, 7311.159216690149, 7311.817204569716, 6479.967600161969, 6969.128858892638, 7049.118860142482, 7258.927410725825, 7289.416846652149, 7039.380942383178, 7058.823529411806, 7066.187388450188, 7072.893906591303, 7081.964590177016, 7272.381847543018, 7288.599127048064, 7299.708011679686, 7315.30504602062, 7333.266673332624, 6130.264368275835, 6777.017332486663, 7071.939209118674, 7134.536255143399, 7144.749668807834], "unit": "score", "name": "prototype.html", "value": 5236.732671803322}, {"lowerIsBetter": false, "replicates": [677.8305423644089, 693.4209934704521, 727.4289682599159, 739.7743688175104, 798.6605692580654, 15332.310046047927, 15403.84596154038, 15565.0, 15565.766513502307, 15607.453739119133, 11996.160268781188, 14321.71356572868, 14389.424423023067, 14413.351399187035, 14419.63950901228, 815.5066184958104, 991.0634450444333, 1005.3967619428339, 1013.7617659849966, 1016.872890888639, 993.5479356892597, 1004.6734811186357, 1007.9748006299828, 1010.1312870930993, 1012.2762225009117, 1053.152212468966, 1066.4987455895755, 1070.1973519860105, 1072.4316112460408, 1076.2412499188054, 1038.2991480750502, 1045.033344156652, 1058.2327812336073, 1060.194252368202, 1065.3607835298835, 787.3425689549176, 789.9960500197519, 791.9111222069654, 799.4124318625805, 800.1958032177682, 15433.5369938902, 15454.304556294994, 15835.0, 15927.601809954727, 15966.680666386723, 13156.736865262737, 13199.406026728724, 13265.336733163304, 13307.933460332733, 13312.267825269606, 10294.536745846455, 10367.844482332772, 10375.636852710195, 10376.0, 10747.946260268649, 3520.471929210639, 3549.804760738158, 3581.5164952731307, 3654.086478380405, 3821.1975485147873, 81053.18946810575, 83024.0, 83121.0, 83479.0, 83889.74165387524, 8279.95860020696, 8689.435186712843, 9785.951070244746, 9980.251481138943, 10121.595136194617, 23576.64635030476, 23594.410139746644, 23600.409989750395, 23608.527829443316, 23623.409414764425, 7507.512011719221, 7527.8118047048165, 7642.847143057108, 7670.041244844395, 7676.57778822159, 4075.370522082395, 4083.816228269735, 4123.9381409278885, 4152.065785198306, 4188.979055104766, 3746.4942232798767, 3787.962120378816, 3804.2201348723447, 3806.4861243731743, 3807.5621303550447, 672.653583404547, 678.5385937562505, 684.7432212920155, 695.4158506854267, 709.836737550356, 577.6216578141324, 600.2347007565389, 621.8880601491685, 625.4746013348717, 639.3063526074169, 8223.136570660114, 8433.409661323649, 8458.703945361884, 8564.48613083217, 8633.33664645669, 615.7844754335947, 618.2704408797662, 620.2123303404651, 628.7516431009725, 643.427349658804, 3870.4000879863665, 3880.8059597020037, 3971.5829837867186, 3973.2848087343978, 4082.6937979651643, 94354.0, 95258.0, 95664.0, 101162.0, 101692.0, 62676.0, 76162.61918690371, 76984.0, 77512.61243693746, 77759.0, 71771.92342114872, 72257.2774272261, 75947.24052759512, 76742.84885726719, 76805.46389072188, 8568.228859402683, 8670.436421632574, 8683.782905427415, 8757.693480728143, 8804.867926980973, 49121.50878491169, 49237.50762492473, 51495.48504514907, 53668.73165634147, 53814.73092634668, 68419.65790171018, 70334.64832675803, 70636.0, 72988.63505682438, 73403.63298183474, 9457.763555911157, 11431.085513159094, 11599.130065244972, 11601.477933493183, 11716.824247636463, 12037.78004834629, 12111.212771169845, 12284.631461056182, 12287.508499659914, 12604.49582016709, 11581.247218930743, 11644.53421863116, 11864.0, 11869.76260474803, 11899.94050029779, 11709.824352634887, 11753.353565553976, 11899.940500297444, 11956.0, 12282.955948744457, 11620.883791162318, 11668.70828229301, 11682.299062056303, 11975.28148311104, 12065.215760975509, 8340.0, 9788.314817962675, 9870.358426702242, 9951.104400603981, 9993.600255989679, 9765.707028789147, 10024.694320306224, 10030.749231269278, 10036.297459177787, 10142.695719128438], "unit": "score", "name": "yui.html", "value": 7213.467814548167}], "name": "dromaeo_css", "value": 7835.156323846625}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [116.0, 107.0, 113.0, 114.0, 116.0, 130.0, 116.0, 115.0, 114.0, 115.0], "unit": "score", "name": "ai-astar", "value": 115.6}, {"lowerIsBetter": true, "replicates": [148.0, 120.0, 152.0, 148.0, 122.0, 137.0, 134.0, 119.0, 152.0, 121.0], "unit": "score", "name": "audio-beat-detection", "value": 135.3}, {"lowerIsBetter": true, "replicates": [192.0, 198.0, 195.0, 196.0, 195.0, 192.0, 196.0, 197.0, 193.0, 196.0], "unit": "score", "name": "audio-dft", "value": 195.0}, {"lowerIsBetter": true, "replicates": [65.0, 74.0, 70.0, 115.0, 56.0, 55.0, 56.0, 58.0, 116.0, 56.0], "unit": "score", "name": "audio-fft", "value": 72.1}, {"lowerIsBetter": true, "replicates": [82.0, 84.0, 117.0, 88.0, 99.0, 107.0, 158.0, 114.0, 94.0, 158.0], "unit": "score", "name": "audio-oscillator", "value": 110.1}, {"lowerIsBetter": true, "replicates": [88.0, 85.0, 107.0, 110.0, 88.0, 108.0, 88.0, 93.0, 110.0, 129.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 100.6}, {"lowerIsBetter": true, "replicates": [94.0, 94.0, 103.0, 130.0, 94.0, 116.0, 147.0, 93.0, 93.0, 94.0], "unit": "score", "name": "imaging-darkroom", "value": 105.8}, {"lowerIsBetter": true, "replicates": [91.0, 93.0, 91.0, 93.0, 92.0, 92.0, 92.0, 122.0, 107.0, 144.0], "unit": "score", "name": "imaging-desaturate", "value": 101.7}, {"lowerIsBetter": true, "replicates": [52.0, 59.0, 59.0, 59.0, 61.0, 57.0, 58.0, 68.0, 55.0, 57.0], "unit": "score", "name": "json-parse-financial", "value": 58.5}, {"lowerIsBetter": true, "replicates": [64.0, 65.0, 73.0, 75.0, 73.0, 71.0, 69.0, 65.0, 66.0, 68.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 68.9}, {"lowerIsBetter": true, "replicates": [80.0, 81.0, 99.0, 109.0, 111.0, 82.0, 81.0, 82.0, 81.0, 86.0], "unit": "score", "name": "stanford-crypto-aes", "value": 89.2}, {"lowerIsBetter": true, "replicates": [136.0, 128.0, 120.0, 132.0, 133.0, 123.0, 120.0, 129.0, 146.0, 139.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 130.6}, {"lowerIsBetter": true, "replicates": [123.0, 136.0, 137.0, 137.0, 135.0, 125.0, 133.0, 134.0, 136.0, 173.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 136.9}, {"lowerIsBetter": true, "replicates": [71.0, 68.0, 66.0, 65.0, 68.0, 67.0, 63.0, 67.0, 73.0, 68.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 67.6}], "name": "kraken", "value": 1487.9}]} 16:08:38 INFO - Return code: 0 16:08:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:08:38 ERROR - # TBPL SUCCESS # 16:08:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:08:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:08:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:08:38 INFO - 2016-04-22 16:08:38.681 screenresolution[1685:10836] starting screenresolution argv=screenresolution get 16:08:38 INFO - 2016-04-22 16:08:38.684 screenresolution[1685:10836] Display 0: 1600x1200x32@60 16:08:38 INFO - 2016-04-22 16:08:38.690 screenresolution[1686:10839] starting screenresolution argv=screenresolution list 16:08:38 INFO - Available Modes on Display 0 16:08:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:08:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:08:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:08:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:08:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:08:38 INFO - Intel Iris: 16:08:38 INFO - Chipset Model: Intel Iris 16:08:38 INFO - Type: GPU 16:08:38 INFO - Bus: Built-In 16:08:38 INFO - VRAM (Dynamic, Max): 1536 MB 16:08:38 INFO - Vendor: Intel (0x8086) 16:08:38 INFO - Device ID: 0x0a2e 16:08:38 INFO - Revision ID: 0x0009 16:08:38 INFO - Displays: 16:08:38 INFO - Display: 16:08:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:08:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:08:38 INFO - Display Serial Number: 1600x1200 60 16:08:38 INFO - Main Display: Yes 16:08:38 INFO - Mirror: Off 16:08:38 INFO - Online: Yes 16:08:38 INFO - Rotation: Supported 16:08:38 INFO - Return code: 0 16:08:38 INFO - Running post-action listener: _resource_record_post_action 16:08:38 INFO - Running post-run listener: _resource_record_post_run 16:08:39 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 37717504; Write bytes: 671145984; Read time: 389; Write time: 2080 16:08:39 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 361394688; Write bytes: 356779520; Read time: 28258; Write time: 699 16:08:39 INFO - run-tests - Wall time: 1171s; CPU: 54.0%; Read bytes: 35898880; Write bytes: 304237056; Read time: 369; Write time: 1352 16:08:39 INFO - Running post-run listener: _upload_blobber_files 16:08:39 INFO - Blob upload gear active. 16:08:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:08:39 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:08:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:08:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:08:40 INFO - (blobuploader) - INFO - Open directory for files ... 16:08:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:08:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:08:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:08:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:08:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:08:41 INFO - (blobuploader) - INFO - Done attempting. 16:08:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:08:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:08:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:08:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:08:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:08:43 INFO - (blobuploader) - INFO - Done attempting. 16:08:43 INFO - (blobuploader) - INFO - Iteration through files over. 16:08:43 INFO - Return code: 0 16:08:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:08:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:08:43 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/40bb69b755a67922f34c34f4ac298b8c8ab29bd1472730d614a52165f8d4b857a34c7848641ed691ef5b992445de21f60f147737cdc6fb68f94612178aafa292", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5036acd0b5741d58044ab1ad240919fcd0e4b6a597a074b3d403cd00b5a5e4e41275213aee4ac53abe6f08c681756eca664ab6029c93e36979a3c66829f75452"} 16:08:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:08:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:08:43 INFO - Contents: 16:08:43 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/40bb69b755a67922f34c34f4ac298b8c8ab29bd1472730d614a52165f8d4b857a34c7848641ed691ef5b992445de21f60f147737cdc6fb68f94612178aafa292", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5036acd0b5741d58044ab1ad240919fcd0e4b6a597a074b3d403cd00b5a5e4e41275213aee4ac53abe6f08c681756eca664ab6029c93e36979a3c66829f75452"} 16:08:43 INFO - Running post-run listener: copy_logs_to_upload_dir 16:08:43 INFO - Copying logs to upload dir... 16:08:43 INFO - mkdir: /builds/slave/test/build/upload/logs 16:08:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.125381 ========= master_lag: 1.16 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-04-22 16:08:44.661712) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-22 16:08:44.665609) ========= 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.oIyrKS3VOT/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.Me1zZKH9gk/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/40bb69b755a67922f34c34f4ac298b8c8ab29bd1472730d614a52165f8d4b857a34c7848641ed691ef5b992445de21f60f147737cdc6fb68f94612178aafa292", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5036acd0b5741d58044ab1ad240919fcd0e4b6a597a074b3d403cd00b5a5e4e41275213aee4ac53abe6f08c681756eca664ab6029c93e36979a3c66829f75452"} build_url:https://queue.taskcluster.net/v1/task/WMriI2IhRtGQ_iEYFHgolw/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010827 build_url: 'https://queue.taskcluster.net/v1/task/WMriI2IhRtGQ_iEYFHgolw/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/40bb69b755a67922f34c34f4ac298b8c8ab29bd1472730d614a52165f8d4b857a34c7848641ed691ef5b992445de21f60f147737cdc6fb68f94612178aafa292", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5036acd0b5741d58044ab1ad240919fcd0e4b6a597a074b3d403cd00b5a5e4e41275213aee4ac53abe6f08c681756eca664ab6029c93e36979a3c66829f75452"}' ========= master_lag: 0.66 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-22 16:08:45.340824) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 4 secs) (at 2016-04-22 16:08:45.341117) ========= 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.oIyrKS3VOT/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.Me1zZKH9gk/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/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.005120 ========= master_lag: 4.59 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 4 secs) (at 2016-04-22 16:08:49.931542) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-22 16:08:49.931813) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-22 16:08:50.327960) ========= ========= Total master_lag: 6.47 =========