builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0120 starttime: 1461701371.17 results: success (0) buildid: 20160426062558 builduid: 18458ff6a626441ea2f2e0a4fd3bd5e1 revision: cbeae0c4410bef3864b3d3f4ad939321c61cb15b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-26 13:09:31.168451) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-26 13:09:31.168987) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-26 13:09:31.169302) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-26 13:09:31.188170) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 13:09:31.188621) ========= 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.R4XVaThNfj/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.PdiKnQxcSs/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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-26 13:09:31-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 152M=0s 2016-04-26 13:09:31 (152 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.381133 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 13:09:31.586757) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 13:09:31.587099) ========= 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.R4XVaThNfj/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.PdiKnQxcSs/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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.019483 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 13:09:31.636089) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-26 13:09:31.636445) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev cbeae0c4410bef3864b3d3f4ad939321c61cb15b --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 cbeae0c4410bef3864b3d3f4ad939321c61cb15b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.R4XVaThNfj/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.PdiKnQxcSs/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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-26 13:09:31,711 truncating revision to first 12 chars 2016-04-26 13:09:31,711 Setting DEBUG logging. 2016-04-26 13:09:31,711 attempt 1/10 2016-04-26 13:09:31,711 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/cbeae0c4410b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-26 13:09:32,712 unpacking tar archive at: mozilla-aurora-cbeae0c4410b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.252187 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-26 13:09:32.921600) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-26 13:09:32.921919) ========= script_repo_revision: cbeae0c4410bef3864b3d3f4ad939321c61cb15b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-26 13:09:32.922311) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-26 13:09:32.922575) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-26 13:09:33.344358) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 30 secs) (at 2016-04-26 13:09:33.344763) ========= /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.R4XVaThNfj/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.PdiKnQxcSs/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 13:09:33 INFO - MultiFileLogger online at 20160426 13:09:33 in /builds/slave/test 13:09:33 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 13:09:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:09:33 INFO - {'append_to_log': False, 13:09:33 INFO - 'base_work_dir': '/builds/slave/test', 13:09:33 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 13:09:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:09:33 INFO - 'branch': 'Mozilla-Aurora', 13:09:33 INFO - 'buildbot_json_path': 'buildprops.json', 13:09:33 INFO - 'config_files': ('talos/mac_config.py',), 13:09:33 INFO - 'default_actions': ('clobber', 13:09:33 INFO - 'read-buildbot-config', 13:09:33 INFO - 'download-and-extract', 13:09:33 INFO - 'populate-webroot', 13:09:33 INFO - 'create-virtualenv', 13:09:33 INFO - 'install', 13:09:33 INFO - 'run-tests'), 13:09:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:09:33 INFO - 'download_minidump_stackwalk': True, 13:09:33 INFO - 'download_symbols': 'ondemand', 13:09:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:09:33 INFO - 'tooltool.py': '/tools/tooltool.py', 13:09:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:09:33 INFO - '/tools/misc-python/virtualenv.py')}, 13:09:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:09:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:09:33 INFO - 'installer_path': 'installer.exe', 13:09:33 INFO - 'log_level': 'info', 13:09:33 INFO - 'log_name': 'talos', 13:09:33 INFO - 'log_to_console': True, 13:09:33 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:09:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:09:33 INFO - 'opt_config_files': (), 13:09:33 INFO - 'pip_index': False, 13:09:33 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:09:33 INFO - 'cmd': ('bash', 13:09:33 INFO - '-c', 13:09:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:09:33 INFO - 'enabled': True, 13:09:33 INFO - 'halt_on_failure': False, 13:09:33 INFO - 'name': 'check_screen_resolution'},), 13:09:33 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:09:33 INFO - 'cmd': ('bash', 13:09:33 INFO - '-c', 13:09:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:09:33 INFO - 'enabled': True, 13:09:33 INFO - 'halt_on_failure': False, 13:09:33 INFO - 'name': 'check_screen_resolution'},), 13:09:33 INFO - 'run_cmd_checks_enabled': True, 13:09:33 INFO - 'sps_profile': False, 13:09:33 INFO - 'sps_profile_interval': 0, 13:09:33 INFO - 'suite': 'dromaeojs', 13:09:33 INFO - 'system_bits': '32', 13:09:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:09:33 INFO - 'title': 't-yosemite-r7-0120', 13:09:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:09:33 INFO - 'use_talos_json': True, 13:09:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:09:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:09:33 INFO - 'work_dir': 'build'} 13:09:33 INFO - ##### 13:09:33 INFO - ##### Running clobber step. 13:09:33 INFO - ##### 13:09:33 INFO - Running pre-action listener: _resource_record_pre_action 13:09:33 INFO - Running main action method: clobber 13:09:33 INFO - rmtree: /builds/slave/test/build 13:09:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:09:35 INFO - Running post-action listener: _resource_record_post_action 13:09:35 INFO - ##### 13:09:35 INFO - ##### Running read-buildbot-config step. 13:09:35 INFO - ##### 13:09:35 INFO - Running pre-action listener: _resource_record_pre_action 13:09:35 INFO - Running main action method: read_buildbot_config 13:09:35 INFO - Using buildbot properties: 13:09:35 INFO - { 13:09:35 INFO - "project": "", 13:09:35 INFO - "product": "firefox", 13:09:35 INFO - "script_repo_revision": "production", 13:09:35 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 13:09:35 INFO - "repository": "", 13:09:35 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 13:09:35 INFO - "buildid": "20160426062558", 13:09:35 INFO - "pgo_build": "False", 13:09:35 INFO - "basedir": "/builds/slave/test", 13:09:35 INFO - "buildnumber": 206, 13:09:35 INFO - "slavename": "t-yosemite-r7-0120", 13:09:35 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 13:09:35 INFO - "platform": "yosemite_r7", 13:09:35 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 13:09:35 INFO - "branch": "mozilla-aurora", 13:09:35 INFO - "repo_path": "releases/mozilla-aurora", 13:09:35 INFO - "revision": "cbeae0c4410bef3864b3d3f4ad939321c61cb15b", 13:09:35 INFO - "stage_platform": "macosx64", 13:09:35 INFO - "builduid": "18458ff6a626441ea2f2e0a4fd3bd5e1", 13:09:35 INFO - "slavebuilddir": "test" 13:09:35 INFO - } 13:09:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 13:09:35 INFO - Running post-action listener: _resource_record_post_action 13:09:35 INFO - ##### 13:09:35 INFO - ##### Running download-and-extract step. 13:09:35 INFO - ##### 13:09:35 INFO - Running pre-action listener: _resource_record_pre_action 13:09:35 INFO - Running main action method: download_and_extract 13:09:35 INFO - mkdir: /builds/slave/test/build/tests 13:09:35 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')]} 13:09:35 INFO - https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 13:09:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 13:09:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 13:09:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 13:09:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 13:09:36 INFO - Downloaded 1199 bytes. 13:09:36 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 13:09:36 INFO - Using the following test package requirements: 13:09:36 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 13:09:36 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:09:36 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 13:09:36 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:09:36 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 13:09:36 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:09:36 INFO - u'jsshell-mac.zip'], 13:09:36 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:09:36 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 13:09:36 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 13:09:36 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:09:36 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 13:09:36 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:09:36 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 13:09:36 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:09:36 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 13:09:36 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:09:36 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 13:09:36 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 13:09:36 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')]} 13:09:36 INFO - https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:09:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:09:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:09:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:09:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 13:11:20 INFO - Downloaded 18110203 bytes. 13:11:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:11:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 13:11:23 INFO - Return code: 0 13:11:23 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 13:11:23 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')]} 13:11:23 INFO - https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:11:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:11:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:11:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:11:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 13:11:24 INFO - Downloaded 18110203 bytes. 13:11:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:11:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 13:11:28 INFO - Return code: 0 13:11:28 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')]} 13:11:28 INFO - https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 13:11:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 13:11:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 13:11:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 13:11:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 13:12:11 INFO - Downloaded 10840145 bytes. 13:12:11 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:12:11 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 13:12:11 INFO - Return code: 0 13:12:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:12:11 INFO - https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 13:12:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 13:12:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 13:12:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 13:12:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 13:12:14 INFO - Downloaded 114070774 bytes. 13:12:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 13:12:14 INFO - mkdir: /builds/slave/test/properties 13:12:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:12:14 INFO - Writing to file /builds/slave/test/properties/build_url 13:12:14 INFO - Contents: 13:12:14 INFO - build_url:https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 13:12:17 INFO - Running post-action listener: _resource_record_post_action 13:12:17 INFO - Running post-action listener: set_extra_try_arguments 13:12:17 INFO - ##### 13:12:17 INFO - ##### Running populate-webroot step. 13:12:17 INFO - ##### 13:12:17 INFO - Running pre-action listener: _resource_record_pre_action 13:12:17 INFO - Running main action method: populate_webroot 13:12:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:12:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:12:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:12:17 INFO - '--tppagecycles', 13:12:17 INFO - '7'], 13:12:17 INFO - 'tests': ['tsvgm']}}, 13:12:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:12:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:12:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:12:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:12:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:12:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:12:17 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:12:17 INFO - 'talos_options': ['--disable-e10s'], 13:12:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:12:17 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:12:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:12:17 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:12:17 INFO - 'talos_options': ['--disable-e10s'], 13:12:17 INFO - 'tests': ['damp', 'tps']}, 13:12:17 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:12:17 INFO - 'tests': ['damp', 'tps']}, 13:12:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:12:17 INFO - 'tests': ['dromaeo_dom']}, 13:12:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:12:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:12:17 INFO - 'tests': ['a11yr', 13:12:17 INFO - 'ts_paint', 13:12:17 INFO - 'tpaint', 13:12:17 INFO - 'sessionrestore', 13:12:17 INFO - 'sessionrestore_no_auto_restore', 13:12:17 INFO - 'tabpaint']}, 13:12:17 INFO - 'other-e10s': {'tests': ['a11yr', 13:12:17 INFO - 'ts_paint', 13:12:17 INFO - 'tpaint', 13:12:17 INFO - 'sessionrestore', 13:12:17 INFO - 'sessionrestore_no_auto_restore', 13:12:17 INFO - 'tabpaint']}, 13:12:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:12:17 INFO - 'ts_paint', 13:12:17 INFO - 'tpaint', 13:12:17 INFO - 'sessionrestore', 13:12:17 INFO - 'sessionrestore_no_auto_restore', 13:12:17 INFO - 'tabpaint']}, 13:12:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:12:17 INFO - 'ts_paint', 13:12:17 INFO - 'tpaint', 13:12:17 INFO - 'sessionrestore', 13:12:17 INFO - 'sessionrestore_no_auto_restore', 13:12:17 INFO - 'tabpaint']}, 13:12:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:12:17 INFO - 'tests': ['a11yr', 13:12:17 INFO - 'ts_paint', 13:12:17 INFO - 'tpaint', 13:12:17 INFO - 'sessionrestore', 13:12:17 INFO - 'sessionrestore_no_auto_restore', 13:12:17 INFO - 'tabpaint']}, 13:12:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:12:17 INFO - 'tests': ['a11yr', 13:12:17 INFO - 'ts_paint', 13:12:17 INFO - 'tpaint', 13:12:17 INFO - 'sessionrestore', 13:12:17 INFO - 'sessionrestore_no_auto_restore', 13:12:17 INFO - 'tabpaint']}, 13:12:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:12:17 INFO - 'tests': ['tsvgx', 13:12:17 INFO - 'tsvgr_opacity', 13:12:17 INFO - 'tart', 13:12:17 INFO - 'tscrollx', 13:12:17 INFO - 'cart']}, 13:12:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:12:17 INFO - 'tsvgr_opacity', 13:12:17 INFO - 'tart', 13:12:17 INFO - 'tscrollx', 13:12:17 INFO - 'cart']}, 13:12:17 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:12:17 INFO - 'talos_options': ['--disable-e10s'], 13:12:17 INFO - 'tests': ['tp5o']}, 13:12:17 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:12:17 INFO - 'tests': ['tp5o']}, 13:12:17 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:12:17 INFO - 'talos_options': ['--disable-e10s', 13:12:17 INFO - '--xperf_path', 13:12:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:12:17 INFO - 'tests': ['tp5n']}, 13:12:17 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:12:17 INFO - 'talos_options': ['--xperf_path', 13:12:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:12:17 INFO - 'tests': ['tp5n']}}, 13:12:17 INFO - 'talos.zip': {'path': '', 13:12:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:12:17 INFO - Running post-action listener: _resource_record_post_action 13:12:17 INFO - ##### 13:12:17 INFO - ##### Running create-virtualenv step. 13:12:17 INFO - ##### 13:12:17 INFO - Running pre-action listener: _resource_record_pre_action 13:12:17 INFO - Running main action method: create_virtualenv 13:12:17 INFO - Creating virtualenv /builds/slave/test/build/venv 13:12:17 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 13:12:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:12:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:12:17 INFO - Using real prefix '/tools/python27' 13:12:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:12:17 INFO - Installing distribute.............................................................................................................................................................................................done. 13:12:20 INFO - Installing pip.................done. 13:12:20 INFO - Return code: 0 13:12:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:12:20 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')]} 13:12:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:12:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:20 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')]} 13:12:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:12:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:20 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 0x1086bca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086cc420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe379cdccb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10855be00>, '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 0x1083c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108556b20>, '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.R4XVaThNfj/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.PdiKnQxcSs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 13:12:20 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 13:12:20 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 13:12:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R4XVaThNfj/Render', 13:12:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:12:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:12:20 INFO - 'HOME': '/Users/cltbld', 13:12:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:12:20 INFO - 'LOGNAME': 'cltbld', 13:12:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:12:20 INFO - 'MOZ_NO_REMOTE': '1', 13:12:20 INFO - 'NO_EM_RESTART': '1', 13:12:20 INFO - 'PAGER': '/bin/cat', 13:12:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:12:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:12:20 INFO - 'PWD': '/builds/slave/test', 13:12:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:12:20 INFO - 'SHELL': '/bin/bash', 13:12:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PdiKnQxcSs/Listeners', 13:12:20 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 13:12:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:12:20 INFO - 'USER': 'cltbld', 13:12:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:12:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:12:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:12:20 INFO - 'XPC_FLAGS': '0x0', 13:12:20 INFO - 'XPC_SERVICE_NAME': '0', 13:12:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:12:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:12:21 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 13:12:21 INFO - Cleaning up... 13:12:21 INFO - Return code: 0 13:12:21 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 13:12:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:12:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:12:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:12:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:12:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1086bca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086cc420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe379cdccb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10855be00>, '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 0x1083c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108556b20>, '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.R4XVaThNfj/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.PdiKnQxcSs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 13:12:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 13:12:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 13:12:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R4XVaThNfj/Render', 13:12:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:12:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:12:21 INFO - 'HOME': '/Users/cltbld', 13:12:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:12:21 INFO - 'LOGNAME': 'cltbld', 13:12:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:12:21 INFO - 'MOZ_NO_REMOTE': '1', 13:12:21 INFO - 'NO_EM_RESTART': '1', 13:12:21 INFO - 'PAGER': '/bin/cat', 13:12:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:12:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:12:21 INFO - 'PWD': '/builds/slave/test', 13:12:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:12:21 INFO - 'SHELL': '/bin/bash', 13:12:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PdiKnQxcSs/Listeners', 13:12:21 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 13:12:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:12:21 INFO - 'USER': 'cltbld', 13:12:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:12:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:12:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:12:21 INFO - 'XPC_FLAGS': '0x0', 13:12:21 INFO - 'XPC_SERVICE_NAME': '0', 13:12:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:12:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:12:21 INFO - Downloading/unpacking psutil>=0.7.1 13:12:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:12:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:12:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:12:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:12:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:12:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:12:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:12:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:12:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:12:24 INFO - Installing collected packages: psutil 13:12:24 INFO - Running setup.py install for psutil 13:12:24 INFO - building 'psutil._psutil_osx' extension 13:12:24 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 13:12:25 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 13:12:25 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 13:12:25 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 13:12:25 INFO - building 'psutil._psutil_posix' extension 13:12:25 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 13:12:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:12:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:12:25 INFO - ^ 13:12:25 INFO - 1 warning generated. 13:12:25 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 13:12:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:12:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:12:25 INFO - Successfully installed psutil 13:12:25 INFO - Cleaning up... 13:12:25 INFO - Return code: 0 13:12:25 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 13:12:25 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')]} 13:12:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:12:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:26 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')]} 13:12:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:12:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:26 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 0x1086bca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086cc420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe379cdccb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10855be00>, '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 0x1083c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108556b20>, '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.R4XVaThNfj/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.PdiKnQxcSs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 13:12:26 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 13:12:26 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 13:12:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R4XVaThNfj/Render', 13:12:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:12:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:12:26 INFO - 'HOME': '/Users/cltbld', 13:12:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:12:26 INFO - 'LOGNAME': 'cltbld', 13:12:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:12:26 INFO - 'MOZ_NO_REMOTE': '1', 13:12:26 INFO - 'NO_EM_RESTART': '1', 13:12:26 INFO - 'PAGER': '/bin/cat', 13:12:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:12:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:12:26 INFO - 'PWD': '/builds/slave/test', 13:12:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:12:26 INFO - 'SHELL': '/bin/bash', 13:12:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PdiKnQxcSs/Listeners', 13:12:26 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 13:12:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:12:26 INFO - 'USER': 'cltbld', 13:12:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:12:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:12:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:12:26 INFO - 'XPC_FLAGS': '0x0', 13:12:26 INFO - 'XPC_SERVICE_NAME': '0', 13:12:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:12:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:12:26 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 13:12:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:12:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:12:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:12:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:12:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:12:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:12:28 INFO - Downloading mozsystemmonitor-0.0.tar.gz 13:12:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:12:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 13:12:28 INFO - Installing collected packages: mozsystemmonitor 13:12:28 INFO - Running setup.py install for mozsystemmonitor 13:12:28 INFO - Successfully installed mozsystemmonitor 13:12:28 INFO - Cleaning up... 13:12:28 INFO - Return code: 0 13:12:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:12:28 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')]} 13:12:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:12:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:28 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')]} 13:12:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:12:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:28 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 0x1086bca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086cc420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe379cdccb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10855be00>, '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 0x1083c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108556b20>, '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.R4XVaThNfj/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.PdiKnQxcSs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 13:12:28 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 13:12:28 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 13:12:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R4XVaThNfj/Render', 13:12:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:12:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:12:28 INFO - 'HOME': '/Users/cltbld', 13:12:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:12:28 INFO - 'LOGNAME': 'cltbld', 13:12:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:12:28 INFO - 'MOZ_NO_REMOTE': '1', 13:12:28 INFO - 'NO_EM_RESTART': '1', 13:12:28 INFO - 'PAGER': '/bin/cat', 13:12:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:12:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:12:28 INFO - 'PWD': '/builds/slave/test', 13:12:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:12:28 INFO - 'SHELL': '/bin/bash', 13:12:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PdiKnQxcSs/Listeners', 13:12:28 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 13:12:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:12:28 INFO - 'USER': 'cltbld', 13:12:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:12:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:12:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:12:28 INFO - 'XPC_FLAGS': '0x0', 13:12:28 INFO - 'XPC_SERVICE_NAME': '0', 13:12:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:12:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:12:29 INFO - Downloading/unpacking blobuploader==1.2.4 13:12:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:12:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:12:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:12:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:12:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:12:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:12:31 INFO - Downloading blobuploader-1.2.4.tar.gz 13:12:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:12:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:12:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:12:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:12:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:12:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:12:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:12:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:12:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:12:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:12:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:12:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:12:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:12:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:12:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:12:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:12:32 INFO - Downloading docopt-0.6.1.tar.gz 13:12:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:12:32 INFO - Installing collected packages: blobuploader, requests, docopt 13:12:32 INFO - Running setup.py install for blobuploader 13:12:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:12:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:12:32 INFO - Running setup.py install for requests 13:12:33 INFO - Running setup.py install for docopt 13:12:33 INFO - Successfully installed blobuploader requests docopt 13:12:33 INFO - Cleaning up... 13:12:33 INFO - Return code: 0 13:12:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:12:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:12:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:12:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:12:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:12:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:33 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 0x1086bca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086cc420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe379cdccb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10855be00>, '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 0x1083c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108556b20>, '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.R4XVaThNfj/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.PdiKnQxcSs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 13:12:33 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 13:12:33 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 13:12:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R4XVaThNfj/Render', 13:12:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:12:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:12:33 INFO - 'HOME': '/Users/cltbld', 13:12:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:12:33 INFO - 'LOGNAME': 'cltbld', 13:12:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:12:33 INFO - 'MOZ_NO_REMOTE': '1', 13:12:33 INFO - 'NO_EM_RESTART': '1', 13:12:33 INFO - 'PAGER': '/bin/cat', 13:12:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:12:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:12:33 INFO - 'PWD': '/builds/slave/test', 13:12:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:12:33 INFO - 'SHELL': '/bin/bash', 13:12:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PdiKnQxcSs/Listeners', 13:12:33 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 13:12:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:12:33 INFO - 'USER': 'cltbld', 13:12:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:12:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:12:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:12:33 INFO - 'XPC_FLAGS': '0x0', 13:12:33 INFO - 'XPC_SERVICE_NAME': '0', 13:12:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:12:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:12:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:12:33 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-9UE9fJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:12:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:12:34 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-kJYazo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:12:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:12:34 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-uuYyH4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:12:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:12:34 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-PuXbpp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:12:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:12:34 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-zpDweb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:12:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:12:34 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-FmDe_0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:12:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:12:34 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-qEZBMc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:12:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:12:34 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-afkCy1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:12:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:12:34 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-kpuhsb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:12:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:12:34 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-SNTCZ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:12:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:12:34 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-7Gei0q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:12:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:12:35 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-K0_BvY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:12:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:12:35 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-f_WXp9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:12:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:12:35 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-8jUxS4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:12:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:12:35 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-iavEns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:12:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:12:35 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-dhIrmF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:12:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:12:35 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-eT1qdD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:12:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:12:35 INFO - Running setup.py install for manifestparser 13:12:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:12:35 INFO - Running setup.py install for mozcrash 13:12:35 INFO - Running setup.py install for mozdebug 13:12:36 INFO - Running setup.py install for mozdevice 13:12:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:12:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:12:36 INFO - Running setup.py install for mozfile 13:12:36 INFO - Running setup.py install for mozhttpd 13:12:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:12:36 INFO - Running setup.py install for mozinfo 13:12:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:12:36 INFO - Running setup.py install for mozInstall 13:12:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:12:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:12:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:12:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:12:36 INFO - Running setup.py install for mozleak 13:12:37 INFO - Running setup.py install for mozlog 13:12:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:12:37 INFO - Running setup.py install for moznetwork 13:12:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:12:37 INFO - Running setup.py install for mozprocess 13:12:37 INFO - Running setup.py install for mozprofile 13:12:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:12:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:12:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:12:37 INFO - Running setup.py install for mozrunner 13:12:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:12:37 INFO - Running setup.py install for mozscreenshot 13:12:37 INFO - Running setup.py install for moztest 13:12:38 INFO - Running setup.py install for mozversion 13:12:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:12:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:12:38 INFO - Cleaning up... 13:12:38 INFO - Return code: 0 13:12:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:12:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:12:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:12:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:12:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:12:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:38 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 0x1086bca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086cc420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe379cdccb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10855be00>, '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 0x1083c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108556b20>, '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.R4XVaThNfj/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.PdiKnQxcSs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 13:12:38 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 13:12:38 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 13:12:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R4XVaThNfj/Render', 13:12:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:12:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:12:38 INFO - 'HOME': '/Users/cltbld', 13:12:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:12:38 INFO - 'LOGNAME': 'cltbld', 13:12:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:12:38 INFO - 'MOZ_NO_REMOTE': '1', 13:12:38 INFO - 'NO_EM_RESTART': '1', 13:12:38 INFO - 'PAGER': '/bin/cat', 13:12:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:12:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:12:38 INFO - 'PWD': '/builds/slave/test', 13:12:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:12:38 INFO - 'SHELL': '/bin/bash', 13:12:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PdiKnQxcSs/Listeners', 13:12:38 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 13:12:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:12:38 INFO - 'USER': 'cltbld', 13:12:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:12:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:12:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:12:38 INFO - 'XPC_FLAGS': '0x0', 13:12:38 INFO - 'XPC_SERVICE_NAME': '0', 13:12:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:12:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:12:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:12:38 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-eyfelI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:12:38 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)) 13:12:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:12:38 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-vu0F0N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:12:38 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)) 13:12:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:12:38 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-byecG7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:12:38 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)) 13:12:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:12:38 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-A2PqL5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:12:38 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)) 13:12:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:12:38 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-iicDls-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:12:39 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)) 13:12:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:12:39 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-Eg4fox-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:12:39 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)) 13:12:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:12:39 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-0srAPs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:12:39 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)) 13:12:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:12:39 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-Touwfr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:12:39 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)) 13:12:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:12:39 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-nJG4Li-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:12:39 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)) 13:12:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:12:39 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-60DpDe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:12:39 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)) 13:12:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:12:39 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-bEbGiH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:12:39 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)) 13:12:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:12:39 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-oxJpvO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:12:39 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)) 13:12:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:12:39 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-Ig_Evy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:12:39 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)) 13:12:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:12:39 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-wMoORl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:12:40 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)) 13:12:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:12:40 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-bAN1NK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:12:40 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)) 13:12:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:12:40 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-99My5t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:12:40 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)) 13:12:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:12:40 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-ZHliTO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:12:40 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)) 13:12:40 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)) 13:12:40 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)) 13:12:40 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)) 13:12:40 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)) 13:12:40 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)) 13:12:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:12:40 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 13:12:40 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 13:12:40 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 13:12:40 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 13:12:40 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 13:12:40 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 13:12:42 INFO - Downloading blessings-1.6.tar.gz 13:12:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:12:42 INFO - Installing collected packages: blessings 13:12:42 INFO - Running setup.py install for blessings 13:12:43 INFO - Successfully installed blessings 13:12:43 INFO - Cleaning up... 13:12:43 INFO - Return code: 0 13:12:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:12:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:12:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:12:43 INFO - Reading from file tmpfile_stdout 13:12:43 INFO - Current package versions: 13:12:43 INFO - blessings == 1.6 13:12:43 INFO - blobuploader == 1.2.4 13:12:43 INFO - docopt == 0.6.1 13:12:43 INFO - manifestparser == 1.1 13:12:43 INFO - mozInstall == 1.12 13:12:43 INFO - mozcrash == 0.17 13:12:43 INFO - mozdebug == 0.1 13:12:43 INFO - mozdevice == 0.48 13:12:43 INFO - mozfile == 1.2 13:12:43 INFO - mozhttpd == 0.7 13:12:43 INFO - mozinfo == 0.9 13:12:43 INFO - mozleak == 0.1 13:12:43 INFO - mozlog == 3.1 13:12:43 INFO - moznetwork == 0.27 13:12:43 INFO - mozprocess == 0.22 13:12:43 INFO - mozprofile == 0.28 13:12:43 INFO - mozrunner == 6.11 13:12:43 INFO - mozscreenshot == 0.1 13:12:43 INFO - mozsystemmonitor == 0.0 13:12:43 INFO - moztest == 0.7 13:12:43 INFO - mozversion == 1.4 13:12:43 INFO - psutil == 3.1.1 13:12:43 INFO - requests == 1.2.3 13:12:43 INFO - wsgiref == 0.1.2 13:12:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:12: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')]} 13:12:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:12:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12: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')]} 13:12:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:12:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:43 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 0x1086bca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086cc420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe379cdccb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10855be00>, '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 0x1083c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108556b20>, '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.R4XVaThNfj/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.PdiKnQxcSs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 13:12:43 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 13:12:43 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 13:12:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R4XVaThNfj/Render', 13:12:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:12:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:12:43 INFO - 'HOME': '/Users/cltbld', 13:12:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:12:43 INFO - 'LOGNAME': 'cltbld', 13:12:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:12:43 INFO - 'MOZ_NO_REMOTE': '1', 13:12:43 INFO - 'NO_EM_RESTART': '1', 13:12:43 INFO - 'PAGER': '/bin/cat', 13:12:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:12:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:12:43 INFO - 'PWD': '/builds/slave/test', 13:12:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:12:43 INFO - 'SHELL': '/bin/bash', 13:12:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PdiKnQxcSs/Listeners', 13:12:43 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 13:12:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:12:43 INFO - 'USER': 'cltbld', 13:12:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:12:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:12:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:12:43 INFO - 'XPC_FLAGS': '0x0', 13:12:43 INFO - 'XPC_SERVICE_NAME': '0', 13:12:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:12:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:12:43 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)) 13:12:43 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)) 13:12:43 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)) 13:12:43 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)) 13:12:43 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)) 13:12:43 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)) 13:12:43 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)) 13:12:43 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)) 13:12:43 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)) 13:12:43 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)) 13:12:43 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)) 13:12:43 INFO - Cleaning up... 13:12:43 INFO - Return code: 0 13:12:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:12: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')]} 13:12:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:12:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12: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')]} 13:12:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:12:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1086bca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086cc420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe379cdccb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10855be00>, '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 0x1083c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108556b20>, '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.R4XVaThNfj/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.PdiKnQxcSs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 13:12: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', 'jsonschema'] in /builds/slave/test/build 13:12: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 jsonschema 13:12:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R4XVaThNfj/Render', 13:12:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:12:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:12:43 INFO - 'HOME': '/Users/cltbld', 13:12:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:12:43 INFO - 'LOGNAME': 'cltbld', 13:12:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:12:43 INFO - 'MOZ_NO_REMOTE': '1', 13:12:43 INFO - 'NO_EM_RESTART': '1', 13:12:43 INFO - 'PAGER': '/bin/cat', 13:12:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:12:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:12:43 INFO - 'PWD': '/builds/slave/test', 13:12:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:12:43 INFO - 'SHELL': '/bin/bash', 13:12:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PdiKnQxcSs/Listeners', 13:12:43 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 13:12:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:12:43 INFO - 'USER': 'cltbld', 13:12:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:12:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:12:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:12:43 INFO - 'XPC_FLAGS': '0x0', 13:12:43 INFO - 'XPC_SERVICE_NAME': '0', 13:12:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:12:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:12:43 INFO - Downloading/unpacking jsonschema 13:12:43 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 13:12:43 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 13:12:43 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 13:12:43 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 13:12:43 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 13:12:43 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 13:12:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:12:46 INFO - Downloading/unpacking functools32 (from jsonschema) 13:12:46 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 13:12:46 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 13:12:46 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 13:12:46 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 13:12:46 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 13:12:46 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 13:12:46 INFO - Downloading functools32-3.2.3-2.tar.gz 13:12:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:12:46 INFO - warning: no files found matching '*.txt' 13:12:46 INFO - no previously-included directories found matching 'build' 13:12:46 INFO - no previously-included directories found matching 'dist' 13:12:46 INFO - no previously-included directories found matching '.git*' 13:12:46 INFO - Installing collected packages: jsonschema, functools32 13:12:46 INFO - Running setup.py install for functools32 13:12:46 INFO - warning: no files found matching '*.txt' 13:12:46 INFO - no previously-included directories found matching 'build' 13:12:46 INFO - no previously-included directories found matching 'dist' 13:12:46 INFO - no previously-included directories found matching '.git*' 13:12:47 INFO - Successfully installed jsonschema functools32 13:12:47 INFO - Cleaning up... 13:12:47 INFO - Return code: 0 13:12:47 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 13:12:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:12:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:12:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:12:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:12:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:12:47 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 0x1086bca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1086cc420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe379cdccb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10855be00>, '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 0x1083c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108556b20>, '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.R4XVaThNfj/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.PdiKnQxcSs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 13:12:47 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 13:12:47 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 13:12:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R4XVaThNfj/Render', 13:12:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:12:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:12:47 INFO - 'HOME': '/Users/cltbld', 13:12:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:12:47 INFO - 'LOGNAME': 'cltbld', 13:12:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:12:47 INFO - 'MOZ_NO_REMOTE': '1', 13:12:47 INFO - 'NO_EM_RESTART': '1', 13:12:47 INFO - 'PAGER': '/bin/cat', 13:12:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:12:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:12:47 INFO - 'PWD': '/builds/slave/test', 13:12:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:12:47 INFO - 'SHELL': '/bin/bash', 13:12:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PdiKnQxcSs/Listeners', 13:12:47 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 13:12:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:12:47 INFO - 'USER': 'cltbld', 13:12:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:12:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:12:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:12:47 INFO - 'XPC_FLAGS': '0x0', 13:12:47 INFO - 'XPC_SERVICE_NAME': '0', 13:12:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:12:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:12:47 INFO - Downloading/unpacking flake8 13:12: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 13:12: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 13:12: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 13:12: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 13:12: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 13:12: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 13:12:49 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 13:12:49 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 13:12: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 13:12: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 13:12: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 13:12: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 13:12: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 13:12: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 13:12:50 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 13:12:50 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 13:12: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 13:12: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 13:12: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 13:12: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 13:12: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 13:12: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 13:12:50 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 13:12: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 13:12: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 13:12: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 13:12: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 13:12: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 13:12: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 13:12:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 13:12:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:12:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:12:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:12:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:12:50 INFO - no previously-included directories found matching 'docs/_build' 13:12:50 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 13:12:51 INFO - Running setup.py install for pep8 13:12:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:12:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:12:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:12:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:12:51 INFO - no previously-included directories found matching 'docs/_build' 13:12:51 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 13:12:51 INFO - Successfully installed flake8 mccabe pyflakes pep8 13:12:51 INFO - Cleaning up... 13:12:51 INFO - Return code: 0 13:12:51 INFO - Running post-action listener: _resource_record_post_action 13:12:51 INFO - Running post-action listener: _start_resource_monitoring 13:12:51 INFO - Starting resource monitoring. 13:12:51 INFO - ##### 13:12:51 INFO - ##### Running install step. 13:12:51 INFO - ##### 13:12:51 INFO - Running pre-action listener: _resource_record_pre_action 13:12:51 INFO - Running main action method: install 13:12:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:12:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:12:51 INFO - Reading from file tmpfile_stdout 13:12:51 INFO - Detecting whether we're running mozinstall >=1.0... 13:12:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:12:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:12:51 INFO - Reading from file tmpfile_stdout 13:12:51 INFO - Output received: 13:12:51 INFO - Usage: mozinstall [options] installer 13:12:51 INFO - Options: 13:12:51 INFO - -h, --help show this help message and exit 13:12:51 INFO - -d DEST, --destination=DEST 13:12:51 INFO - Directory to install application into. [default: 13:12:51 INFO - "/builds/slave/test"] 13:12:51 INFO - --app=APP Application being installed. [default: firefox] 13:12:51 INFO - mkdir: /builds/slave/test/build/application 13:12:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 13:12:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 13:13:23 INFO - Reading from file tmpfile_stdout 13:13:23 INFO - Output received: 13:13:23 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 13:13:23 INFO - Running post-action listener: _resource_record_post_action 13:13:23 INFO - ##### 13:13:23 INFO - ##### Running run-tests step. 13:13:23 INFO - ##### 13:13:23 INFO - Running pre-action listener: _resource_record_pre_action 13:13:23 INFO - Running main action method: run_tests 13:13:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:13:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:13:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:13:23 INFO - 2016-04-26 13:13:23.477 screenresolution[2254:18014] starting screenresolution argv=screenresolution get 13:13:23 INFO - 2016-04-26 13:13:23.480 screenresolution[2254:18014] Display 0: 1600x1200x32@60 13:13:23 INFO - 2016-04-26 13:13:23.486 screenresolution[2255:18016] starting screenresolution argv=screenresolution list 13:13:23 INFO - Available Modes on Display 0 13:13:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:13:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:13:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:13:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:13:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:13:23 INFO - Intel Iris: 13:13:23 INFO - Chipset Model: Intel Iris 13:13:23 INFO - Type: GPU 13:13:23 INFO - Bus: Built-In 13:13:23 INFO - VRAM (Dynamic, Max): 1536 MB 13:13:23 INFO - Vendor: Intel (0x8086) 13:13:23 INFO - Device ID: 0x0a2e 13:13:23 INFO - Revision ID: 0x0009 13:13:23 INFO - Displays: 13:13:23 INFO - Display: 13:13:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:13:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:13:23 INFO - Display Serial Number: 1600x1200 60 13:13:23 INFO - Main Display: Yes 13:13:23 INFO - Mirror: Off 13:13:23 INFO - Online: Yes 13:13:23 INFO - Rotation: Supported 13:13:23 INFO - Return code: 0 13:13:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:13:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:13:23 INFO - Python 2.7.3 13:13:23 INFO - Return code: 0 13:13:23 INFO - grabbing minidump binary from tooltool 13:13:23 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')]} 13:13:23 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 0x10855be00>, '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 0x1083c4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108556b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:13:23 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 13:13:23 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 13:13:23 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:13:23 INFO - Return code: 0 13:13:23 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:13:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:13:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:13:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:13:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:13:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 13:13:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 13:13:24 INFO - Return code: 0 13:13:24 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/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0120', '--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 13:13:24 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/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0120 --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 13:13:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R4XVaThNfj/Render', 13:13:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:13:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:13:24 INFO - 'HOME': '/Users/cltbld', 13:13:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:13:24 INFO - 'LOGNAME': 'cltbld', 13:13:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:13:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:13:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:13:24 INFO - 'MOZ_NO_REMOTE': '1', 13:13:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:13:24 INFO - 'NO_EM_RESTART': '1', 13:13:24 INFO - 'PAGER': '/bin/cat', 13:13:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:13:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:13:24 INFO - 'PWD': '/builds/slave/test', 13:13:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:13:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:13:24 INFO - 'SHELL': '/bin/bash', 13:13:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PdiKnQxcSs/Listeners', 13:13:24 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 13:13:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:13:24 INFO - 'USER': 'cltbld', 13:13:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:13:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:13:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:13:24 INFO - 'XPC_FLAGS': '0x0', 13:13:24 INFO - 'XPC_SERVICE_NAME': '0', 13:13:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:13:24 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/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0120', '--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 13:13:25 INFO - mozversion application_buildid: 20160426062558 13:13:25 INFO - mozversion application_changeset: cbeae0c4410bef3864b3d3f4ad939321c61cb15b 13:13:25 INFO - mozversion application_display_name: FirefoxDeveloperEdition 13:13:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:13:25 INFO - mozversion application_name: Firefox 13:13:25 INFO - mozversion application_remotingname: firefox-dev 13:13:25 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:13:25 INFO - mozversion application_vendor: Mozilla 13:13:25 INFO - mozversion application_version: 48.0a2 13:13:25 INFO - mozversion platform_buildid: 20160426062558 13:13:25 INFO - mozversion platform_changeset: cbeae0c4410bef3864b3d3f4ad939321c61cb15b 13:13:25 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:13:25 INFO - mozversion platform_version: 48.0a2 13:13:25 INFO - using testdate: 1461701605 13:13:25 INFO - actual date: 1461701605 13:13:25 INFO - starting webserver on 'localhost:52493' 13:13:25 INFO - SUITE-START | Running 2 tests 13:13:25 INFO - TEST-START | dromaeo_css 13:13:25 INFO - operating with platform_type : mac_ 13:13:25 INFO - Initialising browser for dromaeo_css test... 13:13:25 INFO - TEST-INFO | started process 2273 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/tmpmhd3Ib/profile http://localhost:52493/getInfo.html) 13:13:27 INFO - PROCESS | 2273 | __metrics Screen width/height:1600/1200 13:13:27 INFO - PROCESS | 2273 | colorDepth:24 13:13:27 INFO - PROCESS | 2273 | Browser inner width/height: 1024/689 13:13:27 INFO - PROCESS | 2273 | __metrics 13:13:27 INFO - PROCESS | 2273 | 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" 13:13:27 ERROR - PROCESS | 2273 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 13:13:28 INFO - TEST-INFO | 2273: exit 0 13:13:28 INFO - Browser initialized. 13:13:28 INFO - Running cycle 1/1 for dromaeo_css test... 13:13:28 INFO - TEST-INFO | started process 2275 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/tmpmhd3Ib/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:13:40 INFO - PROCESS | 2275 | RSS: Main: 211677184 13:13:40 INFO - PROCESS | 2275 | 13:14:31 INFO - PROCESS | 2275 | 1461701671815 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] 13:16:42 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:52493/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52493/tests/dromaeo/cssquery-ext.html) 13:16:43 INFO - PROCESS | 2275 | RSS: Main: 352313344 13:16:43 INFO - PROCESS | 2275 | 13:19:46 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:52493/tests/dromaeo/cssquery-ext.html (next: http://localhost:52493/tests/dromaeo/cssquery-jquery.html) 13:19:47 INFO - PROCESS | 2275 | RSS: Main: 359260160 13:19:47 INFO - PROCESS | 2275 | 13:22:49 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:52493/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52493/tests/dromaeo/cssquery-mootools.html) 13:22:50 INFO - PROCESS | 2275 | RSS: Main: 361562112 13:22:50 INFO - PROCESS | 2275 | 13:25:52 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:52493/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52493/tests/dromaeo/cssquery-prototype.html) 13:25:52 INFO - PROCESS | 2275 | RSS: Main: 362229760 13:25:52 INFO - PROCESS | 2275 | 13:28:56 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:52493/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52493/tests/dromaeo/cssquery-yui.html) 13:28:56 INFO - PROCESS | 2275 | RSS: Main: 340037632 13:28:56 INFO - PROCESS | 2275 | 13:31:59 INFO - PROCESS | 2275 | Cycle 1(1): loaded http://localhost:52493/tests/dromaeo/cssquery-yui.html (next: http://localhost:52493/tests/dromaeo/cssquery-dojo.html) 13:31:59 INFO - PROCESS | 2275 | RSS: Main: 367640576 13:31:59 INFO - PROCESS | 2275 | 13:31:59 INFO - PROCESS | 2275 | __start_tp_report 13:31:59 INFO - PROCESS | 2275 | _x_x_mozilla_page_load 13:31:59 INFO - PROCESS | 2275 | _x_x_mozilla_page_load_details 13:31:59 INFO - PROCESS | 2275 | |i|pagename|runs| 13:31:59 INFO - PROCESS | 2275 | |0;dojo.html;2858.5283428234343;3355.362481128585;3412.2834204816986;3440.139965008748;3470.861165553378;5628.380878103405;5630.577706671996;5636.436356364362;5647.04000319946;5664.065429204182;5634.9718251408685;5642.322921249445;5652.34997975233;5665.065264231407;5686.914696279559;4268.935965960513;5039.344885164924;5112.130937740574;5125.871853203681;5175.404828444721;5115.693058416507;5118.6416950813455;5151.742412879341;5161;5255.605829562779;5170.379554453452;5221.269022336875;5225.163973764198;5250.553702935255;5277.920831187516;4340.956590434087;5265.236540701577;5272.815451459181;5280.07598670235;5318.989392015544;5047.3438453001245;5091.981603679242;5153.768080436352;5205.635605507616;5208.348956380452;5523.6409633373705;5528.889422211573;5554.527865131468;5575.107982722745;5680.289963754531;5289.259503669489;6238.750449981995;6245.843853903643;6261.780837670705;6267.153934218868;5188.870278243036;6131.009681329152;6294.464970477515;6333.651649159295;6338.30278669346;5726.82819515411;5775.249217601685;5792.94207057924;5823.38854420284;5838.941610583925;6326.49388048955;6346.809595712136;6356.682165891687;6362.2683391409655;6365.395287447685;5916.023856063702;6039.758409663565;6167.5682702210415;6193.938060619336;6194.132821405007;6180.227471566054;6306.4639505642535;6307.684615769193;6309.463695585835;6314.305426403092;6190.845228869314;6194.752209911643;6236.750529978841;6237.719302631393;6240.4071613196675;6047.15339852421;6142.969285153546;6172.598781079216;6267.185265915402;6269.373062693694;4842.6368022398465;4850.1269771440775;4864.732439715779;4865.391826021747;4870.537298956593;4359.738415695068;4361.953131248478;4391.187630288407;4403.53762854902;4519.254323036662;5690.77236910519;5699.829005129853;5708.115242137475;5721.599488035881;5730.484256416859;6279.3720627937755;6321.873562528724;6330.177076979963;6340.365963403715;6351.523635727246;5511.476409741068;5618.157276408571;5621.100623900156;5628.409017053233;5700.657960522382;5325.281087053276;5336.546393556591;5338.626296159232;5340.759665815048;5343.759530821122;6267.185265915402;6286;6324.209473815773;6348.523860710465;6351.587146835441;6216.689165541795;6307.96846015767;6314.1160237565855;6333.8733225336155;6344;6342.112104305309;6354.015127655221;6360.045993101072;6369.45859601939;6371.012493063583;5784.785195109074;6003.159557661844;6102.633841969496;6171.59884607499;6201.782937597162;6311.147995020613;6317.052442133717;6323.525735569759;6329.1139240505445;6352.237731472253;6118.2658081028785;6125.111858780363;6130.7241174146375;6136.9873970794315;6141.508679305734;4715.3869996900185;5851.093080572518;5969.641821490724;6135.232666670144;6151.477124444473;5910.615809972338;5914.7338369774325;5953.315368732651;5959.582829201914;5960.880782384242;6130.111133885651;6140.3245642978345;6142.525793809543;6146.0473626587955;6147.631142131487;5778.855528611818;5847.619904706181;5896.086106653475;5940.732667029907;5969.104634304889 13:31:59 INFO - PROCESS | 2275 | |1;ext.html;502.813958835231;513.0124510317639;521.3431076843176;527.8548399190222;564.68659893759;30566.69433305666;31737.68262317376;31754.682453175432;31761;39043.41434878479;44837.327440088346;46052.84867878296;46085.769571152094;46208.3068753969;46290;9518.5240737963;9791.461469619167;9804.950975245114;9805.65680201193;9808.509574521246;8432.704855330065;8661.177188167145;9602.903970960306;9780.706578802647;11400.657980260605;11610.419479026057;11713.70715732105;11736.471858766376;11743.823842642367;11748.471318790633;11533.711657208594;11670.591529296435;11672.416379181008;11681.415929203591;11688.590899318484;8472.491650500928;10890.237683362167;11144.832827507595;11169.106471482342;11179.552817887276;39017.804910975545;39790.60209397898;39832.40251396231;39854.00364990869;39975.600243997476;3211.5824942757527;3298.8515516801804;3349.5980482342275;3351.447011243142;3570.321638888603;27648;28041.85979070112;28303.575446368322;28499.857500712573;29019.70980290212;2741.4791189673897;2746.368335282876;2800.382005929047;2813.8311701297785;2874.2670618992024;386444;389797;390161;390200;390661;34968.82515587406;35651.10872228215;61767.6911615439;62166.75666486645;62234.37765622286;35815;36229.45655815165;36254.45618315727;36275;36428.63571364305;16049.518514444586;16266.674666506604;16274.837251627569;16275.91862040706;16288.26702798377;12098.274103553815;13619.523316683868;13712.108712933627;13733.244671542961;13735.656608584864;7686.423518236043;7770.067591889009;7787.532748035136;7804.84390312202;8050.5572193529915;3412.1810765416617;3455.9827200863833;3458.5503884494856;3491.266833964845;3503.9474407883904;949.6723630347519;956.4596003258137;1012.0688966151158;1144.6279959013355;1196.4556126962211;30095.548566771515;30158.793648253824;30264.243393915327;30743.8462807689;30864;979.3291595257272;1094.5293523784849;1125.0493333133488;1143.9428028598536;1167.241293159453;15420.306086226146;15654.843451565566;15734.449294274646;15988.520344389686;16045.679086418206;668122;670419;674323;676625;678754;49582;57070.57323566861;57975.71012144912;58019.709901450224;62569;1497.161589509878;1514.7879296898664;1524.9923750381176;1526.381815364757;1535.0559405965473;27872.303192419542;29217;29309.413811724073;29777.702222977492;30147.547786782776;49975;49989.500104998486;50301.496985029684;50362.74818625883;50376.49623503865;485768;502346;502871;503434;504040;27435.039773607827;27929.441411171258;27947.301317467227;27956.720432795413;27963.720362796925;46085.53914460813;47277.76361118173;47329.76335118302;48080;48550.51449485601;38212.42681359837;39222.2155556893;39875;39875;40551.797241013606;31423.371532568763;37868.62131378651;38329.4250586247;38608.6139138605;39307.80346098251;35749.46375804313;36150.91547253481;36800.63199367972;37374.8131259342;38855.41716874306;31694;32033.43314620544;32158.356832862744;33496.83251583727;34136.658633413346;25092.498150036532;25110.246692599252;25222.466654775733;25233.49533009366;25462.618060728735 13:31:59 INFO - PROCESS | 2275 | |2;jquery.html;396.81944715154606;402.121364817873;406.9511658600968;407.7961019490255;418.8094417040245;18629.534261643465;20160;20932.174156488887;20968.26611068613;21029.948502574887;20374.490637734027;20427.591448171064;20553.897230513863;21481.67777483339;21499.570008599818;5946.5242780577555;6134.294556126036;6176.258848938111;6183.19618449601;6200.472959798422;5896.675682837442;6081.969590152065;6082.0268756998885;6130.816075517741;6171.321154672983;6045.818625441244;6079.270487541501;6083.087536869461;6088.086786981966;6097.481714054311;5762.510186634141;5985.910211346833;5987.461128498456;5999.940000599981;6059.18201042858;4464.419625448703;5226.764795584208;5238.947610523884;5249.737513124367;5257.421683614799;12862.742745145044;13653.249071301174;25065.62401563978;26179.738202617922;26222.08222712196;2129.4356995396233;2162.7188465499544;2324.5699545583952;2394.3295877154424;2501.7623325784202;8156.225158609923;9790.510474476248;10000.949995249977;12899.161554499022;13768.931155344259;1728.3605066125488;1736.6179440523065;1757.9824201758074;1775.6093659394912;1855.2671694680525;132474;134268;134828.32585837107;136823;140515.89226161616;9597.616095356247;9599.32804703664;11512.827307590225;11855.881441185651;11872.497613204125;12739.808902866464;15395.538133856002;15477;15495.535133946;15519.5344139676;8859.734207973772;9077.04691007448;9158.542072896462;9207.447553146832;9366.250699943988;9031.374352616593;9046.457212567268;9065.682701105561;9168.724938251864;9172.816543669089;4587.151376995266;4683.508231635629;4684.484706682262;4690.695104818176;4731.929021064687;1690.408357075014;1934.177974360891;1982.6728589782524;1996.8003199679915;2013.1645367172753;843.3337663245967;851.1616058182685;949.586929685589;982.1896935028487;995.875515560555;17739.024353660414;18990.43028709141;19482.318118865773;20917.91659387544;20977.580448390945;926.5876684875301;929.707142250189;958.7363475044418;985.2708995343537;1011.1253765492796;10526.947365263279;10893.509792059376;10896.727581810359;10942.56229750817;10968.23222374442;598489;620670;631595;660591;661600;115075.27387089201;135129;135928.9610655841;139313.3034334822;139983.30008349894;986.807572523351;994.8408254679216;1006.6174853555602;1011.261778901406;1018.1396719771781;17604.855684380473;17859.910700446413;18059.006754628623;18111.094445277948;18749;42137.36793948155;42828.785856071765;51922.65762819423;53218.46781532135;53932.191017135556;73045.26954730385;76064.61967690126;78824;80525.79211311718;81784.9801424963;8109.945707058045;8126.634301456331;8359.247667709935;8473.788155296166;8540.420022295895;11309.71725706864;11659.650210493699;12189.329586872807;12681.23912565249;13107.475700972236;10751.552237913324;11107.444627768418;11159.665210043711;11487.827682584935;11520.53917843277;11258.887411125785;11394.886051139383;11434.428278586203;11580.942095289469;11666.183367164556;11642.126840486828;11711.76576468483;11952.282863027898;11964.820527692265;12043.698907527381;7971.481853679492;8256.504609723435;8294.212049855254;8315.501069935823;8721.781955451164;10796.804792810784;11251.437428128724;11302.265352752045;11467.827982580102;11589.478473468545 13:31:59 INFO - PROCESS | 2275 | |3;mootools.html;1412.759830828759;1590.2287390615554;1601.6636506333668;1856.5173055005694;1993.1130646862152;8066.1530539293335;8884.600192991315;9103.726888193349;9128.178463938244;9139.954300228506;8673.739787806377;8917.063708310616;8925;8932;8938.687145949893;2482.3173627252513;2508.6237064440297;2509.736477669846;2511.3721569607505;2513.6857892763405;2453.472503411766;2500.199936020474;2513.937151571207;2516.2702816183287;2518.2319392585255;2472.802175825939;2489.601663733803;2497.8376405533672;2510.2092840755104;2513.5978243481045;2098.3495116513927;2441.5483135619966;2480.7891329237036;2490.6015037593897;2503.048841440241;2425.987870060656;2461.643061756053;2470.814688898322;2471.3574470637764;2485.7141428735745;8758.912410875873;8763.12368763125;8940.97178824437;8944.418612790212;9100.954495227481;5353.652012619167;5637.238972738669;5639.436056394327;5644.350899646511;5644.858878528029;3059;3551.378508760982;3653.1415117447377;3732.7760334380023;3738.5139931808963;1031.381171297223;1059.0944742245347;1059.179136169467;1071.710638127709;1079.838024296362;11628.953394194563;11750;11765.647030589096;11767.646970590811;11800.468978896057;4634.907301853944;4644.256918892961;4755.952440475551;4774.543764151967;4779.5220477951925;9415.858762118574;9417.387869788441;9444.0555944405;9446.811063778687;9457.43255404678;4596.5403459654435;5438.347398312228;5442.265294185314;5450.727463626803;5454.127339625641;4516.458025036951;4517.570830771071;4969.204927211701;4983.775730092155;5093.592512598983;3869.187470631199;3887.688984881203;3941.231459865299;3970.7021973352116;3982.123932734794;1226.7055906582357;1229.3791635224154;1232.8335674683983;1241.85097788266;1247.2516490106045;722.4004076616396;923.4551614547315;951.1439704266078;959;979.5445118020154;5058.215976523645;5757.625754325955;5909.6158749681135;6016.458518733248;6060.42425969541;448.2827476038378;454.3344001038495;457.5310306935351;999.93000489965;1054.6941386997837;3935.9212815743526;4026.67786577079;4048.4129801178665;4088.693347998912;4123.113530590937;10762.300450470695;10828.72928176786;11183.776324473627;11191.440427978569;11359.943200283946;10751.462426878625;11155.27490713101;11211;11300.717482063013;11301.547938082385;8387;8423.70517031901;8509.489430634305;8519.636858102675;8530.957345213359;5319;5684.919865225594;5776.364599894092;5957;6039.909401359071;10138.391696498235;10290.691279261635;10310.175185985254;10468.057874791306;10474.266801323834;10048.497575121068;10203.540840662039;10360.740981475523;10471.42407167613;10650.094741947021;7021.543599666005;7283.599402032939;7298.306660867209;7697;7824.9608751955875;7437.776866694008;7814.921850781647;7894.289513943772;8553.786155345917;8560.443571167854;7308.59802710856;7467.514611550232;7591.278828511283;7623.275788800055;7646.044244469442;7361;7363.374113200223;7374.520656157332;7471.551706897603;7495.550266983998;7125.5368401053765;7129.251428599922;7211.8557628848175;7220.675069621774;7243.0946131733535;4956.082826596706;5069.9746501267255;5118.360204974379;5140.894707637875;5199.454057324077;4952.628552858622;5070.619703522177;5109.565686916505;5111.207762796773;5371.650842695212 13:31:59 INFO - PROCESS | 2275 | |4;prototype.html;319.72823100364695;321.2098238333699;328.33512137920707;329.4942263625335;331.7428992530787;4347.956520434795;4439.711418757783;4445.622122119616;5114.718690472027;5363.919541206885;4799.376081109451;5257.842264732064;5280.498352656491;5293.285406470125;5304;3574.3208790329886;3925.7644541327613;4014.2774300625874;4028.798560071985;4195.62239398454;3361.6302206757236;3398.4367191092006;3706.962930370689;3988.24223397555;4087.9386809197886;3410.59072911251;3777.489004398235;3983.342748446503;4028.858989935353;4046.291898917678;3545.308664810363;3743.438484227374;3786.6024067473068;3896.5908579599018;4052.0072582217545;3555.484454754072;3593.8921832345072;3599.4600809878466;3605.296967091418;3607.873724419659;3954.5056867891512;4310.504292006428;4450.820532558874;4668.276417155346;5100.719460429675;4476.865694029184;4658.743769092698;4683.508231635663;4702.330672611242;4713.835015774432;4717.193270004342;5201;5223.451537588537;5572.275604171432;5656.830295091154;3191.48936170214;3573.6069032406413;3997.620226078518;4029.9395509067385;4030.717849750519;5211.687298762086;5432.320959880015;5545.805896793593;5902.940970590282;5924.7926322578505;3626.8549258029384;3732.309522738302;4038.959610403917;4077.306857834116;4082.3468245080644;3225.5645487858837;4107.650849677811;4141.855035073758;4142.514491305193;4150.128473020639;4703.318018887242;4706.317583950308;4726.550977657117;4741.810327586858;4752.809887604526;5370.4629537045985;5625.38120806711;5640.915386269209;5658.179563963202;5666.603337766317;1280.2766436963086;1502.481643832876;1507.8793696504254;1518.172595935218;1534.4092524378195;870.3168013109679;990.7721224118493;1008.9949550252348;1020.505055048301;1030.253066526762;5036.244563315532;5084.41529224144;5197.532222100029;5237.266782650432;5273.235380869753;5776.855578610568;5809.535237181015;5830.854228644318;5834.474897259182;5868.857866878644;4238.809253583596;4800.903981920342;4859.441164266084;4956.3804524434445;5224.52979231871;3306.5040243963117;3348.748843836722;3358.6809253120914;3359.2441700617655;3364.9327013460083;5977.22296101504;6394.23269207698;6610.471162307004;6620.2787275308765;6681.265060843303;6215.192025036717;6219.827887777266;6530;6535.346465353522;6542.869142617121;5338.786448542015;5663.631863928831;5688.744006519716;6651.268360480343;6654.467642588581;5250.057478732893;6342.270638876588;6349.7142628580905;6374.553781235269;6394.584352017104;6273.215848018997;6282.183316168869;6293;6294.363465498912;6302.5735091989;5569.972150139223;6505.844665337402;6529;6579.276279609335;6692.933070669425;3574.946375804313;4125.195586860534;4147.004718867511;4152.169566086855;4176;6115.3578874217565;6278.833802338186;6310.337414571404;6346.555741098079;6364.045393191059;5863.885861686266;5897.469227769521;6085.421884920926;6178.783742568989;6193.659348735863;4876.5854902333695;6029.758809647565;6131.448169664752;6140.236893398078;6149.323574406722;5703.201551782837;5814.738336774771;5972.432618901197;6012;6035.249777564457;5612.915806262828;6092.482139018233;6118.571699981137;6118.847028824137;6149.385061493815;5980.611260268069;6003.8198854034445;6025.276966764016;6038.969805150946;6053.515718742402 13:31:59 INFO - PROCESS | 2275 | |5;yui.html;667.4160109903834;674.6795272245683;731.9414446844249;752.8870669399589;753.118850944395;15098.849011509881;15101.320440580173;15374.615634609127;16338.673226535491;17191.82808171918;14734.115953042825;14778;14808.11151330921;14823.555293341191;14843.183624900654;738.0995185873226;759.6391713935864;902.9909700903005;931.5575101826612;941.3928016429398;918.4902379179553;925.3846192282158;925.3892430995544;926.8609708543738;926.9907300926972;983.5868935047262;986.3588667366233;987.723437437515;988.7330420786384;989.6585677941099;661.2395744893363;774.3959711425098;793.4445887878479;793.5159552672865;982.8722266105397;713.3152173912998;738.6823665823694;738.8005238585606;740.0416460683427;742.0279433941554;15037.849621503863;15083.396664133421;15133.545993620099;15142.924285378502;15203.695926081416;11100.056495197918;11372.976432121066;12164.81752773709;12262.080343974238;12520.874791252061;9909.603615855358;10042.799144017152;10098.990100989915;10174.186065114773;10288.485575721184;3398.660133986606;3423.8801641942414;3480.112571294329;3498.8775392861126;3577.7853328800356;80926;81069.37861242742;81275;81322.59338703328;81835;9321.580528876217;9460.95269523648;9577.138057574852;9614.663486778069;9843.409395436296;21332.14671413157;21354;21358.893205533874;21370.893145534174;21372.251971180933;7218.242084581148;7431.40548756109;7495.7001719930595;7504.099508059068;7574.394048476109;4093.304138296437;4139.922818374206;4160.667146628262;4182.958170418317;4225.894352641147;3609.566851977727;3717.3308804414924;3725.068732816796;3727.7204209684382;3727.8322475488126;541.9891602167935;569.5756661287368;667.2893368562466;671.563483735566;690.1097584116534;517.9093658609803;534.8475684429919;574.4944448885038;606.9590652031699;616.134331264574;7978.880316795253;8222.301104406193;8311.709090181814;8411.621477033548;8468.280196183394;530.7160669042044;558.2073455692926;568.2328856044307;590.498076634857;626.029654036242;3944.6647035001774;3993.3011722948368;4018.4374187613757;4034.3141665916864;4051.837926482967;88973.55513222392;92262;92860;95338;95865;76538;76763.08092297625;76989.23010769821;77062.2293777055;78515;69904;71065;71077;71287.28712712806;72218;7672.61636918163;7739.1100023495765;7788.1433042366425;7907.090684571348;7950.960245198737;44802.655920322446;45195.0960980772;45233.86915327143;45966.770166148955;46173.538264616924;58985.70507147437;62338.68830655818;64604;64981.35018649753;65138;9867.605295788087;9891.455969921722;10139.442330671589;10391.428471434143;10709.892901071202;10854.457277135954;10990.560377584807;11039.668809935714;11154;11548.364839933884;7717.807054823675;10845.457727113455;11380.772384552098;11753.47109380097;11800.881991179978;11245.100391968459;11503.367314797766;11534.82697759551;11580;11893.167478276437;10656.57373705043;10702.678919632424;10902.073323767569;11022.669319920415;11132.165087618623;9487.573059212215;9565.473898935625;9587.712368628952;9773;9865.901340986786;9647.324687271823;9708.466034367895;9784.755381115245;9819.705408837746;9842.75393115178 13:31:59 INFO - PROCESS | 2275 | __end_tp_report 13:31:59 INFO - PROCESS | 2275 | __start_cc_report 13:31:59 INFO - PROCESS | 2275 | _x_x_mozilla_cycle_collect,1271 13:31:59 INFO - PROCESS | 2275 | __end_cc_report 13:31:59 INFO - PROCESS | 2275 | __startTimestamp1461702719594__endTimestamp 13:31:59 INFO - PROCESS | 2275 | 13:31:59 INFO - PROCESS | 2275 | ------- Summary: start ------- 13:31:59 INFO - PROCESS | 2275 | Number of tests: 6 13:31:59 INFO - PROCESS | 2275 | 13:31:59 INFO - PROCESS | 2275 | [#0] dojo.html Cycles:165 Average:5705.12 Median:5896.09 stddev:682.88 (11.6%) stddev-sans-first:647.43 13:31:59 INFO - PROCESS | 2275 | Values: 2858.5 3355.4 3412.3 3440.1 3470.9 5628.4 5630.6 5636.4 5647.0 5664.1 5635.0 5642.3 5652.3 5665.1 5686.9 4268.9 5039.3 5112.1 5125.9 5175.4 5115.7 5118.6 5151.7 5161.0 5255.6 5170.4 5221.3 5225.2 5250.6 5277.9 4341.0 5265.2 5272.8 5280.1 5319.0 5047.3 5092.0 5153.8 5205.6 5208.3 5523.6 5528.9 5554.5 5575.1 5680.3 5289.3 6238.8 6245.8 6261.8 6267.2 5188.9 6131.0 6294.5 6333.7 6338.3 5726.8 5775.2 5792.9 5823.4 5838.9 6326.5 6346.8 6356.7 6362.3 6365.4 5916.0 6039.8 6167.6 6193.9 6194.1 6180.2 6306.5 6307.7 6309.5 6314.3 6190.8 6194.8 6236.8 6237.7 6240.4 6047.2 6143.0 6172.6 6267.2 6269.4 4842.6 4850.1 4864.7 4865.4 4870.5 4359.7 4362.0 4391.2 4403.5 4519.3 5690.8 5699.8 5708.1 5721.6 5730.5 6279.4 6321.9 6330.2 6340.4 6351.5 5511.5 5618.2 5621.1 5628.4 5700.7 5325.3 5336.5 5338.6 5340.8 5343.8 6267.2 6286.0 6324.2 6348.5 6351.6 6216.7 6308.0 6314.1 6333.9 6344.0 6342.1 6354.0 6360.0 6369.5 6371.0 5784.8 6003.2 6102.6 6171.6 6201.8 6311.1 6317.1 6323.5 6329.1 6352.2 6118.3 6125.1 6130.7 6137.0 6141.5 4715.4 5851.1 5969.6 6135.2 6151.5 5910.6 5914.7 5953.3 5959.6 5960.9 6130.1 6140.3 6142.5 6146.0 6147.6 5778.9 5847.6 5896.1 5940.7 5969.1 13:31:59 INFO - PROCESS | 2275 | 13:31:59 INFO - PROCESS | 2275 | [#1] ext.html Cycles:180 Average:64758.28 Median:19236.39 stddev:142988.36 (743.3%) stddev-sans-first:143308.09 13:31:59 INFO - PROCESS | 2275 | Values: 502.8 513.0 521.3 527.9 564.7 30566.7 31737.7 31754.7 31761.0 39043.4 44837.3 46052.8 46085.8 46208.3 46290.0 9518.5 9791.5 9805.0 9805.7 9808.5 8432.7 8661.2 9602.9 9780.7 11400.7 11610.4 11713.7 11736.5 11743.8 11748.5 11533.7 11670.6 11672.4 11681.4 11688.6 8472.5 10890.2 11144.8 11169.1 11179.6 39017.8 39790.6 39832.4 39854.0 39975.6 3211.6 3298.9 3349.6 3351.4 3570.3 27648.0 28041.9 28303.6 28499.9 29019.7 2741.5 2746.4 2800.4 2813.8 2874.3 386444.0 389797.0 390161.0 390200.0 390661.0 34968.8 35651.1 61767.7 62166.8 62234.4 35815.0 36229.5 36254.5 36275.0 36428.6 16049.5 16266.7 16274.8 16275.9 16288.3 12098.3 13619.5 13712.1 13733.2 13735.7 7686.4 7770.1 7787.5 7804.8 8050.6 3412.2 3456.0 3458.6 3491.3 3503.9 949.7 956.5 1012.1 1144.6 1196.5 30095.5 30158.8 30264.2 30743.8 30864.0 979.3 1094.5 1125.0 1143.9 1167.2 15420.3 15654.8 15734.4 15988.5 16045.7 668122.0 670419.0 674323.0 676625.0 678754.0 49582.0 57070.6 57975.7 58019.7 62569.0 1497.2 1514.8 1525.0 1526.4 1535.1 27872.3 29217.0 29309.4 29777.7 30147.5 49975.0 49989.5 50301.5 50362.7 50376.5 485768.0 502346.0 502871.0 503434.0 504040.0 27435.0 27929.4 27947.3 27956.7 27963.7 46085.5 47277.8 47329.8 48080.0 48550.5 38212.4 39222.2 39875.0 39875.0 40551.8 31423.4 37868.6 38329.4 38608.6 39307.8 35749.5 36150.9 36800.6 37374.8 38855.4 31694.0 32033.4 32158.4 33496.8 34136.7 25092.5 25110.2 25222.5 25233.5 25462.6 13:31:59 INFO - PROCESS | 2275 | 13:31:59 INFO - PROCESS | 2275 | [#2] jquery.html Cycles:180 Average:36493.56 Median:2448.05 stddev:106217.51 (4338.9%) stddev-sans-first:106480.90 13:31:59 INFO - PROCESS | 2275 | Values: 396.8 402.1 407.0 407.8 418.8 18629.5 20160.0 20932.2 20968.3 21029.9 20374.5 20427.6 20553.9 21481.7 21499.6 5946.5 6134.3 6176.3 6183.2 6200.5 5896.7 6082.0 6082.0 6130.8 6171.3 6045.8 6079.3 6083.1 6088.1 6097.5 5762.5 5985.9 5987.5 5999.9 6059.2 4464.4 5226.8 5238.9 5249.7 5257.4 12862.7 13653.2 25065.6 26179.7 26222.1 2129.4 2162.7 2324.6 2394.3 2501.8 8156.2 9790.5 10000.9 12899.2 13768.9 1728.4 1736.6 1758.0 1775.6 1855.3 132474.0 134268.0 134828.3 136823.0 140515.9 9597.6 9599.3 11512.8 11855.9 11872.5 12739.8 15395.5 15477.0 15495.5 15519.5 8859.7 9077.0 9158.5 9207.4 9366.3 9031.4 9046.5 9065.7 9168.7 9172.8 4587.2 4683.5 4684.5 4690.7 4731.9 1690.4 1934.2 1982.7 1996.8 2013.2 843.3 851.2 949.6 982.2 995.9 17739.0 18990.4 19482.3 20917.9 20977.6 926.6 929.7 958.7 985.3 1011.1 10526.9 10893.5 10896.7 10942.6 10968.2 598489.0 620670.0 631595.0 660591.0 661600.0 115075.3 135129.0 135929.0 139313.3 139983.3 986.8 994.8 1006.6 1011.3 1018.1 17604.9 17859.9 18059.0 18111.1 18749.0 42137.4 42828.8 51922.7 53218.5 53932.2 73045.3 76064.6 78824.0 80525.8 81785.0 8109.9 8126.6 8359.2 8473.8 8540.4 11309.7 11659.7 12189.3 12681.2 13107.5 10751.6 11107.4 11159.7 11487.8 11520.5 11258.9 11394.9 11434.4 11580.9 11666.2 11642.1 11711.8 11952.3 11964.8 12043.7 7971.5 8256.5 8294.2 8315.5 8721.8 10796.8 11251.4 11302.3 11467.8 11589.5 13:31:59 INFO - PROCESS | 2275 | 13:31:59 INFO - PROCESS | 2275 | [#3] mootools.html Cycles:180 Average:5788.61 Median:4777.03 stddev:3253.40 (68.1%) stddev-sans-first:3245.90 13:31:59 INFO - PROCESS | 2275 | Values: 1412.8 1590.2 1601.7 1856.5 1993.1 8066.2 8884.6 9103.7 9128.2 9140.0 8673.7 8917.1 8925.0 8932.0 8938.7 2482.3 2508.6 2509.7 2511.4 2513.7 2453.5 2500.2 2513.9 2516.3 2518.2 2472.8 2489.6 2497.8 2510.2 2513.6 2098.3 2441.5 2480.8 2490.6 2503.0 2426.0 2461.6 2470.8 2471.4 2485.7 8758.9 8763.1 8941.0 8944.4 9101.0 5353.7 5637.2 5639.4 5644.4 5644.9 3059.0 3551.4 3653.1 3732.8 3738.5 1031.4 1059.1 1059.2 1071.7 1079.8 11629.0 11750.0 11765.6 11767.6 11800.5 4634.9 4644.3 4756.0 4774.5 4779.5 9415.9 9417.4 9444.1 9446.8 9457.4 4596.5 5438.3 5442.3 5450.7 5454.1 4516.5 4517.6 4969.2 4983.8 5093.6 3869.2 3887.7 3941.2 3970.7 3982.1 1226.7 1229.4 1232.8 1241.9 1247.3 722.4 923.5 951.1 959.0 979.5 5058.2 5757.6 5909.6 6016.5 6060.4 448.3 454.3 457.5 999.9 1054.7 3935.9 4026.7 4048.4 4088.7 4123.1 10762.3 10828.7 11183.8 11191.4 11359.9 10751.5 11155.3 11211.0 11300.7 11301.5 8387.0 8423.7 8509.5 8519.6 8531.0 5319.0 5684.9 5776.4 5957.0 6039.9 10138.4 10290.7 10310.2 10468.1 10474.3 10048.5 10203.5 10360.7 10471.4 10650.1 7021.5 7283.6 7298.3 7697.0 7825.0 7437.8 7814.9 7894.3 8553.8 8560.4 7308.6 7467.5 7591.3 7623.3 7646.0 7361.0 7363.4 7374.5 7471.6 7495.6 7125.5 7129.3 7211.9 7220.7 7243.1 4956.1 5070.0 5118.4 5140.9 5199.5 4952.6 5070.6 5109.6 5111.2 5371.7 13:31:59 INFO - PROCESS | 2275 | 13:31:59 INFO - PROCESS | 2275 | [#4] prototype.html Cycles:180 Average:4738.33 Median:5156.13 stddev:1529.20 (29.7%) stddev-sans-first:1497.10 13:31:59 INFO - PROCESS | 2275 | Values: 319.7 321.2 328.3 329.5 331.7 4348.0 4439.7 4445.6 5114.7 5363.9 4799.4 5257.8 5280.5 5293.3 5304.0 3574.3 3925.8 4014.3 4028.8 4195.6 3361.6 3398.4 3707.0 3988.2 4087.9 3410.6 3777.5 3983.3 4028.9 4046.3 3545.3 3743.4 3786.6 3896.6 4052.0 3555.5 3593.9 3599.5 3605.3 3607.9 3954.5 4310.5 4450.8 4668.3 5100.7 4476.9 4658.7 4683.5 4702.3 4713.8 4717.2 5201.0 5223.5 5572.3 5656.8 3191.5 3573.6 3997.6 4029.9 4030.7 5211.7 5432.3 5545.8 5902.9 5924.8 3626.9 3732.3 4039.0 4077.3 4082.3 3225.6 4107.7 4141.9 4142.5 4150.1 4703.3 4706.3 4726.6 4741.8 4752.8 5370.5 5625.4 5640.9 5658.2 5666.6 1280.3 1502.5 1507.9 1518.2 1534.4 870.3 990.8 1009.0 1020.5 1030.3 5036.2 5084.4 5197.5 5237.3 5273.2 5776.9 5809.5 5830.9 5834.5 5868.9 4238.8 4800.9 4859.4 4956.4 5224.5 3306.5 3348.7 3358.7 3359.2 3364.9 5977.2 6394.2 6610.5 6620.3 6681.3 6215.2 6219.8 6530.0 6535.3 6542.9 5338.8 5663.6 5688.7 6651.3 6654.5 5250.1 6342.3 6349.7 6374.6 6394.6 6273.2 6282.2 6293.0 6294.4 6302.6 5570.0 6505.8 6529.0 6579.3 6692.9 3574.9 4125.2 4147.0 4152.2 4176.0 6115.4 6278.8 6310.3 6346.6 6364.0 5863.9 5897.5 6085.4 6178.8 6193.7 4876.6 6029.8 6131.4 6140.2 6149.3 5703.2 5814.7 5972.4 6012.0 6035.2 5612.9 6092.5 6118.6 6118.8 6149.4 5980.6 6003.8 6025.3 6039.0 6053.5 13:31:59 INFO - PROCESS | 2275 | 13:31:59 INFO - PROCESS | 2275 | [#5] yui.html Cycles:180 Average:18041.68 Median:64792.68 stddev:25436.19 (39.3%) stddev-sans-first:25474.09 13:31:59 INFO - PROCESS | 2275 | Values: 667.4 674.7 731.9 752.9 753.1 15098.8 15101.3 15374.6 16338.7 17191.8 14734.1 14778.0 14808.1 14823.6 14843.2 738.1 759.6 903.0 931.6 941.4 918.5 925.4 925.4 926.9 927.0 983.6 986.4 987.7 988.7 989.7 661.2 774.4 793.4 793.5 982.9 713.3 738.7 738.8 740.0 742.0 15037.8 15083.4 15133.5 15142.9 15203.7 11100.1 11373.0 12164.8 12262.1 12520.9 9909.6 10042.8 10099.0 10174.2 10288.5 3398.7 3423.9 3480.1 3498.9 3577.8 80926.0 81069.4 81275.0 81322.6 81835.0 9321.6 9461.0 9577.1 9614.7 9843.4 21332.1 21354.0 21358.9 21370.9 21372.3 7218.2 7431.4 7495.7 7504.1 7574.4 4093.3 4139.9 4160.7 4183.0 4225.9 3609.6 3717.3 3725.1 3727.7 3727.8 542.0 569.6 667.3 671.6 690.1 517.9 534.8 574.5 607.0 616.1 7978.9 8222.3 8311.7 8411.6 8468.3 530.7 558.2 568.2 590.5 626.0 3944.7 3993.3 4018.4 4034.3 4051.8 88973.6 92262.0 92860.0 95338.0 95865.0 76538.0 76763.1 76989.2 77062.2 78515.0 69904.0 71065.0 71077.0 71287.3 72218.0 7672.6 7739.1 7788.1 7907.1 7951.0 44802.7 45195.1 45233.9 45966.8 46173.5 58985.7 62338.7 64604.0 64981.4 65138.0 9867.6 9891.5 10139.4 10391.4 10709.9 10854.5 10990.6 11039.7 11154.0 11548.4 7717.8 10845.5 11380.8 11753.5 11800.9 11245.1 11503.4 11534.8 11580.0 11893.2 10656.6 10702.7 10902.1 11022.7 11132.2 9487.6 9565.5 9587.7 9773.0 9865.9 9647.3 9708.5 9784.8 9819.7 9842.8 13:31:59 INFO - PROCESS | 2275 | -------- Summary: end -------- 13:31:59 INFO - PROCESS | 2275 | 13:32:00 INFO - TEST-INFO | 2275: exit 0 13:32:00 INFO - TEST-OK | dromaeo_css | took 1115053ms 13:32:00 INFO - TEST-START | kraken 13:32:00 INFO - operating with platform_type : mac_ 13:32:00 INFO - Initialising browser for kraken test... 13:32:00 INFO - TEST-INFO | started process 2278 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/tmp9A5R36/profile http://localhost:52493/getInfo.html) 13:32:02 INFO - PROCESS | 2278 | __metrics Screen width/height:1600/1200 13:32:02 INFO - PROCESS | 2278 | colorDepth:24 13:32:02 INFO - PROCESS | 2278 | Browser inner width/height: 1024/689 13:32:02 INFO - PROCESS | 2278 | __metrics 13:32:02 INFO - PROCESS | 2278 | 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" 13:32:02 ERROR - PROCESS | 2278 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 13:32:02 INFO - TEST-INFO | 2278: exit 0 13:32:02 INFO - Browser initialized. 13:32:02 INFO - Running cycle 1/1 for kraken test... 13:32:02 INFO - TEST-INFO | started process 2280 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/tmp9A5R36/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:32:14 INFO - PROCESS | 2280 | RSS: Main: 211632128 13:32:14 INFO - PROCESS | 2280 | 13:32:17 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:52493/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52493/tests/kraken/driver.html?testName=audio-beat-detection) 13:32:18 INFO - PROCESS | 2280 | RSS: Main: 269819904 13:32:18 INFO - PROCESS | 2280 | 13:32:21 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:52493/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52493/tests/kraken/driver.html?testName=audio-dft) 13:32:21 INFO - PROCESS | 2280 | RSS: Main: 284270592 13:32:21 INFO - PROCESS | 2280 | 13:32:24 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:52493/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52493/tests/kraken/driver.html?testName=audio-fft) 13:32:25 INFO - PROCESS | 2280 | RSS: Main: 374501376 13:32:25 INFO - PROCESS | 2280 | 13:32:27 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:52493/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52493/tests/kraken/driver.html?testName=audio-oscillator) 13:32:28 INFO - PROCESS | 2280 | RSS: Main: 278577152 13:32:28 INFO - PROCESS | 2280 | 13:32:30 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:52493/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52493/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:32:31 INFO - PROCESS | 2280 | RSS: Main: 279072768 13:32:31 INFO - PROCESS | 2280 | 13:32:33 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:52493/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52493/tests/kraken/driver.html?testName=imaging-darkroom) 13:32:34 INFO - PROCESS | 2280 | RSS: Main: 316448768 13:32:34 INFO - PROCESS | 2280 | 13:32:37 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:52493/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52493/tests/kraken/driver.html?testName=imaging-desaturate) 13:32:38 INFO - PROCESS | 2280 | RSS: Main: 319037440 13:32:38 INFO - PROCESS | 2280 | 13:32:41 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:52493/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52493/tests/kraken/driver.html?testName=json-parse-financial) 13:32:41 INFO - PROCESS | 2280 | RSS: Main: 316518400 13:32:41 INFO - PROCESS | 2280 | 13:32:43 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:52493/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52493/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:32:43 INFO - PROCESS | 2280 | RSS: Main: 307318784 13:32:43 INFO - PROCESS | 2280 | 13:32:45 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:52493/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52493/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:32:46 INFO - PROCESS | 2280 | RSS: Main: 314597376 13:32:46 INFO - PROCESS | 2280 | 13:32:48 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:52493/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52493/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:32:49 INFO - PROCESS | 2280 | RSS: Main: 316657664 13:32:49 INFO - PROCESS | 2280 | 13:32:52 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:52493/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52493/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:32:52 INFO - PROCESS | 2280 | RSS: Main: 318844928 13:32:52 INFO - PROCESS | 2280 | 13:32:55 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:52493/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52493/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:32:56 INFO - PROCESS | 2280 | RSS: Main: 316227584 13:32:56 INFO - PROCESS | 2280 | 13:32:58 INFO - PROCESS | 2280 | Cycle 1(1): loaded http://localhost:52493/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52493/tests/kraken/driver.html?testName=ai-astar) 13:32:58 INFO - PROCESS | 2280 | RSS: Main: 380571648 13:32:58 INFO - PROCESS | 2280 | 13:32:58 INFO - PROCESS | 2280 | __start_tp_report 13:32:58 INFO - PROCESS | 2280 | _x_x_mozilla_page_load 13:32:58 INFO - PROCESS | 2280 | _x_x_mozilla_page_load_details 13:32:58 INFO - PROCESS | 2280 | |i|pagename|runs| 13:32:58 INFO - PROCESS | 2280 | |0;ai-astar;115;114;108;114;112;117;111;112;105;114 13:32:58 INFO - PROCESS | 2280 | |1;audio-beat-detection;158;122;157;177;139;147;138;118;170;121 13:32:58 INFO - PROCESS | 2280 | |2;audio-dft;187;184;188;183;186;184;185;187;186;190 13:32:58 INFO - PROCESS | 2280 | |3;audio-fft;72;79;73;155;55;55;57;64;134;54 13:32:58 INFO - PROCESS | 2280 | |4;audio-oscillator;90;89;144;75;107;132;167;79;79;128 13:32:58 INFO - PROCESS | 2280 | |5;imaging-gaussian-blur;89;87;89;86;89;85;91;87;93;129 13:32:58 INFO - PROCESS | 2280 | |6;imaging-darkroom;92;93;94;93;92;94;111;89;131;148 13:32:58 INFO - PROCESS | 2280 | |7;imaging-desaturate;127;131;81;84;84;96;129;130;84;86 13:32:58 INFO - PROCESS | 2280 | |8;json-parse-financial;60;64;65;63;64;65;64;82;59;59 13:32:58 INFO - PROCESS | 2280 | |9;json-stringify-tinderbox;63;64;68;74;79;71;69;68;69;69 13:32:58 INFO - PROCESS | 2280 | |10;stanford-crypto-aes;81;84;80;93;103;102;105;80;82;84 13:32:58 INFO - PROCESS | 2280 | |11;stanford-crypto-ccm;152;158;128;136;132;137;126;156;194;161 13:32:58 INFO - PROCESS | 2280 | |12;stanford-crypto-pbkdf2;166;164;167;170;168;168;161;219;180;169 13:32:58 INFO - PROCESS | 2280 | |13;stanford-crypto-sha256-iterative;70;69;69;69;68;69;68;70;67;67 13:32:58 INFO - PROCESS | 2280 | __end_tp_report 13:32:58 INFO - PROCESS | 2280 | __start_cc_report 13:32:58 INFO - PROCESS | 2280 | _x_x_mozilla_cycle_collect,1967 13:32:58 INFO - PROCESS | 2280 | __end_cc_report 13:32:58 INFO - PROCESS | 2280 | __startTimestamp1461702778455__endTimestamp 13:32:58 INFO - PROCESS | 2280 | 13:32:58 INFO - PROCESS | 2280 | ------- Summary: start ------- 13:32:58 INFO - PROCESS | 2280 | Number of tests: 14 13:32:58 INFO - PROCESS | 2280 | 13:32:58 INFO - PROCESS | 2280 | [#0] ai-astar Cycles:10 Average:112.20 Median:114.00 stddev:3.52 (3.1%) stddev-sans-first:3.59 13:32:58 INFO - PROCESS | 2280 | Values: 115.0 114.0 108.0 114.0 112.0 117.0 111.0 112.0 105.0 114.0 13:32:58 INFO - PROCESS | 2280 | 13:32:58 INFO - PROCESS | 2280 | [#1] audio-beat-detection Cycles:10 Average:144.70 Median:152.00 stddev:20.77 (13.7%) stddev-sans-first:21.47 13:32:58 INFO - PROCESS | 2280 | Values: 158.0 122.0 157.0 177.0 139.0 147.0 138.0 118.0 170.0 121.0 13:32:58 INFO - PROCESS | 2280 | 13:32:58 INFO - PROCESS | 2280 | [#2] audio-dft Cycles:10 Average:186.00 Median:186.50 stddev:2.11 (1.1%) stddev-sans-first:2.20 13:32:58 INFO - PROCESS | 2280 | Values: 187.0 184.0 188.0 183.0 186.0 184.0 185.0 187.0 186.0 190.0 13:32:58 INFO - PROCESS | 2280 | 13:32:58 INFO - PROCESS | 2280 | [#3] audio-fft Cycles:10 Average:79.80 Median:60.50 stddev:35.54 (58.7%) stddev-sans-first:37.58 13:32:58 INFO - PROCESS | 2280 | Values: 72.0 79.0 73.0 155.0 55.0 55.0 57.0 64.0 134.0 54.0 13:32:58 INFO - PROCESS | 2280 | 13:32:58 INFO - PROCESS | 2280 | [#4] audio-oscillator Cycles:10 Average:109.00 Median:77.00 stddev:31.97 (41.5%) stddev-sans-first:33.16 13:32:58 INFO - PROCESS | 2280 | Values: 90.0 89.0 144.0 75.0 107.0 132.0 167.0 79.0 79.0 128.0 13:32:58 INFO - PROCESS | 2280 | 13:32:58 INFO - PROCESS | 2280 | [#5] imaging-gaussian-blur Cycles:10 Average:92.50 Median:89.00 stddev:13.04 (14.7%) stddev-sans-first:13.77 13:32:58 INFO - PROCESS | 2280 | Values: 89.0 87.0 89.0 86.0 89.0 85.0 91.0 87.0 93.0 129.0 13:32:58 INFO - PROCESS | 2280 | 13:32:58 INFO - PROCESS | 2280 | [#6] imaging-darkroom Cycles:10 Average:103.70 Median:92.50 stddev:20.19 (21.8%) stddev-sans-first:20.96 13:32:58 INFO - PROCESS | 2280 | Values: 92.0 93.0 94.0 93.0 92.0 94.0 111.0 89.0 131.0 148.0 13:32:58 INFO - PROCESS | 2280 | 13:32:58 INFO - PROCESS | 2280 | [#7] imaging-desaturate Cycles:10 Average:103.20 Median:84.00 stddev:22.78 (27.1%) stddev-sans-first:22.47 13:32:58 INFO - PROCESS | 2280 | Values: 127.0 131.0 81.0 84.0 84.0 96.0 129.0 130.0 84.0 86.0 13:32:58 INFO - PROCESS | 2280 | 13:32:58 INFO - PROCESS | 2280 | [#8] json-parse-financial Cycles:10 Average:64.50 Median:64.00 stddev:6.59 (10.3%) stddev-sans-first:6.78 13:32:58 INFO - PROCESS | 2280 | Values: 60.0 64.0 65.0 63.0 64.0 65.0 64.0 82.0 59.0 59.0 13:32:58 INFO - PROCESS | 2280 | 13:32:58 INFO - PROCESS | 2280 | [#9] json-stringify-tinderbox Cycles:10 Average:69.40 Median:69.00 stddev:4.60 (6.7%) stddev-sans-first:4.26 13:32:58 INFO - PROCESS | 2280 | Values: 63.0 64.0 68.0 74.0 79.0 71.0 69.0 68.0 69.0 69.0 13:32:58 INFO - PROCESS | 2280 | 13:32:58 INFO - PROCESS | 2280 | [#10] stanford-crypto-aes Cycles:10 Average:89.40 Median:81.50 stddev:10.33 (12.7%) stddev-sans-first:10.50 13:32:58 INFO - PROCESS | 2280 | Values: 81.0 84.0 80.0 93.0 103.0 102.0 105.0 80.0 82.0 84.0 13:32:58 INFO - PROCESS | 2280 | 13:32:58 INFO - PROCESS | 2280 | [#11] stanford-crypto-ccm Cycles:10 Average:148.00 Median:154.00 stddev:20.74 (13.5%) stddev-sans-first:21.94 13:32:58 INFO - PROCESS | 2280 | Values: 152.0 158.0 128.0 136.0 132.0 137.0 126.0 156.0 194.0 161.0 13:32:58 INFO - PROCESS | 2280 | 13:32:58 INFO - PROCESS | 2280 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.20 Median:168.50 stddev:16.83 (10.0%) stddev-sans-first:17.65 13:32:58 INFO - PROCESS | 2280 | Values: 166.0 164.0 167.0 170.0 168.0 168.0 161.0 219.0 180.0 169.0 13:32:58 INFO - PROCESS | 2280 | 13:32:58 INFO - PROCESS | 2280 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.60 Median:69.00 stddev:1.07 (1.6%) stddev-sans-first:1.01 13:32:58 INFO - PROCESS | 2280 | Values: 70.0 69.0 69.0 69.0 68.0 69.0 68.0 70.0 67.0 67.0 13:32:58 INFO - PROCESS | 2280 | -------- Summary: end -------- 13:32:58 INFO - PROCESS | 2280 | 13:32:59 INFO - TEST-INFO | 2280: exit 0 13:32:59 INFO - TEST-OK | kraken | took 59109ms 13:32:59 INFO - SUITE-END | took 1174s 13:32:59 INFO - Completed test suite (00:19:34) 13:32:59 INFO - javascript benchmark 13:32:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2858.5283428234343, 3355.362481128585, 3412.2834204816986, 3440.139965008748, 3470.861165553378, 5628.380878103405, 5630.577706671996, 5636.436356364362, 5647.04000319946, 5664.065429204182, 5634.9718251408685, 5642.322921249445, 5652.34997975233, 5665.065264231407, 5686.914696279559, 4268.935965960513, 5039.344885164924, 5112.130937740574, 5125.871853203681, 5175.404828444721, 5115.693058416507, 5118.6416950813455, 5151.742412879341, 5161.0, 5255.605829562779, 5170.379554453452, 5221.269022336875, 5225.163973764198, 5250.553702935255, 5277.920831187516, 4340.956590434087, 5265.236540701577, 5272.815451459181, 5280.07598670235, 5318.989392015544, 5047.3438453001245, 5091.981603679242, 5153.768080436352, 5205.635605507616, 5208.348956380452, 5523.6409633373705, 5528.889422211573, 5554.527865131468, 5575.107982722745, 5680.289963754531, 5289.259503669489, 6238.750449981995, 6245.843853903643, 6261.780837670705, 6267.153934218868, 5188.870278243036, 6131.009681329152, 6294.464970477515, 6333.651649159295, 6338.30278669346, 5726.82819515411, 5775.249217601685, 5792.94207057924, 5823.38854420284, 5838.941610583925, 6326.49388048955, 6346.809595712136, 6356.682165891687, 6362.2683391409655, 6365.395287447685, 5916.023856063702, 6039.758409663565, 6167.5682702210415, 6193.938060619336, 6194.132821405007, 6180.227471566054, 6306.4639505642535, 6307.684615769193, 6309.463695585835, 6314.305426403092, 6190.845228869314, 6194.752209911643, 6236.750529978841, 6237.719302631393, 6240.4071613196675, 6047.15339852421, 6142.969285153546, 6172.598781079216, 6267.185265915402, 6269.373062693694, 4842.6368022398465, 4850.1269771440775, 4864.732439715779, 4865.391826021747, 4870.537298956593, 4359.738415695068, 4361.953131248478, 4391.187630288407, 4403.53762854902, 4519.254323036662, 5690.77236910519, 5699.829005129853, 5708.115242137475, 5721.599488035881, 5730.484256416859, 6279.3720627937755, 6321.873562528724, 6330.177076979963, 6340.365963403715, 6351.523635727246, 5511.476409741068, 5618.157276408571, 5621.100623900156, 5628.409017053233, 5700.657960522382, 5325.281087053276, 5336.546393556591, 5338.626296159232, 5340.759665815048, 5343.759530821122, 6267.185265915402, 6286.0, 6324.209473815773, 6348.523860710465, 6351.587146835441, 6216.689165541795, 6307.96846015767, 6314.1160237565855, 6333.8733225336155, 6344.0, 6342.112104305309, 6354.015127655221, 6360.045993101072, 6369.45859601939, 6371.012493063583, 5784.785195109074, 6003.159557661844, 6102.633841969496, 6171.59884607499, 6201.782937597162, 6311.147995020613, 6317.052442133717, 6323.525735569759, 6329.1139240505445, 6352.237731472253, 6118.2658081028785, 6125.111858780363, 6130.7241174146375, 6136.9873970794315, 6141.508679305734, 4715.3869996900185, 5851.093080572518, 5969.641821490724, 6135.232666670144, 6151.477124444473, 5910.615809972338, 5914.7338369774325, 5953.315368732651, 5959.582829201914, 5960.880782384242, 6130.111133885651, 6140.3245642978345, 6142.525793809543, 6146.0473626587955, 6147.631142131487, 5778.855528611818, 5847.619904706181, 5896.086106653475, 5940.732667029907, 5969.104634304889], "value": 5660.288202340975, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [502.813958835231, 513.0124510317639, 521.3431076843176, 527.8548399190222, 564.68659893759, 30566.69433305666, 31737.68262317376, 31754.682453175432, 31761.0, 39043.41434878479, 44837.327440088346, 46052.84867878296, 46085.769571152094, 46208.3068753969, 46290.0, 9518.5240737963, 9791.461469619167, 9804.950975245114, 9805.65680201193, 9808.509574521246, 8432.704855330065, 8661.177188167145, 9602.903970960306, 9780.706578802647, 11400.657980260605, 11610.419479026057, 11713.70715732105, 11736.471858766376, 11743.823842642367, 11748.471318790633, 11533.711657208594, 11670.591529296435, 11672.416379181008, 11681.415929203591, 11688.590899318484, 8472.491650500928, 10890.237683362167, 11144.832827507595, 11169.106471482342, 11179.552817887276, 39017.804910975545, 39790.60209397898, 39832.40251396231, 39854.00364990869, 39975.600243997476, 3211.5824942757527, 3298.8515516801804, 3349.5980482342275, 3351.447011243142, 3570.321638888603, 27648.0, 28041.85979070112, 28303.575446368322, 28499.857500712573, 29019.70980290212, 2741.4791189673897, 2746.368335282876, 2800.382005929047, 2813.8311701297785, 2874.2670618992024, 386444.0, 389797.0, 390161.0, 390200.0, 390661.0, 34968.82515587406, 35651.10872228215, 61767.6911615439, 62166.75666486645, 62234.37765622286, 35815.0, 36229.45655815165, 36254.45618315727, 36275.0, 36428.63571364305, 16049.518514444586, 16266.674666506604, 16274.837251627569, 16275.91862040706, 16288.26702798377, 12098.274103553815, 13619.523316683868, 13712.108712933627, 13733.244671542961, 13735.656608584864, 7686.423518236043, 7770.067591889009, 7787.532748035136, 7804.84390312202, 8050.5572193529915, 3412.1810765416617, 3455.9827200863833, 3458.5503884494856, 3491.266833964845, 3503.9474407883904, 949.6723630347519, 956.4596003258137, 1012.0688966151158, 1144.6279959013355, 1196.4556126962211, 30095.548566771515, 30158.793648253824, 30264.243393915327, 30743.8462807689, 30864.0, 979.3291595257272, 1094.5293523784849, 1125.0493333133488, 1143.9428028598536, 1167.241293159453, 15420.306086226146, 15654.843451565566, 15734.449294274646, 15988.520344389686, 16045.679086418206, 668122.0, 670419.0, 674323.0, 676625.0, 678754.0, 49582.0, 57070.57323566861, 57975.71012144912, 58019.709901450224, 62569.0, 1497.161589509878, 1514.7879296898664, 1524.9923750381176, 1526.381815364757, 1535.0559405965473, 27872.303192419542, 29217.0, 29309.413811724073, 29777.702222977492, 30147.547786782776, 49975.0, 49989.500104998486, 50301.496985029684, 50362.74818625883, 50376.49623503865, 485768.0, 502346.0, 502871.0, 503434.0, 504040.0, 27435.039773607827, 27929.441411171258, 27947.301317467227, 27956.720432795413, 27963.720362796925, 46085.53914460813, 47277.76361118173, 47329.76335118302, 48080.0, 48550.51449485601, 38212.42681359837, 39222.2155556893, 39875.0, 39875.0, 40551.797241013606, 31423.371532568763, 37868.62131378651, 38329.4250586247, 38608.6139138605, 39307.80346098251, 35749.46375804313, 36150.91547253481, 36800.63199367972, 37374.8131259342, 38855.41716874306, 31694.0, 32033.43314620544, 32158.356832862744, 33496.83251583727, 34136.658633413346, 25092.498150036532, 25110.246692599252, 25222.466654775733, 25233.49533009366, 25462.618060728735], "value": 18756.713301570013, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.81944715154606, 402.121364817873, 406.9511658600968, 407.7961019490255, 418.8094417040245, 18629.534261643465, 20160.0, 20932.174156488887, 20968.26611068613, 21029.948502574887, 20374.490637734027, 20427.591448171064, 20553.897230513863, 21481.67777483339, 21499.570008599818, 5946.5242780577555, 6134.294556126036, 6176.258848938111, 6183.19618449601, 6200.472959798422, 5896.675682837442, 6081.969590152065, 6082.0268756998885, 6130.816075517741, 6171.321154672983, 6045.818625441244, 6079.270487541501, 6083.087536869461, 6088.086786981966, 6097.481714054311, 5762.510186634141, 5985.910211346833, 5987.461128498456, 5999.940000599981, 6059.18201042858, 4464.419625448703, 5226.764795584208, 5238.947610523884, 5249.737513124367, 5257.421683614799, 12862.742745145044, 13653.249071301174, 25065.62401563978, 26179.738202617922, 26222.08222712196, 2129.4356995396233, 2162.7188465499544, 2324.5699545583952, 2394.3295877154424, 2501.7623325784202, 8156.225158609923, 9790.510474476248, 10000.949995249977, 12899.161554499022, 13768.931155344259, 1728.3605066125488, 1736.6179440523065, 1757.9824201758074, 1775.6093659394912, 1855.2671694680525, 132474.0, 134268.0, 134828.32585837107, 136823.0, 140515.89226161616, 9597.616095356247, 9599.32804703664, 11512.827307590225, 11855.881441185651, 11872.497613204125, 12739.808902866464, 15395.538133856002, 15477.0, 15495.535133946, 15519.5344139676, 8859.734207973772, 9077.04691007448, 9158.542072896462, 9207.447553146832, 9366.250699943988, 9031.374352616593, 9046.457212567268, 9065.682701105561, 9168.724938251864, 9172.816543669089, 4587.151376995266, 4683.508231635629, 4684.484706682262, 4690.695104818176, 4731.929021064687, 1690.408357075014, 1934.177974360891, 1982.6728589782524, 1996.8003199679915, 2013.1645367172753, 843.3337663245967, 851.1616058182685, 949.586929685589, 982.1896935028487, 995.875515560555, 17739.024353660414, 18990.43028709141, 19482.318118865773, 20917.91659387544, 20977.580448390945, 926.5876684875301, 929.707142250189, 958.7363475044418, 985.2708995343537, 1011.1253765492796, 10526.947365263279, 10893.509792059376, 10896.727581810359, 10942.56229750817, 10968.23222374442, 598489.0, 620670.0, 631595.0, 660591.0, 661600.0, 115075.27387089201, 135129.0, 135928.9610655841, 139313.3034334822, 139983.30008349894, 986.807572523351, 994.8408254679216, 1006.6174853555602, 1011.261778901406, 1018.1396719771781, 17604.855684380473, 17859.910700446413, 18059.006754628623, 18111.094445277948, 18749.0, 42137.36793948155, 42828.785856071765, 51922.65762819423, 53218.46781532135, 53932.191017135556, 73045.26954730385, 76064.61967690126, 78824.0, 80525.79211311718, 81784.9801424963, 8109.945707058045, 8126.634301456331, 8359.247667709935, 8473.788155296166, 8540.420022295895, 11309.71725706864, 11659.650210493699, 12189.329586872807, 12681.23912565249, 13107.475700972236, 10751.552237913324, 11107.444627768418, 11159.665210043711, 11487.827682584935, 11520.53917843277, 11258.887411125785, 11394.886051139383, 11434.428278586203, 11580.942095289469, 11666.183367164556, 11642.126840486828, 11711.76576468483, 11952.282863027898, 11964.820527692265, 12043.698907527381, 7971.481853679492, 8256.504609723435, 8294.212049855254, 8315.501069935823, 8721.781955451164, 10796.804792810784, 11251.437428128724, 11302.265352752045, 11467.827982580102, 11589.478473468545], "value": 9663.155270239766, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1412.759830828759, 1590.2287390615554, 1601.6636506333668, 1856.5173055005694, 1993.1130646862152, 8066.1530539293335, 8884.600192991315, 9103.726888193349, 9128.178463938244, 9139.954300228506, 8673.739787806377, 8917.063708310616, 8925.0, 8932.0, 8938.687145949893, 2482.3173627252513, 2508.6237064440297, 2509.736477669846, 2511.3721569607505, 2513.6857892763405, 2453.472503411766, 2500.199936020474, 2513.937151571207, 2516.2702816183287, 2518.2319392585255, 2472.802175825939, 2489.601663733803, 2497.8376405533672, 2510.2092840755104, 2513.5978243481045, 2098.3495116513927, 2441.5483135619966, 2480.7891329237036, 2490.6015037593897, 2503.048841440241, 2425.987870060656, 2461.643061756053, 2470.814688898322, 2471.3574470637764, 2485.7141428735745, 8758.912410875873, 8763.12368763125, 8940.97178824437, 8944.418612790212, 9100.954495227481, 5353.652012619167, 5637.238972738669, 5639.436056394327, 5644.350899646511, 5644.858878528029, 3059.0, 3551.378508760982, 3653.1415117447377, 3732.7760334380023, 3738.5139931808963, 1031.381171297223, 1059.0944742245347, 1059.179136169467, 1071.710638127709, 1079.838024296362, 11628.953394194563, 11750.0, 11765.647030589096, 11767.646970590811, 11800.468978896057, 4634.907301853944, 4644.256918892961, 4755.952440475551, 4774.543764151967, 4779.5220477951925, 9415.858762118574, 9417.387869788441, 9444.0555944405, 9446.811063778687, 9457.43255404678, 4596.5403459654435, 5438.347398312228, 5442.265294185314, 5450.727463626803, 5454.127339625641, 4516.458025036951, 4517.570830771071, 4969.204927211701, 4983.775730092155, 5093.592512598983, 3869.187470631199, 3887.688984881203, 3941.231459865299, 3970.7021973352116, 3982.123932734794, 1226.7055906582357, 1229.3791635224154, 1232.8335674683983, 1241.85097788266, 1247.2516490106045, 722.4004076616396, 923.4551614547315, 951.1439704266078, 959.0, 979.5445118020154, 5058.215976523645, 5757.625754325955, 5909.6158749681135, 6016.458518733248, 6060.42425969541, 448.2827476038378, 454.3344001038495, 457.5310306935351, 999.93000489965, 1054.6941386997837, 3935.9212815743526, 4026.67786577079, 4048.4129801178665, 4088.693347998912, 4123.113530590937, 10762.300450470695, 10828.72928176786, 11183.776324473627, 11191.440427978569, 11359.943200283946, 10751.462426878625, 11155.27490713101, 11211.0, 11300.717482063013, 11301.547938082385, 8387.0, 8423.70517031901, 8509.489430634305, 8519.636858102675, 8530.957345213359, 5319.0, 5684.919865225594, 5776.364599894092, 5957.0, 6039.909401359071, 10138.391696498235, 10290.691279261635, 10310.175185985254, 10468.057874791306, 10474.266801323834, 10048.497575121068, 10203.540840662039, 10360.740981475523, 10471.42407167613, 10650.094741947021, 7021.543599666005, 7283.599402032939, 7298.306660867209, 7697.0, 7824.9608751955875, 7437.776866694008, 7814.921850781647, 7894.289513943772, 8553.786155345917, 8560.443571167854, 7308.59802710856, 7467.514611550232, 7591.278828511283, 7623.275788800055, 7646.044244469442, 7361.0, 7363.374113200223, 7374.520656157332, 7471.551706897603, 7495.550266983998, 7125.5368401053765, 7129.251428599922, 7211.8557628848175, 7220.675069621774, 7243.0946131733535, 4956.082826596706, 5069.9746501267255, 5118.360204974379, 5140.894707637875, 5199.454057324077, 4952.628552858622, 5070.619703522177, 5109.565686916505, 5111.207762796773, 5371.650842695212], "value": 4623.068796934164, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.72823100364695, 321.2098238333699, 328.33512137920707, 329.4942263625335, 331.7428992530787, 4347.956520434795, 4439.711418757783, 4445.622122119616, 5114.718690472027, 5363.919541206885, 4799.376081109451, 5257.842264732064, 5280.498352656491, 5293.285406470125, 5304.0, 3574.3208790329886, 3925.7644541327613, 4014.2774300625874, 4028.798560071985, 4195.62239398454, 3361.6302206757236, 3398.4367191092006, 3706.962930370689, 3988.24223397555, 4087.9386809197886, 3410.59072911251, 3777.489004398235, 3983.342748446503, 4028.858989935353, 4046.291898917678, 3545.308664810363, 3743.438484227374, 3786.6024067473068, 3896.5908579599018, 4052.0072582217545, 3555.484454754072, 3593.8921832345072, 3599.4600809878466, 3605.296967091418, 3607.873724419659, 3954.5056867891512, 4310.504292006428, 4450.820532558874, 4668.276417155346, 5100.719460429675, 4476.865694029184, 4658.743769092698, 4683.508231635663, 4702.330672611242, 4713.835015774432, 4717.193270004342, 5201.0, 5223.451537588537, 5572.275604171432, 5656.830295091154, 3191.48936170214, 3573.6069032406413, 3997.620226078518, 4029.9395509067385, 4030.717849750519, 5211.687298762086, 5432.320959880015, 5545.805896793593, 5902.940970590282, 5924.7926322578505, 3626.8549258029384, 3732.309522738302, 4038.959610403917, 4077.306857834116, 4082.3468245080644, 3225.5645487858837, 4107.650849677811, 4141.855035073758, 4142.514491305193, 4150.128473020639, 4703.318018887242, 4706.317583950308, 4726.550977657117, 4741.810327586858, 4752.809887604526, 5370.4629537045985, 5625.38120806711, 5640.915386269209, 5658.179563963202, 5666.603337766317, 1280.2766436963086, 1502.481643832876, 1507.8793696504254, 1518.172595935218, 1534.4092524378195, 870.3168013109679, 990.7721224118493, 1008.9949550252348, 1020.505055048301, 1030.253066526762, 5036.244563315532, 5084.41529224144, 5197.532222100029, 5237.266782650432, 5273.235380869753, 5776.855578610568, 5809.535237181015, 5830.854228644318, 5834.474897259182, 5868.857866878644, 4238.809253583596, 4800.903981920342, 4859.441164266084, 4956.3804524434445, 5224.52979231871, 3306.5040243963117, 3348.748843836722, 3358.6809253120914, 3359.2441700617655, 3364.9327013460083, 5977.22296101504, 6394.23269207698, 6610.471162307004, 6620.2787275308765, 6681.265060843303, 6215.192025036717, 6219.827887777266, 6530.0, 6535.346465353522, 6542.869142617121, 5338.786448542015, 5663.631863928831, 5688.744006519716, 6651.268360480343, 6654.467642588581, 5250.057478732893, 6342.270638876588, 6349.7142628580905, 6374.553781235269, 6394.584352017104, 6273.215848018997, 6282.183316168869, 6293.0, 6294.363465498912, 6302.5735091989, 5569.972150139223, 6505.844665337402, 6529.0, 6579.276279609335, 6692.933070669425, 3574.946375804313, 4125.195586860534, 4147.004718867511, 4152.169566086855, 4176.0, 6115.3578874217565, 6278.833802338186, 6310.337414571404, 6346.555741098079, 6364.045393191059, 5863.885861686266, 5897.469227769521, 6085.421884920926, 6178.783742568989, 6193.659348735863, 4876.5854902333695, 6029.758809647565, 6131.448169664752, 6140.236893398078, 6149.323574406722, 5703.201551782837, 5814.738336774771, 5972.432618901197, 6012.0, 6035.249777564457, 5612.915806262828, 6092.482139018233, 6118.571699981137, 6118.847028824137, 6149.385061493815, 5980.611260268069, 6003.8198854034445, 6025.276966764016, 6038.969805150946, 6053.515718742402], "value": 4267.535217811227, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [667.4160109903834, 674.6795272245683, 731.9414446844249, 752.8870669399589, 753.118850944395, 15098.849011509881, 15101.320440580173, 15374.615634609127, 16338.673226535491, 17191.82808171918, 14734.115953042825, 14778.0, 14808.11151330921, 14823.555293341191, 14843.183624900654, 738.0995185873226, 759.6391713935864, 902.9909700903005, 931.5575101826612, 941.3928016429398, 918.4902379179553, 925.3846192282158, 925.3892430995544, 926.8609708543738, 926.9907300926972, 983.5868935047262, 986.3588667366233, 987.723437437515, 988.7330420786384, 989.6585677941099, 661.2395744893363, 774.3959711425098, 793.4445887878479, 793.5159552672865, 982.8722266105397, 713.3152173912998, 738.6823665823694, 738.8005238585606, 740.0416460683427, 742.0279433941554, 15037.849621503863, 15083.396664133421, 15133.545993620099, 15142.924285378502, 15203.695926081416, 11100.056495197918, 11372.976432121066, 12164.81752773709, 12262.080343974238, 12520.874791252061, 9909.603615855358, 10042.799144017152, 10098.990100989915, 10174.186065114773, 10288.485575721184, 3398.660133986606, 3423.8801641942414, 3480.112571294329, 3498.8775392861126, 3577.7853328800356, 80926.0, 81069.37861242742, 81275.0, 81322.59338703328, 81835.0, 9321.580528876217, 9460.95269523648, 9577.138057574852, 9614.663486778069, 9843.409395436296, 21332.14671413157, 21354.0, 21358.893205533874, 21370.893145534174, 21372.251971180933, 7218.242084581148, 7431.40548756109, 7495.7001719930595, 7504.099508059068, 7574.394048476109, 4093.304138296437, 4139.922818374206, 4160.667146628262, 4182.958170418317, 4225.894352641147, 3609.566851977727, 3717.3308804414924, 3725.068732816796, 3727.7204209684382, 3727.8322475488126, 541.9891602167935, 569.5756661287368, 667.2893368562466, 671.563483735566, 690.1097584116534, 517.9093658609803, 534.8475684429919, 574.4944448885038, 606.9590652031699, 616.134331264574, 7978.880316795253, 8222.301104406193, 8311.709090181814, 8411.621477033548, 8468.280196183394, 530.7160669042044, 558.2073455692926, 568.2328856044307, 590.498076634857, 626.029654036242, 3944.6647035001774, 3993.3011722948368, 4018.4374187613757, 4034.3141665916864, 4051.837926482967, 88973.55513222392, 92262.0, 92860.0, 95338.0, 95865.0, 76538.0, 76763.08092297625, 76989.23010769821, 77062.2293777055, 78515.0, 69904.0, 71065.0, 71077.0, 71287.28712712806, 72218.0, 7672.61636918163, 7739.1100023495765, 7788.1433042366425, 7907.090684571348, 7950.960245198737, 44802.655920322446, 45195.0960980772, 45233.86915327143, 45966.770166148955, 46173.538264616924, 58985.70507147437, 62338.68830655818, 64604.0, 64981.35018649753, 65138.0, 9867.605295788087, 9891.455969921722, 10139.442330671589, 10391.428471434143, 10709.892901071202, 10854.457277135954, 10990.560377584807, 11039.668809935714, 11154.0, 11548.364839933884, 7717.807054823675, 10845.457727113455, 11380.772384552098, 11753.47109380097, 11800.881991179978, 11245.100391968459, 11503.367314797766, 11534.82697759551, 11580.0, 11893.167478276437, 10656.57373705043, 10702.678919632424, 10902.073323767569, 11022.669319920415, 11132.165087618623, 9487.573059212215, 9565.473898935625, 9587.712368628952, 9773.0, 9865.901340986786, 9647.324687271823, 9708.466034367895, 9784.755381115245, 9819.705408837746, 9842.75393115178], "value": 6822.3241826671965, "unit": "score"}], "name": "dromaeo_css", "value": 7189.510139995658, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 108.0, 114.0, 112.0, 117.0, 111.0, 112.0, 105.0, 114.0], "value": 112.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 122.0, 157.0, 177.0, 139.0, 147.0, 138.0, 118.0, 170.0, 121.0], "value": 144.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 184.0, 188.0, 183.0, 186.0, 184.0, 185.0, 187.0, 186.0, 190.0], "value": 186.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 79.0, 73.0, 155.0, 55.0, 55.0, 57.0, 64.0, 134.0, 54.0], "value": 79.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 144.0, 75.0, 107.0, 132.0, 167.0, 79.0, 79.0, 128.0], "value": 109.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 89.0, 86.0, 89.0, 85.0, 91.0, 87.0, 93.0, 129.0], "value": 92.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 94.0, 93.0, 92.0, 94.0, 111.0, 89.0, 131.0, 148.0], "value": 103.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 131.0, 81.0, 84.0, 84.0, 96.0, 129.0, 130.0, 84.0, 86.0], "value": 103.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 64.0, 65.0, 63.0, 64.0, 65.0, 64.0, 82.0, 59.0, 59.0], "value": 64.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 68.0, 74.0, 79.0, 71.0, 69.0, 68.0, 69.0, 69.0], "value": 69.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 84.0, 80.0, 93.0, 103.0, 102.0, 105.0, 80.0, 82.0, 84.0], "value": 89.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 158.0, 128.0, 136.0, 132.0, 137.0, 126.0, 156.0, 194.0, 161.0], "value": 148.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 164.0, 167.0, 170.0, 168.0, 168.0, 161.0, 219.0, 180.0, 169.0], "value": 173.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 69.0, 69.0, 68.0, 69.0, 68.0, 70.0, 67.0, 67.0], "value": 68.6, "unit": "score"}], "name": "kraken", "value": 1544.2, "alertThreshold": 2.0}]} 13:32:59 INFO - Return code: 0 13:32:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 13:32:59 ERROR - # TBPL SUCCESS # 13:32:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:32:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:32:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:32:59 INFO - 2016-04-26 13:32:59.778 screenresolution[2284:20082] starting screenresolution argv=screenresolution get 13:32:59 INFO - 2016-04-26 13:32:59.781 screenresolution[2284:20082] Display 0: 1600x1200x32@60 13:32:59 INFO - 2016-04-26 13:32:59.787 screenresolution[2285:20085] starting screenresolution argv=screenresolution list 13:32:59 INFO - Available Modes on Display 0 13:32:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:32:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:32:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:32:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:33:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:33:00 INFO - Intel Iris: 13:33:00 INFO - Chipset Model: Intel Iris 13:33:00 INFO - Type: GPU 13:33:00 INFO - Bus: Built-In 13:33:00 INFO - VRAM (Dynamic, Max): 1536 MB 13:33:00 INFO - Vendor: Intel (0x8086) 13:33:00 INFO - Device ID: 0x0a2e 13:33:00 INFO - Revision ID: 0x0009 13:33:00 INFO - Displays: 13:33:00 INFO - Display: 13:33:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:33:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:33:00 INFO - Display Serial Number: 1600x1200 60 13:33:00 INFO - Main Display: Yes 13:33:00 INFO - Mirror: Off 13:33:00 INFO - Online: Yes 13:33:00 INFO - Rotation: Supported 13:33:00 INFO - Return code: 0 13:33:00 INFO - Running post-action listener: _resource_record_post_action 13:33:00 INFO - Running post-run listener: _resource_record_post_run 13:33:01 INFO - Total resource usage - Wall time: 1208s; CPU: 53.0%; Read bytes: 319488; Write bytes: 653433856; Read time: 48; Write time: 1857 13:33:01 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 354353664; Write bytes: 357210112; Read time: 27782; Write time: 716 13:33:01 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 319488; Write bytes: 295375872; Read time: 48; Write time: 1138 13:33:01 INFO - Running post-run listener: _upload_blobber_files 13:33:01 INFO - Blob upload gear active. 13:33:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:33:01 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 13:33:01 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'] 13:33:01 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 13:33:01 INFO - (blobuploader) - INFO - Open directory for files ... 13:33:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:33:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:33:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:33:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:33:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:33:02 INFO - (blobuploader) - INFO - Done attempting. 13:33:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:33:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:33:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:33:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:33:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:33:03 INFO - (blobuploader) - INFO - Done attempting. 13:33:03 INFO - (blobuploader) - INFO - Iteration through files over. 13:33:03 INFO - Return code: 0 13:33:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:33:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:33:03 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/53ecef713d11049d5b27ed9aecc3d970ae90be119fa1e9b0bee7062cb9ce411b8033c586fb747c69731faeec197a77df65a94b95ddf40b2043d447d829766a83", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e8aea700ed49374dfd5172868d0e16e63070e96ea5ff1306492fc01821a222896c6cf95f40770d651843bc7230538dda082bd06c587e9a11feafb5e1ea2e645a"} 13:33:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:33:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:33:03 INFO - Contents: 13:33:03 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/53ecef713d11049d5b27ed9aecc3d970ae90be119fa1e9b0bee7062cb9ce411b8033c586fb747c69731faeec197a77df65a94b95ddf40b2043d447d829766a83", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e8aea700ed49374dfd5172868d0e16e63070e96ea5ff1306492fc01821a222896c6cf95f40770d651843bc7230538dda082bd06c587e9a11feafb5e1ea2e645a"} 13:33:03 INFO - Running post-run listener: copy_logs_to_upload_dir 13:33:03 INFO - Copying logs to upload dir... 13:33:03 INFO - mkdir: /builds/slave/test/build/upload/logs 13:33:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1410.507670 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 30 secs) (at 2016-04-26 13:33:03.891701) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-26 13:33:03.895574) ========= 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.R4XVaThNfj/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.PdiKnQxcSs/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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/53ecef713d11049d5b27ed9aecc3d970ae90be119fa1e9b0bee7062cb9ce411b8033c586fb747c69731faeec197a77df65a94b95ddf40b2043d447d829766a83", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e8aea700ed49374dfd5172868d0e16e63070e96ea5ff1306492fc01821a222896c6cf95f40770d651843bc7230538dda082bd06c587e9a11feafb5e1ea2e645a"} build_url:https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011133 build_url: 'https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/53ecef713d11049d5b27ed9aecc3d970ae90be119fa1e9b0bee7062cb9ce411b8033c586fb747c69731faeec197a77df65a94b95ddf40b2043d447d829766a83", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e8aea700ed49374dfd5172868d0e16e63070e96ea5ff1306492fc01821a222896c6cf95f40770d651843bc7230538dda082bd06c587e9a11feafb5e1ea2e645a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-26 13:33:03.927461) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 13:33:03.928166) ========= 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.R4XVaThNfj/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.PdiKnQxcSs/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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.005128 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 13:33:03.980470) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-26 13:33:03.981172) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-26 13:33:03.981979) ========= ========= Total master_lag: 0.19 =========