builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0059 starttime: 1461365232.4 results: success (0) buildid: 20160422142650 builduid: 0e0a78d064b14b7f8537b3303394d2df revision: 922334c70324e4295dcfcfafe6da4ae2e1154834 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-22 15:47:12.400756) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-22 15:47:12.401194) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-22 15:47:12.401498) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-22 15:47:12.450677) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 15:47:12.450985) ========= 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.m5kUaxvtR0/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.b71wKnQjd3/Listeners TMPDIR=/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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:12-- 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% 20.9M=0.001s 2016-04-22 15:47:12 (20.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.173314 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 15:47:12.644872) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 15:47:12.645564) ========= 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.m5kUaxvtR0/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.b71wKnQjd3/Listeners TMPDIR=/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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.026404 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 15:47:12.700163) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-22 15:47:12.700602) ========= 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.m5kUaxvtR0/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.b71wKnQjd3/Listeners TMPDIR=/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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:12,771 truncating revision to first 12 chars 2016-04-22 15:47:12,772 Setting DEBUG logging. 2016-04-22 15:47:12,772 attempt 1/10 2016-04-22 15:47:12,772 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:14,333 unpacking tar archive at: mozilla-aurora-922334c70324/testing/mozharness/ program finished with exit code 0 elapsedTime=1.814163 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-22 15:47:14.534366) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-22 15:47:14.534687) ========= script_repo_revision: 922334c70324e4295dcfcfafe6da4ae2e1154834 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-22 15:47:14.535123) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-22 15:47:14.535424) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-22 15:47:14.549536) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-04-22 15:47:14.549852) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.m5kUaxvtR0/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.b71wKnQjd3/Listeners TMPDIR=/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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:14 INFO - MultiFileLogger online at 20160422 15:47:14 in /builds/slave/test 15:47:14 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 15:47:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:47:14 INFO - {'append_to_log': False, 15:47:14 INFO - 'base_work_dir': '/builds/slave/test', 15:47:14 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 15:47:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:47:14 INFO - 'branch': 'Mozilla-Aurora', 15:47:14 INFO - 'buildbot_json_path': 'buildprops.json', 15:47:14 INFO - 'config_files': ('talos/mac_config.py',), 15:47:14 INFO - 'default_actions': ('clobber', 15:47:14 INFO - 'read-buildbot-config', 15:47:14 INFO - 'download-and-extract', 15:47:14 INFO - 'populate-webroot', 15:47:14 INFO - 'create-virtualenv', 15:47:14 INFO - 'install', 15:47:14 INFO - 'run-tests'), 15:47:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:47:14 INFO - 'download_minidump_stackwalk': True, 15:47:14 INFO - 'download_symbols': 'ondemand', 15:47:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:47:14 INFO - 'tooltool.py': '/tools/tooltool.py', 15:47:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:47:14 INFO - '/tools/misc-python/virtualenv.py')}, 15:47:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:47:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:47:14 INFO - 'installer_path': 'installer.exe', 15:47:14 INFO - 'log_level': 'info', 15:47:14 INFO - 'log_name': 'talos', 15:47:14 INFO - 'log_to_console': True, 15:47:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:47:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:47:14 INFO - 'opt_config_files': (), 15:47:14 INFO - 'pip_index': False, 15:47:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:47:14 INFO - 'cmd': ('bash', 15:47:14 INFO - '-c', 15:47:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:47:14 INFO - 'enabled': True, 15:47:14 INFO - 'halt_on_failure': False, 15:47:14 INFO - 'name': 'check_screen_resolution'},), 15:47:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:47:14 INFO - 'cmd': ('bash', 15:47:14 INFO - '-c', 15:47:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:47:14 INFO - 'enabled': True, 15:47:14 INFO - 'halt_on_failure': False, 15:47:14 INFO - 'name': 'check_screen_resolution'},), 15:47:14 INFO - 'run_cmd_checks_enabled': True, 15:47:14 INFO - 'sps_profile': False, 15:47:14 INFO - 'sps_profile_interval': 0, 15:47:14 INFO - 'suite': 'dromaeojs', 15:47:14 INFO - 'system_bits': '32', 15:47:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:47:14 INFO - 'title': 't-yosemite-r7-0059', 15:47:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:47:14 INFO - 'use_talos_json': True, 15:47:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:47:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:47:14 INFO - 'work_dir': 'build'} 15:47:14 INFO - ##### 15:47:14 INFO - ##### Running clobber step. 15:47:14 INFO - ##### 15:47:14 INFO - Running pre-action listener: _resource_record_pre_action 15:47:14 INFO - Running main action method: clobber 15:47:14 INFO - rmtree: /builds/slave/test/build 15:47:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:47:16 INFO - Running post-action listener: _resource_record_post_action 15:47:16 INFO - ##### 15:47:16 INFO - ##### Running read-buildbot-config step. 15:47:16 INFO - ##### 15:47:16 INFO - Running pre-action listener: _resource_record_pre_action 15:47:16 INFO - Running main action method: read_buildbot_config 15:47:16 INFO - Using buildbot properties: 15:47:16 INFO - { 15:47:16 INFO - "project": "", 15:47:16 INFO - "product": "firefox", 15:47:16 INFO - "script_repo_revision": "production", 15:47:16 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 15:47:16 INFO - "repository": "", 15:47:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 15:47:16 INFO - "buildid": "20160422142650", 15:47:16 INFO - "pgo_build": "False", 15:47:16 INFO - "basedir": "/builds/slave/test", 15:47:16 INFO - "buildnumber": 311, 15:47:16 INFO - "slavename": "t-yosemite-r7-0059", 15:47:16 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 15:47:16 INFO - "platform": "yosemite_r7", 15:47:16 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 15:47:16 INFO - "branch": "mozilla-aurora", 15:47:16 INFO - "repo_path": "releases/mozilla-aurora", 15:47:16 INFO - "revision": "922334c70324e4295dcfcfafe6da4ae2e1154834", 15:47:16 INFO - "stage_platform": "macosx64", 15:47:16 INFO - "builduid": "0e0a78d064b14b7f8537b3303394d2df", 15:47:16 INFO - "slavebuilddir": "test" 15:47:16 INFO - } 15:47:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/WMriI2IhRtGQ_iEYFHgolw/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg. 15:47:16 INFO - Running post-action listener: _resource_record_post_action 15:47:16 INFO - ##### 15:47:16 INFO - ##### Running download-and-extract step. 15:47:16 INFO - ##### 15:47:16 INFO - Running pre-action listener: _resource_record_pre_action 15:47:16 INFO - Running main action method: download_and_extract 15:47:16 INFO - mkdir: /builds/slave/test/build/tests 15:47:16 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:16 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:16 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:16 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:16 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:16 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:17 INFO - Downloaded 1277 bytes. 15:47:17 INFO - Reading from file /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 15:47:17 INFO - Using the following test package requirements: 15:47:17 INFO - {u'common': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 15:47:17 INFO - u'cppunittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 15:47:17 INFO - u'firefox-47.0a2.en-US.mac.cppunittest.tests.zip'], 15:47:17 INFO - u'gtest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 15:47:17 INFO - u'firefox-47.0a2.en-US.mac.gtest.tests.zip'], 15:47:17 INFO - u'jittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 15:47:17 INFO - u'jsshell-mac.zip'], 15:47:17 INFO - u'mochitest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 15:47:17 INFO - u'firefox-47.0a2.en-US.mac.mochitest.tests.zip'], 15:47:17 INFO - u'mozbase': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 15:47:17 INFO - u'reftest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 15:47:17 INFO - u'firefox-47.0a2.en-US.mac.reftest.tests.zip'], 15:47:17 INFO - u'talos': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 15:47:17 INFO - u'firefox-47.0a2.en-US.mac.talos.tests.zip'], 15:47:17 INFO - u'web-platform': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 15:47:17 INFO - u'firefox-47.0a2.en-US.mac.web-platform.tests.zip'], 15:47:17 INFO - u'webapprt': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 15:47:17 INFO - u'xpcshell': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 15:47:17 INFO - u'firefox-47.0a2.en-US.mac.xpcshell.tests.zip']} 15:47:17 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip'] for test suite category: common 15:47: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:47:17 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:17 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:17 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:17 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:17 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:19 INFO - Downloaded 17917533 bytes. 15:47:19 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:19 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:22 INFO - Return code: 0 15:47:22 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: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:47:22 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:22 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:22 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:22 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:22 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:23 INFO - Downloaded 17917533 bytes. 15:47:23 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:23 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:27 INFO - Return code: 0 15:47: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:47:27 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:27 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:27 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:27 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:27 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:29 INFO - Downloaded 10845198 bytes. 15:47:29 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:29 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:29 INFO - Return code: 0 15:47:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:47:29 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:29 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:29 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:29 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:29 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:47:34 INFO - Downloaded 115767620 bytes. 15:47:34 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:47:34 INFO - mkdir: /builds/slave/test/properties 15:47:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:47:34 INFO - Writing to file /builds/slave/test/properties/build_url 15:47:34 INFO - Contents: 15:47:34 INFO - build_url:https://queue.taskcluster.net/v1/task/WMriI2IhRtGQ_iEYFHgolw/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 15:47:35 INFO - Running post-action listener: _resource_record_post_action 15:47:35 INFO - Running post-action listener: set_extra_try_arguments 15:47:35 INFO - ##### 15:47:35 INFO - ##### Running populate-webroot step. 15:47:35 INFO - ##### 15:47:35 INFO - Running pre-action listener: _resource_record_pre_action 15:47:35 INFO - Running main action method: populate_webroot 15:47:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:47:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:47:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:47:35 INFO - '--tppagecycles', 15:47:35 INFO - '7'], 15:47:35 INFO - 'tests': ['tsvgm']}}, 15:47:35 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 15:47:35 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 15:47:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:47:35 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 15:47:35 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 15:47:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:47:35 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:47:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:47:35 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:47:35 INFO - 'talos_options': ['--e10s'], 15:47:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:47:35 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:47:35 INFO - 'tests': ['damp', 'tps']}, 15:47:35 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:47:35 INFO - 'talos_options': ['--e10s'], 15:47:35 INFO - 'tests': ['damp', 'tps']}, 15:47:35 INFO - 'g3': {'tests': ['dromaeo_dom']}, 15:47:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:47:35 INFO - 'other': {'tests': ['a11yr', 15:47:35 INFO - 'ts_paint', 15:47:35 INFO - 'tpaint', 15:47:35 INFO - 'sessionrestore', 15:47:35 INFO - 'sessionrestore_no_auto_restore', 15:47:35 INFO - 'tabpaint']}, 15:47:35 INFO - 'other-e10s': {'talos_options': ['--e10s'], 15:47:35 INFO - 'tests': ['a11yr', 15:47:35 INFO - 'ts_paint', 15:47:35 INFO - 'tpaint', 15:47:35 INFO - 'sessionrestore', 15:47:35 INFO - 'sessionrestore_no_auto_restore', 15:47:35 INFO - 'tabpaint']}, 15:47:35 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 15:47:35 INFO - 'tests': ['a11yr', 15:47:35 INFO - 'ts_paint', 15:47:35 INFO - 'tpaint', 15:47:35 INFO - 'sessionrestore', 15:47:35 INFO - 'sessionrestore_no_auto_restore', 15:47:35 INFO - 'tabpaint']}, 15:47:35 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 15:47:35 INFO - 'tests': ['a11yr', 15:47:35 INFO - 'ts_paint', 15:47:35 INFO - 'tpaint', 15:47:35 INFO - 'sessionrestore', 15:47:35 INFO - 'sessionrestore_no_auto_restore', 15:47:35 INFO - 'tabpaint']}, 15:47:35 INFO - 'other_l64': {'tests': ['a11yr', 15:47:35 INFO - 'ts_paint', 15:47:35 INFO - 'tpaint', 15:47:35 INFO - 'sessionrestore', 15:47:35 INFO - 'sessionrestore_no_auto_restore', 15:47:35 INFO - 'tabpaint']}, 15:47:35 INFO - 'other_nol64': {'tests': ['a11yr', 15:47:35 INFO - 'ts_paint', 15:47:35 INFO - 'tpaint', 15:47:35 INFO - 'sessionrestore', 15:47:35 INFO - 'sessionrestore_no_auto_restore', 15:47:35 INFO - 'tabpaint']}, 15:47:35 INFO - 'svgr': {'tests': ['tsvgx', 15:47:35 INFO - 'tsvgr_opacity', 15:47:35 INFO - 'tart', 15:47:35 INFO - 'tscrollx', 15:47:35 INFO - 'cart']}, 15:47:35 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 15:47:35 INFO - 'tests': ['tsvgx', 15:47:35 INFO - 'tsvgr_opacity', 15:47:35 INFO - 'tart', 15:47:35 INFO - 'tscrollx', 15:47:35 INFO - 'cart']}, 15:47:35 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:47:35 INFO - 'tests': ['tp5o']}, 15:47:35 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:47:35 INFO - 'talos_options': ['--e10s'], 15:47:35 INFO - 'tests': ['tp5o']}, 15:47:35 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:47:35 INFO - 'talos_options': ['--xperf_path', 15:47:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:47:35 INFO - 'tests': ['tp5n']}, 15:47:35 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:47:35 INFO - 'talos_options': ['--e10s', 15:47:35 INFO - '--xperf_path', 15:47:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:47:35 INFO - 'tests': ['tp5n']}}, 15:47:35 INFO - 'talos.zip': {'path': '', 15:47:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:47:35 INFO - Running post-action listener: _resource_record_post_action 15:47:35 INFO - ##### 15:47:35 INFO - ##### Running create-virtualenv step. 15:47:35 INFO - ##### 15:47:35 INFO - Running pre-action listener: _resource_record_pre_action 15:47:35 INFO - Running main action method: create_virtualenv 15:47:35 INFO - Creating virtualenv /builds/slave/test/build/venv 15:47:35 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:47:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:47:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:47:35 INFO - Using real prefix '/tools/python27' 15:47:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:47:36 INFO - Installing distribute.............................................................................................................................................................................................done. 15:47:39 INFO - Installing pip.................done. 15:47:39 INFO - Return code: 0 15:47:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 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 - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 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 - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x10988da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1098944c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff01acbdce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109731d30>, '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 0x109595db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109728b20>, '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.m5kUaxvtR0/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.b71wKnQjd3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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:47:39 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:47:39 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:47:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m5kUaxvtR0/Render', 15:47:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:47:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:47:39 INFO - 'HOME': '/Users/cltbld', 15:47:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:47:39 INFO - 'LOGNAME': 'cltbld', 15:47:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:39 INFO - 'MOZ_NO_REMOTE': '1', 15:47:39 INFO - 'NO_EM_RESTART': '1', 15:47:39 INFO - 'PAGER': '/bin/cat', 15:47:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:47:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:39 INFO - 'PWD': '/builds/slave/test', 15:47:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:47:39 INFO - 'SHELL': '/bin/bash', 15:47:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b71wKnQjd3/Listeners', 15:47:39 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 15:47:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:47:39 INFO - 'USER': 'cltbld', 15:47:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:47:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:47:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:39 INFO - 'XPC_FLAGS': '0x0', 15:47:39 INFO - 'XPC_SERVICE_NAME': '0', 15:47:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:47:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:39 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:47:39 INFO - Cleaning up... 15:47:39 INFO - Return code: 0 15:47:39 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 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 - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 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 - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x10988da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1098944c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff01acbdce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109731d30>, '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 0x109595db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109728b20>, '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.m5kUaxvtR0/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.b71wKnQjd3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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:47:39 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:47:39 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:47:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m5kUaxvtR0/Render', 15:47:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:47:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:47:39 INFO - 'HOME': '/Users/cltbld', 15:47:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:47:39 INFO - 'LOGNAME': 'cltbld', 15:47:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:39 INFO - 'MOZ_NO_REMOTE': '1', 15:47:39 INFO - 'NO_EM_RESTART': '1', 15:47:39 INFO - 'PAGER': '/bin/cat', 15:47:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:47:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:39 INFO - 'PWD': '/builds/slave/test', 15:47:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:47:39 INFO - 'SHELL': '/bin/bash', 15:47:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b71wKnQjd3/Listeners', 15:47:39 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 15:47:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:47:39 INFO - 'USER': 'cltbld', 15:47:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:47:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:47:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:39 INFO - 'XPC_FLAGS': '0x0', 15:47:39 INFO - 'XPC_SERVICE_NAME': '0', 15:47:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:47:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:39 INFO - Downloading/unpacking psutil>=0.7.1 15:47:39 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:47:39 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:47:39 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:47:39 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:47:39 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:47:39 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:47:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:47:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:47:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:47:42 INFO - Installing collected packages: psutil 15:47:42 INFO - Running setup.py install for psutil 15:47:42 INFO - building 'psutil._psutil_osx' extension 15:47:42 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:47:43 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:47:43 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:47:43 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:47:43 INFO - building 'psutil._psutil_posix' extension 15:47:43 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:47:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:47:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:47:43 INFO - ^ 15:47:43 INFO - 1 warning generated. 15:47:43 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:47:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:47:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:47:43 INFO - Successfully installed psutil 15:47:43 INFO - Cleaning up... 15:47:43 INFO - Return code: 0 15:47:43 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 15:47:43 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:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47:43 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:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47:43 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 0x10988da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1098944c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff01acbdce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109731d30>, '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 0x109595db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109728b20>, '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.m5kUaxvtR0/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.b71wKnQjd3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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:47:43 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:47:43 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:47:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m5kUaxvtR0/Render', 15:47:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:47:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:47:43 INFO - 'HOME': '/Users/cltbld', 15:47:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:47:43 INFO - 'LOGNAME': 'cltbld', 15:47:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:43 INFO - 'MOZ_NO_REMOTE': '1', 15:47:43 INFO - 'NO_EM_RESTART': '1', 15:47:43 INFO - 'PAGER': '/bin/cat', 15:47:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:47:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:43 INFO - 'PWD': '/builds/slave/test', 15:47:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:47:43 INFO - 'SHELL': '/bin/bash', 15:47:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b71wKnQjd3/Listeners', 15:47:43 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 15:47:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:47:43 INFO - 'USER': 'cltbld', 15:47:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:47:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:47:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:43 INFO - 'XPC_FLAGS': '0x0', 15:47:43 INFO - 'XPC_SERVICE_NAME': '0', 15:47:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:47:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:44 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 15:47:44 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:47:44 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:47:44 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:47:44 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:47:44 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:47:44 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:47:46 INFO - Downloading mozsystemmonitor-0.0.tar.gz 15:47:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:47:46 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:47:46 INFO - Installing collected packages: mozsystemmonitor 15:47:46 INFO - Running setup.py install for mozsystemmonitor 15:47:46 INFO - Successfully installed mozsystemmonitor 15:47:46 INFO - Cleaning up... 15:47:46 INFO - Return code: 0 15:47:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 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 - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 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 - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47:46 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 0x10988da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1098944c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff01acbdce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109731d30>, '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 0x109595db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109728b20>, '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.m5kUaxvtR0/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.b71wKnQjd3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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:47:46 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:47:46 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:47:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m5kUaxvtR0/Render', 15:47:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:47:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:47:46 INFO - 'HOME': '/Users/cltbld', 15:47:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:47:46 INFO - 'LOGNAME': 'cltbld', 15:47:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:46 INFO - 'MOZ_NO_REMOTE': '1', 15:47:46 INFO - 'NO_EM_RESTART': '1', 15:47:46 INFO - 'PAGER': '/bin/cat', 15:47:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:47:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:46 INFO - 'PWD': '/builds/slave/test', 15:47:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:47:46 INFO - 'SHELL': '/bin/bash', 15:47:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b71wKnQjd3/Listeners', 15:47:46 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 15:47:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:47:46 INFO - 'USER': 'cltbld', 15:47:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:47:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:47:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:46 INFO - 'XPC_FLAGS': '0x0', 15:47:46 INFO - 'XPC_SERVICE_NAME': '0', 15:47:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:47:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:47 INFO - Downloading/unpacking blobuploader==1.2.4 15:47:47 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:47:47 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:47:47 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:47:47 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:47:47 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:47:47 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:47:49 INFO - Downloading blobuploader-1.2.4.tar.gz 15:47:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:47:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:47:49 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:47:49 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:47:49 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:47:49 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:47:49 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:47:49 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:47:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:47:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:47:50 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:47:50 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:47:50 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:47:50 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:47:50 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:47:50 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:47:50 INFO - Downloading docopt-0.6.1.tar.gz 15:47:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:47:50 INFO - Installing collected packages: blobuploader, requests, docopt 15:47:50 INFO - Running setup.py install for blobuploader 15:47:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:47:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:47:50 INFO - Running setup.py install for requests 15:47:51 INFO - Running setup.py install for docopt 15:47:51 INFO - Successfully installed blobuploader requests docopt 15:47:51 INFO - Cleaning up... 15:47:51 INFO - Return code: 0 15:47:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:47:51 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:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47:51 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:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47:51 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 0x10988da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1098944c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff01acbdce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109731d30>, '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 0x109595db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109728b20>, '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.m5kUaxvtR0/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.b71wKnQjd3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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:47:51 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:47:51 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:47:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m5kUaxvtR0/Render', 15:47:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:47:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:47:51 INFO - 'HOME': '/Users/cltbld', 15:47:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:47:51 INFO - 'LOGNAME': 'cltbld', 15:47:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:51 INFO - 'MOZ_NO_REMOTE': '1', 15:47:51 INFO - 'NO_EM_RESTART': '1', 15:47:51 INFO - 'PAGER': '/bin/cat', 15:47:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:47:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:51 INFO - 'PWD': '/builds/slave/test', 15:47:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:47:51 INFO - 'SHELL': '/bin/bash', 15:47:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b71wKnQjd3/Listeners', 15:47:51 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 15:47:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:47:51 INFO - 'USER': 'cltbld', 15:47:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:47:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:47:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:51 INFO - 'XPC_FLAGS': '0x0', 15:47:51 INFO - 'XPC_SERVICE_NAME': '0', 15:47:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:47:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:47:51 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-rDIf6r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:47:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:47:51 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-uTsrm5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:47:52 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-E_tTHA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:47:52 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-aAQLGY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:47:52 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-usC_By-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:47:52 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-mf63e6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:47:52 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-3VyeOl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:47:52 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-v3kFtF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:47:52 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-PaEMAq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:47:52 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-A0lNEQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:47:52 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-erB7lY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:47:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:47:53 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-eW36WD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:47:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:47:53 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-i1E5xn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:47:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:47:53 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-C17BV2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:47:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:47:53 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-TANBnL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:47:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:47:53 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-y5TJrv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:47:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:47:53 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-L9Plos-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:47:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:47:53 INFO - Running setup.py install for manifestparser 15:47:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:47:53 INFO - Running setup.py install for mozcrash 15:47:53 INFO - Running setup.py install for mozdebug 15:47:54 INFO - Running setup.py install for mozdevice 15:47:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:47:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:47:54 INFO - Running setup.py install for mozfile 15:47:54 INFO - Running setup.py install for mozhttpd 15:47:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:47:54 INFO - Running setup.py install for mozinfo 15:47:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:47:54 INFO - Running setup.py install for mozInstall 15:47:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:47:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:47:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:47:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:47:54 INFO - Running setup.py install for mozleak 15:47:54 INFO - Running setup.py install for mozlog 15:47:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:47:55 INFO - Running setup.py install for moznetwork 15:47:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:47:55 INFO - Running setup.py install for mozprocess 15:47:55 INFO - Running setup.py install for mozprofile 15:47:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:47:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:47:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:47:55 INFO - Running setup.py install for mozrunner 15:47:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:47:55 INFO - Running setup.py install for mozscreenshot 15:47:55 INFO - Running setup.py install for moztest 15:47:56 INFO - Running setup.py install for mozversion 15:47:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:47:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:47:56 INFO - Cleaning up... 15:47:56 INFO - Return code: 0 15:47:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:47:56 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:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47:56 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:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47:56 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 0x10988da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1098944c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff01acbdce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109731d30>, '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 0x109595db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109728b20>, '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.m5kUaxvtR0/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.b71wKnQjd3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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:47:56 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:47:56 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:47:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m5kUaxvtR0/Render', 15:47:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:47:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:47:56 INFO - 'HOME': '/Users/cltbld', 15:47:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:47:56 INFO - 'LOGNAME': 'cltbld', 15:47:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:56 INFO - 'MOZ_NO_REMOTE': '1', 15:47:56 INFO - 'NO_EM_RESTART': '1', 15:47:56 INFO - 'PAGER': '/bin/cat', 15:47:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:47:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:56 INFO - 'PWD': '/builds/slave/test', 15:47:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:47:56 INFO - 'SHELL': '/bin/bash', 15:47:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b71wKnQjd3/Listeners', 15:47:56 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 15:47:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:47:56 INFO - 'USER': 'cltbld', 15:47:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:47:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:47:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:56 INFO - 'XPC_FLAGS': '0x0', 15:47:56 INFO - 'XPC_SERVICE_NAME': '0', 15:47:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:47:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:47:56 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-QGhpmg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:47:56 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:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:47:56 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-OQq_F0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:47:56 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:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:47:56 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-hulY6q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:47:56 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:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:47:56 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-ukwG1u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:47:56 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:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:47:56 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-FrDSeC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:47:56 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:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:47:56 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-IR617Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:47:57 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:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:47:57 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-MWFsrV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:47:57 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:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:47:57 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-qsFUTb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:47:57 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:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:47:57 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-tGLslD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:47:57 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:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:47:57 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-FfrnPC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:47:57 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:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:47:57 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-qfzuXv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:47:57 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:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:47:57 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-bMMmXz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:47:57 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:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:47:57 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-N07Ho1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:47:57 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:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:47:57 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-ue9z1F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:47:57 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:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:47:57 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-4_kjjt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:47:58 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:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:47:58 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-Hiv2pK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:47:58 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:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:47:58 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-Fsx0T8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:47:58 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:47:58 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:47:58 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:47:58 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:47:58 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:47:58 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:47:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:47:58 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:47:58 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:47:58 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:47:58 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:47:58 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:47:58 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:00 INFO - Downloading blessings-1.6.tar.gz 15:48:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:48:00 INFO - Installing collected packages: blessings 15:48:00 INFO - Running setup.py install for blessings 15:48:00 INFO - Successfully installed blessings 15:48:00 INFO - Cleaning up... 15:48:01 INFO - Return code: 0 15:48:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:48:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:48:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:48:01 INFO - Reading from file tmpfile_stdout 15:48:01 INFO - Current package versions: 15:48:01 INFO - blessings == 1.6 15:48:01 INFO - blobuploader == 1.2.4 15:48:01 INFO - docopt == 0.6.1 15:48:01 INFO - manifestparser == 1.1 15:48:01 INFO - mozInstall == 1.12 15:48:01 INFO - mozcrash == 0.17 15:48:01 INFO - mozdebug == 0.1 15:48:01 INFO - mozdevice == 0.48 15:48:01 INFO - mozfile == 1.2 15:48:01 INFO - mozhttpd == 0.7 15:48:01 INFO - mozinfo == 0.9 15:48:01 INFO - mozleak == 0.1 15:48:01 INFO - mozlog == 3.1 15:48:01 INFO - moznetwork == 0.27 15:48:01 INFO - mozprocess == 0.22 15:48:01 INFO - mozprofile == 0.28 15:48:01 INFO - mozrunner == 6.11 15:48:01 INFO - mozscreenshot == 0.1 15:48:01 INFO - mozsystemmonitor == 0.0 15:48:01 INFO - moztest == 0.7 15:48:01 INFO - mozversion == 1.4 15:48:01 INFO - psutil == 3.1.1 15:48:01 INFO - requests == 1.2.3 15:48:01 INFO - wsgiref == 0.1.2 15:48:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:48:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:01 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 0x10988da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1098944c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff01acbdce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109731d30>, '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 0x109595db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109728b20>, '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.m5kUaxvtR0/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.b71wKnQjd3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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:01 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:01 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:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m5kUaxvtR0/Render', 15:48:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:48:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:48:01 INFO - 'HOME': '/Users/cltbld', 15:48:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:48:01 INFO - 'LOGNAME': 'cltbld', 15:48:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:01 INFO - 'MOZ_NO_REMOTE': '1', 15:48:01 INFO - 'NO_EM_RESTART': '1', 15:48:01 INFO - 'PAGER': '/bin/cat', 15:48:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:48:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:01 INFO - 'PWD': '/builds/slave/test', 15:48:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:48:01 INFO - 'SHELL': '/bin/bash', 15:48:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b71wKnQjd3/Listeners', 15:48:01 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 15:48:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:48:01 INFO - 'USER': 'cltbld', 15:48:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:48:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:48:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:01 INFO - 'XPC_FLAGS': '0x0', 15:48:01 INFO - 'XPC_SERVICE_NAME': '0', 15:48:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:48:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:01 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:01 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:01 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:01 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:01 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:01 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:01 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:01 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:01 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:01 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:01 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:01 INFO - Cleaning up... 15:48:01 INFO - Return code: 0 15:48:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:48:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:01 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 0x10988da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1098944c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff01acbdce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109731d30>, '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 0x109595db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109728b20>, '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.m5kUaxvtR0/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.b71wKnQjd3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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:01 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:01 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:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m5kUaxvtR0/Render', 15:48:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:48:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:48:01 INFO - 'HOME': '/Users/cltbld', 15:48:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:48:01 INFO - 'LOGNAME': 'cltbld', 15:48:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:01 INFO - 'MOZ_NO_REMOTE': '1', 15:48:01 INFO - 'NO_EM_RESTART': '1', 15:48:01 INFO - 'PAGER': '/bin/cat', 15:48:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:48:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:01 INFO - 'PWD': '/builds/slave/test', 15:48:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:48:01 INFO - 'SHELL': '/bin/bash', 15:48:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b71wKnQjd3/Listeners', 15:48:01 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 15:48:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:48:01 INFO - 'USER': 'cltbld', 15:48:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:48:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:48:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:01 INFO - 'XPC_FLAGS': '0x0', 15:48:01 INFO - 'XPC_SERVICE_NAME': '0', 15:48:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:48:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:01 INFO - Downloading/unpacking jsonschema 15:48:01 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:01 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:01 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:01 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:01 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:01 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:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:48:04 INFO - Downloading/unpacking functools32 (from jsonschema) 15:48:04 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:04 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:04 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:04 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:04 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:04 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:04 INFO - Downloading functools32-3.2.3-2.tar.gz 15:48:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:48:04 INFO - warning: no files found matching '*.txt' 15:48:04 INFO - no previously-included directories found matching 'build' 15:48:04 INFO - no previously-included directories found matching 'dist' 15:48:04 INFO - no previously-included directories found matching '.git*' 15:48:04 INFO - Installing collected packages: jsonschema, functools32 15:48:04 INFO - Running setup.py install for functools32 15:48:04 INFO - warning: no files found matching '*.txt' 15:48:04 INFO - no previously-included directories found matching 'build' 15:48:04 INFO - no previously-included directories found matching 'dist' 15:48:04 INFO - no previously-included directories found matching '.git*' 15:48:05 INFO - Successfully installed jsonschema functools32 15:48:05 INFO - Cleaning up... 15:48:05 INFO - Return code: 0 15:48:05 INFO - Installing flake8 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10988da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1098944c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff01acbdce0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109731d30>, '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 0x109595db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109728b20>, '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.m5kUaxvtR0/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.b71wKnQjd3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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', 'flake8'] 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 flake8 15:48:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m5kUaxvtR0/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.b71wKnQjd3/Listeners', 15:48:05 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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 flake8 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 - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:48:08 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:48:08 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:08 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 - 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:08 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:08 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:08 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 - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:48:08 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:48:08 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:08 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 - 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:08 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:08 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:08 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:09 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:48:09 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:09 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:09 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:09 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:09 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:09 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:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:48:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:48:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:48:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:48:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:48:09 INFO - no previously-included directories found matching 'docs/_build' 15:48:09 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:48:09 INFO - Running setup.py install for pep8 15:48:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:48:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:48:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:48:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:48:10 INFO - no previously-included directories found matching 'docs/_build' 15:48:10 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:48:10 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:48:10 INFO - Cleaning up... 15:48:10 INFO - Return code: 0 15:48:10 INFO - Running post-action listener: _resource_record_post_action 15:48:10 INFO - Running post-action listener: _start_resource_monitoring 15:48:10 INFO - Starting resource monitoring. 15:48:10 INFO - ##### 15:48:10 INFO - ##### Running install step. 15:48:10 INFO - ##### 15:48:10 INFO - Running pre-action listener: _resource_record_pre_action 15:48:10 INFO - Running main action method: install 15:48:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:48:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:48:10 INFO - Reading from file tmpfile_stdout 15:48:10 INFO - Detecting whether we're running mozinstall >=1.0... 15:48:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:48:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:48:10 INFO - Reading from file tmpfile_stdout 15:48:10 INFO - Output received: 15:48:10 INFO - Usage: mozinstall [options] installer 15:48:10 INFO - Options: 15:48:10 INFO - -h, --help show this help message and exit 15:48:10 INFO - -d DEST, --destination=DEST 15:48:10 INFO - Directory to install application into. [default: 15:48:10 INFO - "/builds/slave/test"] 15:48:10 INFO - --app=APP Application being installed. [default: firefox] 15:48:10 INFO - mkdir: /builds/slave/test/build/application 15:48:10 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:10 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:48:43 INFO - Reading from file tmpfile_stdout 15:48:43 INFO - Output received: 15:48:43 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 15:48:43 INFO - Running post-action listener: _resource_record_post_action 15:48:43 INFO - ##### 15:48:43 INFO - ##### Running run-tests step. 15:48:43 INFO - ##### 15:48:43 INFO - Running pre-action listener: _resource_record_pre_action 15:48:43 INFO - Running main action method: run_tests 15:48:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:48:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:48:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:48:43 INFO - 2016-04-22 15:48:43.038 screenresolution[1642:7465] starting screenresolution argv=screenresolution get 15:48:43 INFO - 2016-04-22 15:48:43.041 screenresolution[1642:7465] Display 0: 1600x1200x32@60 15:48:43 INFO - 2016-04-22 15:48:43.047 screenresolution[1643:7467] starting screenresolution argv=screenresolution list 15:48:43 INFO - Available Modes on Display 0 15:48:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:48:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:48:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:48:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:48:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:48:43 INFO - Intel Iris: 15:48:43 INFO - Chipset Model: Intel Iris 15:48:43 INFO - Type: GPU 15:48:43 INFO - Bus: Built-In 15:48:43 INFO - VRAM (Dynamic, Max): 1536 MB 15:48:43 INFO - Vendor: Intel (0x8086) 15:48:43 INFO - Device ID: 0x0a2e 15:48:43 INFO - Revision ID: 0x0009 15:48:43 INFO - Displays: 15:48:43 INFO - Display: 15:48:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:48:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:48:43 INFO - Display Serial Number: 1600x1200 60 15:48:43 INFO - Main Display: Yes 15:48:43 INFO - Mirror: Off 15:48:43 INFO - Online: Yes 15:48:43 INFO - Rotation: Supported 15:48:43 INFO - Return code: 0 15:48:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:48:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:48:43 INFO - Python 2.7.3 15:48:43 INFO - Return code: 0 15:48:43 INFO - grabbing minidump binary from tooltool 15:48:43 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:43 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 0x109731d30>, '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 0x109595db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109728b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:48:43 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:48:43 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:48:43 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:48:43 INFO - Return code: 0 15:48:43 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:48:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:48:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:48:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:48:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:48:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:48:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:48:44 INFO - Return code: 0 15:48:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WMriI2IhRtGQ_iEYFHgolw/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0059', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 15:48:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WMriI2IhRtGQ_iEYFHgolw/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0059 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 15:48:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m5kUaxvtR0/Render', 15:48:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:48:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:48:44 INFO - 'HOME': '/Users/cltbld', 15:48:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:48:44 INFO - 'LOGNAME': 'cltbld', 15:48:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:48:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:48:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:44 INFO - 'MOZ_NO_REMOTE': '1', 15:48:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:48:44 INFO - 'NO_EM_RESTART': '1', 15:48:44 INFO - 'PAGER': '/bin/cat', 15:48:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:48:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:44 INFO - 'PWD': '/builds/slave/test', 15:48:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:48:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:48:44 INFO - 'SHELL': '/bin/bash', 15:48:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b71wKnQjd3/Listeners', 15:48:44 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 15:48:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:48:44 INFO - 'USER': 'cltbld', 15:48:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:48:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:48:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:44 INFO - 'XPC_FLAGS': '0x0', 15:48:44 INFO - 'XPC_SERVICE_NAME': '0', 15:48:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:48:44 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WMriI2IhRtGQ_iEYFHgolw/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0059', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 15:48:44 INFO - mozversion application_buildid: 20160422142650 15:48:44 INFO - mozversion application_changeset: 922334c70324e4295dcfcfafe6da4ae2e1154834 15:48:44 INFO - mozversion application_display_name: FirefoxDeveloperEdition 15:48:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:48:44 INFO - mozversion application_name: Firefox 15:48:44 INFO - mozversion application_remotingname: firefox-dev 15:48:44 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:48:44 INFO - mozversion application_vendor: Mozilla 15:48:44 INFO - mozversion application_version: 47.0a2 15:48:44 INFO - mozversion platform_buildid: 20160422142650 15:48:44 INFO - mozversion platform_changeset: 922334c70324e4295dcfcfafe6da4ae2e1154834 15:48:44 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:48:44 INFO - mozversion platform_version: 47.0a2 15:48:44 INFO - using testdate: 1461365324 15:48:44 INFO - actual date: 1461365324 15:48:44 INFO - starting webserver on 'localhost:49272' 15:48:44 INFO - SUITE-START | Running 2 tests 15:48:44 INFO - TEST-START | dromaeo_css 15:48:44 INFO - operating with platform_type : mac_ 15:48:44 INFO - Initialising browser for dromaeo_css test... 15:48:44 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/tmpnNXme1/profile http://localhost:49272/getInfo.html) 15:48:46 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 15:48:46 INFO - PROCESS | 1661 | colorDepth:24 15:48:46 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 15:48:46 INFO - PROCESS | 1661 | __metrics 15:48:47 INFO - PROCESS | 1661 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:48:47 INFO - PROCESS | 1661 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:48:47 INFO - TEST-INFO | 1661: exit 0 15:48:47 INFO - Browser initialized. 15:48:47 INFO - Running cycle 1/1 for dromaeo_css test... 15:48:47 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/tmpnNXme1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:48:59 INFO - PROCESS | 1663 | RSS: Main: 207683584 15:48:59 INFO - PROCESS | 1663 | 15:49:50 INFO - PROCESS | 1663 | 1461365390530 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:00 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 15:52:01 INFO - PROCESS | 1663 | RSS: Main: 323616768 15:52:01 INFO - PROCESS | 1663 | 15:55:04 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 15:55:04 INFO - PROCESS | 1663 | RSS: Main: 338997248 15:55:04 INFO - PROCESS | 1663 | 15:58:06 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 15:58:07 INFO - PROCESS | 1663 | RSS: Main: 345997312 15:58:07 INFO - PROCESS | 1663 | 16:01:09 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 16:01:09 INFO - PROCESS | 1663 | RSS: Main: 302395392 16:01:09 INFO - PROCESS | 1663 | 16:04:12 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 16:04:13 INFO - PROCESS | 1663 | RSS: Main: 306896896 16:04:13 INFO - PROCESS | 1663 | 16:07:16 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 16:07:16 INFO - PROCESS | 1663 | RSS: Main: 409034752 16:07:16 INFO - PROCESS | 1663 | 16:07:16 INFO - PROCESS | 1663 | __start_tp_report 16:07:16 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 16:07:16 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 16:07:16 INFO - PROCESS | 1663 | |i|pagename|runs| 16:07:16 INFO - PROCESS | 1663 | |0;dojo.html;3430.416829139046;3498.3178280235365;3530.3468858261217;3531.32904748098;3556.2176321209326;5759.934412133758;5764.654120752747;5769.653820770756;5773.624714393567;5799.3620701722775;5759.510441612468;5791.102379131231;5793.102069179274;5812.883742325162;5815.214945982291;5141.1517099678695;5207.921881171786;5218.086834803923;5246.422893481714;5341.145416733323;5314.388845282803;5324.520793128618;5325.520703136717;5340.145576707728;5347.957148356072;4494.617957473619;5262.578993680497;5299.443558426367;5324.254604355393;5332.600054995872;5316.229146773716;5351.785928562872;5361.678299302054;5362.544183744387;5385.542228910547;4361.629261512775;5068.594512438996;5235.371755389339;5243.370795504564;5251.842444726664;5634.6337488063555;5683.914741278884;5688.402717714622;5693.430656934315;5710.2576665033275;6132.570720049598;6302.810915672537;6305.211848518935;6309.5898766580485;6325.462335701425;6207.286162091327;6233.438990490878;6248.343923887972;6253.218347706536;6286.842828929268;5893.852653683649;5903.852403689899;5913.965056115163;5915.497182739472;5942.257217847769;6203.348648391944;6231.189945307081;6346.587471814318;6349.555531112777;6350.206224221973;6221.968890155611;6245.75016999324;6246.094316324107;6257.530685198537;6266.843328916722;6275.403836635513;6277.027060805583;6284.151639528696;6286.9056964145575;6301.8109456716375;6097.054956481752;6186.845328866814;6197.225346831646;6287.276963149205;6290.937090629127;6262.624242545462;6282.465990390868;6320.936790632127;6322;6340;4991.401031876126;4993.575546078551;4999;5003.074431230234;5015.322931404214;4355.455568053993;4397.31841564558;4485.304777759453;4509;4510.594046535827;5949.315828679757;5959.731812068468;5986.3714309997695;5987.520998320211;5997.070454079625;6272.686365681698;6295.748170073146;6321.936780632227;6333.493320534346;6338.778142764981;4748.738819364899;5837.88324233513;5918.496927761103;5925.170476133345;6006.699665016732;5456.890862182734;5476.726163691799;5477.890442191214;5480.945190548123;5481.534069604048;6420.133282006869;6428.935710642927;6429.935700643027;6438.935610643834;6448.871022579522;6211.223597050369;6222.346653601307;6283.7800676976085;6322.968385158045;6327.525435592257;6405.583637063576;6415.935840641534;6429.935700642934;6431.421172094534;6444.548881578245;6240.656763878031;6258.874822503615;6268.655223962726;6270;6270.184875966095;6160.599561028519;6189.81430557084;6264.624122552661;6277.027060805583;6279.058141278845;6155.784547540815;6185.474234690102;6196.783112591038;6205.503559715302;6213.658248796179;5965.194698715618;6042.607230530001;6189.535784816067;6199.938000619936;6204.844878878064;6000.699965001645;6069.02895536712;6070.423309785563;6098.75604975796;6126.172966649446;6188.628682279078;6193.194884664965;6197.907031394623;6214.533909956681;6220.34686357944;5868.794592189253;5972.940270597239;6078.817635470943;6082.847928801815;6086.6652334122045 16:07:16 INFO - PROCESS | 1663 | |1;ext.html;486.5839707050474;600.2436929468871;629.8330942300288;669.5580916595047;670.8360993675973;31533.526997095032;39892.803215903536;40156.59843401565;40181.59818401815;40437.989050273754;42812.929676758096;43368.3494747579;43428.78285608575;43459;43503.34744978828;9642.75893102671;9870.703878883645;9872.555734991942;9887.851682224773;9889.406635601887;9636.988116247798;9768.609255629766;9781.657641982532;9796;9799.755006124868;9787.021297870191;9841.507924603777;9849.803003939916;9854.852177217348;9884.061014203638;9868.161206297473;9928.106470417624;9929.304948653597;9934.850977235346;9942.55258513365;8345.833083338299;9908.256880733901;9922.206223502102;9924.957879422629;9926.602935882556;42013.579864201274;42458.787706061274;42462.78768606168;42524.57475425237;42543;3205.37495188436;3323.1692076980753;3335.8332083395735;3452.361313157074;3714.387126124186;28790.424191516053;29111.41777164445;29447.411051778843;29609.40781184386;29776.553351699742;2704.9004494864153;2732.508148533263;2770.7367800058964;2778.9861050694817;2799.4681010608124;483947.5802620964;485892;487488;488194;489168;65418;65882.34117658858;65917.67041164763;65967;66093.33906660872;76654.616726916;77715;77808;77844;78008.21991780009;18274.08629568543;18584.81415185858;18613;18653.253869845325;18665.81334186668;13912.234827084405;13997.65005874841;14019.859801401855;14020.439182432792;14023.158610483404;21908.561828763653;22208;22236.776977266083;22275;22590.774092258867;8023.037235531773;8023.1575684553445;9254.352195346395;9514.38156519824;9538.713838584854;960.7934294126796;1021.6015753856002;1033.1373303291834;1037.7872536130076;1106.441247170174;29604.259893502836;29757.40485190284;30196.94310699115;30334.241643959078;30334.84832575867;1147.254284714942;1185;1197.0124647166124;1211.2429731417865;1298.448159532195;11533.480993355319;14980.47568335125;16118.032918024956;16239.51281461558;16385.262663180187;516111;522819;523239;523625;525447;59560.702196489605;61805;61881.69059154675;61885.69057154685;61892;1416.8016477693131;1435.0456946130705;1531.47929703899;1535.439564558928;1581.2410043179339;26617.33456663599;28842.567361489175;29544.261393465335;29643.555346670248;30137.69862301349;46978.29532557083;47194.820129497035;47661.28508072451;47692.52307476881;47747.806304841266;358583.24250272533;361817;362077;362549;362736;29713;34021.65978340185;34381.79663711858;34892.65107348894;34947.9515614532;41847.790761046;42468.5753142477;42570.787146064074;44146.558534414246;44218.77890610526;34422.96731098071;35698;36572.63427365799;37066.62933370632;37672.62327376691;35219.823900881354;35221;35627.64372356243;35863;35880.64119358877;36653;36787.816060919526;36898.8155059223;37139.44290835586;38069.4289585662;27682.723172768016;31851;32374.35251295008;32666;33024.339513210085;24722.13472528453;25101.74898250994;25222.621660674737;25281.867091611704;25355.492890142465 16:07:16 INFO - PROCESS | 1663 | |2;jquery.html;381.95416550014005;385.5200275656808;387.8732282718701;390.0891418537714;393.08019234990115;16303.347866085373;16611.750823737624;19493.707594386076;20339.593208135848;20929.95350232486;16249.837501624981;20551.74063406319;20687.896560517176;21029.05369258383;21396.823174725385;5892.204552385438;6041.758329666808;6043;6089.1170780236835;6097.359777223394;5090.414602320743;5778.133280007991;6053.545984051192;6071.998120310143;6161.476274516672;6038;6041.939580604182;6052.515798736091;6056.121862329959;6065.423784740443;5672.347680016789;5987.580869339148;6008.669523176223;6073.362296958822;6186.257649082094;5898.321693005317;5903.085021821625;5903.232579764646;5915.231019967377;5935.881282374372;13410.396532156077;14062.718745625132;14073.85926140746;14102.71794564103;14109.576712698636;2202.7907348802;2334.568104900599;2451.705795304557;2594.9740502594923;2797.216779301799;10279.434631095286;10459.372437653765;13968.720625587432;14239.430422783076;15289.388424463008;1635.5256975477098;1665.96668066638;1794.874358794885;1821.271491403436;1824.5529845187882;100768;109013.45493272562;109915.45042274738;110512.4474377631;111623.88376116134;11370.602028928948;11444.027257683023;11489.54041838317;11502.884971150348;11618.825717614243;14240.786388204186;14333.56999290002;14336.569902902722;14400.711985760227;14439.494617688331;7431;8745.562721863882;8866.985225023524;8873.467591944374;8966.372353935292;9083.50040747752;9086.045965173695;9090.681826136053;9199.172074513326;9210.861837072449;4468.486124095705;4529.9547004530195;4532.4334458192725;4532.886677833015;4588.541145885385;1885.990570047141;1922.3944457495843;1923.6345094432293;1926.5280006398523;1971.4677037199876;776.8407476467311;997.2371136080903;1026.4970164619283;1051.779126383468;1080.6649938519083;20169.49576260576;20749.273775417787;20863.687044694343;21041.368758937257;21376.893115534323;947.6827210177859;1019.3170575714289;1029.5006921643146;1036.7719101797593;1133.7335726104359;10680.511039665646;11207.797076096464;11310;11352.14858885587;11394.202405831678;538578;624630;631462;638625;639592;102684.48657756664;110786.89213107766;117258;119457.40271298587;121800.39099804444;1061.3466334164589;1082.5615625671617;1093.573506332531;1094.4254266509986;1100.6147848253047;17987.730184047512;18303.725444118616;18436.262549498406;18929.148188331834;19402.320918767775;43160.35259471048;55992;57441.42558574361;57544;57660.73565707018;72577.63711181622;74058.62970685112;75651.62174189094;75897.24102758901;76060.85908711485;7659.080910290801;7809.375249980101;8172.42793004484;8370.66517339324;8506.532140732319;9658.582881690296;11119.610813621484;13635.931820340835;13652.726945461234;13844.515441959484;11610.70973225676;11647.068234541386;11771.47028383742;11792.351420671603;12009.219400738924;11306.773864522827;11540.596079137189;11573.594924177612;11695.473703683525;11847.703807404883;11841.993430558154;11930.463129159036;11939.403029848647;12034.759304813679;12271.69320766988;8301;8364.121767214356;8396.538190399586;8412.957935210285;8584.613692383733;11867.287962722265;11931.821022684493;12070.27578345302;12132.272063676208;12143.939280303895 16:07:16 INFO - PROCESS | 1663 | |3;mootools.html;1469.4709904434394;1653.8924969876957;1682.9663406731866;2018.1322031526438;2089.8014688604576;8384.790380240489;8953.328500362475;9134.451932884032;9144.817103657939;9148.4968326742;8834.293256539477;9101.59042843072;9104.817903641922;9120.817583648322;9131.543422828849;2291.5531471363092;2520.798336133105;2534.6958364996226;2573.0351118330673;2590.1581985854577;2290.347251033456;2626.2121363590845;2626.960595591068;2630.5002049610607;2632.0656167060706;2533.873306334676;2597.402597402589;2603.9349016274555;2607.6479675243886;2607.921762347123;2547.1594373856674;2583.470388570348;2609.1650671785037;2616.751408616178;2617.0316982716326;2570.948581028369;2600.7919366450637;2608.543504886637;2618.48939456806;2622.10848311575;9215.308851836138;9280.025597312253;9293.21007714345;9304.627814887397;9317.673881414186;5630.971845140789;5745.683987380692;5810.070388737781;5812.360640329561;5821.214136091616;3621.6559426854406;3699.093722038118;3752.7935963521995;3772.3775577029755;3783.886483405502;1064.4518073192312;1065.1478816946412;1069.9732506687317;1085.9674209773718;1108.0027974822644;12570.30863302518;12573;12583.811242831272;12624.936875315565;12648.55730049453;5021.924671129936;5022.874428139326;5095.057414378278;5142.357205349332;5145.456363091058;10570.471476426148;10615;10625.043746062736;10643.36139831597;10643.787124257471;5766.740496677659;5777.959967205859;5781.4218578141845;5793.899159159746;5806.5645076618575;4619.538046195354;4745.406824146981;4772.331873537708;4788.1381351356395;5002.049610573979;3366.107981384935;4130.855420060284;4135.565765594569;4165.4584903962295;4195.958040419618;1368.6852024034529;1380.1167252958114;1380.151207007703;1390.5828251524501;1392.78411846164;1352.8038434426953;1419.978700319496;1426.1585664458019;1429.907056041354;1451.3831621560794;7376.594287314249;8346;8485.830283394298;8562;8576.571171441403;523.1685385465225;524.8477941396952;537.5806870640906;537.5914305128052;538.6418032008748;6359.6184228945485;6371.808845734636;6376.075469056961;6394.936050639527;6396.5522413431545;10894.836577451344;11311.095112390989;11328.886711132947;11414.543418263342;11441.942790285995;10860.891391085988;11118.054965327876;11138.108951283877;11145.72135696614;11271.211015228855;8619.267362274151;8688.087750786077;8711.259542938795;8732.91267087321;8780.165884240987;7077.893831592633;8204.425690201628;8255.050669173123;8258.917410825814;8371.372147089114;9397.953010234905;9746.31775775689;9776.120149186607;9965.302428830202;10544;10004.299699021;10077.596896124072;10227.897721022695;10409.47952602382;10426.687199383727;7553.395728341609;7702.961485192538;7781.416393770377;7789;7830.765077047698;8640.956795215983;8644.351673624378;8699.652013919625;8705.608247629;8708.085651006806;7605.771826845204;7736.883946740691;7741.5742134183165;7826.413019023482;7827.6868925244635;7399.445041622008;7561.621918903923;7704.8459030820195;7757.301842834172;7850.293473587176;7970.760877173462;8003.679852806055;8086.353091752763;8089.555074470725;8094.433389662667;7241.855162896818;7330.120385553554;7334;7336.082989626297;7340.376068034277;7361.30067643573;7372.016366791074;7467.066616672916;7468.663910124166;7473.40212782987 16:07:16 INFO - PROCESS | 1663 | |4;prototype.html;335.3109360264656;339.52636072678615;345.86856994342156;351.10818520956786;352.8659109538375;6727.192736871576;6787.592744435331;6831.795046148623;6853.725850965961;6877.931220687791;6772;6772.661366931658;6784.050232967388;6793.796186114412;6799.4560435165195;4763.499832517579;4891.315215869781;4941.184704523749;5018.222175562775;5048.848534543969;4942.827001054964;5017.774200161001;5017.799288028493;5034;5041.1178043842365;4962.851114466554;5036.924446133311;5040.798368065273;5049.368828896388;5055.46917573655;4778.235482322829;4807.014562014796;4955.331030310898;4984;5002.799888004475;4287.335463003239;4291.871243862689;4293.441852559178;4299.312110062407;4307.78461076948;5177.689338639693;6410.134631824691;6447.161868957052;6462.547621666486;6497.18785151856;4759.809607615691;5927.792527261525;5933.110033494967;5940.198073260098;5942.494887934531;6899.827504312382;7174.923761435774;7179.964100179518;7199.028131202325;7204.675789589481;4799.1601469743;4821.252705830567;4827.541383568555;4833;4833.347498087748;7487.3261406473675;7658.61706914652;7665.118511371152;7672.194419585919;7697.30724234816;4906.681065730716;4921.655484116149;4937.8271760488215;4939.90120197594;4945.085159245551;5000.724960127155;5006.549410553068;5012.298278241049;5020.221865610836;5026.824061157842;5651.378348381675;5876.6180198286975;5884.735186916599;5889.057750759858;5891.970540147272;7195.13658361;7214.531055481482;7269.20038795732;7274.672639731225;7305.963470182722;1723.293449685648;1746.318935615111;1752.0626464841248;1752.4742577226782;1755.3856150347276;1064.7710742190466;1100.5212732461405;1121.4168632311153;1136.1819489967374;1161.4773351991469;6480.708368123445;6557.573757705733;6565.540412171103;6723.462123030244;6738.35985581369;6055.455009049207;7510.887336689954;7540.736074237376;7543.622818859035;7557.773266802004;6679.532432729759;6690.83272918181;6691.7323307068145;6700.128983232149;6706.295838936939;3828.157805282833;3930.1550166713723;3981.0047488127966;3981.1639555693628;3982.761034337949;6730.32696730323;7138.214796372395;7881.133075361706;7942.523448593103;7970;6289.276733175652;7792;7803.80490487731;7806.360664260455;7806.843863122705;6346.333634968354;7904.525728456196;7904.644291006918;7916.84166316682;7925.128235894163;5345.823918737871;7008.4042856355745;7278.235785242473;7352.338289553966;7542.585157816154;7401.59291238987;7419.256474728327;7436.479446438698;7460.0674915635545;7480.0649918760155;7952.443328966917;7964.283214510722;7966.04407471084;7974.8006299840645;7981.760547183594;4690.8123675053985;4747.881302967454;4748.738819364969;4755.714657120584;4765.451973023008;6060.393960603904;7199.1361036675935;7550.2449755026255;7552.546847189186;7558.13081495635;7229.710811567689;7240.96379518099;7242.203357630762;7261.6006119663925;7319.817004574928;7271.600061996641;7279.308465695751;7303.817404564928;7305.525140865827;7308.415326773739;5778.248827652378;7107.502474826712;7108.502404831612;7113.928860711327;7123.857522849617;6921.653917304014;7196.676149573177;7259.854802904018;7295.088113985752;7302.9269707304375;6730.125083739083;6886.276940921131;6934.133233345832;6990.09128813251;7088.326608972139 16:07:16 INFO - PROCESS | 1663 | |5;yui.html;705.7953193573863;755.9379072403275;777.3004296133482;787.7046107709609;790.818111834278;14531.782023269647;14646.267686615653;14682;14717.779233311509;14729.705405891877;14152.858471415282;14246.287685615704;14259;14264.358103885324;14273.71452570948;771.3212373111668;795.964181611826;796.3947399976031;796.8246985663174;796.8685166947447;899.6041741633693;945.3902233059672;945.8108378324329;946.9005754395793;948.8624149498319;1028.681108856257;1031.3863251365426;1031.767852233249;1032.349619739563;1032.4734385463394;1010.3281317923572;1026.14829691356;1027.6557353286657;1028.5834237133972;1029.6036026129918;604.4710877981765;736.5396627108057;763.8739607964679;767.777344570074;769.7036640893241;15341.309641066067;15444.92277538605;15453.5363939082;15456.922715386463;15458.922705386514;12174;12379.62861114168;12866.67833304175;13046.608601741962;13519;10501.002404771534;10657.413842238751;10662.147028237723;10764.515596798085;11155.665330040112;2986.9850650746607;3489.284696637183;3519.894403167909;3552.307300076486;3633.92799124259;78310;78875;79037;82518.58740706318;83404.74892876612;9681.66114186;9684.273679474067;9717.757056073513;9836.163926066221;9874.012598740068;21740.45648858791;21792.89103554504;22076.896155192175;22463.887680561493;22531.211407600655;7544.47188696786;7576.393888488908;7782.105057918412;7884.448088633855;7976.401769867167;3867.57456679765;4104.548499665035;4116.958830411718;4156.501219853637;4279.764612946318;3726.869559565402;3774.207416442523;3782.3759079751535;3783.3379158647126;3786.1670432504807;607.547377203977;661.8113837556274;678.837079101022;690.0994202565599;696.2376198063113;604.9546284028715;605.2434456928839;610.7343305662051;627.4259052966485;643.1542521584101;7973.401994850409;8102.311303539147;8350.916490835136;8474.237318641352;8662.956685216534;579.8869220502046;594.4352864778477;595.5116804220584;596.8269201931478;627.7583130494793;3931.8623848165175;3953.011747063234;3953.6244056814558;4001.1797581495725;4052.250333688277;84543.15456845476;88235.11764882269;88593.55703221442;89615.10384896198;89842;77360.2263977353;77760.61119694365;78178;78840;78864.6056769724;68882;70680.64659676772;71497.28502715011;71794.64102679453;72148.2785172152;6596.373344532261;8384.706535271236;8564.229219370287;8618.655253789779;8911.465312081296;48230.75884620554;49189.32701393362;49553.50446495489;49616.526843122076;50010.749946250035;69896.65051674709;70092.649536752;70529.6473517629;72281.55436891338;73052.6347368281;11228.157888158257;11459.255148415326;11468.713282168013;11633.243839150427;11641.825372619587;11991.700207494883;11994.160408771302;12078.698032549257;12093;12795.552155674506;11258.788164857147;11543.653690389301;11649.767004660029;11802.881971180179;11876.049916006526;11267.830982535104;11922.165448418875;11948.641540753792;11955.402229888645;12092.697682558006;11468.4265786712;11727.882721172678;11859.05127589808;11865;11883.821742673694;9052.3210759192;9685.612575496902;9715.36850104741;9739.366941149086;9973.451460169761;9230.446173229358;9336.673216437392;9820.754481137743;9844.9015509844;9974.700758977242 16:07:16 INFO - PROCESS | 1663 | __end_tp_report 16:07:16 INFO - PROCESS | 1663 | __start_cc_report 16:07:16 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,1021 16:07:16 INFO - PROCESS | 1663 | __end_cc_report 16:07:16 INFO - PROCESS | 1663 | __startTimestamp1461366436324__endTimestamp 16:07:16 INFO - PROCESS | 1663 | 16:07:16 INFO - PROCESS | 1663 | ------- Summary: start ------- 16:07:16 INFO - PROCESS | 1663 | Number of tests: 6 16:07:16 INFO - PROCESS | 1663 | 16:07:16 INFO - PROCESS | 1663 | [#0] dojo.html Cycles:165 Average:5811.19 Median:6078.82 stddev:650.85 (10.7%) stddev-sans-first:625.47 16:07:16 INFO - PROCESS | 1663 | Values: 3430.4 3498.3 3530.3 3531.3 3556.2 5759.9 5764.7 5769.7 5773.6 5799.4 5759.5 5791.1 5793.1 5812.9 5815.2 5141.2 5207.9 5218.1 5246.4 5341.1 5314.4 5324.5 5325.5 5340.1 5348.0 4494.6 5262.6 5299.4 5324.3 5332.6 5316.2 5351.8 5361.7 5362.5 5385.5 4361.6 5068.6 5235.4 5243.4 5251.8 5634.6 5683.9 5688.4 5693.4 5710.3 6132.6 6302.8 6305.2 6309.6 6325.5 6207.3 6233.4 6248.3 6253.2 6286.8 5893.9 5903.9 5914.0 5915.5 5942.3 6203.3 6231.2 6346.6 6349.6 6350.2 6222.0 6245.8 6246.1 6257.5 6266.8 6275.4 6277.0 6284.2 6286.9 6301.8 6097.1 6186.8 6197.2 6287.3 6290.9 6262.6 6282.5 6320.9 6322.0 6340.0 4991.4 4993.6 4999.0 5003.1 5015.3 4355.5 4397.3 4485.3 4509.0 4510.6 5949.3 5959.7 5986.4 5987.5 5997.1 6272.7 6295.7 6321.9 6333.5 6338.8 4748.7 5837.9 5918.5 5925.2 6006.7 5456.9 5476.7 5477.9 5480.9 5481.5 6420.1 6428.9 6429.9 6438.9 6448.9 6211.2 6222.3 6283.8 6323.0 6327.5 6405.6 6415.9 6429.9 6431.4 6444.5 6240.7 6258.9 6268.7 6270.0 6270.2 6160.6 6189.8 6264.6 6277.0 6279.1 6155.8 6185.5 6196.8 6205.5 6213.7 5965.2 6042.6 6189.5 6199.9 6204.8 6000.7 6069.0 6070.4 6098.8 6126.2 6188.6 6193.2 6197.9 6214.5 6220.3 5868.8 5972.9 6078.8 6082.8 6086.7 16:07:16 INFO - PROCESS | 1663 | 16:07:16 INFO - PROCESS | 1663 | [#1] ext.html Cycles:180 Average:61555.97 Median:37103.04 stddev:122674.41 (330.6%) stddev-sans-first:122932.85 16:07:16 INFO - PROCESS | 1663 | Values: 486.6 600.2 629.8 669.6 670.8 31533.5 39892.8 40156.6 40181.6 40438.0 42812.9 43368.3 43428.8 43459.0 43503.3 9642.8 9870.7 9872.6 9887.9 9889.4 9637.0 9768.6 9781.7 9796.0 9799.8 9787.0 9841.5 9849.8 9854.9 9884.1 9868.2 9928.1 9929.3 9934.9 9942.6 8345.8 9908.3 9922.2 9925.0 9926.6 42013.6 42458.8 42462.8 42524.6 42543.0 3205.4 3323.2 3335.8 3452.4 3714.4 28790.4 29111.4 29447.4 29609.4 29776.6 2704.9 2732.5 2770.7 2779.0 2799.5 483947.6 485892.0 487488.0 488194.0 489168.0 65418.0 65882.3 65917.7 65967.0 66093.3 76654.6 77715.0 77808.0 77844.0 78008.2 18274.1 18584.8 18613.0 18653.3 18665.8 13912.2 13997.7 14019.9 14020.4 14023.2 21908.6 22208.0 22236.8 22275.0 22590.8 8023.0 8023.2 9254.4 9514.4 9538.7 960.8 1021.6 1033.1 1037.8 1106.4 29604.3 29757.4 30196.9 30334.2 30334.8 1147.3 1185.0 1197.0 1211.2 1298.4 11533.5 14980.5 16118.0 16239.5 16385.3 516111.0 522819.0 523239.0 523625.0 525447.0 59560.7 61805.0 61881.7 61885.7 61892.0 1416.8 1435.0 1531.5 1535.4 1581.2 26617.3 28842.6 29544.3 29643.6 30137.7 46978.3 47194.8 47661.3 47692.5 47747.8 358583.2 361817.0 362077.0 362549.0 362736.0 29713.0 34021.7 34381.8 34892.7 34948.0 41847.8 42468.6 42570.8 44146.6 44218.8 34423.0 35698.0 36572.6 37066.6 37672.6 35219.8 35221.0 35627.6 35863.0 35880.6 36653.0 36787.8 36898.8 37139.4 38069.4 27682.7 31851.0 32374.4 32666.0 33024.3 24722.1 25101.7 25222.6 25281.9 25355.5 16:07:16 INFO - PROCESS | 1663 | 16:07:16 INFO - PROCESS | 1663 | [#2] jquery.html Cycles:180 Average:34625.88 Median:20254.54 stddev:102086.17 (504.0%) stddev-sans-first:102340.16 16:07:16 INFO - PROCESS | 1663 | Values: 382.0 385.5 387.9 390.1 393.1 16303.3 16611.8 19493.7 20339.6 20930.0 16249.8 20551.7 20687.9 21029.1 21396.8 5892.2 6041.8 6043.0 6089.1 6097.4 5090.4 5778.1 6053.5 6072.0 6161.5 6038.0 6041.9 6052.5 6056.1 6065.4 5672.3 5987.6 6008.7 6073.4 6186.3 5898.3 5903.1 5903.2 5915.2 5935.9 13410.4 14062.7 14073.9 14102.7 14109.6 2202.8 2334.6 2451.7 2595.0 2797.2 10279.4 10459.4 13968.7 14239.4 15289.4 1635.5 1666.0 1794.9 1821.3 1824.6 100768.0 109013.5 109915.5 110512.4 111623.9 11370.6 11444.0 11489.5 11502.9 11618.8 14240.8 14333.6 14336.6 14400.7 14439.5 7431.0 8745.6 8867.0 8873.5 8966.4 9083.5 9086.0 9090.7 9199.2 9210.9 4468.5 4530.0 4532.4 4532.9 4588.5 1886.0 1922.4 1923.6 1926.5 1971.5 776.8 997.2 1026.5 1051.8 1080.7 20169.5 20749.3 20863.7 21041.4 21376.9 947.7 1019.3 1029.5 1036.8 1133.7 10680.5 11207.8 11310.0 11352.1 11394.2 538578.0 624630.0 631462.0 638625.0 639592.0 102684.5 110786.9 117258.0 119457.4 121800.4 1061.3 1082.6 1093.6 1094.4 1100.6 17987.7 18303.7 18436.3 18929.1 19402.3 43160.4 55992.0 57441.4 57544.0 57660.7 72577.6 74058.6 75651.6 75897.2 76060.9 7659.1 7809.4 8172.4 8370.7 8506.5 9658.6 11119.6 13635.9 13652.7 13844.5 11610.7 11647.1 11771.5 11792.4 12009.2 11306.8 11540.6 11573.6 11695.5 11847.7 11842.0 11930.5 11939.4 12034.8 12271.7 8301.0 8364.1 8396.5 8413.0 8584.6 11867.3 11931.8 12070.3 12132.3 12143.9 16:07:16 INFO - PROCESS | 1663 | 16:07:16 INFO - PROCESS | 1663 | [#3] mootools.html Cycles:180 Average:6311.12 Median:2834.31 stddev:3357.48 (118.5%) stddev-sans-first:3347.17 16:07:16 INFO - PROCESS | 1663 | Values: 1469.5 1653.9 1683.0 2018.1 2089.8 8384.8 8953.3 9134.5 9144.8 9148.5 8834.3 9101.6 9104.8 9120.8 9131.5 2291.6 2520.8 2534.7 2573.0 2590.2 2290.3 2626.2 2627.0 2630.5 2632.1 2533.9 2597.4 2603.9 2607.6 2607.9 2547.2 2583.5 2609.2 2616.8 2617.0 2570.9 2600.8 2608.5 2618.5 2622.1 9215.3 9280.0 9293.2 9304.6 9317.7 5631.0 5745.7 5810.1 5812.4 5821.2 3621.7 3699.1 3752.8 3772.4 3783.9 1064.5 1065.1 1070.0 1086.0 1108.0 12570.3 12573.0 12583.8 12624.9 12648.6 5021.9 5022.9 5095.1 5142.4 5145.5 10570.5 10615.0 10625.0 10643.4 10643.8 5766.7 5778.0 5781.4 5793.9 5806.6 4619.5 4745.4 4772.3 4788.1 5002.0 3366.1 4130.9 4135.6 4165.5 4196.0 1368.7 1380.1 1380.2 1390.6 1392.8 1352.8 1420.0 1426.2 1429.9 1451.4 7376.6 8346.0 8485.8 8562.0 8576.6 523.2 524.8 537.6 537.6 538.6 6359.6 6371.8 6376.1 6394.9 6396.6 10894.8 11311.1 11328.9 11414.5 11441.9 10860.9 11118.1 11138.1 11145.7 11271.2 8619.3 8688.1 8711.3 8732.9 8780.2 7077.9 8204.4 8255.1 8258.9 8371.4 9398.0 9746.3 9776.1 9965.3 10544.0 10004.3 10077.6 10227.9 10409.5 10426.7 7553.4 7703.0 7781.4 7789.0 7830.8 8641.0 8644.4 8699.7 8705.6 8708.1 7605.8 7736.9 7741.6 7826.4 7827.7 7399.4 7561.6 7704.8 7757.3 7850.3 7970.8 8003.7 8086.4 8089.6 8094.4 7241.9 7330.1 7334.0 7336.1 7340.4 7361.3 7372.0 7467.1 7468.7 7473.4 16:07:16 INFO - PROCESS | 1663 | 16:07:16 INFO - PROCESS | 1663 | [#4] prototype.html Cycles:180 Average:5899.60 Median:6691.28 stddev:1862.64 (27.8%) stddev-sans-first:1820.44 16:07:16 INFO - PROCESS | 1663 | Values: 335.3 339.5 345.9 351.1 352.9 6727.2 6787.6 6831.8 6853.7 6877.9 6772.0 6772.7 6784.1 6793.8 6799.5 4763.5 4891.3 4941.2 5018.2 5048.8 4942.8 5017.8 5017.8 5034.0 5041.1 4962.9 5036.9 5040.8 5049.4 5055.5 4778.2 4807.0 4955.3 4984.0 5002.8 4287.3 4291.9 4293.4 4299.3 4307.8 5177.7 6410.1 6447.2 6462.5 6497.2 4759.8 5927.8 5933.1 5940.2 5942.5 6899.8 7174.9 7180.0 7199.0 7204.7 4799.2 4821.3 4827.5 4833.0 4833.3 7487.3 7658.6 7665.1 7672.2 7697.3 4906.7 4921.7 4937.8 4939.9 4945.1 5000.7 5006.5 5012.3 5020.2 5026.8 5651.4 5876.6 5884.7 5889.1 5892.0 7195.1 7214.5 7269.2 7274.7 7306.0 1723.3 1746.3 1752.1 1752.5 1755.4 1064.8 1100.5 1121.4 1136.2 1161.5 6480.7 6557.6 6565.5 6723.5 6738.4 6055.5 7510.9 7540.7 7543.6 7557.8 6679.5 6690.8 6691.7 6700.1 6706.3 3828.2 3930.2 3981.0 3981.2 3982.8 6730.3 7138.2 7881.1 7942.5 7970.0 6289.3 7792.0 7803.8 7806.4 7806.8 6346.3 7904.5 7904.6 7916.8 7925.1 5345.8 7008.4 7278.2 7352.3 7542.6 7401.6 7419.3 7436.5 7460.1 7480.1 7952.4 7964.3 7966.0 7974.8 7981.8 4690.8 4747.9 4748.7 4755.7 4765.5 6060.4 7199.1 7550.2 7552.5 7558.1 7229.7 7241.0 7242.2 7261.6 7319.8 7271.6 7279.3 7303.8 7305.5 7308.4 5778.2 7107.5 7108.5 7113.9 7123.9 6921.7 7196.7 7259.9 7295.1 7302.9 6730.1 6886.3 6934.1 6990.1 7088.3 16:07:16 INFO - PROCESS | 1663 | 16:07:16 INFO - PROCESS | 1663 | [#5] yui.html Cycles:180 Average:18415.33 Median:587.16 stddev:25545.50 (4350.7%) stddev-sans-first:25582.55 16:07:16 INFO - PROCESS | 1663 | Values: 705.8 755.9 777.3 787.7 790.8 14531.8 14646.3 14682.0 14717.8 14729.7 14152.9 14246.3 14259.0 14264.4 14273.7 771.3 796.0 796.4 796.8 796.9 899.6 945.4 945.8 946.9 948.9 1028.7 1031.4 1031.8 1032.3 1032.5 1010.3 1026.1 1027.7 1028.6 1029.6 604.5 736.5 763.9 767.8 769.7 15341.3 15444.9 15453.5 15456.9 15458.9 12174.0 12379.6 12866.7 13046.6 13519.0 10501.0 10657.4 10662.1 10764.5 11155.7 2987.0 3489.3 3519.9 3552.3 3633.9 78310.0 78875.0 79037.0 82518.6 83404.7 9681.7 9684.3 9717.8 9836.2 9874.0 21740.5 21792.9 22076.9 22463.9 22531.2 7544.5 7576.4 7782.1 7884.4 7976.4 3867.6 4104.5 4117.0 4156.5 4279.8 3726.9 3774.2 3782.4 3783.3 3786.2 607.5 661.8 678.8 690.1 696.2 605.0 605.2 610.7 627.4 643.2 7973.4 8102.3 8350.9 8474.2 8663.0 579.9 594.4 595.5 596.8 627.8 3931.9 3953.0 3953.6 4001.2 4052.3 84543.2 88235.1 88593.6 89615.1 89842.0 77360.2 77760.6 78178.0 78840.0 78864.6 68882.0 70680.6 71497.3 71794.6 72148.3 6596.4 8384.7 8564.2 8618.7 8911.5 48230.8 49189.3 49553.5 49616.5 50010.7 69896.7 70092.6 70529.6 72281.6 73052.6 11228.2 11459.3 11468.7 11633.2 11641.8 11991.7 11994.2 12078.7 12093.0 12795.6 11258.8 11543.7 11649.8 11802.9 11876.0 11267.8 11922.2 11948.6 11955.4 12092.7 11468.4 11727.9 11859.1 11865.0 11883.8 9052.3 9685.6 9715.4 9739.4 9973.5 9230.4 9336.7 9820.8 9844.9 9974.7 16:07:16 INFO - PROCESS | 1663 | -------- Summary: end -------- 16:07:16 INFO - PROCESS | 1663 | 16:07:16 INFO - TEST-INFO | 1663: exit 0 16:07:16 INFO - TEST-OK | dromaeo_css | took 1112075ms 16:07:16 INFO - TEST-START | kraken 16:07:16 INFO - operating with platform_type : mac_ 16:07:16 INFO - Initialising browser for kraken test... 16:07:16 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/tmp8N4i17/profile http://localhost:49272/getInfo.html) 16:07:18 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 16:07:18 INFO - PROCESS | 1672 | colorDepth:24 16:07:18 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 16:07:18 INFO - PROCESS | 1672 | __metrics 16:07:19 INFO - PROCESS | 1672 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:07:19 INFO - PROCESS | 1672 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:07:19 INFO - TEST-INFO | 1672: exit 0 16:07:19 INFO - Browser initialized. 16:07:19 INFO - Running cycle 1/1 for kraken test... 16:07:19 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/tmp8N4i17/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:07:31 INFO - PROCESS | 1674 | RSS: Main: 207810560 16:07:31 INFO - PROCESS | 1674 | 16:07:34 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 16:07:34 INFO - PROCESS | 1674 | RSS: Main: 263360512 16:07:34 INFO - PROCESS | 1674 | 16:07:37 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 16:07:38 INFO - PROCESS | 1674 | RSS: Main: 273743872 16:07:38 INFO - PROCESS | 1674 | 16:07:41 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 16:07:41 INFO - PROCESS | 1674 | RSS: Main: 264302592 16:07:41 INFO - PROCESS | 1674 | 16:07:43 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 16:07:44 INFO - PROCESS | 1674 | RSS: Main: 265080832 16:07:44 INFO - PROCESS | 1674 | 16:07:46 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:07:46 INFO - PROCESS | 1674 | RSS: Main: 264597504 16:07:46 INFO - PROCESS | 1674 | 16:07:49 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 16:07:50 INFO - PROCESS | 1674 | RSS: Main: 265306112 16:07:50 INFO - PROCESS | 1674 | 16:07:53 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 16:07:53 INFO - PROCESS | 1674 | RSS: Main: 266338304 16:07:53 INFO - PROCESS | 1674 | 16:07:56 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 16:07:57 INFO - PROCESS | 1674 | RSS: Main: 266698752 16:07:57 INFO - PROCESS | 1674 | 16:07:58 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:07:59 INFO - PROCESS | 1674 | RSS: Main: 300134400 16:07:59 INFO - PROCESS | 1674 | 16:08:01 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:08:01 INFO - PROCESS | 1674 | RSS: Main: 301006848 16:08:01 INFO - PROCESS | 1674 | 16:08:03 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:08:04 INFO - PROCESS | 1674 | RSS: Main: 306167808 16:08:04 INFO - PROCESS | 1674 | 16:08:06 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:08:07 INFO - PROCESS | 1674 | RSS: Main: 307900416 16:08:07 INFO - PROCESS | 1674 | 16:08:09 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:08:10 INFO - PROCESS | 1674 | RSS: Main: 307822592 16:08:10 INFO - PROCESS | 1674 | 16:08:12 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 16:08:12 INFO - PROCESS | 1674 | RSS: Main: 370733056 16:08:12 INFO - PROCESS | 1674 | 16:08:12 INFO - PROCESS | 1674 | __start_tp_report 16:08:12 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 16:08:12 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 16:08:12 INFO - PROCESS | 1674 | |i|pagename|runs| 16:08:12 INFO - PROCESS | 1674 | |0;ai-astar;114;108;112;111;113;113;117;110;111;111 16:08:12 INFO - PROCESS | 1674 | |1;audio-beat-detection;152;120;153;184;144;156;116;149;151;117 16:08:12 INFO - PROCESS | 1674 | |2;audio-dft;183;186;184;187;186;184;178;183;185;184 16:08:12 INFO - PROCESS | 1674 | |3;audio-fft;73;71;76;112;56;60;63;79;151;82 16:08:12 INFO - PROCESS | 1674 | |4;audio-oscillator;91;96;130;77;79;83;116;77;78;114 16:08:12 INFO - PROCESS | 1674 | |5;imaging-gaussian-blur;90;130;86;83;86;83;86;86;96;84 16:08:12 INFO - PROCESS | 1674 | |6;imaging-darkroom;93;92;108;149;91;92;92;93;91;92 16:08:12 INFO - PROCESS | 1674 | |7;imaging-desaturate;87;94;87;86;84;86;86;95;132;85 16:08:12 INFO - PROCESS | 1674 | |8;json-parse-financial;52;58;57;57;58;59;57;68;55;69 16:08:12 INFO - PROCESS | 1674 | |9;json-stringify-tinderbox;65;70;64;73;67;66;66;67;67;68 16:08:12 INFO - PROCESS | 1674 | |10;stanford-crypto-aes;82;81;81;82;80;79;79;79;80;84 16:08:12 INFO - PROCESS | 1674 | |11;stanford-crypto-ccm;130;121;119;118;116;130;120;128;129;114 16:08:12 INFO - PROCESS | 1674 | |12;stanford-crypto-pbkdf2;135;124;133;158;133;125;131;132;135;132 16:08:12 INFO - PROCESS | 1674 | |13;stanford-crypto-sha256-iterative;67;65;68;92;95;92;65;67;66;66 16:08:12 INFO - PROCESS | 1674 | __end_tp_report 16:08:12 INFO - PROCESS | 1674 | __start_cc_report 16:08:12 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,1235 16:08:12 INFO - PROCESS | 1674 | __end_cc_report 16:08:12 INFO - PROCESS | 1674 | __startTimestamp1461366492571__endTimestamp 16:08:12 INFO - PROCESS | 1674 | 16:08:12 INFO - PROCESS | 1674 | ------- Summary: start ------- 16:08:12 INFO - PROCESS | 1674 | Number of tests: 14 16:08:12 INFO - PROCESS | 1674 | 16:08:12 INFO - PROCESS | 1674 | [#0] ai-astar Cycles:10 Average:112.00 Median:112.50 stddev:2.45 (2.2%) stddev-sans-first:2.49 16:08:12 INFO - PROCESS | 1674 | Values: 114.0 108.0 112.0 111.0 113.0 113.0 117.0 110.0 111.0 111.0 16:08:12 INFO - PROCESS | 1674 | 16:08:12 INFO - PROCESS | 1674 | [#1] audio-beat-detection Cycles:10 Average:144.20 Median:151.50 stddev:21.22 (14.0%) stddev-sans-first:22.32 16:08:12 INFO - PROCESS | 1674 | Values: 152.0 120.0 153.0 184.0 144.0 156.0 116.0 149.0 151.0 117.0 16:08:12 INFO - PROCESS | 1674 | 16:08:12 INFO - PROCESS | 1674 | [#2] audio-dft Cycles:10 Average:184.00 Median:184.50 stddev:2.49 (1.4%) stddev-sans-first:2.62 16:08:12 INFO - PROCESS | 1674 | Values: 183.0 186.0 184.0 187.0 186.0 184.0 178.0 183.0 185.0 184.0 16:08:12 INFO - PROCESS | 1674 | 16:08:12 INFO - PROCESS | 1674 | [#3] audio-fft Cycles:10 Average:82.30 Median:72.00 stddev:28.73 (39.9%) stddev-sans-first:30.27 16:08:12 INFO - PROCESS | 1674 | Values: 73.0 71.0 76.0 112.0 56.0 60.0 63.0 79.0 151.0 82.0 16:08:12 INFO - PROCESS | 1674 | 16:08:12 INFO - PROCESS | 1674 | [#4] audio-oscillator Cycles:10 Average:94.10 Median:78.50 stddev:19.36 (24.7%) stddev-sans-first:20.50 16:08:12 INFO - PROCESS | 1674 | Values: 91.0 96.0 130.0 77.0 79.0 83.0 116.0 77.0 78.0 114.0 16:08:12 INFO - PROCESS | 1674 | 16:08:12 INFO - PROCESS | 1674 | [#5] imaging-gaussian-blur Cycles:10 Average:91.00 Median:86.00 stddev:14.24 (16.6%) stddev-sans-first:15.10 16:08:12 INFO - PROCESS | 1674 | Values: 90.0 130.0 86.0 83.0 86.0 83.0 86.0 86.0 96.0 84.0 16:08:12 INFO - PROCESS | 1674 | 16:08:12 INFO - PROCESS | 1674 | [#6] imaging-darkroom Cycles:10 Average:99.30 Median:92.00 stddev:18.18 (19.8%) stddev-sans-first:19.14 16:08:12 INFO - PROCESS | 1674 | Values: 93.0 92.0 108.0 149.0 91.0 92.0 92.0 93.0 91.0 92.0 16:08:12 INFO - PROCESS | 1674 | 16:08:12 INFO - PROCESS | 1674 | [#7] imaging-desaturate Cycles:10 Average:92.20 Median:86.50 stddev:14.47 (16.7%) stddev-sans-first:15.22 16:08:12 INFO - PROCESS | 1674 | Values: 87.0 94.0 87.0 86.0 84.0 86.0 86.0 95.0 132.0 85.0 16:08:12 INFO - PROCESS | 1674 | 16:08:12 INFO - PROCESS | 1674 | [#8] json-parse-financial Cycles:10 Average:59.00 Median:58.00 stddev:5.37 (9.3%) stddev-sans-first:5.07 16:08:12 INFO - PROCESS | 1674 | Values: 52.0 58.0 57.0 57.0 58.0 59.0 57.0 68.0 55.0 69.0 16:08:12 INFO - PROCESS | 1674 | 16:08:12 INFO - PROCESS | 1674 | [#9] json-stringify-tinderbox Cycles:10 Average:67.30 Median:67.00 stddev:2.58 (3.9%) stddev-sans-first:2.60 16:08:12 INFO - PROCESS | 1674 | Values: 65.0 70.0 64.0 73.0 67.0 66.0 66.0 67.0 67.0 68.0 16:08:12 INFO - PROCESS | 1674 | 16:08:12 INFO - PROCESS | 1674 | [#10] stanford-crypto-aes Cycles:10 Average:80.70 Median:81.00 stddev:1.64 (2.0%) stddev-sans-first:1.67 16:08:12 INFO - PROCESS | 1674 | Values: 82.0 81.0 81.0 82.0 80.0 79.0 79.0 79.0 80.0 84.0 16:08:12 INFO - PROCESS | 1674 | 16:08:12 INFO - PROCESS | 1674 | [#11] stanford-crypto-ccm Cycles:10 Average:122.50 Median:124.50 stddev:6.15 (4.9%) stddev-sans-first:5.89 16:08:12 INFO - PROCESS | 1674 | Values: 130.0 121.0 119.0 118.0 116.0 130.0 120.0 128.0 129.0 114.0 16:08:12 INFO - PROCESS | 1674 | 16:08:12 INFO - PROCESS | 1674 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:133.80 Median:133.00 stddev:9.30 (7.0%) stddev-sans-first:9.85 16:08:12 INFO - PROCESS | 1674 | Values: 135.0 124.0 133.0 158.0 133.0 125.0 131.0 132.0 135.0 132.0 16:08:12 INFO - PROCESS | 1674 | 16:08:12 INFO - PROCESS | 1674 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.30 Median:67.50 stddev:12.96 (19.2%) stddev-sans-first:13.48 16:08:12 INFO - PROCESS | 1674 | Values: 67.0 65.0 68.0 92.0 95.0 92.0 65.0 67.0 66.0 66.0 16:08:12 INFO - PROCESS | 1674 | -------- Summary: end -------- 16:08:12 INFO - PROCESS | 1674 | 16:08:13 INFO - TEST-INFO | 1674: exit 0 16:08:13 INFO - TEST-OK | kraken | took 56394ms 16:08:13 INFO - SUITE-END | took 1168s 16:08:13 INFO - Completed test suite (00:19:28) 16:08:13 INFO - javascript benchmark 16:08:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3430.416829139046, 3498.3178280235365, 3530.3468858261217, 3531.32904748098, 3556.2176321209326, 5759.934412133758, 5764.654120752747, 5769.653820770756, 5773.624714393567, 5799.3620701722775, 5759.510441612468, 5791.102379131231, 5793.102069179274, 5812.883742325162, 5815.214945982291, 5141.1517099678695, 5207.921881171786, 5218.086834803923, 5246.422893481714, 5341.145416733323, 5314.388845282803, 5324.520793128618, 5325.520703136717, 5340.145576707728, 5347.957148356072, 4494.617957473619, 5262.578993680497, 5299.443558426367, 5324.254604355393, 5332.600054995872, 5316.229146773716, 5351.785928562872, 5361.678299302054, 5362.544183744387, 5385.542228910547, 4361.629261512775, 5068.594512438996, 5235.371755389339, 5243.370795504564, 5251.842444726664, 5634.6337488063555, 5683.914741278884, 5688.402717714622, 5693.430656934315, 5710.2576665033275, 6132.570720049598, 6302.810915672537, 6305.211848518935, 6309.5898766580485, 6325.462335701425, 6207.286162091327, 6233.438990490878, 6248.343923887972, 6253.218347706536, 6286.842828929268, 5893.852653683649, 5903.852403689899, 5913.965056115163, 5915.497182739472, 5942.257217847769, 6203.348648391944, 6231.189945307081, 6346.587471814318, 6349.555531112777, 6350.206224221973, 6221.968890155611, 6245.75016999324, 6246.094316324107, 6257.530685198537, 6266.843328916722, 6275.403836635513, 6277.027060805583, 6284.151639528696, 6286.9056964145575, 6301.8109456716375, 6097.054956481752, 6186.845328866814, 6197.225346831646, 6287.276963149205, 6290.937090629127, 6262.624242545462, 6282.465990390868, 6320.936790632127, 6322.0, 6340.0, 4991.401031876126, 4993.575546078551, 4999.0, 5003.074431230234, 5015.322931404214, 4355.455568053993, 4397.31841564558, 4485.304777759453, 4509.0, 4510.594046535827, 5949.315828679757, 5959.731812068468, 5986.3714309997695, 5987.520998320211, 5997.070454079625, 6272.686365681698, 6295.748170073146, 6321.936780632227, 6333.493320534346, 6338.778142764981, 4748.738819364899, 5837.88324233513, 5918.496927761103, 5925.170476133345, 6006.699665016732, 5456.890862182734, 5476.726163691799, 5477.890442191214, 5480.945190548123, 5481.534069604048, 6420.133282006869, 6428.935710642927, 6429.935700643027, 6438.935610643834, 6448.871022579522, 6211.223597050369, 6222.346653601307, 6283.7800676976085, 6322.968385158045, 6327.525435592257, 6405.583637063576, 6415.935840641534, 6429.935700642934, 6431.421172094534, 6444.548881578245, 6240.656763878031, 6258.874822503615, 6268.655223962726, 6270.0, 6270.184875966095, 6160.599561028519, 6189.81430557084, 6264.624122552661, 6277.027060805583, 6279.058141278845, 6155.784547540815, 6185.474234690102, 6196.783112591038, 6205.503559715302, 6213.658248796179, 5965.194698715618, 6042.607230530001, 6189.535784816067, 6199.938000619936, 6204.844878878064, 6000.699965001645, 6069.02895536712, 6070.423309785563, 6098.75604975796, 6126.172966649446, 6188.628682279078, 6193.194884664965, 6197.907031394623, 6214.533909956681, 6220.34686357944, 5868.794592189253, 5972.940270597239, 6078.817635470943, 6082.847928801815, 6086.6652334122045], "unit": "score", "name": "dojo.html", "value": 5770.163385991379}, {"lowerIsBetter": false, "replicates": [486.5839707050474, 600.2436929468871, 629.8330942300288, 669.5580916595047, 670.8360993675973, 31533.526997095032, 39892.803215903536, 40156.59843401565, 40181.59818401815, 40437.989050273754, 42812.929676758096, 43368.3494747579, 43428.78285608575, 43459.0, 43503.34744978828, 9642.75893102671, 9870.703878883645, 9872.555734991942, 9887.851682224773, 9889.406635601887, 9636.988116247798, 9768.609255629766, 9781.657641982532, 9796.0, 9799.755006124868, 9787.021297870191, 9841.507924603777, 9849.803003939916, 9854.852177217348, 9884.061014203638, 9868.161206297473, 9928.106470417624, 9929.304948653597, 9934.850977235346, 9942.55258513365, 8345.833083338299, 9908.256880733901, 9922.206223502102, 9924.957879422629, 9926.602935882556, 42013.579864201274, 42458.787706061274, 42462.78768606168, 42524.57475425237, 42543.0, 3205.37495188436, 3323.1692076980753, 3335.8332083395735, 3452.361313157074, 3714.387126124186, 28790.424191516053, 29111.41777164445, 29447.411051778843, 29609.40781184386, 29776.553351699742, 2704.9004494864153, 2732.508148533263, 2770.7367800058964, 2778.9861050694817, 2799.4681010608124, 483947.5802620964, 485892.0, 487488.0, 488194.0, 489168.0, 65418.0, 65882.34117658858, 65917.67041164763, 65967.0, 66093.33906660872, 76654.616726916, 77715.0, 77808.0, 77844.0, 78008.21991780009, 18274.08629568543, 18584.81415185858, 18613.0, 18653.253869845325, 18665.81334186668, 13912.234827084405, 13997.65005874841, 14019.859801401855, 14020.439182432792, 14023.158610483404, 21908.561828763653, 22208.0, 22236.776977266083, 22275.0, 22590.774092258867, 8023.037235531773, 8023.1575684553445, 9254.352195346395, 9514.38156519824, 9538.713838584854, 960.7934294126796, 1021.6015753856002, 1033.1373303291834, 1037.7872536130076, 1106.441247170174, 29604.259893502836, 29757.40485190284, 30196.94310699115, 30334.241643959078, 30334.84832575867, 1147.254284714942, 1185.0, 1197.0124647166124, 1211.2429731417865, 1298.448159532195, 11533.480993355319, 14980.47568335125, 16118.032918024956, 16239.51281461558, 16385.262663180187, 516111.0, 522819.0, 523239.0, 523625.0, 525447.0, 59560.702196489605, 61805.0, 61881.69059154675, 61885.69057154685, 61892.0, 1416.8016477693131, 1435.0456946130705, 1531.47929703899, 1535.439564558928, 1581.2410043179339, 26617.33456663599, 28842.567361489175, 29544.261393465335, 29643.555346670248, 30137.69862301349, 46978.29532557083, 47194.820129497035, 47661.28508072451, 47692.52307476881, 47747.806304841266, 358583.24250272533, 361817.0, 362077.0, 362549.0, 362736.0, 29713.0, 34021.65978340185, 34381.79663711858, 34892.65107348894, 34947.9515614532, 41847.790761046, 42468.5753142477, 42570.787146064074, 44146.558534414246, 44218.77890610526, 34422.96731098071, 35698.0, 36572.63427365799, 37066.62933370632, 37672.62327376691, 35219.823900881354, 35221.0, 35627.64372356243, 35863.0, 35880.64119358877, 36653.0, 36787.816060919526, 36898.8155059223, 37139.44290835586, 38069.4289585662, 27682.723172768016, 31851.0, 32374.35251295008, 32666.0, 33024.339513210085, 24722.13472528453, 25101.74898250994, 25222.621660674737, 25281.867091611704, 25355.492890142465], "unit": "score", "name": "ext.html", "value": 20217.03612290813}, {"lowerIsBetter": false, "replicates": [381.95416550014005, 385.5200275656808, 387.8732282718701, 390.0891418537714, 393.08019234990115, 16303.347866085373, 16611.750823737624, 19493.707594386076, 20339.593208135848, 20929.95350232486, 16249.837501624981, 20551.74063406319, 20687.896560517176, 21029.05369258383, 21396.823174725385, 5892.204552385438, 6041.758329666808, 6043.0, 6089.1170780236835, 6097.359777223394, 5090.414602320743, 5778.133280007991, 6053.545984051192, 6071.998120310143, 6161.476274516672, 6038.0, 6041.939580604182, 6052.515798736091, 6056.121862329959, 6065.423784740443, 5672.347680016789, 5987.580869339148, 6008.669523176223, 6073.362296958822, 6186.257649082094, 5898.321693005317, 5903.085021821625, 5903.232579764646, 5915.231019967377, 5935.881282374372, 13410.396532156077, 14062.718745625132, 14073.85926140746, 14102.71794564103, 14109.576712698636, 2202.7907348802, 2334.568104900599, 2451.705795304557, 2594.9740502594923, 2797.216779301799, 10279.434631095286, 10459.372437653765, 13968.720625587432, 14239.430422783076, 15289.388424463008, 1635.5256975477098, 1665.96668066638, 1794.874358794885, 1821.271491403436, 1824.5529845187882, 100768.0, 109013.45493272562, 109915.45042274738, 110512.4474377631, 111623.88376116134, 11370.602028928948, 11444.027257683023, 11489.54041838317, 11502.884971150348, 11618.825717614243, 14240.786388204186, 14333.56999290002, 14336.569902902722, 14400.711985760227, 14439.494617688331, 7431.0, 8745.562721863882, 8866.985225023524, 8873.467591944374, 8966.372353935292, 9083.50040747752, 9086.045965173695, 9090.681826136053, 9199.172074513326, 9210.861837072449, 4468.486124095705, 4529.9547004530195, 4532.4334458192725, 4532.886677833015, 4588.541145885385, 1885.990570047141, 1922.3944457495843, 1923.6345094432293, 1926.5280006398523, 1971.4677037199876, 776.8407476467311, 997.2371136080903, 1026.4970164619283, 1051.779126383468, 1080.6649938519083, 20169.49576260576, 20749.273775417787, 20863.687044694343, 21041.368758937257, 21376.893115534323, 947.6827210177859, 1019.3170575714289, 1029.5006921643146, 1036.7719101797593, 1133.7335726104359, 10680.511039665646, 11207.797076096464, 11310.0, 11352.14858885587, 11394.202405831678, 538578.0, 624630.0, 631462.0, 638625.0, 639592.0, 102684.48657756664, 110786.89213107766, 117258.0, 119457.40271298587, 121800.39099804444, 1061.3466334164589, 1082.5615625671617, 1093.573506332531, 1094.4254266509986, 1100.6147848253047, 17987.730184047512, 18303.725444118616, 18436.262549498406, 18929.148188331834, 19402.320918767775, 43160.35259471048, 55992.0, 57441.42558574361, 57544.0, 57660.73565707018, 72577.63711181622, 74058.62970685112, 75651.62174189094, 75897.24102758901, 76060.85908711485, 7659.080910290801, 7809.375249980101, 8172.42793004484, 8370.66517339324, 8506.532140732319, 9658.582881690296, 11119.610813621484, 13635.931820340835, 13652.726945461234, 13844.515441959484, 11610.70973225676, 11647.068234541386, 11771.47028383742, 11792.351420671603, 12009.219400738924, 11306.773864522827, 11540.596079137189, 11573.594924177612, 11695.473703683525, 11847.703807404883, 11841.993430558154, 11930.463129159036, 11939.403029848647, 12034.759304813679, 12271.69320766988, 8301.0, 8364.121767214356, 8396.538190399586, 8412.957935210285, 8584.613692383733, 11867.287962722265, 11931.821022684493, 12070.27578345302, 12132.272063676208, 12143.939280303895], "unit": "score", "name": "jquery.html", "value": 9609.298707885057}, {"lowerIsBetter": false, "replicates": [1469.4709904434394, 1653.8924969876957, 1682.9663406731866, 2018.1322031526438, 2089.8014688604576, 8384.790380240489, 8953.328500362475, 9134.451932884032, 9144.817103657939, 9148.4968326742, 8834.293256539477, 9101.59042843072, 9104.817903641922, 9120.817583648322, 9131.543422828849, 2291.5531471363092, 2520.798336133105, 2534.6958364996226, 2573.0351118330673, 2590.1581985854577, 2290.347251033456, 2626.2121363590845, 2626.960595591068, 2630.5002049610607, 2632.0656167060706, 2533.873306334676, 2597.402597402589, 2603.9349016274555, 2607.6479675243886, 2607.921762347123, 2547.1594373856674, 2583.470388570348, 2609.1650671785037, 2616.751408616178, 2617.0316982716326, 2570.948581028369, 2600.7919366450637, 2608.543504886637, 2618.48939456806, 2622.10848311575, 9215.308851836138, 9280.025597312253, 9293.21007714345, 9304.627814887397, 9317.673881414186, 5630.971845140789, 5745.683987380692, 5810.070388737781, 5812.360640329561, 5821.214136091616, 3621.6559426854406, 3699.093722038118, 3752.7935963521995, 3772.3775577029755, 3783.886483405502, 1064.4518073192312, 1065.1478816946412, 1069.9732506687317, 1085.9674209773718, 1108.0027974822644, 12570.30863302518, 12573.0, 12583.811242831272, 12624.936875315565, 12648.55730049453, 5021.924671129936, 5022.874428139326, 5095.057414378278, 5142.357205349332, 5145.456363091058, 10570.471476426148, 10615.0, 10625.043746062736, 10643.36139831597, 10643.787124257471, 5766.740496677659, 5777.959967205859, 5781.4218578141845, 5793.899159159746, 5806.5645076618575, 4619.538046195354, 4745.406824146981, 4772.331873537708, 4788.1381351356395, 5002.049610573979, 3366.107981384935, 4130.855420060284, 4135.565765594569, 4165.4584903962295, 4195.958040419618, 1368.6852024034529, 1380.1167252958114, 1380.151207007703, 1390.5828251524501, 1392.78411846164, 1352.8038434426953, 1419.978700319496, 1426.1585664458019, 1429.907056041354, 1451.3831621560794, 7376.594287314249, 8346.0, 8485.830283394298, 8562.0, 8576.571171441403, 523.1685385465225, 524.8477941396952, 537.5806870640906, 537.5914305128052, 538.6418032008748, 6359.6184228945485, 6371.808845734636, 6376.075469056961, 6394.936050639527, 6396.5522413431545, 10894.836577451344, 11311.095112390989, 11328.886711132947, 11414.543418263342, 11441.942790285995, 10860.891391085988, 11118.054965327876, 11138.108951283877, 11145.72135696614, 11271.211015228855, 8619.267362274151, 8688.087750786077, 8711.259542938795, 8732.91267087321, 8780.165884240987, 7077.893831592633, 8204.425690201628, 8255.050669173123, 8258.917410825814, 8371.372147089114, 9397.953010234905, 9746.31775775689, 9776.120149186607, 9965.302428830202, 10544.0, 10004.299699021, 10077.596896124072, 10227.897721022695, 10409.47952602382, 10426.687199383727, 7553.395728341609, 7702.961485192538, 7781.416393770377, 7789.0, 7830.765077047698, 8640.956795215983, 8644.351673624378, 8699.652013919625, 8705.608247629, 8708.085651006806, 7605.771826845204, 7736.883946740691, 7741.5742134183165, 7826.413019023482, 7827.6868925244635, 7399.445041622008, 7561.621918903923, 7704.8459030820195, 7757.301842834172, 7850.293473587176, 7970.760877173462, 8003.679852806055, 8086.353091752763, 8089.555074470725, 8094.433389662667, 7241.855162896818, 7330.120385553554, 7334.0, 7336.082989626297, 7340.376068034277, 7361.30067643573, 7372.016366791074, 7467.066616672916, 7468.663910124166, 7473.40212782987], "unit": "score", "name": "mootools.html", "value": 5059.979611433002}, {"lowerIsBetter": false, "replicates": [335.3109360264656, 339.52636072678615, 345.86856994342156, 351.10818520956786, 352.8659109538375, 6727.192736871576, 6787.592744435331, 6831.795046148623, 6853.725850965961, 6877.931220687791, 6772.0, 6772.661366931658, 6784.050232967388, 6793.796186114412, 6799.4560435165195, 4763.499832517579, 4891.315215869781, 4941.184704523749, 5018.222175562775, 5048.848534543969, 4942.827001054964, 5017.774200161001, 5017.799288028493, 5034.0, 5041.1178043842365, 4962.851114466554, 5036.924446133311, 5040.798368065273, 5049.368828896388, 5055.46917573655, 4778.235482322829, 4807.014562014796, 4955.331030310898, 4984.0, 5002.799888004475, 4287.335463003239, 4291.871243862689, 4293.441852559178, 4299.312110062407, 4307.78461076948, 5177.689338639693, 6410.134631824691, 6447.161868957052, 6462.547621666486, 6497.18785151856, 4759.809607615691, 5927.792527261525, 5933.110033494967, 5940.198073260098, 5942.494887934531, 6899.827504312382, 7174.923761435774, 7179.964100179518, 7199.028131202325, 7204.675789589481, 4799.1601469743, 4821.252705830567, 4827.541383568555, 4833.0, 4833.347498087748, 7487.3261406473675, 7658.61706914652, 7665.118511371152, 7672.194419585919, 7697.30724234816, 4906.681065730716, 4921.655484116149, 4937.8271760488215, 4939.90120197594, 4945.085159245551, 5000.724960127155, 5006.549410553068, 5012.298278241049, 5020.221865610836, 5026.824061157842, 5651.378348381675, 5876.6180198286975, 5884.735186916599, 5889.057750759858, 5891.970540147272, 7195.13658361, 7214.531055481482, 7269.20038795732, 7274.672639731225, 7305.963470182722, 1723.293449685648, 1746.318935615111, 1752.0626464841248, 1752.4742577226782, 1755.3856150347276, 1064.7710742190466, 1100.5212732461405, 1121.4168632311153, 1136.1819489967374, 1161.4773351991469, 6480.708368123445, 6557.573757705733, 6565.540412171103, 6723.462123030244, 6738.35985581369, 6055.455009049207, 7510.887336689954, 7540.736074237376, 7543.622818859035, 7557.773266802004, 6679.532432729759, 6690.83272918181, 6691.7323307068145, 6700.128983232149, 6706.295838936939, 3828.157805282833, 3930.1550166713723, 3981.0047488127966, 3981.1639555693628, 3982.761034337949, 6730.32696730323, 7138.214796372395, 7881.133075361706, 7942.523448593103, 7970.0, 6289.276733175652, 7792.0, 7803.80490487731, 7806.360664260455, 7806.843863122705, 6346.333634968354, 7904.525728456196, 7904.644291006918, 7916.84166316682, 7925.128235894163, 5345.823918737871, 7008.4042856355745, 7278.235785242473, 7352.338289553966, 7542.585157816154, 7401.59291238987, 7419.256474728327, 7436.479446438698, 7460.0674915635545, 7480.0649918760155, 7952.443328966917, 7964.283214510722, 7966.04407471084, 7974.8006299840645, 7981.760547183594, 4690.8123675053985, 4747.881302967454, 4748.738819364969, 4755.714657120584, 4765.451973023008, 6060.393960603904, 7199.1361036675935, 7550.2449755026255, 7552.546847189186, 7558.13081495635, 7229.710811567689, 7240.96379518099, 7242.203357630762, 7261.6006119663925, 7319.817004574928, 7271.600061996641, 7279.308465695751, 7303.817404564928, 7305.525140865827, 7308.415326773739, 5778.248827652378, 7107.502474826712, 7108.502404831612, 7113.928860711327, 7123.857522849617, 6921.653917304014, 7196.676149573177, 7259.854802904018, 7295.088113985752, 7302.9269707304375, 6730.125083739083, 6886.276940921131, 6934.133233345832, 6990.09128813251, 7088.326608972139], "unit": "score", "name": "prototype.html", "value": 5286.844975124032}, {"lowerIsBetter": false, "replicates": [705.7953193573863, 755.9379072403275, 777.3004296133482, 787.7046107709609, 790.818111834278, 14531.782023269647, 14646.267686615653, 14682.0, 14717.779233311509, 14729.705405891877, 14152.858471415282, 14246.287685615704, 14259.0, 14264.358103885324, 14273.71452570948, 771.3212373111668, 795.964181611826, 796.3947399976031, 796.8246985663174, 796.8685166947447, 899.6041741633693, 945.3902233059672, 945.8108378324329, 946.9005754395793, 948.8624149498319, 1028.681108856257, 1031.3863251365426, 1031.767852233249, 1032.349619739563, 1032.4734385463394, 1010.3281317923572, 1026.14829691356, 1027.6557353286657, 1028.5834237133972, 1029.6036026129918, 604.4710877981765, 736.5396627108057, 763.8739607964679, 767.777344570074, 769.7036640893241, 15341.309641066067, 15444.92277538605, 15453.5363939082, 15456.922715386463, 15458.922705386514, 12174.0, 12379.62861114168, 12866.67833304175, 13046.608601741962, 13519.0, 10501.002404771534, 10657.413842238751, 10662.147028237723, 10764.515596798085, 11155.665330040112, 2986.9850650746607, 3489.284696637183, 3519.894403167909, 3552.307300076486, 3633.92799124259, 78310.0, 78875.0, 79037.0, 82518.58740706318, 83404.74892876612, 9681.66114186, 9684.273679474067, 9717.757056073513, 9836.163926066221, 9874.012598740068, 21740.45648858791, 21792.89103554504, 22076.896155192175, 22463.887680561493, 22531.211407600655, 7544.47188696786, 7576.393888488908, 7782.105057918412, 7884.448088633855, 7976.401769867167, 3867.57456679765, 4104.548499665035, 4116.958830411718, 4156.501219853637, 4279.764612946318, 3726.869559565402, 3774.207416442523, 3782.3759079751535, 3783.3379158647126, 3786.1670432504807, 607.547377203977, 661.8113837556274, 678.837079101022, 690.0994202565599, 696.2376198063113, 604.9546284028715, 605.2434456928839, 610.7343305662051, 627.4259052966485, 643.1542521584101, 7973.401994850409, 8102.311303539147, 8350.916490835136, 8474.237318641352, 8662.956685216534, 579.8869220502046, 594.4352864778477, 595.5116804220584, 596.8269201931478, 627.7583130494793, 3931.8623848165175, 3953.011747063234, 3953.6244056814558, 4001.1797581495725, 4052.250333688277, 84543.15456845476, 88235.11764882269, 88593.55703221442, 89615.10384896198, 89842.0, 77360.2263977353, 77760.61119694365, 78178.0, 78840.0, 78864.6056769724, 68882.0, 70680.64659676772, 71497.28502715011, 71794.64102679453, 72148.2785172152, 6596.373344532261, 8384.706535271236, 8564.229219370287, 8618.655253789779, 8911.465312081296, 48230.75884620554, 49189.32701393362, 49553.50446495489, 49616.526843122076, 50010.749946250035, 69896.65051674709, 70092.649536752, 70529.6473517629, 72281.55436891338, 73052.6347368281, 11228.157888158257, 11459.255148415326, 11468.713282168013, 11633.243839150427, 11641.825372619587, 11991.700207494883, 11994.160408771302, 12078.698032549257, 12093.0, 12795.552155674506, 11258.788164857147, 11543.653690389301, 11649.767004660029, 11802.881971180179, 11876.049916006526, 11267.830982535104, 11922.165448418875, 11948.641540753792, 11955.402229888645, 12092.697682558006, 11468.4265786712, 11727.882721172678, 11859.05127589808, 11865.0, 11883.821742673694, 9052.3210759192, 9685.612575496902, 9715.36850104741, 9739.366941149086, 9973.451460169761, 9230.446173229358, 9336.673216437392, 9820.754481137743, 9844.9015509844, 9974.700758977242], "unit": "score", "name": "yui.html", "value": 7047.800177235884}], "name": "dromaeo_css", "value": 7718.004384579187}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [114.0, 108.0, 112.0, 111.0, 113.0, 113.0, 117.0, 110.0, 111.0, 111.0], "unit": "score", "name": "ai-astar", "value": 112.0}, {"lowerIsBetter": true, "replicates": [152.0, 120.0, 153.0, 184.0, 144.0, 156.0, 116.0, 149.0, 151.0, 117.0], "unit": "score", "name": "audio-beat-detection", "value": 144.2}, {"lowerIsBetter": true, "replicates": [183.0, 186.0, 184.0, 187.0, 186.0, 184.0, 178.0, 183.0, 185.0, 184.0], "unit": "score", "name": "audio-dft", "value": 184.0}, {"lowerIsBetter": true, "replicates": [73.0, 71.0, 76.0, 112.0, 56.0, 60.0, 63.0, 79.0, 151.0, 82.0], "unit": "score", "name": "audio-fft", "value": 82.3}, {"lowerIsBetter": true, "replicates": [91.0, 96.0, 130.0, 77.0, 79.0, 83.0, 116.0, 77.0, 78.0, 114.0], "unit": "score", "name": "audio-oscillator", "value": 94.1}, {"lowerIsBetter": true, "replicates": [90.0, 130.0, 86.0, 83.0, 86.0, 83.0, 86.0, 86.0, 96.0, 84.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 91.0}, {"lowerIsBetter": true, "replicates": [93.0, 92.0, 108.0, 149.0, 91.0, 92.0, 92.0, 93.0, 91.0, 92.0], "unit": "score", "name": "imaging-darkroom", "value": 99.3}, {"lowerIsBetter": true, "replicates": [87.0, 94.0, 87.0, 86.0, 84.0, 86.0, 86.0, 95.0, 132.0, 85.0], "unit": "score", "name": "imaging-desaturate", "value": 92.2}, {"lowerIsBetter": true, "replicates": [52.0, 58.0, 57.0, 57.0, 58.0, 59.0, 57.0, 68.0, 55.0, 69.0], "unit": "score", "name": "json-parse-financial", "value": 59.0}, {"lowerIsBetter": true, "replicates": [65.0, 70.0, 64.0, 73.0, 67.0, 66.0, 66.0, 67.0, 67.0, 68.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.3}, {"lowerIsBetter": true, "replicates": [82.0, 81.0, 81.0, 82.0, 80.0, 79.0, 79.0, 79.0, 80.0, 84.0], "unit": "score", "name": "stanford-crypto-aes", "value": 80.7}, {"lowerIsBetter": true, "replicates": [130.0, 121.0, 119.0, 118.0, 116.0, 130.0, 120.0, 128.0, 129.0, 114.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 122.5}, {"lowerIsBetter": true, "replicates": [135.0, 124.0, 133.0, 158.0, 133.0, 125.0, 131.0, 132.0, 135.0, 132.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 133.8}, {"lowerIsBetter": true, "replicates": [67.0, 65.0, 68.0, 92.0, 95.0, 92.0, 65.0, 67.0, 66.0, 66.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 74.3}], "name": "kraken", "value": 1436.7}]} 16:08:13 INFO - Return code: 0 16:08:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:08:13 INFO - # TBPL SUCCESS # 16:08:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:08:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:08:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:08:13 INFO - 2016-04-22 16:08:13.912 screenresolution[1678:9592] starting screenresolution argv=screenresolution get 16:08:13 INFO - 2016-04-22 16:08:13.914 screenresolution[1678:9592] Display 0: 1600x1200x32@60 16:08:13 INFO - 2016-04-22 16:08:13.920 screenresolution[1679:9595] starting screenresolution argv=screenresolution list 16:08:13 INFO - Available Modes on Display 0 16:08:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:08:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:08:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:08:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:08:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:08:14 INFO - Intel Iris: 16:08:14 INFO - Chipset Model: Intel Iris 16:08:14 INFO - Type: GPU 16:08:14 INFO - Bus: Built-In 16:08:14 INFO - VRAM (Dynamic, Max): 1536 MB 16:08:14 INFO - Vendor: Intel (0x8086) 16:08:14 INFO - Device ID: 0x0a2e 16:08:14 INFO - Revision ID: 0x0009 16:08:14 INFO - Displays: 16:08:14 INFO - Display: 16:08:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:08:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:08:14 INFO - Display Serial Number: 1600x1200 60 16:08:14 INFO - Main Display: Yes 16:08:14 INFO - Mirror: Off 16:08:14 INFO - Online: Yes 16:08:14 INFO - Rotation: Supported 16:08:14 INFO - Return code: 0 16:08:14 INFO - Running post-action listener: _resource_record_post_action 16:08:14 INFO - Running post-run listener: _resource_record_post_run 16:08:15 INFO - Total resource usage - Wall time: 1203s; CPU: 52.0%; Read bytes: 37897728; Write bytes: 664070144; Read time: 396; Write time: 2043 16:08:15 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 354054656; Write bytes: 354829824; Read time: 27543; Write time: 686 16:08:15 INFO - run-tests - Wall time: 1171s; CPU: 52.0%; Read bytes: 36939264; Write bytes: 298897920; Read time: 381; Write time: 1327 16:08:15 INFO - Running post-run listener: _upload_blobber_files 16:08:15 INFO - Blob upload gear active. 16:08:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:08:15 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:15 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:15 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:15 INFO - (blobuploader) - INFO - Open directory for files ... 16:08:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:08:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:08:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:08:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:08:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:08:16 INFO - (blobuploader) - INFO - Done attempting. 16:08:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:08:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:08:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:08:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:08:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:08:17 INFO - (blobuploader) - INFO - Done attempting. 16:08:17 INFO - (blobuploader) - INFO - Iteration through files over. 16:08:17 INFO - Return code: 0 16:08:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:08:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:08:17 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5f04e1c96360efa187425e81eae0446874f1bb2cc1718eb88e7d0d7f662801755854bd4256087b0d4900f6f70be1383b3d180c40f3e5ce6961fb5b74bef8644e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/352c4ca0edac89042a5624f4a4104df8a54e0b34a3ae99f2566c8b83b5e4b5e61a65779913d54f26e3851327d94b4fc932c1bf4fc1ec2b265d454471f34c0e4f"} 16:08:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:08:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:08:17 INFO - Contents: 16:08:17 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5f04e1c96360efa187425e81eae0446874f1bb2cc1718eb88e7d0d7f662801755854bd4256087b0d4900f6f70be1383b3d180c40f3e5ce6961fb5b74bef8644e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/352c4ca0edac89042a5624f4a4104df8a54e0b34a3ae99f2566c8b83b5e4b5e61a65779913d54f26e3851327d94b4fc932c1bf4fc1ec2b265d454471f34c0e4f"} 16:08:17 INFO - Running post-run listener: copy_logs_to_upload_dir 16:08:17 INFO - Copying logs to upload dir... 16:08:17 INFO - mkdir: /builds/slave/test/build/upload/logs 16:08:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.840129 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2016-04-22 16:08:17.421569) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-22 16:08:17.425215) ========= 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.m5kUaxvtR0/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.b71wKnQjd3/Listeners TMPDIR=/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5f04e1c96360efa187425e81eae0446874f1bb2cc1718eb88e7d0d7f662801755854bd4256087b0d4900f6f70be1383b3d180c40f3e5ce6961fb5b74bef8644e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/352c4ca0edac89042a5624f4a4104df8a54e0b34a3ae99f2566c8b83b5e4b5e61a65779913d54f26e3851327d94b4fc932c1bf4fc1ec2b265d454471f34c0e4f"} 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.011105 build_url: 'https://queue.taskcluster.net/v1/task/WMriI2IhRtGQ_iEYFHgolw/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5f04e1c96360efa187425e81eae0446874f1bb2cc1718eb88e7d0d7f662801755854bd4256087b0d4900f6f70be1383b3d180c40f3e5ce6961fb5b74bef8644e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/352c4ca0edac89042a5624f4a4104df8a54e0b34a3ae99f2566c8b83b5e4b5e61a65779913d54f26e3851327d94b4fc932c1bf4fc1ec2b265d454471f34c0e4f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-22 16:08:17.452201) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 16:08:17.452512) ========= 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.m5kUaxvtR0/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.b71wKnQjd3/Listeners TMPDIR=/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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.005036 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-22 16:08:17.500402) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-22 16:08:17.500693) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-22 16:08:17.501031) ========= ========= Total master_lag: 0.16 =========