builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0174 starttime: 1461859962.07 results: success (0) buildid: 20160428071449 builduid: 56a48c653ee24eab91fc08fb9b572b43 revision: 53765e008b97622b5c7e26d22b2db016b0e53dc1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-28 09:12:42.072343) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-28 09:12:42.072756) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-28 09:12:42.073028) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-28 09:12:42.117061) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 09:12:42.117345) ========= 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.1VtZhajZ2P/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.5DBc3Ljn3r/Listeners TMPDIR=/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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-28 09:12:42-- 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% 163M=0s 2016-04-28 09:12:42 (163 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.080286 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 09:12:42.251882) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 09:12:42.255217) ========= 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.1VtZhajZ2P/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.5DBc3Ljn3r/Listeners TMPDIR=/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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.027063 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 09:12:42.297248) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 09:12:42.297615) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 53765e008b97622b5c7e26d22b2db016b0e53dc1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 53765e008b97622b5c7e26d22b2db016b0e53dc1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1VtZhajZ2P/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.5DBc3Ljn3r/Listeners TMPDIR=/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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-28 09:12:42,371 truncating revision to first 12 chars 2016-04-28 09:12:42,371 Setting DEBUG logging. 2016-04-28 09:12:42,372 attempt 1/10 2016-04-28 09:12:42,372 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/53765e008b97?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-28 09:12:43,022 unpacking tar archive at: mozilla-central-53765e008b97/testing/mozharness/ program finished with exit code 0 elapsedTime=0.900262 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-28 09:12:43.217625) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-28 09:12:43.217953) ========= script_repo_revision: 53765e008b97622b5c7e26d22b2db016b0e53dc1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-28 09:12:43.218351) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-28 09:12:43.218621) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-28 09:12:43.232264) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-04-28 09:12:43.232618) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1VtZhajZ2P/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.5DBc3Ljn3r/Listeners TMPDIR=/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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 09:12:43 INFO - MultiFileLogger online at 20160428 09:12:43 in /builds/slave/test 09:12:43 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:12:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:12:43 INFO - {'append_to_log': False, 09:12:43 INFO - 'base_work_dir': '/builds/slave/test', 09:12:43 INFO - 'blob_upload_branch': 'Firefox', 09:12:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:12:43 INFO - 'branch': 'Firefox', 09:12:43 INFO - 'buildbot_json_path': 'buildprops.json', 09:12:43 INFO - 'config_files': ('talos/mac_config.py',), 09:12:43 INFO - 'default_actions': ('clobber', 09:12:43 INFO - 'read-buildbot-config', 09:12:43 INFO - 'download-and-extract', 09:12:43 INFO - 'populate-webroot', 09:12:43 INFO - 'create-virtualenv', 09:12:43 INFO - 'install', 09:12:43 INFO - 'run-tests'), 09:12:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:12:43 INFO - 'download_minidump_stackwalk': True, 09:12:43 INFO - 'download_symbols': 'ondemand', 09:12:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:12:43 INFO - 'tooltool.py': '/tools/tooltool.py', 09:12:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:12:43 INFO - '/tools/misc-python/virtualenv.py')}, 09:12:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:12:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:12:43 INFO - 'installer_path': 'installer.exe', 09:12:43 INFO - 'log_level': 'info', 09:12:43 INFO - 'log_name': 'talos', 09:12:43 INFO - 'log_to_console': True, 09:12:43 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:12:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:12:43 INFO - 'opt_config_files': (), 09:12:43 INFO - 'pip_index': False, 09:12:43 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:12:43 INFO - 'cmd': ('bash', 09:12:43 INFO - '-c', 09:12:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:12:43 INFO - 'enabled': True, 09:12:43 INFO - 'halt_on_failure': False, 09:12:43 INFO - 'name': 'check_screen_resolution'},), 09:12:43 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:12:43 INFO - 'cmd': ('bash', 09:12:43 INFO - '-c', 09:12:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:12:43 INFO - 'enabled': True, 09:12:43 INFO - 'halt_on_failure': False, 09:12:43 INFO - 'name': 'check_screen_resolution'},), 09:12:43 INFO - 'run_cmd_checks_enabled': True, 09:12:43 INFO - 'sps_profile': False, 09:12:43 INFO - 'sps_profile_interval': 0, 09:12:43 INFO - 'suite': 'dromaeojs-e10s', 09:12:43 INFO - 'system_bits': '32', 09:12:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:12:43 INFO - 'title': 't-yosemite-r7-0174', 09:12:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:12:43 INFO - 'use_talos_json': True, 09:12:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:12:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:12:43 INFO - 'work_dir': 'build'} 09:12:43 INFO - ##### 09:12:43 INFO - ##### Running clobber step. 09:12:43 INFO - ##### 09:12:43 INFO - Running pre-action listener: _resource_record_pre_action 09:12:43 INFO - Running main action method: clobber 09:12:43 INFO - rmtree: /builds/slave/test/build 09:12:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:12:45 INFO - Running post-action listener: _resource_record_post_action 09:12:45 INFO - ##### 09:12:45 INFO - ##### Running read-buildbot-config step. 09:12:45 INFO - ##### 09:12:45 INFO - Running pre-action listener: _resource_record_pre_action 09:12:45 INFO - Running main action method: read_buildbot_config 09:12:45 INFO - Using buildbot properties: 09:12:45 INFO - { 09:12:45 INFO - "project": "", 09:12:45 INFO - "product": "firefox", 09:12:45 INFO - "script_repo_revision": "production", 09:12:45 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 09:12:45 INFO - "repository": "", 09:12:45 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 09:12:45 INFO - "buildid": "20160428071449", 09:12:45 INFO - "pgo_build": "False", 09:12:45 INFO - "basedir": "/builds/slave/test", 09:12:45 INFO - "buildnumber": 259, 09:12:45 INFO - "slavename": "t-yosemite-r7-0174", 09:12:45 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 09:12:45 INFO - "platform": "yosemite_r7", 09:12:45 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:12:45 INFO - "branch": "mozilla-central", 09:12:45 INFO - "repo_path": "mozilla-central", 09:12:45 INFO - "revision": "53765e008b97622b5c7e26d22b2db016b0e53dc1", 09:12:45 INFO - "stage_platform": "macosx64", 09:12:45 INFO - "builduid": "56a48c653ee24eab91fc08fb9b572b43", 09:12:45 INFO - "slavebuilddir": "test" 09:12:45 INFO - } 09:12:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/frV0SoWYTXWfDw-ZNFBtvg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 09:12:45 INFO - Running post-action listener: _resource_record_post_action 09:12:45 INFO - ##### 09:12:45 INFO - ##### Running download-and-extract step. 09:12:45 INFO - ##### 09:12:45 INFO - Running pre-action listener: _resource_record_pre_action 09:12:45 INFO - Running main action method: download_and_extract 09:12:45 INFO - mkdir: /builds/slave/test/build/tests 09:12:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:12:45 INFO - https://queue.taskcluster.net/v1/task/frV0SoWYTXWfDw-ZNFBtvg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:12:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/frV0SoWYTXWfDw-ZNFBtvg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 09:12:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/frV0SoWYTXWfDw-ZNFBtvg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 09:12:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/frV0SoWYTXWfDw-ZNFBtvg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 09:12:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/frV0SoWYTXWfDw-ZNFBtvg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 09:12:46 INFO - Downloaded 1199 bytes. 09:12:46 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 09:12:46 INFO - Using the following test package requirements: 09:12:46 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 09:12:46 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:12:46 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 09:12:46 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:12:46 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 09:12:46 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:12:46 INFO - u'jsshell-mac.zip'], 09:12:46 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:12:46 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 09:12:46 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 09:12:46 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:12:46 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 09:12:46 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:12:46 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 09:12:46 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:12:46 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 09:12:46 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 09:12:46 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 09:12:46 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:12:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:12:46 INFO - https://queue.taskcluster.net/v1/task/frV0SoWYTXWfDw-ZNFBtvg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:12:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/frV0SoWYTXWfDw-ZNFBtvg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:12:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/frV0SoWYTXWfDw-ZNFBtvg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:12:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/frV0SoWYTXWfDw-ZNFBtvg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:12:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/frV0SoWYTXWfDw-ZNFBtvg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:12:47 INFO - Downloaded 18116464 bytes. 09:12:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:12:47 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:12:50 INFO - Return code: 0 09:12:50 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:12:50 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')]} 09:12:50 INFO - https://queue.taskcluster.net/v1/task/frV0SoWYTXWfDw-ZNFBtvg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:12:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/frV0SoWYTXWfDw-ZNFBtvg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:12:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/frV0SoWYTXWfDw-ZNFBtvg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:12:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/frV0SoWYTXWfDw-ZNFBtvg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 09:12:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/frV0SoWYTXWfDw-ZNFBtvg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:12:52 INFO - Downloaded 18116464 bytes. 09:12:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:12:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:12:55 INFO - Return code: 0 09:12:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:12:55 INFO - https://queue.taskcluster.net/v1/task/frV0SoWYTXWfDw-ZNFBtvg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:12:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/frV0SoWYTXWfDw-ZNFBtvg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 09:12:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/frV0SoWYTXWfDw-ZNFBtvg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 09:12:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/frV0SoWYTXWfDw-ZNFBtvg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 09:12:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/frV0SoWYTXWfDw-ZNFBtvg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:12:57 INFO - Downloaded 10840145 bytes. 09:12:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:12:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:12:57 INFO - Return code: 0 09:12:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:12:57 INFO - https://queue.taskcluster.net/v1/task/frV0SoWYTXWfDw-ZNFBtvg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 09:12:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/frV0SoWYTXWfDw-ZNFBtvg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:12:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/frV0SoWYTXWfDw-ZNFBtvg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:12:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/frV0SoWYTXWfDw-ZNFBtvg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 09:12:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/frV0SoWYTXWfDw-ZNFBtvg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 09:13:00 INFO - Downloaded 110207109 bytes. 09:13:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/frV0SoWYTXWfDw-ZNFBtvg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:13:00 INFO - mkdir: /builds/slave/test/properties 09:13:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:13:00 INFO - Writing to file /builds/slave/test/properties/build_url 09:13:00 INFO - Contents: 09:13:00 INFO - build_url:https://queue.taskcluster.net/v1/task/frV0SoWYTXWfDw-ZNFBtvg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 09:13:01 INFO - Running post-action listener: _resource_record_post_action 09:13:01 INFO - Running post-action listener: set_extra_try_arguments 09:13:01 INFO - ##### 09:13:01 INFO - ##### Running populate-webroot step. 09:13:01 INFO - ##### 09:13:01 INFO - Running pre-action listener: _resource_record_pre_action 09:13:01 INFO - Running main action method: populate_webroot 09:13:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:13:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:13:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:13:01 INFO - '--tppagecycles', 09:13:01 INFO - '7'], 09:13:01 INFO - 'tests': ['tsvgm']}}, 09:13:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:13:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:13:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:13:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:13:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:13:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:13:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:13:01 INFO - 'talos_options': ['--disable-e10s'], 09:13:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:13:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:13:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:13:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:13:01 INFO - 'talos_options': ['--disable-e10s'], 09:13:01 INFO - 'tests': ['damp', 'tps']}, 09:13:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:13:01 INFO - 'tests': ['damp', 'tps']}, 09:13:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:13:01 INFO - 'tests': ['dromaeo_dom']}, 09:13:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:13:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:13:01 INFO - 'tests': ['a11yr', 09:13:01 INFO - 'ts_paint', 09:13:01 INFO - 'tpaint', 09:13:01 INFO - 'sessionrestore', 09:13:01 INFO - 'sessionrestore_no_auto_restore', 09:13:01 INFO - 'tabpaint']}, 09:13:01 INFO - 'other-e10s': {'tests': ['a11yr', 09:13:01 INFO - 'ts_paint', 09:13:01 INFO - 'tpaint', 09:13:01 INFO - 'sessionrestore', 09:13:01 INFO - 'sessionrestore_no_auto_restore', 09:13:01 INFO - 'tabpaint']}, 09:13:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:13:01 INFO - 'ts_paint', 09:13:01 INFO - 'tpaint', 09:13:01 INFO - 'sessionrestore', 09:13:01 INFO - 'sessionrestore_no_auto_restore', 09:13:01 INFO - 'tabpaint']}, 09:13:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:13:01 INFO - 'ts_paint', 09:13:01 INFO - 'tpaint', 09:13:01 INFO - 'sessionrestore', 09:13:01 INFO - 'sessionrestore_no_auto_restore', 09:13:01 INFO - 'tabpaint']}, 09:13:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:13:01 INFO - 'tests': ['a11yr', 09:13:01 INFO - 'ts_paint', 09:13:01 INFO - 'tpaint', 09:13:01 INFO - 'sessionrestore', 09:13:01 INFO - 'sessionrestore_no_auto_restore', 09:13:01 INFO - 'tabpaint']}, 09:13:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:13:01 INFO - 'tests': ['a11yr', 09:13:01 INFO - 'ts_paint', 09:13:01 INFO - 'tpaint', 09:13:01 INFO - 'sessionrestore', 09:13:01 INFO - 'sessionrestore_no_auto_restore', 09:13:01 INFO - 'tabpaint']}, 09:13:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:13:01 INFO - 'tests': ['tsvgx', 09:13:01 INFO - 'tsvgr_opacity', 09:13:01 INFO - 'tart', 09:13:01 INFO - 'tscrollx', 09:13:01 INFO - 'cart']}, 09:13:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:13:01 INFO - 'tsvgr_opacity', 09:13:01 INFO - 'tart', 09:13:01 INFO - 'tscrollx', 09:13:01 INFO - 'cart']}, 09:13:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:13:01 INFO - 'talos_options': ['--disable-e10s'], 09:13:01 INFO - 'tests': ['tp5o']}, 09:13:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:13:01 INFO - 'tests': ['tp5o']}, 09:13:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:13:01 INFO - 'talos_options': ['--disable-e10s', 09:13:01 INFO - '--xperf_path', 09:13:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:13:01 INFO - 'tests': ['tp5n']}, 09:13:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:13:01 INFO - 'talos_options': ['--xperf_path', 09:13:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:13:01 INFO - 'tests': ['tp5n']}}, 09:13:01 INFO - 'talos.zip': {'path': '', 09:13:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:13:01 INFO - Running post-action listener: _resource_record_post_action 09:13:01 INFO - ##### 09:13:01 INFO - ##### Running create-virtualenv step. 09:13:01 INFO - ##### 09:13:01 INFO - Running pre-action listener: _resource_record_pre_action 09:13:01 INFO - Running main action method: create_virtualenv 09:13:01 INFO - Creating virtualenv /builds/slave/test/build/venv 09:13:01 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:13:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:13:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:13:01 INFO - Using real prefix '/tools/python27' 09:13:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:13:02 INFO - Installing distribute.............................................................................................................................................................................................done. 09:13:05 INFO - Installing pip.................done. 09:13:05 INFO - Return code: 0 09:13:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:13:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102199a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021a9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4b1471530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102038e00>, '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 0x101ea1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102033b20>, '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.1VtZhajZ2P/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.5DBc3Ljn3r/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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 09:13:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:13:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:13:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1VtZhajZ2P/Render', 09:13:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:13:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:13:05 INFO - 'HOME': '/Users/cltbld', 09:13:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:13:05 INFO - 'LOGNAME': 'cltbld', 09:13:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:05 INFO - 'MOZ_NO_REMOTE': '1', 09:13:05 INFO - 'NO_EM_RESTART': '1', 09:13:05 INFO - 'PAGER': '/bin/cat', 09:13:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:13:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:05 INFO - 'PWD': '/builds/slave/test', 09:13:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:13:05 INFO - 'SHELL': '/bin/bash', 09:13:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5DBc3Ljn3r/Listeners', 09:13:05 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 09:13:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:13:05 INFO - 'USER': 'cltbld', 09:13:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:13:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:13:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:05 INFO - 'XPC_FLAGS': '0x0', 09:13:05 INFO - 'XPC_SERVICE_NAME': '0', 09:13:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:13:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:05 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:13:05 INFO - Cleaning up... 09:13:05 INFO - Return code: 0 09:13:05 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:13:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102199a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021a9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4b1471530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102038e00>, '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 0x101ea1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102033b20>, '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.1VtZhajZ2P/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.5DBc3Ljn3r/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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 09:13:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 09:13:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 09:13:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1VtZhajZ2P/Render', 09:13:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:13:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:13:05 INFO - 'HOME': '/Users/cltbld', 09:13:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:13:05 INFO - 'LOGNAME': 'cltbld', 09:13:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:05 INFO - 'MOZ_NO_REMOTE': '1', 09:13:05 INFO - 'NO_EM_RESTART': '1', 09:13:05 INFO - 'PAGER': '/bin/cat', 09:13:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:13:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:05 INFO - 'PWD': '/builds/slave/test', 09:13:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:13:05 INFO - 'SHELL': '/bin/bash', 09:13:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5DBc3Ljn3r/Listeners', 09:13:05 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 09:13:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:13:05 INFO - 'USER': 'cltbld', 09:13:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:13:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:13:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:05 INFO - 'XPC_FLAGS': '0x0', 09:13:05 INFO - 'XPC_SERVICE_NAME': '0', 09:13:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:13:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:06 INFO - Downloading/unpacking psutil>=0.7.1 09:13:06 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 09:13:06 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 09:13:06 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 09:13:06 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 09:13:06 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 09:13:06 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 09:13:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:13:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:13:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:13:08 INFO - Installing collected packages: psutil 09:13:08 INFO - Running setup.py install for psutil 09:13:09 INFO - building 'psutil._psutil_osx' extension 09:13:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:13:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:13:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:13:09 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:13:09 INFO - building 'psutil._psutil_posix' extension 09:13:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:13:09 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:13:09 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:13:09 INFO - ^ 09:13:09 INFO - 1 warning generated. 09:13:09 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:13:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:13:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:13:10 INFO - Successfully installed psutil 09:13:10 INFO - Cleaning up... 09:13:10 INFO - Return code: 0 09:13:10 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:13:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:10 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 0x102199a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021a9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4b1471530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102038e00>, '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 0x101ea1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102033b20>, '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.1VtZhajZ2P/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.5DBc3Ljn3r/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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 09:13:10 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 09:13:10 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 09:13:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1VtZhajZ2P/Render', 09:13:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:13:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:13:10 INFO - 'HOME': '/Users/cltbld', 09:13:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:13:10 INFO - 'LOGNAME': 'cltbld', 09:13:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:10 INFO - 'MOZ_NO_REMOTE': '1', 09:13:10 INFO - 'NO_EM_RESTART': '1', 09:13:10 INFO - 'PAGER': '/bin/cat', 09:13:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:13:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:10 INFO - 'PWD': '/builds/slave/test', 09:13:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:13:10 INFO - 'SHELL': '/bin/bash', 09:13:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5DBc3Ljn3r/Listeners', 09:13:10 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 09:13:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:13:10 INFO - 'USER': 'cltbld', 09:13:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:13:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:13:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:10 INFO - 'XPC_FLAGS': '0x0', 09:13:10 INFO - 'XPC_SERVICE_NAME': '0', 09:13:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:13:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:10 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:13:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:12 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:13:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:13:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:13:12 INFO - Installing collected packages: mozsystemmonitor 09:13:12 INFO - Running setup.py install for mozsystemmonitor 09:13:12 INFO - Successfully installed mozsystemmonitor 09:13:12 INFO - Cleaning up... 09:13:13 INFO - Return code: 0 09:13:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:13:13 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')]} 09:13:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:13 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')]} 09:13:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:13 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 0x102199a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021a9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4b1471530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102038e00>, '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 0x101ea1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102033b20>, '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.1VtZhajZ2P/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.5DBc3Ljn3r/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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 09:13:13 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 09:13:13 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 09:13:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1VtZhajZ2P/Render', 09:13:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:13:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:13:13 INFO - 'HOME': '/Users/cltbld', 09:13:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:13:13 INFO - 'LOGNAME': 'cltbld', 09:13:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:13 INFO - 'MOZ_NO_REMOTE': '1', 09:13:13 INFO - 'NO_EM_RESTART': '1', 09:13:13 INFO - 'PAGER': '/bin/cat', 09:13:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:13:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:13 INFO - 'PWD': '/builds/slave/test', 09:13:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:13:13 INFO - 'SHELL': '/bin/bash', 09:13:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5DBc3Ljn3r/Listeners', 09:13:13 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 09:13:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:13:13 INFO - 'USER': 'cltbld', 09:13:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:13:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:13:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:13 INFO - 'XPC_FLAGS': '0x0', 09:13:13 INFO - 'XPC_SERVICE_NAME': '0', 09:13:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:13:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:13 INFO - Downloading/unpacking blobuploader==1.2.4 09:13:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:15 INFO - Downloading blobuploader-1.2.4.tar.gz 09:13:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:13:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:13:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:13:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:13:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:16 INFO - Downloading docopt-0.6.1.tar.gz 09:13:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:13:16 INFO - Installing collected packages: blobuploader, requests, docopt 09:13:16 INFO - Running setup.py install for blobuploader 09:13:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:13:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:13:17 INFO - Running setup.py install for requests 09:13:17 INFO - Running setup.py install for docopt 09:13:17 INFO - Successfully installed blobuploader requests docopt 09:13:17 INFO - Cleaning up... 09:13:17 INFO - Return code: 0 09:13:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:13:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102199a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021a9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4b1471530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102038e00>, '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 0x101ea1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102033b20>, '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.1VtZhajZ2P/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.5DBc3Ljn3r/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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 09:13:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:13:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:13:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1VtZhajZ2P/Render', 09:13:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:13:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:13:17 INFO - 'HOME': '/Users/cltbld', 09:13:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:13:17 INFO - 'LOGNAME': 'cltbld', 09:13:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:17 INFO - 'MOZ_NO_REMOTE': '1', 09:13:17 INFO - 'NO_EM_RESTART': '1', 09:13:17 INFO - 'PAGER': '/bin/cat', 09:13:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:13:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:17 INFO - 'PWD': '/builds/slave/test', 09:13:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:13:17 INFO - 'SHELL': '/bin/bash', 09:13:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5DBc3Ljn3r/Listeners', 09:13:17 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 09:13:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:13:17 INFO - 'USER': 'cltbld', 09:13:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:13:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:13:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:17 INFO - 'XPC_FLAGS': '0x0', 09:13:17 INFO - 'XPC_SERVICE_NAME': '0', 09:13:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:13:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:13:18 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-ttXCw_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:13:18 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-LR5vNU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:13:18 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-qvz6hv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:13:18 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-gWrmzJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:13:18 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-fEJbiF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:13:18 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-5HaeQk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:13:18 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-B42mLZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:13:18 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-s1EKLb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:13:18 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-ruuQiY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:13:18 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-BXtuRD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:13:19 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-TMKSIB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:13:19 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-Jiukps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:13:19 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-TzI2ob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:13:19 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-SMEqmO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:13:19 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-3RgIfy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:13:19 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-82k1pG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:13:19 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-Mh84n7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:13:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:13:19 INFO - Running setup.py install for manifestparser 09:13:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:13:19 INFO - Running setup.py install for mozcrash 09:13:20 INFO - Running setup.py install for mozdebug 09:13:20 INFO - Running setup.py install for mozdevice 09:13:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:13:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:13:20 INFO - Running setup.py install for mozfile 09:13:20 INFO - Running setup.py install for mozhttpd 09:13:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:13:20 INFO - Running setup.py install for mozinfo 09:13:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:13:20 INFO - Running setup.py install for mozInstall 09:13:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:13:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:13:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:13:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:13:20 INFO - Running setup.py install for mozleak 09:13:21 INFO - Running setup.py install for mozlog 09:13:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:13:21 INFO - Running setup.py install for moznetwork 09:13:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:13:21 INFO - Running setup.py install for mozprocess 09:13:21 INFO - Running setup.py install for mozprofile 09:13:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:13:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:13:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:13:21 INFO - Running setup.py install for mozrunner 09:13:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:13:21 INFO - Running setup.py install for mozscreenshot 09:13:22 INFO - Running setup.py install for moztest 09:13:22 INFO - Running setup.py install for mozversion 09:13:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:13:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:13:22 INFO - Cleaning up... 09:13:22 INFO - Return code: 0 09:13:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:13:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102199a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021a9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4b1471530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102038e00>, '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 0x101ea1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102033b20>, '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.1VtZhajZ2P/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.5DBc3Ljn3r/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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 09:13:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:13:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:13:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1VtZhajZ2P/Render', 09:13:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:13:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:13:22 INFO - 'HOME': '/Users/cltbld', 09:13:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:13:22 INFO - 'LOGNAME': 'cltbld', 09:13:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:22 INFO - 'MOZ_NO_REMOTE': '1', 09:13:22 INFO - 'NO_EM_RESTART': '1', 09:13:22 INFO - 'PAGER': '/bin/cat', 09:13:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:13:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:22 INFO - 'PWD': '/builds/slave/test', 09:13:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:13:22 INFO - 'SHELL': '/bin/bash', 09:13:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5DBc3Ljn3r/Listeners', 09:13:22 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 09:13:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:13:22 INFO - 'USER': 'cltbld', 09:13:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:13:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:13:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:22 INFO - 'XPC_FLAGS': '0x0', 09:13:22 INFO - 'XPC_SERVICE_NAME': '0', 09:13:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:13:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:13:22 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-YflCgL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:13:22 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:13:22 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-cupw8Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:13:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:13:22 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-Ri4P9N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:13:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:13:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:13:22 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-VYhoxJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:13:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:13:23 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-9H46OL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:13:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:13:23 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-ygqnkP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:13:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:13:23 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-Dbrpnm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:13:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:13:23 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-GljRfJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:13:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:13:23 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-OgQ0x1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:13:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:13:23 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-F_IuEP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:13:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:13:23 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-UU0zym-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:13:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:13:23 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-OyUntc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:13:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:13:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:13:23 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-wwCa20-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:13:24 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)) 09:13:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:13:24 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-Nzf5Ax-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:13:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:13:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:13:24 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-HIrYrd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:13:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:13:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:13:24 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-SGV1_u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:13:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:13:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:13:24 INFO - Running setup.py (path:/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/pip-P9DZ2X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:13:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:13:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:13:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:13:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:13:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:13:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:13:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:13:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:27 INFO - Downloading blessings-1.6.tar.gz 09:13:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:13:27 INFO - Installing collected packages: blessings 09:13:27 INFO - Running setup.py install for blessings 09:13:27 INFO - Successfully installed blessings 09:13:27 INFO - Cleaning up... 09:13:27 INFO - Return code: 0 09:13:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:13:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:13:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:13:27 INFO - Reading from file tmpfile_stdout 09:13:27 INFO - Current package versions: 09:13:27 INFO - blessings == 1.6 09:13:27 INFO - blobuploader == 1.2.4 09:13:27 INFO - docopt == 0.6.1 09:13:27 INFO - manifestparser == 1.1 09:13:27 INFO - mozInstall == 1.12 09:13:27 INFO - mozcrash == 0.17 09:13:27 INFO - mozdebug == 0.1 09:13:27 INFO - mozdevice == 0.48 09:13:27 INFO - mozfile == 1.2 09:13:27 INFO - mozhttpd == 0.7 09:13:27 INFO - mozinfo == 0.9 09:13:27 INFO - mozleak == 0.1 09:13:27 INFO - mozlog == 3.1 09:13:27 INFO - moznetwork == 0.27 09:13:27 INFO - mozprocess == 0.22 09:13:27 INFO - mozprofile == 0.28 09:13:27 INFO - mozrunner == 6.11 09:13:27 INFO - mozscreenshot == 0.1 09:13:27 INFO - mozsystemmonitor == 0.0 09:13:27 INFO - moztest == 0.7 09:13:27 INFO - mozversion == 1.4 09:13:27 INFO - psutil == 3.1.1 09:13:27 INFO - requests == 1.2.3 09:13:27 INFO - wsgiref == 0.1.2 09:13:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:13:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102199a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021a9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4b1471530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102038e00>, '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 0x101ea1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102033b20>, '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.1VtZhajZ2P/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.5DBc3Ljn3r/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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 09:13:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:13:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:13:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1VtZhajZ2P/Render', 09:13:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:13:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:13:27 INFO - 'HOME': '/Users/cltbld', 09:13:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:13:27 INFO - 'LOGNAME': 'cltbld', 09:13:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:27 INFO - 'MOZ_NO_REMOTE': '1', 09:13:27 INFO - 'NO_EM_RESTART': '1', 09:13:27 INFO - 'PAGER': '/bin/cat', 09:13:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:13:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:27 INFO - 'PWD': '/builds/slave/test', 09:13:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:13:27 INFO - 'SHELL': '/bin/bash', 09:13:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5DBc3Ljn3r/Listeners', 09:13:27 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 09:13:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:13:27 INFO - 'USER': 'cltbld', 09:13:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:13:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:13:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:27 INFO - 'XPC_FLAGS': '0x0', 09:13:27 INFO - 'XPC_SERVICE_NAME': '0', 09:13:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:13:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:13:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:13:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:13:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:13:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:13:28 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)) 09:13:28 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)) 09:13:28 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)) 09:13:28 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)) 09:13:28 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)) 09:13:28 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)) 09:13:28 INFO - Cleaning up... 09:13:28 INFO - Return code: 0 09:13:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:13: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')]} 09:13:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13: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')]} 09:13:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102199a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021a9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4b1471530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102038e00>, '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 0x101ea1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102033b20>, '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.1VtZhajZ2P/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.5DBc3Ljn3r/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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 09:13: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', 'jsonschema'] in /builds/slave/test/build 09:13: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 jsonschema 09:13:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1VtZhajZ2P/Render', 09:13:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:13:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:13:28 INFO - 'HOME': '/Users/cltbld', 09:13:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:13:28 INFO - 'LOGNAME': 'cltbld', 09:13:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:28 INFO - 'MOZ_NO_REMOTE': '1', 09:13:28 INFO - 'NO_EM_RESTART': '1', 09:13:28 INFO - 'PAGER': '/bin/cat', 09:13:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:13:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:28 INFO - 'PWD': '/builds/slave/test', 09:13:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:13:28 INFO - 'SHELL': '/bin/bash', 09:13:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5DBc3Ljn3r/Listeners', 09:13:28 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 09:13:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:13:28 INFO - 'USER': 'cltbld', 09:13:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:13:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:13:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:28 INFO - 'XPC_FLAGS': '0x0', 09:13:28 INFO - 'XPC_SERVICE_NAME': '0', 09:13:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:13:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:28 INFO - Downloading/unpacking jsonschema 09:13:28 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 09:13:28 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 09:13:28 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 09:13:28 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 09:13:28 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 09:13:28 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 09:13:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:13:30 INFO - Downloading/unpacking functools32 (from jsonschema) 09:13:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:31 INFO - Downloading functools32-3.2.3-2.tar.gz 09:13:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:13:31 INFO - warning: no files found matching '*.txt' 09:13:31 INFO - no previously-included directories found matching 'build' 09:13:31 INFO - no previously-included directories found matching 'dist' 09:13:31 INFO - no previously-included directories found matching '.git*' 09:13:31 INFO - Installing collected packages: jsonschema, functools32 09:13:31 INFO - Running setup.py install for functools32 09:13:31 INFO - warning: no files found matching '*.txt' 09:13:31 INFO - no previously-included directories found matching 'build' 09:13:31 INFO - no previously-included directories found matching 'dist' 09:13:31 INFO - no previously-included directories found matching '.git*' 09:13:31 INFO - Successfully installed jsonschema functools32 09:13:31 INFO - Cleaning up... 09:13:31 INFO - Return code: 0 09:13:31 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:13:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:13:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:13:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:13:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:13:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102199a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021a9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4b1471530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102038e00>, '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 0x101ea1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102033b20>, '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.1VtZhajZ2P/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.5DBc3Ljn3r/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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 09:13:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 09:13:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 09:13:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1VtZhajZ2P/Render', 09:13:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:13:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:13:31 INFO - 'HOME': '/Users/cltbld', 09:13:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:13:31 INFO - 'LOGNAME': 'cltbld', 09:13:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:31 INFO - 'MOZ_NO_REMOTE': '1', 09:13:31 INFO - 'NO_EM_RESTART': '1', 09:13:31 INFO - 'PAGER': '/bin/cat', 09:13:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:13:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:31 INFO - 'PWD': '/builds/slave/test', 09:13:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:13:31 INFO - 'SHELL': '/bin/bash', 09:13:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5DBc3Ljn3r/Listeners', 09:13:31 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 09:13:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:13:31 INFO - 'USER': 'cltbld', 09:13:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:13:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:13:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:31 INFO - 'XPC_FLAGS': '0x0', 09:13:31 INFO - 'XPC_SERVICE_NAME': '0', 09:13:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:13:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:13:32 INFO - Downloading/unpacking flake8 09:13: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 09:13: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 09:13: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 09:13: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 09:13: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 09:13: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 09:13:34 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:13:34 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:13:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:13:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:13:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:13:35 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:13:35 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:13:35 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 09:13:35 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 09:13:35 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 09:13:35 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 09:13:35 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 09:13:35 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 09:13:35 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:13:35 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 09:13:35 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 09:13:35 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 09:13:35 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 09:13:35 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 09:13:35 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 09:13:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:13:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:13:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:13:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:13:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:13:36 INFO - no previously-included directories found matching 'docs/_build' 09:13:36 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:13:36 INFO - Running setup.py install for pep8 09:13:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:13:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:13:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:13:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:13:36 INFO - no previously-included directories found matching 'docs/_build' 09:13:36 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:13:36 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:13:36 INFO - Cleaning up... 09:13:36 INFO - Return code: 0 09:13:36 INFO - Running post-action listener: _resource_record_post_action 09:13:36 INFO - Running post-action listener: _start_resource_monitoring 09:13:36 INFO - Starting resource monitoring. 09:13:36 INFO - ##### 09:13:36 INFO - ##### Running install step. 09:13:36 INFO - ##### 09:13:36 INFO - Running pre-action listener: _resource_record_pre_action 09:13:36 INFO - Running main action method: install 09:13:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:13:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:13:36 INFO - Reading from file tmpfile_stdout 09:13:36 INFO - Detecting whether we're running mozinstall >=1.0... 09:13:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:13:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:13:36 INFO - Reading from file tmpfile_stdout 09:13:36 INFO - Output received: 09:13:36 INFO - Usage: mozinstall [options] installer 09:13:36 INFO - Options: 09:13:36 INFO - -h, --help show this help message and exit 09:13:36 INFO - -d DEST, --destination=DEST 09:13:36 INFO - Directory to install application into. [default: 09:13:36 INFO - "/builds/slave/test"] 09:13:36 INFO - --app=APP Application being installed. [default: firefox] 09:13:36 INFO - mkdir: /builds/slave/test/build/application 09:13:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:13:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:14:10 INFO - Reading from file tmpfile_stdout 09:14:10 INFO - Output received: 09:14:10 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:14:10 INFO - Running post-action listener: _resource_record_post_action 09:14:10 INFO - ##### 09:14:10 INFO - ##### Running run-tests step. 09:14:10 INFO - ##### 09:14:10 INFO - Running pre-action listener: _resource_record_pre_action 09:14:10 INFO - Running main action method: run_tests 09:14:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:14:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:14:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:14:10 INFO - 2016-04-28 09:14:10.351 screenresolution[1867:8027] starting screenresolution argv=screenresolution get 09:14:10 INFO - 2016-04-28 09:14:10.353 screenresolution[1867:8027] Display 0: 1600x1200x32@60 09:14:10 INFO - 2016-04-28 09:14:10.359 screenresolution[1868:8029] starting screenresolution argv=screenresolution list 09:14:10 INFO - Available Modes on Display 0 09:14:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:14:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:14:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:14:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:14:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:14:10 INFO - Intel Iris: 09:14:10 INFO - Chipset Model: Intel Iris 09:14:10 INFO - Type: GPU 09:14:10 INFO - Bus: Built-In 09:14:10 INFO - VRAM (Dynamic, Max): 1536 MB 09:14:10 INFO - Vendor: Intel (0x8086) 09:14:10 INFO - Device ID: 0x0a2e 09:14:10 INFO - Revision ID: 0x0009 09:14:10 INFO - Displays: 09:14:10 INFO - Display: 09:14:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:14:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:14:10 INFO - Display Serial Number: 1600x1200 60 09:14:10 INFO - Main Display: Yes 09:14:10 INFO - Mirror: Off 09:14:10 INFO - Online: Yes 09:14:10 INFO - Rotation: Supported 09:14:10 INFO - Return code: 0 09:14:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:14:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:14:10 INFO - Python 2.7.3 09:14:10 INFO - Return code: 0 09:14:10 INFO - grabbing minidump binary from tooltool 09:14:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:14:10 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102038e00>, '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 0x101ea1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102033b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:14:10 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:14:10 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:14:10 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:14:10 INFO - Return code: 0 09:14:10 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:14:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:14:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:14:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:14:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:14:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:14:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:14:11 INFO - Return code: 0 09:14:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/frV0SoWYTXWfDw-ZNFBtvg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0174', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:14:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/frV0SoWYTXWfDw-ZNFBtvg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0174 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:14:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1VtZhajZ2P/Render', 09:14:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:14:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:14:11 INFO - 'HOME': '/Users/cltbld', 09:14:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:14:11 INFO - 'LOGNAME': 'cltbld', 09:14:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:14:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:14:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:14:11 INFO - 'MOZ_NO_REMOTE': '1', 09:14:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:14:11 INFO - 'NO_EM_RESTART': '1', 09:14:11 INFO - 'PAGER': '/bin/cat', 09:14:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:14:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:14:11 INFO - 'PWD': '/builds/slave/test', 09:14:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:14:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:14:11 INFO - 'SHELL': '/bin/bash', 09:14:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5DBc3Ljn3r/Listeners', 09:14:11 INFO - 'TMPDIR': '/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/', 09:14:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:14:11 INFO - 'USER': 'cltbld', 09:14:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:14:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:14:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:14:11 INFO - 'XPC_FLAGS': '0x0', 09:14:11 INFO - 'XPC_SERVICE_NAME': '0', 09:14:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:14:11 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/frV0SoWYTXWfDw-ZNFBtvg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0174', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:14:11 INFO - mozversion application_buildid: 20160428071449 09:14:11 INFO - mozversion application_changeset: 53765e008b97622b5c7e26d22b2db016b0e53dc1 09:14:11 INFO - mozversion application_display_name: Nightly 09:14:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:14:11 INFO - mozversion application_name: Firefox 09:14:11 INFO - mozversion application_remotingname: firefox 09:14:11 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:14:12 INFO - mozversion application_vendor: Mozilla 09:14:12 INFO - mozversion application_version: 49.0a1 09:14:12 INFO - mozversion platform_buildid: 20160428071449 09:14:12 INFO - mozversion platform_changeset: 53765e008b97622b5c7e26d22b2db016b0e53dc1 09:14:12 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:14:12 INFO - mozversion platform_version: 49.0a1 09:14:12 INFO - using testdate: 1461860051 09:14:12 INFO - actual date: 1461860051 09:14:12 INFO - starting webserver on 'localhost:49275' 09:14:12 INFO - SUITE-START | Running 2 tests 09:14:12 INFO - TEST-START | dromaeo_css 09:14:12 INFO - operating with platform_type : mac_ 09:14:12 INFO - Initialising browser for dromaeo_css test... 09:14:12 INFO - TEST-INFO | started process 1886 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/tmpTAzs1T/profile http://localhost:49275/getInfo.html) 09:14:14 INFO - PROCESS | 1886 | __metrics Screen width/height:1600/1200 09:14:14 INFO - PROCESS | 1886 | colorDepth:24 09:14:14 INFO - PROCESS | 1886 | Browser inner width/height: 1024/689 09:14:14 INFO - PROCESS | 1886 | __metrics 09:14:14 ERROR - PROCESS | 1886 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 09:14:15 INFO - TEST-INFO | 1886: exit 0 09:14:15 INFO - Browser initialized. 09:14:15 INFO - Running cycle 1/1 for dromaeo_css test... 09:14:15 INFO - TEST-INFO | started process 1889 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/tmpTAzs1T/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:14:27 INFO - PROCESS | 1889 | RSS: Main: 249823232 09:14:27 INFO - PROCESS | 1889 | 09:15:17 INFO - PROCESS | 1889 | 1461860117244 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] 09:17:28 INFO - PROCESS | 1889 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 09:17:29 INFO - PROCESS | 1889 | RSS: Main: 262082560 09:17:29 INFO - PROCESS | 1889 | 09:20:32 INFO - PROCESS | 1889 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 09:20:32 INFO - PROCESS | 1889 | RSS: Main: 273719296 09:20:32 INFO - PROCESS | 1889 | 09:23:34 INFO - PROCESS | 1889 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 09:23:35 INFO - PROCESS | 1889 | RSS: Main: 275812352 09:23:35 INFO - PROCESS | 1889 | 09:26:37 INFO - PROCESS | 1889 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 09:26:37 INFO - PROCESS | 1889 | RSS: Main: 277909504 09:26:37 INFO - PROCESS | 1889 | 09:29:40 INFO - PROCESS | 1889 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 09:29:40 INFO - PROCESS | 1889 | RSS: Main: 280223744 09:29:40 INFO - PROCESS | 1889 | 09:32:43 INFO - PROCESS | 1889 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 09:32:43 INFO - PROCESS | 1889 | RSS: Main: 281436160 09:32:43 INFO - PROCESS | 1889 | 09:32:43 INFO - PROCESS | 1889 | __start_tp_report 09:32:43 INFO - PROCESS | 1889 | _x_x_mozilla_page_load 09:32:43 INFO - PROCESS | 1889 | _x_x_mozilla_page_load_details 09:32:43 INFO - PROCESS | 1889 | |i|pagename|runs| 09:32:43 INFO - PROCESS | 1889 | |0;dojo.html;3428.7771294865834;3455.8617655293788;3493.881957773512;3515.3848076586605;3541.150123970248;5757.856053598657;5786.363500014995;5788.334341550723;5803.3326167490695;5814.651120932747;5713.285839270091;5797.101449275358;5857.560682948774;5895.911561326584;5901.822945311636;4556.886077848064;5188.922166167501;5267.841964741045;5296.523312901838;5338.599605029619;5227.26818245446;5350.8127215547465;5407.324084489439;5409.404965453797;5416.891662166754;5087.898242035176;5217.217417387422;5278.604104692145;5284.260203571503;5325.334333208349;4635.6523260755575;5238.711870847102;5241.292425522543;5272.472752724697;5286.97356513215;5236.581073514109;5246.291750613656;5254.369475662907;5254.553362964152;5276.366835979707;5429.4570542945785;5616.887662246773;5652.689102099383;5797.594168408213;5798.82603521895;6199.690015499252;6223.25320961483;6248.31268560458;6265.216847894013;6265.373462653743;5363.302770639792;6281.403266689657;6300.968495157541;6304.653244071575;6306.747730090791;5778.653280803123;5924.6149000314845;5996.67018313997;6023.638581685069;6045.8488537786025;6283.748650053946;6318.905216421757;6325.14610527582;6335.936640633627;6369.331220221903;6113.602615829956;6223.751049958041;6232.937670623236;6235.282942461675;6235.345288744707;6214;6223.906641400383;6273.7176827042895;6292.307846136921;6309.369063093745;6133.08984587446;6178.66017369049;6187.3503282155625;6245.156903817927;6264.436200741955;6186.721597528032;6216.7824126155365;6222.408871157233;6248.968755156286;6266.185395898504;4886.706797592155;4950.851474455772;5000.62495312853;5001.374828146481;5046.899062018739;4435.82256709728;4456.729831997865;4472.1502914446155;4494.685372023927;4523.479799822997;5746.454086861741;5874.823755287348;5920.940790592039;5936.5547583931375;5940;6221.844453888689;6243.157173781481;6284.183056202664;6284.465820405317;6313.905291420632;5868.38381969887;5926.61477004002;5951.791687290924;5956.642601443928;6020.939790602126;4273.017206042566;4976.278439626234;5065.39215294167;5501.064818980783;5505.862353441196;6306.621602703853;6369.5541312108635;6411.230652321751;6414.10202571631;6425.164728585254;6232.968835155795;6275.780347687809;6282.905756413749;6327.778527751507;6385.97824348102;5283.735813209401;6395.42441180296;6422.775202867877;6484.1246431731115;6506.934930650822;6241.21984751906;6244.781432649836;6307.274663413583;6310.716017779303;6314.873702526015;6280.466160376236;6287.1512345832725;6297.370262973666;6300.684965751786;6301.4643755279485;6055.72749226277;6107.84730381744;6110.862540392742;6203.069539569101;6262.843428914131;6094.695265236632;6136.764169924414;6241.750329986931;6250.3749625037135;6256.687165641791;5648.293963254593;6064.241969753781;6081.057436097412;6096.055111457731;6113.174721412553;6099.249254464657;6107.480864126599;6178.104174894705;6244.344343844013;6265.906011409924;6068.089786532056;6135.447809696971;6165.290991535851;6167.599106058092;6176.949918513774 09:32:43 INFO - PROCESS | 1889 | |1;ext.html;509.28699820251666;540.7999040355068;557.5567423898001;564.3848205456052;610.543511492181;35419;36074.45888311674;36234.456483152775;36281.81859090704;36320.54717811284;37879.05302367442;38449.80775096128;38455.80772096143;38462.8076859616;38718.612813871856;9180.678676246349;9348.345615806897;9415.340926135174;9440.386374885646;9444.81110377792;9298.767530811716;9306.953465232664;9312.441253524776;9331.6733914313;9444.244460443148;8096.757097287091;8540.615672294762;9413.717588472322;9415.105564971316;9480.81038379232;9371.625134994627;9374;9430.056994300583;9443.622255109787;9464.95267523665;9308.162265396079;9315;9414.576344064533;9432.434053956784;9449.952750236205;36457.81771091154;36718.8164059178;36831.81584092062;37228.44157337642;37436.625633743584;2681.6513853199153;3006.2033561106323;3044.756419486436;3342.8495717692517;3500.177458297298;25269.24192274235;25601;25893.834777435062;25999.74000259992;26561.07036253741;2613.63409122723;2629.815912886099;2648.8543130127837;2804.5232310507263;2847.2170153207826;266963;268349;269555;270901.64549177326;271079;32212.677873220968;32833;32884.50673239903;55724.72137639286;55933.72033139808;34309;34360.96917092491;34378.140546486036;34621.82689086589;34639.30721385609;14800;14980.400783968737;14982.700345993018;15024.399024038916;15027.196736391703;12784.552540661032;12833.871661283454;12853.742925141445;12855.357232138356;12857.935710321388;8014.519128852287;8052.677892884336;8112.918870811334;8136.959315203386;8145;3368.79787212768;3420.401429749784;3429.9657003430148;3430.365382404263;3455.6026057003264;981.7790997025755;1042.4512067993835;1055.7888422315568;1061.766411389493;1119.7088756923258;28893.42213155725;28945.565816512768;29032.709672903424;29592.3724195167;29723.554146687817;1018.8369860822232;1064.7923654887222;1083.5990683447176;1178.8762179971152;1186.2882270637722;14887.702245955237;14965.326560304813;15153.393864245527;15180.772288415683;15275.541733748005;340660;400514;408711;409083;438628;56290.718546407006;56667.14999275014;57653;57733.42266577364;57800.42199578034;1238.7955987262;1465.4065103632834;1473.506375364265;1481.933313000896;1482.6145202247494;23267;26763.598546021436;29191.562126567693;29828.254293642833;30039.399212016073;50359.74820125876;50487.49512504975;51091.489085108675;51737.22394164015;52613.68465788233;467772;470155;471656;482005.5899720479;483489;39867.80066099651;40644;41230;41246.793766030976;41345;43681.78159109311;43691;43876.56123438725;43930.780346098065;44425;29945.550816738203;36486.63513364832;36744.0813979642;37453;37540.62459375481;36053.738119165704;36100.09749756277;36455.45364724233;36756.44865327076;36776.26447471089;29572.556411654274;34198.31603367969;34809.65190348165;35966.46050309195;36919.077023074635;30666;30961.845190773904;30981.30678455876;32113.35773284568;32118.197045073124;23877.403064923517;24175;24305.961754780652;24358.391040224134;24395 09:32:43 INFO - PROCESS | 1889 | |2;jquery.html;349.75517138003386;393.20768651167884;400.2315554136059;402.863026570966;414.92323920074807;18264.817351826478;19064.71402928957;20590.38228853133;20688.27591034314;20699.689504657425;11936.985356244708;12242.387880605957;12248.571300004502;12268.754624907497;12635.684107897307;5185.766640501187;5361.5978801589845;5398.514133727964;5400.108982017982;5405.324334458192;3898.922021559567;5612;5953.821385358447;5966.433188847052;5967.8209653710455;5663.603547751659;5888.764449422018;5925.111233315016;5937.109433584975;5937.109433584975;5816.040353341715;5898.587098903078;5931.406859314077;5949.226600541945;5959.404059594028;5345.599080069011;5744.856378590527;5749.568782341341;5774.624649397776;5816.807554451328;13308.602596727302;13359.599212023655;13460.865391346058;13612.638736126408;23837.880810595838;2090.320645790109;2197.0772275644267;2305.5273668897835;2307.9538409231723;2328.6623439601162;9503.762405939839;9994.600215991351;10000.349767404667;10006.19950403966;10286.279960402775;1689.8086848771586;1777.7066783980756;1778.5286898971783;1793.4709260768104;1803.7114061750187;117185;123399.38300308531;123405;126336.36831815782;126593.99999999908;9222.584983675752;10699.823019467853;10721.83917241242;11539.538418463168;11545.942270288762;14816.481423150139;14817;14959.102453852804;14971;14971.251437428304;8575.699850505202;8741.125887411208;8797.900262467192;8885.733427997171;8891.02198758136;8576.957115214384;8688.826223475495;8740.650373985096;8830.690925817695;8894.86657700135;3715.888523344304;4580.351073613497;4643.8374656886845;4696.624270058387;4725.669203155746;1823.1886810369258;1866.6733321668653;2002.5193953451067;2003.0785838514446;2005.809448102457;784.9254320839511;914.4604683236789;970.5632465390461;1055.4353420919772;1057.6039627691528;15241.466548670744;17872.195751191226;17924;17928.92426454417;19291.61416771657;957.7270477913901;972.6936015155203;1021.901455934873;1022.3610243597751;1075.989240107589;10338.483075846178;10464.843027354596;10465.581376744996;10487.423191724529;10505.159587233156;521352;522372;530368;531133;644934;93136.53431732798;107575;122287;124074;127222;883.5317281840506;916.4272329793879;975.6243846119153;983.7638966647953;1015.2182819229153;17329.87355821918;17361.045142516778;17690;17749.112544372987;17854.464366069038;49305.50694493153;51621.48378516167;52989.67525811731;53642;53845.730771345894;49990.500094998584;71930;72320.27679723136;72346;73184.63407683141;7592.39260859141;7880.369570434237;7991.320737737125;8077.5961201940845;8086.797830054061;11021.559137634405;12211.938940305241;12227.327496987751;12298.262104273772;12354.196977196454;10527.842082368923;10652.520636571528;10808.243422960317;10852;10854.457277135954;10338.328008679411;10479.68560943173;10611.469426528489;10813.62152324665;10846.837297440386;8823.514706691194;10724.785504289714;10767.515461804081;11141.053010494199;11335.263207891461;7021.192562855337;7894.960525197337;7909;8115.904352912281;8348.958255208685;9705.951470242604;10753.838692419777;11009.61466348674;11058.723531911766;11070.667879963614 09:32:43 INFO - PROCESS | 1889 | |3;mootools.html;1605.1733357320982;1630.494546690526;1896.1277812206383;1922.3079691311134;1960.0591715976332;7921.287084162424;8447.57762111895;8630.95684521578;8894.377393582443;8914.866277005842;8589.613467393967;8655.956720216405;8747.950245970476;8770;8777.078406767278;2388.880555972203;2433.4038160650666;2435.512897420514;2437.025189924027;2440.2923152285816;2424.0788500369836;2437.463757973252;2437.549053425113;2438.670779444779;2461.2616215135386;2438.402591365118;2440.707115146185;2443.853368797878;2449.0938352809485;2449.620308852122;2411.5056413435204;2424.4787370715294;2425.987870060638;2446.2905757330354;2448.779609835123;2091.602595506864;2376.6316220985773;2378.203301893868;2386.033656369173;2410.517896420723;8694.39139260252;8724.258438032775;8751.124887511261;8813.779655508599;8813.986391564988;4683.250679891201;5236.528712415863;5331.466853314676;5500.614956953015;5511;2806.985965070182;3072.877084916601;3082.2602575381748;3582.731295152848;3689.612590677967;918.0956757593765;919.0166521821653;972.4748635736694;991.4002028772633;1050.1598721023151;11608.4195790211;11682.240654357525;11718.06255499558;11741.530338786439;11847.229930054518;4849.854504364875;4902.926456103161;5084.466131056186;5104.259882317043;5129;9109.77225569366;9111.635534578676;9180.44917304964;9192.402493837879;9202.493862837608;5164.638475306692;5174.767135478837;5223.059849227176;5224.242484839677;5227.477252274818;4201.928508230443;4424.84513042042;4845.6608037437045;4848.733319667452;4991.201407774739;3777.9433308500393;3791;3867.41988701697;3907.163633092499;3932.6264004919485;1217.8234156047324;1232.3591732299153;1236.3014896583582;1237.7029512917015;1258.213616489694;914.6798620482778;922.9123233292827;936.5879013234155;945.0691069296737;974.2157563161581;5936.643801371932;5982.521398288127;6007;6150.10823430609;6282.18331616896;835.0021724039835;949.5821838391086;979.7991411760572;1005.6580762540772;1030.3663247103127;3896.2402331545077;3949.960500395017;3952.1898010907694;3987.0829709166474;4036.1927614476635;10177.44024078668;10585.523651435704;10645.78708425827;10688.305260158066;10700.357978521159;10769;10791.298565593212;10803.729906752395;10835.566577336818;10858.945705271422;7853.489523180975;8062.274395304451;8226.300764434971;8260.793480163044;8273.841662167302;5823.4467725566;5847.152162936265;5952.732127054206;5991.490723288569;6229.937700623117;9823.557939892866;10038.891832715211;10065.194784417376;10083.294169408364;10091.142252908585;10041.949790251294;10120.544575493974;10299.845502317321;10446.216533759847;10524.842127367943;6901.413379862567;7406.18531961473;7554.395648348229;7696;7726.995490586188;8026.95986520087;8040.638171282423;8047.919520804717;8084;8109.310708589836;7294.161171465137;7328.560286382834;7517.1731109579;7582.962085189539;7597.6201189941385;6812.62530560804;6856.142982127234;6865.141857267841;6894.413974812197;6897.655117244218;6913.343232392915;6964.4080253179045;6982.022516847544;7059.5058345915295;7112.6088065154845;4906.754662266943;4932.901341973212;4958.975205124096;4964.826231081895;5156.1492353761205;4274.230638484978;4979.477154898684;5057.443681195139;5081.745912704276;5315.893682126413 09:32:43 INFO - PROCESS | 1889 | |4;prototype.html;269.8826010685353;272.75179586576206;299.33696861451887;323.750711951797;326.77452557735177;5033.421156566991;5243.947560524393;5360.758765855536;5419;5546.445355464457;5399.271098401715;5452.536534394581;5482;5487.341519017714;5490.56075513959;3499.1077275294892;3651.707863370937;4138.234426631067;4191.072106830861;4191.098913733546;3889.9611003890022;4160.979195104021;4189.874303770892;4209.6000879916355;4219.523166891594;4061.7562946223175;4072.9389059164137;4078.4086307485395;4095.7133000689964;4106.630403263705;4083.570750237423;4097.569755175723;4111.403846442279;4125.731827431207;4150.584941505856;3393.253484233465;3523.119220194951;3531.94702079469;3535.5327539071254;3567.839447224855;4246.830126794925;4471.6199123074575;5135.3837539495125;5138.383393992707;5172.974135129338;3740.607236240183;4692.483826779052;4735.129623798614;4739.668223224376;4821.590164835993;5007.399112106534;5462.825492519128;5558.416366281523;5698.715064246771;5756.309242890838;3216.115568218735;3735.962640373589;3911.882643520699;3924.2151569685893;3938.6652134568594;5999.730012149464;6020.367861374536;6042.788502402394;6056.273247210319;6085.847853803646;3163.9525407118913;3681.7606855554304;3781.333808350439;3970.68234541236;3990.9424002639325;3384.5123833982;3405.7275417966503;3839.9232015359535;4034.536028356718;4041.747058411902;4655.348251244828;4697.389339385858;4707.1171531387035;4714.5992590630185;4721.929171062437;5454.863628409322;5723.055695810228;5815.098659707752;5821.53427725781;5879.235699359056;1366.5285476510587;1412.413848252984;1459.6788706484556;1473.8651238546474;1474.2555009720022;942.3686130292721;954.8281309364243;966.1304825656965;973.2943615878431;974.5224839828433;5529.599296432665;5812.587776072967;5825.446582574648;5846.450690567003;5866.70666466675;5663;5676.460736230051;6042.425969532887;6437.935620643827;6501.47988160946;5138.61257460491;5595.328560572757;5860.179574859523;5863.941360586425;5880.441358070976;2974.9553756693667;2980.7764417668764;3042.81743095415;3330.385647266884;3377.7973321600784;5135.35808023997;5142;5681.687507187062;6170.753169873245;6466.738985897799;5756.913646295309;6365.2998170201245;6565.934340656532;6626.370494802986;6627.569208001464;5022.447530771612;5653.773849045992;5837.67892765902;5940.732667029994;6615.908375118148;5058.443571207238;5671.347795003628;6023.668698221464;6023.789167379121;6406.391392817675;5144.614153938544;5690.231818704588;5916.615419997686;5962.314333851663;5969.895654259847;5792.884142317214;5954.702264886651;6187.535934804818;6273.341299163704;6324.620522768649;3228.7417006638943;3323.8338083096232;3522.9119272018406;3923.6272554107313;3932.154586763745;4928.91942535915;5604.327480702342;6019.367966363644;6029.031259053593;6030.7286172121485;5653.773849045992;5822.467226756375;5828.795992140254;5852.946469635506;6040.6979651018155;4692.343071969859;5484.478974497416;5825.019493372104;5853.09277062056;5892.734826932884;4429.955700442954;5544.168374743821;5684.498820518958;5719.399463056319;5749.597528172988;4870.561649451566;4991.675541089818;5023.497650235094;5615.241942337895;5916.556258280732;4845.854624361275;5067.873303167303;5124.666896651705;5333.839984800462;5765.971170144123 09:32:43 INFO - PROCESS | 1889 | |5;yui.html;661.7187695229526;700.5831530239506;735.4079965627668;738.4609235258259;780.8555417247813;13932.582022539327;14328.4985025524;14339.64150896228;14373.784393234097;16306;15979.280932358042;16220.51338459848;16301.429449969253;16383.754243686355;16457.83542164578;712.1454254894147;806.5725165662209;893.5800173918265;911.8267529169436;912.6668765900432;894.8434024045798;904.1817155474306;904.6336233825311;904.6924045824418;908.2325435007441;832.3341326938455;898.0301274623391;945.7210123013693;956.7655924298557;956.8947415784259;907.3920473282883;933.5099072986668;943.2124176312752;948.8197242523898;950.7575568230126;673.5554534007531;680.4352387518397;697.3793323941696;701.7263267325746;704.7815177295055;15167.469138580096;15297.46458873934;15493.380264789395;15617.609559760984;15674.529764107096;12342.506299748;12524.436400362005;12537.561185358469;12545.74908501834;12761.553345632858;9955.850662240073;9987.300888937778;10179.491025448699;10478.266521343508;10574.62988795396;3422.161570415264;3442.845071971767;3453.9827300863335;3504.929901401983;3567.6075631680496;72158.19604509877;72434.99999999948;72641.27358726398;74236.62881685558;74380;9296.39573427725;9327.207187389013;9434.669786557575;9543.904560954441;9659;21113.78886211149;21114.261000865205;21165.835879026494;21182;21785;7383.114026316876;7391.371733402613;7502.474826762074;7546.735864244725;7706.229377062249;3540.6990405815795;3579.7673151245085;3611.494390785292;3628.600853906068;3674.559052913614;3539.610642829287;3593.4969104325414;3644.2347107107803;3646.288973650113;3650.3064417760543;630.8580569371946;646.424682032991;651.2185377546963;655.1025095619033;697.5152269172876;519.7738983542172;548.2434240747822;566.6709822628992;583.4632138432652;596.0850095103995;8201.097879233279;8290.834183316421;8308.815462714567;8442.577871106543;8455.281301089353;555.6747158028052;587.6591576885396;590.1383979390052;610.716017052073;614.8032629558588;3799.0104671688587;3917.9608203917596;3938.86213982515;3951.6641085508054;4010.6791456683395;91515.54242228746;99520.50239748755;103092.48453757833;106044;108002;69509;84727.57636211779;88082;88367;90841;66341;66757.66621166863;66789;66912.6654366725;67671.66164169247;7648.579328137006;7729.768106956801;7762.650680719494;7801.102873169658;7828.099768526692;48094;49350.50649493459;49642.50357496379;49813.501864980884;49830.75084624554;63297.36702632915;67521.66239168773;67584.6620766893;71260.64369678119;71651.92522112277;10230.437325946847;10268.794624107326;10279.640212592523;10383.169346452416;10639.840402393815;11243.49404276826;11711.414429278673;11820.76358472843;12013.099017573542;12563.309018004098;11000.22998390105;11058.11535077208;11487.25332853362;11564.479598417924;11795.705107372385;9420.62317507292;11454.599089031844;11510.53957841677;11589.24669896454;11850.644480665595;11079.390633515233;11449.370284634426;11552.191346605992;11659.008984236434;11805.704857378634;9343.252539796937;9482.715518534455;9535.856962145435;9836.655717049869;10585.417802020962;8524.829503409774;9432.764180895532;9804.417091577563;9941.552630131517;10142.49287535635 09:32:43 INFO - PROCESS | 1889 | __end_tp_report 09:32:43 INFO - PROCESS | 1889 | __start_cc_report 09:32:43 INFO - PROCESS | 1889 | _x_x_mozilla_cycle_collect,412 09:32:43 INFO - PROCESS | 1889 | __end_cc_report 09:32:43 INFO - PROCESS | 1889 | __startTimestamp1461861163900__endTimestamp 09:32:43 INFO - PROCESS | 1889 | 09:32:43 INFO - PROCESS | 1889 | ------- Summary: start ------- 09:32:43 INFO - PROCESS | 1889 | Number of tests: 6 09:32:43 INFO - PROCESS | 1889 | 09:32:43 INFO - PROCESS | 1889 | [#0] dojo.html Cycles:165 Average:5793.54 Median:6094.70 stddev:659.35 (10.8%) stddev-sans-first:634.74 09:32:43 INFO - PROCESS | 1889 | Values: 3428.8 3455.9 3493.9 3515.4 3541.2 5757.9 5786.4 5788.3 5803.3 5814.7 5713.3 5797.1 5857.6 5895.9 5901.8 4556.9 5188.9 5267.8 5296.5 5338.6 5227.3 5350.8 5407.3 5409.4 5416.9 5087.9 5217.2 5278.6 5284.3 5325.3 4635.7 5238.7 5241.3 5272.5 5287.0 5236.6 5246.3 5254.4 5254.6 5276.4 5429.5 5616.9 5652.7 5797.6 5798.8 6199.7 6223.3 6248.3 6265.2 6265.4 5363.3 6281.4 6301.0 6304.7 6306.7 5778.7 5924.6 5996.7 6023.6 6045.8 6283.7 6318.9 6325.1 6335.9 6369.3 6113.6 6223.8 6232.9 6235.3 6235.3 6214.0 6223.9 6273.7 6292.3 6309.4 6133.1 6178.7 6187.4 6245.2 6264.4 6186.7 6216.8 6222.4 6249.0 6266.2 4886.7 4950.9 5000.6 5001.4 5046.9 4435.8 4456.7 4472.2 4494.7 4523.5 5746.5 5874.8 5920.9 5936.6 5940.0 6221.8 6243.2 6284.2 6284.5 6313.9 5868.4 5926.6 5951.8 5956.6 6020.9 4273.0 4976.3 5065.4 5501.1 5505.9 6306.6 6369.6 6411.2 6414.1 6425.2 6233.0 6275.8 6282.9 6327.8 6386.0 5283.7 6395.4 6422.8 6484.1 6506.9 6241.2 6244.8 6307.3 6310.7 6314.9 6280.5 6287.2 6297.4 6300.7 6301.5 6055.7 6107.8 6110.9 6203.1 6262.8 6094.7 6136.8 6241.8 6250.4 6256.7 5648.3 6064.2 6081.1 6096.1 6113.2 6099.2 6107.5 6178.1 6244.3 6265.9 6068.1 6135.4 6165.3 6167.6 6176.9 09:32:43 INFO - PROCESS | 1889 | 09:32:43 INFO - PROCESS | 1889 | [#1] ext.html Cycles:180 Average:52241.32 Median:36087.28 stddev:103860.09 (287.8%) stddev-sans-first:104078.82 09:32:43 INFO - PROCESS | 1889 | Values: 509.3 540.8 557.6 564.4 610.5 35419.0 36074.5 36234.5 36281.8 36320.5 37879.1 38449.8 38455.8 38462.8 38718.6 9180.7 9348.3 9415.3 9440.4 9444.8 9298.8 9307.0 9312.4 9331.7 9444.2 8096.8 8540.6 9413.7 9415.1 9480.8 9371.6 9374.0 9430.1 9443.6 9465.0 9308.2 9315.0 9414.6 9432.4 9450.0 36457.8 36718.8 36831.8 37228.4 37436.6 2681.7 3006.2 3044.8 3342.8 3500.2 25269.2 25601.0 25893.8 25999.7 26561.1 2613.6 2629.8 2648.9 2804.5 2847.2 266963.0 268349.0 269555.0 270901.6 271079.0 32212.7 32833.0 32884.5 55724.7 55933.7 34309.0 34361.0 34378.1 34621.8 34639.3 14800.0 14980.4 14982.7 15024.4 15027.2 12784.6 12833.9 12853.7 12855.4 12857.9 8014.5 8052.7 8112.9 8137.0 8145.0 3368.8 3420.4 3430.0 3430.4 3455.6 981.8 1042.5 1055.8 1061.8 1119.7 28893.4 28945.6 29032.7 29592.4 29723.6 1018.8 1064.8 1083.6 1178.9 1186.3 14887.7 14965.3 15153.4 15180.8 15275.5 340660.0 400514.0 408711.0 409083.0 438628.0 56290.7 56667.1 57653.0 57733.4 57800.4 1238.8 1465.4 1473.5 1481.9 1482.6 23267.0 26763.6 29191.6 29828.3 30039.4 50359.7 50487.5 51091.5 51737.2 52613.7 467772.0 470155.0 471656.0 482005.6 483489.0 39867.8 40644.0 41230.0 41246.8 41345.0 43681.8 43691.0 43876.6 43930.8 44425.0 29945.6 36486.6 36744.1 37453.0 37540.6 36053.7 36100.1 36455.5 36756.4 36776.3 29572.6 34198.3 34809.7 35966.5 36919.1 30666.0 30961.8 30981.3 32113.4 32118.2 23877.4 24175.0 24306.0 24358.4 24395.0 09:32:43 INFO - PROCESS | 1889 | 09:32:43 INFO - PROCESS | 1889 | [#2] jquery.html Cycles:180 Average:32429.74 Median:2251.30 stddev:92455.14 (4106.7%) stddev-sans-first:92683.12 09:32:43 INFO - PROCESS | 1889 | Values: 349.8 393.2 400.2 402.9 414.9 18264.8 19064.7 20590.4 20688.3 20699.7 11937.0 12242.4 12248.6 12268.8 12635.7 5185.8 5361.6 5398.5 5400.1 5405.3 3898.9 5612.0 5953.8 5966.4 5967.8 5663.6 5888.8 5925.1 5937.1 5937.1 5816.0 5898.6 5931.4 5949.2 5959.4 5345.6 5744.9 5749.6 5774.6 5816.8 13308.6 13359.6 13460.9 13612.6 23837.9 2090.3 2197.1 2305.5 2308.0 2328.7 9503.8 9994.6 10000.3 10006.2 10286.3 1689.8 1777.7 1778.5 1793.5 1803.7 117185.0 123399.4 123405.0 126336.4 126594.0 9222.6 10699.8 10721.8 11539.5 11545.9 14816.5 14817.0 14959.1 14971.0 14971.3 8575.7 8741.1 8797.9 8885.7 8891.0 8577.0 8688.8 8740.7 8830.7 8894.9 3715.9 4580.4 4643.8 4696.6 4725.7 1823.2 1866.7 2002.5 2003.1 2005.8 784.9 914.5 970.6 1055.4 1057.6 15241.5 17872.2 17924.0 17928.9 19291.6 957.7 972.7 1021.9 1022.4 1076.0 10338.5 10464.8 10465.6 10487.4 10505.2 521352.0 522372.0 530368.0 531133.0 644934.0 93136.5 107575.0 122287.0 124074.0 127222.0 883.5 916.4 975.6 983.8 1015.2 17329.9 17361.0 17690.0 17749.1 17854.5 49305.5 51621.5 52989.7 53642.0 53845.7 49990.5 71930.0 72320.3 72346.0 73184.6 7592.4 7880.4 7991.3 8077.6 8086.8 11021.6 12211.9 12227.3 12298.3 12354.2 10527.8 10652.5 10808.2 10852.0 10854.5 10338.3 10479.7 10611.5 10813.6 10846.8 8823.5 10724.8 10767.5 11141.1 11335.3 7021.2 7895.0 7909.0 8115.9 8349.0 9706.0 10753.8 11009.6 11058.7 11070.7 09:32:43 INFO - PROCESS | 1889 | 09:32:43 INFO - PROCESS | 1889 | [#3] mootools.html Cycles:180 Average:5686.68 Median:5024.32 stddev:3163.37 (63.0%) stddev-sans-first:3157.37 09:32:43 INFO - PROCESS | 1889 | Values: 1605.2 1630.5 1896.1 1922.3 1960.1 7921.3 8447.6 8631.0 8894.4 8914.9 8589.6 8656.0 8748.0 8770.0 8777.1 2388.9 2433.4 2435.5 2437.0 2440.3 2424.1 2437.5 2437.5 2438.7 2461.3 2438.4 2440.7 2443.9 2449.1 2449.6 2411.5 2424.5 2426.0 2446.3 2448.8 2091.6 2376.6 2378.2 2386.0 2410.5 8694.4 8724.3 8751.1 8813.8 8814.0 4683.3 5236.5 5331.5 5500.6 5511.0 2807.0 3072.9 3082.3 3582.7 3689.6 918.1 919.0 972.5 991.4 1050.2 11608.4 11682.2 11718.1 11741.5 11847.2 4849.9 4902.9 5084.5 5104.3 5129.0 9109.8 9111.6 9180.4 9192.4 9202.5 5164.6 5174.8 5223.1 5224.2 5227.5 4201.9 4424.8 4845.7 4848.7 4991.2 3777.9 3791.0 3867.4 3907.2 3932.6 1217.8 1232.4 1236.3 1237.7 1258.2 914.7 922.9 936.6 945.1 974.2 5936.6 5982.5 6007.0 6150.1 6282.2 835.0 949.6 979.8 1005.7 1030.4 3896.2 3950.0 3952.2 3987.1 4036.2 10177.4 10585.5 10645.8 10688.3 10700.4 10769.0 10791.3 10803.7 10835.6 10858.9 7853.5 8062.3 8226.3 8260.8 8273.8 5823.4 5847.2 5952.7 5991.5 6229.9 9823.6 10038.9 10065.2 10083.3 10091.1 10041.9 10120.5 10299.8 10446.2 10524.8 6901.4 7406.2 7554.4 7696.0 7727.0 8027.0 8040.6 8047.9 8084.0 8109.3 7294.2 7328.6 7517.2 7583.0 7597.6 6812.6 6856.1 6865.1 6894.4 6897.7 6913.3 6964.4 6982.0 7059.5 7112.6 4906.8 4932.9 4959.0 4964.8 5156.1 4274.2 4979.5 5057.4 5081.7 5315.9 09:32:43 INFO - PROCESS | 1889 | 09:32:43 INFO - PROCESS | 1889 | [#4] prototype.html Cycles:180 Average:4686.16 Median:5158.79 stddev:1482.96 (28.7%) stddev-sans-first:1449.60 09:32:43 INFO - PROCESS | 1889 | Values: 269.9 272.8 299.3 323.8 326.8 5033.4 5243.9 5360.8 5419.0 5546.4 5399.3 5452.5 5482.0 5487.3 5490.6 3499.1 3651.7 4138.2 4191.1 4191.1 3890.0 4161.0 4189.9 4209.6 4219.5 4061.8 4072.9 4078.4 4095.7 4106.6 4083.6 4097.6 4111.4 4125.7 4150.6 3393.3 3523.1 3531.9 3535.5 3567.8 4246.8 4471.6 5135.4 5138.4 5173.0 3740.6 4692.5 4735.1 4739.7 4821.6 5007.4 5462.8 5558.4 5698.7 5756.3 3216.1 3736.0 3911.9 3924.2 3938.7 5999.7 6020.4 6042.8 6056.3 6085.8 3164.0 3681.8 3781.3 3970.7 3990.9 3384.5 3405.7 3839.9 4034.5 4041.7 4655.3 4697.4 4707.1 4714.6 4721.9 5454.9 5723.1 5815.1 5821.5 5879.2 1366.5 1412.4 1459.7 1473.9 1474.3 942.4 954.8 966.1 973.3 974.5 5529.6 5812.6 5825.4 5846.5 5866.7 5663.0 5676.5 6042.4 6437.9 6501.5 5138.6 5595.3 5860.2 5863.9 5880.4 2975.0 2980.8 3042.8 3330.4 3377.8 5135.4 5142.0 5681.7 6170.8 6466.7 5756.9 6365.3 6565.9 6626.4 6627.6 5022.4 5653.8 5837.7 5940.7 6615.9 5058.4 5671.3 6023.7 6023.8 6406.4 5144.6 5690.2 5916.6 5962.3 5969.9 5792.9 5954.7 6187.5 6273.3 6324.6 3228.7 3323.8 3522.9 3923.6 3932.2 4928.9 5604.3 6019.4 6029.0 6030.7 5653.8 5822.5 5828.8 5852.9 6040.7 4692.3 5484.5 5825.0 5853.1 5892.7 4430.0 5544.2 5684.5 5719.4 5749.6 4870.6 4991.7 5023.5 5615.2 5916.6 4845.9 5067.9 5124.7 5333.8 5766.0 09:32:43 INFO - PROCESS | 1889 | 09:32:43 INFO - PROCESS | 1889 | [#5] yui.html Cycles:180 Average:18448.83 Median:612.76 stddev:26321.05 (4295.5%) stddev-sans-first:26361.00 09:32:43 INFO - PROCESS | 1889 | Values: 661.7 700.6 735.4 738.5 780.9 13932.6 14328.5 14339.6 14373.8 16306.0 15979.3 16220.5 16301.4 16383.8 16457.8 712.1 806.6 893.6 911.8 912.7 894.8 904.2 904.6 904.7 908.2 832.3 898.0 945.7 956.8 956.9 907.4 933.5 943.2 948.8 950.8 673.6 680.4 697.4 701.7 704.8 15167.5 15297.5 15493.4 15617.6 15674.5 12342.5 12524.4 12537.6 12545.7 12761.6 9955.9 9987.3 10179.5 10478.3 10574.6 3422.2 3442.8 3454.0 3504.9 3567.6 72158.2 72435.0 72641.3 74236.6 74380.0 9296.4 9327.2 9434.7 9543.9 9659.0 21113.8 21114.3 21165.8 21182.0 21785.0 7383.1 7391.4 7502.5 7546.7 7706.2 3540.7 3579.8 3611.5 3628.6 3674.6 3539.6 3593.5 3644.2 3646.3 3650.3 630.9 646.4 651.2 655.1 697.5 519.8 548.2 566.7 583.5 596.1 8201.1 8290.8 8308.8 8442.6 8455.3 555.7 587.7 590.1 610.7 614.8 3799.0 3918.0 3938.9 3951.7 4010.7 91515.5 99520.5 103092.5 106044.0 108002.0 69509.0 84727.6 88082.0 88367.0 90841.0 66341.0 66757.7 66789.0 66912.7 67671.7 7648.6 7729.8 7762.7 7801.1 7828.1 48094.0 49350.5 49642.5 49813.5 49830.8 63297.4 67521.7 67584.7 71260.6 71651.9 10230.4 10268.8 10279.6 10383.2 10639.8 11243.5 11711.4 11820.8 12013.1 12563.3 11000.2 11058.1 11487.3 11564.5 11795.7 9420.6 11454.6 11510.5 11589.2 11850.6 11079.4 11449.4 11552.2 11659.0 11805.7 9343.3 9482.7 9535.9 9836.7 10585.4 8524.8 9432.8 9804.4 9941.6 10142.5 09:32:43 INFO - PROCESS | 1889 | -------- Summary: end -------- 09:32:43 INFO - PROCESS | 1889 | 09:32:44 INFO - TEST-INFO | 1889: exit 0 09:32:44 INFO - TEST-OK | dromaeo_css | took 1112446ms 09:32:44 INFO - TEST-START | kraken 09:32:44 INFO - operating with platform_type : mac_ 09:32:44 INFO - Initialising browser for kraken test... 09:32:44 INFO - TEST-INFO | started process 1898 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/tmpCiKiDu/profile http://localhost:49275/getInfo.html) 09:32:47 INFO - PROCESS | 1898 | __metrics Screen width/height:1600/1200 09:32:47 INFO - PROCESS | 1898 | colorDepth:24 09:32:47 INFO - PROCESS | 1898 | Browser inner width/height: 1024/689 09:32:47 INFO - PROCESS | 1898 | __metrics 09:32:47 ERROR - PROCESS | 1898 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 09:32:47 INFO - TEST-INFO | 1898: exit 0 09:32:47 INFO - Browser initialized. 09:32:47 INFO - Running cycle 1/1 for kraken test... 09:32:47 INFO - TEST-INFO | started process 1901 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/tmpCiKiDu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:32:59 INFO - PROCESS | 1901 | RSS: Main: 250015744 09:32:59 INFO - PROCESS | 1901 | 09:33:02 INFO - PROCESS | 1901 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 09:33:03 INFO - PROCESS | 1901 | RSS: Main: 262090752 09:33:03 INFO - PROCESS | 1901 | 09:33:06 INFO - PROCESS | 1901 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 09:33:07 INFO - PROCESS | 1901 | RSS: Main: 271089664 09:33:07 INFO - PROCESS | 1901 | 09:33:10 INFO - PROCESS | 1901 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 09:33:11 INFO - PROCESS | 1901 | RSS: Main: 274022400 09:33:11 INFO - PROCESS | 1901 | 09:33:13 INFO - PROCESS | 1901 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 09:33:13 INFO - PROCESS | 1901 | RSS: Main: 276492288 09:33:13 INFO - PROCESS | 1901 | 09:33:15 INFO - PROCESS | 1901 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:33:16 INFO - PROCESS | 1901 | RSS: Main: 276807680 09:33:16 INFO - PROCESS | 1901 | 09:33:19 INFO - PROCESS | 1901 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 09:33:19 INFO - PROCESS | 1901 | RSS: Main: 277020672 09:33:19 INFO - PROCESS | 1901 | 09:33:22 INFO - PROCESS | 1901 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 09:33:23 INFO - PROCESS | 1901 | RSS: Main: 277839872 09:33:23 INFO - PROCESS | 1901 | 09:33:26 INFO - PROCESS | 1901 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 09:33:26 INFO - PROCESS | 1901 | RSS: Main: 277630976 09:33:26 INFO - PROCESS | 1901 | 09:33:28 INFO - PROCESS | 1901 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:33:29 INFO - PROCESS | 1901 | RSS: Main: 276770816 09:33:29 INFO - PROCESS | 1901 | 09:33:31 INFO - PROCESS | 1901 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:33:31 INFO - PROCESS | 1901 | RSS: Main: 276725760 09:33:31 INFO - PROCESS | 1901 | 09:33:34 INFO - PROCESS | 1901 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:33:35 INFO - PROCESS | 1901 | RSS: Main: 276877312 09:33:35 INFO - PROCESS | 1901 | 09:33:37 INFO - PROCESS | 1901 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:33:38 INFO - PROCESS | 1901 | RSS: Main: 276938752 09:33:38 INFO - PROCESS | 1901 | 09:33:41 INFO - PROCESS | 1901 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:33:42 INFO - PROCESS | 1901 | RSS: Main: 277528576 09:33:42 INFO - PROCESS | 1901 | 09:33:44 INFO - PROCESS | 1901 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 09:33:44 INFO - PROCESS | 1901 | RSS: Main: 278544384 09:33:44 INFO - PROCESS | 1901 | 09:33:44 INFO - PROCESS | 1901 | __start_tp_report 09:33:44 INFO - PROCESS | 1901 | _x_x_mozilla_page_load 09:33:44 INFO - PROCESS | 1901 | _x_x_mozilla_page_load_details 09:33:44 INFO - PROCESS | 1901 | |i|pagename|runs| 09:33:44 INFO - PROCESS | 1901 | |0;ai-astar;116;113;117;115;117;128;115;114;142;172 09:33:44 INFO - PROCESS | 1901 | |1;audio-beat-detection;160;152;144;167;132;167;154;148;205;149 09:33:44 INFO - PROCESS | 1901 | |2;audio-dft;195;216;261;222;263;206;201;196;196;193 09:33:44 INFO - PROCESS | 1901 | |3;audio-fft;68;78;74;156;47;66;44;57;143;50 09:33:44 INFO - PROCESS | 1901 | |4;audio-oscillator;80;87;136;76;79;79;122;82;82;120 09:33:44 INFO - PROCESS | 1901 | |5;imaging-gaussian-blur;89;86;88;87;94;131;88;88;89;73 09:33:44 INFO - PROCESS | 1901 | |6;imaging-darkroom;95;96;95;95;95;122;92;155;153;120 09:33:44 INFO - PROCESS | 1901 | |7;imaging-desaturate;95;89;92;90;93;89;91;92;91;126 09:33:44 INFO - PROCESS | 1901 | |8;json-parse-financial;49;57;84;89;89;53;82;95;94;73 09:33:44 INFO - PROCESS | 1901 | |9;json-stringify-tinderbox;56;55;77;59;94;71;66;74;99;99 09:33:44 INFO - PROCESS | 1901 | |10;stanford-crypto-aes;83;83;88;110;109;106;76;109;110;107 09:33:44 INFO - PROCESS | 1901 | |11;stanford-crypto-ccm;153;140;137;137;147;144;138;150;174;173 09:33:44 INFO - PROCESS | 1901 | |12;stanford-crypto-pbkdf2;205;200;202;201;198;211;253;267;201;224 09:33:44 INFO - PROCESS | 1901 | |13;stanford-crypto-sha256-iterative;72;72;73;70;97;80;68;70;71;68 09:33:44 INFO - PROCESS | 1901 | __end_tp_report 09:33:44 INFO - PROCESS | 1901 | __start_cc_report 09:33:44 INFO - PROCESS | 1901 | _x_x_mozilla_cycle_collect,1075 09:33:44 INFO - PROCESS | 1901 | __end_cc_report 09:33:44 INFO - PROCESS | 1901 | __startTimestamp1461861224538__endTimestamp 09:33:44 INFO - PROCESS | 1901 | 09:33:44 INFO - PROCESS | 1901 | ------- Summary: start ------- 09:33:44 INFO - PROCESS | 1901 | Number of tests: 14 09:33:44 INFO - PROCESS | 1901 | 09:33:44 INFO - PROCESS | 1901 | [#0] ai-astar Cycles:10 Average:124.90 Median:117.00 stddev:18.80 (16.1%) stddev-sans-first:19.66 09:33:44 INFO - PROCESS | 1901 | Values: 116.0 113.0 117.0 115.0 117.0 128.0 115.0 114.0 142.0 172.0 09:33:44 INFO - PROCESS | 1901 | 09:33:44 INFO - PROCESS | 1901 | [#1] audio-beat-detection Cycles:10 Average:157.80 Median:157.00 stddev:19.66 (12.5%) stddev-sans-first:20.84 09:33:44 INFO - PROCESS | 1901 | Values: 160.0 152.0 144.0 167.0 132.0 167.0 154.0 148.0 205.0 149.0 09:33:44 INFO - PROCESS | 1901 | 09:33:44 INFO - PROCESS | 1901 | [#2] audio-dft Cycles:10 Average:214.90 Median:211.00 stddev:26.57 (12.6%) stddev-sans-first:27.19 09:33:44 INFO - PROCESS | 1901 | Values: 195.0 216.0 261.0 222.0 263.0 206.0 201.0 196.0 196.0 193.0 09:33:44 INFO - PROCESS | 1901 | 09:33:44 INFO - PROCESS | 1901 | [#3] audio-fft Cycles:10 Average:78.30 Median:61.50 stddev:39.31 (63.9%) stddev-sans-first:41.52 09:33:44 INFO - PROCESS | 1901 | Values: 68.0 78.0 74.0 156.0 47.0 66.0 44.0 57.0 143.0 50.0 09:33:44 INFO - PROCESS | 1901 | 09:33:44 INFO - PROCESS | 1901 | [#4] audio-oscillator Cycles:10 Average:94.30 Median:79.50 stddev:22.44 (28.2%) stddev-sans-first:23.19 09:33:44 INFO - PROCESS | 1901 | Values: 80.0 87.0 136.0 76.0 79.0 79.0 122.0 82.0 82.0 120.0 09:33:44 INFO - PROCESS | 1901 | 09:33:44 INFO - PROCESS | 1901 | [#5] imaging-gaussian-blur Cycles:10 Average:91.30 Median:88.00 stddev:14.94 (17.0%) stddev-sans-first:15.82 09:33:44 INFO - PROCESS | 1901 | Values: 89.0 86.0 88.0 87.0 94.0 131.0 88.0 88.0 89.0 73.0 09:33:44 INFO - PROCESS | 1901 | 09:33:44 INFO - PROCESS | 1901 | [#6] imaging-darkroom Cycles:10 Average:111.80 Median:95.00 stddev:24.73 (26.0%) stddev-sans-first:25.48 09:33:44 INFO - PROCESS | 1901 | Values: 95.0 96.0 95.0 95.0 95.0 122.0 92.0 155.0 153.0 120.0 09:33:44 INFO - PROCESS | 1901 | 09:33:44 INFO - PROCESS | 1901 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:91.50 stddev:11.11 (12.1%) stddev-sans-first:11.79 09:33:44 INFO - PROCESS | 1901 | Values: 95.0 89.0 92.0 90.0 93.0 89.0 91.0 92.0 91.0 126.0 09:33:44 INFO - PROCESS | 1901 | 09:33:44 INFO - PROCESS | 1901 | [#8] json-parse-financial Cycles:10 Average:76.50 Median:86.50 stddev:17.48 (20.2%) stddev-sans-first:15.44 09:33:44 INFO - PROCESS | 1901 | Values: 49.0 57.0 84.0 89.0 89.0 53.0 82.0 95.0 94.0 73.0 09:33:44 INFO - PROCESS | 1901 | 09:33:44 INFO - PROCESS | 1901 | [#9] json-stringify-tinderbox Cycles:10 Average:75.00 Median:75.50 stddev:17.10 (22.7%) stddev-sans-first:16.70 09:33:44 INFO - PROCESS | 1901 | Values: 56.0 55.0 77.0 59.0 94.0 71.0 66.0 74.0 99.0 99.0 09:33:44 INFO - PROCESS | 1901 | 09:33:44 INFO - PROCESS | 1901 | [#10] stanford-crypto-aes Cycles:10 Average:98.10 Median:93.00 stddev:13.78 (14.8%) stddev-sans-first:13.49 09:33:44 INFO - PROCESS | 1901 | Values: 83.0 83.0 88.0 110.0 109.0 106.0 76.0 109.0 110.0 107.0 09:33:44 INFO - PROCESS | 1901 | 09:33:44 INFO - PROCESS | 1901 | [#11] stanford-crypto-ccm Cycles:10 Average:149.30 Median:148.50 stddev:13.89 (9.4%) stddev-sans-first:14.67 09:33:44 INFO - PROCESS | 1901 | Values: 153.0 140.0 137.0 137.0 147.0 144.0 138.0 150.0 174.0 173.0 09:33:44 INFO - PROCESS | 1901 | 09:33:44 INFO - PROCESS | 1901 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:216.20 Median:208.00 stddev:24.51 (11.8%) stddev-sans-first:25.66 09:33:44 INFO - PROCESS | 1901 | Values: 205.0 200.0 202.0 201.0 198.0 211.0 253.0 267.0 201.0 224.0 09:33:44 INFO - PROCESS | 1901 | 09:33:44 INFO - PROCESS | 1901 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.10 Median:72.00 stddev:8.74 (12.1%) stddev-sans-first:9.23 09:33:44 INFO - PROCESS | 1901 | Values: 72.0 72.0 73.0 70.0 97.0 80.0 68.0 70.0 71.0 68.0 09:33:44 INFO - PROCESS | 1901 | -------- Summary: end -------- 09:33:44 INFO - PROCESS | 1901 | 09:33:45 INFO - TEST-INFO | 1901: exit 0 09:33:45 INFO - TEST-OK | kraken | took 60845ms 09:33:45 INFO - SUITE-END | took 1173s 09:33:45 INFO - Completed test suite (00:19:33) 09:33:45 INFO - javascript benchmark 09:33:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3428.7771294865834, 3455.8617655293788, 3493.881957773512, 3515.3848076586605, 3541.150123970248, 5757.856053598657, 5786.363500014995, 5788.334341550723, 5803.3326167490695, 5814.651120932747, 5713.285839270091, 5797.101449275358, 5857.560682948774, 5895.911561326584, 5901.822945311636, 4556.886077848064, 5188.922166167501, 5267.841964741045, 5296.523312901838, 5338.599605029619, 5227.26818245446, 5350.8127215547465, 5407.324084489439, 5409.404965453797, 5416.891662166754, 5087.898242035176, 5217.217417387422, 5278.604104692145, 5284.260203571503, 5325.334333208349, 4635.6523260755575, 5238.711870847102, 5241.292425522543, 5272.472752724697, 5286.97356513215, 5236.581073514109, 5246.291750613656, 5254.369475662907, 5254.553362964152, 5276.366835979707, 5429.4570542945785, 5616.887662246773, 5652.689102099383, 5797.594168408213, 5798.82603521895, 6199.690015499252, 6223.25320961483, 6248.31268560458, 6265.216847894013, 6265.373462653743, 5363.302770639792, 6281.403266689657, 6300.968495157541, 6304.653244071575, 6306.747730090791, 5778.653280803123, 5924.6149000314845, 5996.67018313997, 6023.638581685069, 6045.8488537786025, 6283.748650053946, 6318.905216421757, 6325.14610527582, 6335.936640633627, 6369.331220221903, 6113.602615829956, 6223.751049958041, 6232.937670623236, 6235.282942461675, 6235.345288744707, 6214.0, 6223.906641400383, 6273.7176827042895, 6292.307846136921, 6309.369063093745, 6133.08984587446, 6178.66017369049, 6187.3503282155625, 6245.156903817927, 6264.436200741955, 6186.721597528032, 6216.7824126155365, 6222.408871157233, 6248.968755156286, 6266.185395898504, 4886.706797592155, 4950.851474455772, 5000.62495312853, 5001.374828146481, 5046.899062018739, 4435.82256709728, 4456.729831997865, 4472.1502914446155, 4494.685372023927, 4523.479799822997, 5746.454086861741, 5874.823755287348, 5920.940790592039, 5936.5547583931375, 5940.0, 6221.844453888689, 6243.157173781481, 6284.183056202664, 6284.465820405317, 6313.905291420632, 5868.38381969887, 5926.61477004002, 5951.791687290924, 5956.642601443928, 6020.939790602126, 4273.017206042566, 4976.278439626234, 5065.39215294167, 5501.064818980783, 5505.862353441196, 6306.621602703853, 6369.5541312108635, 6411.230652321751, 6414.10202571631, 6425.164728585254, 6232.968835155795, 6275.780347687809, 6282.905756413749, 6327.778527751507, 6385.97824348102, 5283.735813209401, 6395.42441180296, 6422.775202867877, 6484.1246431731115, 6506.934930650822, 6241.21984751906, 6244.781432649836, 6307.274663413583, 6310.716017779303, 6314.873702526015, 6280.466160376236, 6287.1512345832725, 6297.370262973666, 6300.684965751786, 6301.4643755279485, 6055.72749226277, 6107.84730381744, 6110.862540392742, 6203.069539569101, 6262.843428914131, 6094.695265236632, 6136.764169924414, 6241.750329986931, 6250.3749625037135, 6256.687165641791, 5648.293963254593, 6064.241969753781, 6081.057436097412, 6096.055111457731, 6113.174721412553, 6099.249254464657, 6107.480864126599, 6178.104174894705, 6244.344343844013, 6265.906011409924, 6068.089786532056, 6135.447809696971, 6165.290991535851, 6167.599106058092, 6176.949918513774], "value": 5752.2400624180655, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [509.28699820251666, 540.7999040355068, 557.5567423898001, 564.3848205456052, 610.543511492181, 35419.0, 36074.45888311674, 36234.456483152775, 36281.81859090704, 36320.54717811284, 37879.05302367442, 38449.80775096128, 38455.80772096143, 38462.8076859616, 38718.612813871856, 9180.678676246349, 9348.345615806897, 9415.340926135174, 9440.386374885646, 9444.81110377792, 9298.767530811716, 9306.953465232664, 9312.441253524776, 9331.6733914313, 9444.244460443148, 8096.757097287091, 8540.615672294762, 9413.717588472322, 9415.105564971316, 9480.81038379232, 9371.625134994627, 9374.0, 9430.056994300583, 9443.622255109787, 9464.95267523665, 9308.162265396079, 9315.0, 9414.576344064533, 9432.434053956784, 9449.952750236205, 36457.81771091154, 36718.8164059178, 36831.81584092062, 37228.44157337642, 37436.625633743584, 2681.6513853199153, 3006.2033561106323, 3044.756419486436, 3342.8495717692517, 3500.177458297298, 25269.24192274235, 25601.0, 25893.834777435062, 25999.74000259992, 26561.07036253741, 2613.63409122723, 2629.815912886099, 2648.8543130127837, 2804.5232310507263, 2847.2170153207826, 266963.0, 268349.0, 269555.0, 270901.64549177326, 271079.0, 32212.677873220968, 32833.0, 32884.50673239903, 55724.72137639286, 55933.72033139808, 34309.0, 34360.96917092491, 34378.140546486036, 34621.82689086589, 34639.30721385609, 14800.0, 14980.400783968737, 14982.700345993018, 15024.399024038916, 15027.196736391703, 12784.552540661032, 12833.871661283454, 12853.742925141445, 12855.357232138356, 12857.935710321388, 8014.519128852287, 8052.677892884336, 8112.918870811334, 8136.959315203386, 8145.0, 3368.79787212768, 3420.401429749784, 3429.9657003430148, 3430.365382404263, 3455.6026057003264, 981.7790997025755, 1042.4512067993835, 1055.7888422315568, 1061.766411389493, 1119.7088756923258, 28893.42213155725, 28945.565816512768, 29032.709672903424, 29592.3724195167, 29723.554146687817, 1018.8369860822232, 1064.7923654887222, 1083.5990683447176, 1178.8762179971152, 1186.2882270637722, 14887.702245955237, 14965.326560304813, 15153.393864245527, 15180.772288415683, 15275.541733748005, 340660.0, 400514.0, 408711.0, 409083.0, 438628.0, 56290.718546407006, 56667.14999275014, 57653.0, 57733.42266577364, 57800.42199578034, 1238.7955987262, 1465.4065103632834, 1473.506375364265, 1481.933313000896, 1482.6145202247494, 23267.0, 26763.598546021436, 29191.562126567693, 29828.254293642833, 30039.399212016073, 50359.74820125876, 50487.49512504975, 51091.489085108675, 51737.22394164015, 52613.68465788233, 467772.0, 470155.0, 471656.0, 482005.5899720479, 483489.0, 39867.80066099651, 40644.0, 41230.0, 41246.793766030976, 41345.0, 43681.78159109311, 43691.0, 43876.56123438725, 43930.780346098065, 44425.0, 29945.550816738203, 36486.63513364832, 36744.0813979642, 37453.0, 37540.62459375481, 36053.738119165704, 36100.09749756277, 36455.45364724233, 36756.44865327076, 36776.26447471089, 29572.556411654274, 34198.31603367969, 34809.65190348165, 35966.46050309195, 36919.077023074635, 30666.0, 30961.845190773904, 30981.30678455876, 32113.35773284568, 32118.197045073124, 23877.403064923517, 24175.0, 24305.961754780652, 24358.391040224134, 24395.0], "value": 17547.530443156043, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.75517138003386, 393.20768651167884, 400.2315554136059, 402.863026570966, 414.92323920074807, 18264.817351826478, 19064.71402928957, 20590.38228853133, 20688.27591034314, 20699.689504657425, 11936.985356244708, 12242.387880605957, 12248.571300004502, 12268.754624907497, 12635.684107897307, 5185.766640501187, 5361.5978801589845, 5398.514133727964, 5400.108982017982, 5405.324334458192, 3898.922021559567, 5612.0, 5953.821385358447, 5966.433188847052, 5967.8209653710455, 5663.603547751659, 5888.764449422018, 5925.111233315016, 5937.109433584975, 5937.109433584975, 5816.040353341715, 5898.587098903078, 5931.406859314077, 5949.226600541945, 5959.404059594028, 5345.599080069011, 5744.856378590527, 5749.568782341341, 5774.624649397776, 5816.807554451328, 13308.602596727302, 13359.599212023655, 13460.865391346058, 13612.638736126408, 23837.880810595838, 2090.320645790109, 2197.0772275644267, 2305.5273668897835, 2307.9538409231723, 2328.6623439601162, 9503.762405939839, 9994.600215991351, 10000.349767404667, 10006.19950403966, 10286.279960402775, 1689.8086848771586, 1777.7066783980756, 1778.5286898971783, 1793.4709260768104, 1803.7114061750187, 117185.0, 123399.38300308531, 123405.0, 126336.36831815782, 126593.99999999908, 9222.584983675752, 10699.823019467853, 10721.83917241242, 11539.538418463168, 11545.942270288762, 14816.481423150139, 14817.0, 14959.102453852804, 14971.0, 14971.251437428304, 8575.699850505202, 8741.125887411208, 8797.900262467192, 8885.733427997171, 8891.02198758136, 8576.957115214384, 8688.826223475495, 8740.650373985096, 8830.690925817695, 8894.86657700135, 3715.888523344304, 4580.351073613497, 4643.8374656886845, 4696.624270058387, 4725.669203155746, 1823.1886810369258, 1866.6733321668653, 2002.5193953451067, 2003.0785838514446, 2005.809448102457, 784.9254320839511, 914.4604683236789, 970.5632465390461, 1055.4353420919772, 1057.6039627691528, 15241.466548670744, 17872.195751191226, 17924.0, 17928.92426454417, 19291.61416771657, 957.7270477913901, 972.6936015155203, 1021.901455934873, 1022.3610243597751, 1075.989240107589, 10338.483075846178, 10464.843027354596, 10465.581376744996, 10487.423191724529, 10505.159587233156, 521352.0, 522372.0, 530368.0, 531133.0, 644934.0, 93136.53431732798, 107575.0, 122287.0, 124074.0, 127222.0, 883.5317281840506, 916.4272329793879, 975.6243846119153, 983.7638966647953, 1015.2182819229153, 17329.87355821918, 17361.045142516778, 17690.0, 17749.112544372987, 17854.464366069038, 49305.50694493153, 51621.48378516167, 52989.67525811731, 53642.0, 53845.730771345894, 49990.500094998584, 71930.0, 72320.27679723136, 72346.0, 73184.63407683141, 7592.39260859141, 7880.369570434237, 7991.320737737125, 8077.5961201940845, 8086.797830054061, 11021.559137634405, 12211.938940305241, 12227.327496987751, 12298.262104273772, 12354.196977196454, 10527.842082368923, 10652.520636571528, 10808.243422960317, 10852.0, 10854.457277135954, 10338.328008679411, 10479.68560943173, 10611.469426528489, 10813.62152324665, 10846.837297440386, 8823.514706691194, 10724.785504289714, 10767.515461804081, 11141.053010494199, 11335.263207891461, 7021.192562855337, 7894.960525197337, 7909.0, 8115.904352912281, 8348.958255208685, 9705.951470242604, 10753.838692419777, 11009.61466348674, 11058.723531911766, 11070.667879963614], "value": 9065.091074522266, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1605.1733357320982, 1630.494546690526, 1896.1277812206383, 1922.3079691311134, 1960.0591715976332, 7921.287084162424, 8447.57762111895, 8630.95684521578, 8894.377393582443, 8914.866277005842, 8589.613467393967, 8655.956720216405, 8747.950245970476, 8770.0, 8777.078406767278, 2388.880555972203, 2433.4038160650666, 2435.512897420514, 2437.025189924027, 2440.2923152285816, 2424.0788500369836, 2437.463757973252, 2437.549053425113, 2438.670779444779, 2461.2616215135386, 2438.402591365118, 2440.707115146185, 2443.853368797878, 2449.0938352809485, 2449.620308852122, 2411.5056413435204, 2424.4787370715294, 2425.987870060638, 2446.2905757330354, 2448.779609835123, 2091.602595506864, 2376.6316220985773, 2378.203301893868, 2386.033656369173, 2410.517896420723, 8694.39139260252, 8724.258438032775, 8751.124887511261, 8813.779655508599, 8813.986391564988, 4683.250679891201, 5236.528712415863, 5331.466853314676, 5500.614956953015, 5511.0, 2806.985965070182, 3072.877084916601, 3082.2602575381748, 3582.731295152848, 3689.612590677967, 918.0956757593765, 919.0166521821653, 972.4748635736694, 991.4002028772633, 1050.1598721023151, 11608.4195790211, 11682.240654357525, 11718.06255499558, 11741.530338786439, 11847.229930054518, 4849.854504364875, 4902.926456103161, 5084.466131056186, 5104.259882317043, 5129.0, 9109.77225569366, 9111.635534578676, 9180.44917304964, 9192.402493837879, 9202.493862837608, 5164.638475306692, 5174.767135478837, 5223.059849227176, 5224.242484839677, 5227.477252274818, 4201.928508230443, 4424.84513042042, 4845.6608037437045, 4848.733319667452, 4991.201407774739, 3777.9433308500393, 3791.0, 3867.41988701697, 3907.163633092499, 3932.6264004919485, 1217.8234156047324, 1232.3591732299153, 1236.3014896583582, 1237.7029512917015, 1258.213616489694, 914.6798620482778, 922.9123233292827, 936.5879013234155, 945.0691069296737, 974.2157563161581, 5936.643801371932, 5982.521398288127, 6007.0, 6150.10823430609, 6282.18331616896, 835.0021724039835, 949.5821838391086, 979.7991411760572, 1005.6580762540772, 1030.3663247103127, 3896.2402331545077, 3949.960500395017, 3952.1898010907694, 3987.0829709166474, 4036.1927614476635, 10177.44024078668, 10585.523651435704, 10645.78708425827, 10688.305260158066, 10700.357978521159, 10769.0, 10791.298565593212, 10803.729906752395, 10835.566577336818, 10858.945705271422, 7853.489523180975, 8062.274395304451, 8226.300764434971, 8260.793480163044, 8273.841662167302, 5823.4467725566, 5847.152162936265, 5952.732127054206, 5991.490723288569, 6229.937700623117, 9823.557939892866, 10038.891832715211, 10065.194784417376, 10083.294169408364, 10091.142252908585, 10041.949790251294, 10120.544575493974, 10299.845502317321, 10446.216533759847, 10524.842127367943, 6901.413379862567, 7406.18531961473, 7554.395648348229, 7696.0, 7726.995490586188, 8026.95986520087, 8040.638171282423, 8047.919520804717, 8084.0, 8109.310708589836, 7294.161171465137, 7328.560286382834, 7517.1731109579, 7582.962085189539, 7597.6201189941385, 6812.62530560804, 6856.142982127234, 6865.141857267841, 6894.413974812197, 6897.655117244218, 6913.343232392915, 6964.4080253179045, 6982.022516847544, 7059.5058345915295, 7112.6088065154845, 4906.754662266943, 4932.901341973212, 4958.975205124096, 4964.826231081895, 5156.1492353761205, 4274.230638484978, 4979.477154898684, 5057.443681195139, 5081.745912704276, 5315.893682126413], "value": 4587.66875876445, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [269.8826010685353, 272.75179586576206, 299.33696861451887, 323.750711951797, 326.77452557735177, 5033.421156566991, 5243.947560524393, 5360.758765855536, 5419.0, 5546.445355464457, 5399.271098401715, 5452.536534394581, 5482.0, 5487.341519017714, 5490.56075513959, 3499.1077275294892, 3651.707863370937, 4138.234426631067, 4191.072106830861, 4191.098913733546, 3889.9611003890022, 4160.979195104021, 4189.874303770892, 4209.6000879916355, 4219.523166891594, 4061.7562946223175, 4072.9389059164137, 4078.4086307485395, 4095.7133000689964, 4106.630403263705, 4083.570750237423, 4097.569755175723, 4111.403846442279, 4125.731827431207, 4150.584941505856, 3393.253484233465, 3523.119220194951, 3531.94702079469, 3535.5327539071254, 3567.839447224855, 4246.830126794925, 4471.6199123074575, 5135.3837539495125, 5138.383393992707, 5172.974135129338, 3740.607236240183, 4692.483826779052, 4735.129623798614, 4739.668223224376, 4821.590164835993, 5007.399112106534, 5462.825492519128, 5558.416366281523, 5698.715064246771, 5756.309242890838, 3216.115568218735, 3735.962640373589, 3911.882643520699, 3924.2151569685893, 3938.6652134568594, 5999.730012149464, 6020.367861374536, 6042.788502402394, 6056.273247210319, 6085.847853803646, 3163.9525407118913, 3681.7606855554304, 3781.333808350439, 3970.68234541236, 3990.9424002639325, 3384.5123833982, 3405.7275417966503, 3839.9232015359535, 4034.536028356718, 4041.747058411902, 4655.348251244828, 4697.389339385858, 4707.1171531387035, 4714.5992590630185, 4721.929171062437, 5454.863628409322, 5723.055695810228, 5815.098659707752, 5821.53427725781, 5879.235699359056, 1366.5285476510587, 1412.413848252984, 1459.6788706484556, 1473.8651238546474, 1474.2555009720022, 942.3686130292721, 954.8281309364243, 966.1304825656965, 973.2943615878431, 974.5224839828433, 5529.599296432665, 5812.587776072967, 5825.446582574648, 5846.450690567003, 5866.70666466675, 5663.0, 5676.460736230051, 6042.425969532887, 6437.935620643827, 6501.47988160946, 5138.61257460491, 5595.328560572757, 5860.179574859523, 5863.941360586425, 5880.441358070976, 2974.9553756693667, 2980.7764417668764, 3042.81743095415, 3330.385647266884, 3377.7973321600784, 5135.35808023997, 5142.0, 5681.687507187062, 6170.753169873245, 6466.738985897799, 5756.913646295309, 6365.2998170201245, 6565.934340656532, 6626.370494802986, 6627.569208001464, 5022.447530771612, 5653.773849045992, 5837.67892765902, 5940.732667029994, 6615.908375118148, 5058.443571207238, 5671.347795003628, 6023.668698221464, 6023.789167379121, 6406.391392817675, 5144.614153938544, 5690.231818704588, 5916.615419997686, 5962.314333851663, 5969.895654259847, 5792.884142317214, 5954.702264886651, 6187.535934804818, 6273.341299163704, 6324.620522768649, 3228.7417006638943, 3323.8338083096232, 3522.9119272018406, 3923.6272554107313, 3932.154586763745, 4928.91942535915, 5604.327480702342, 6019.367966363644, 6029.031259053593, 6030.7286172121485, 5653.773849045992, 5822.467226756375, 5828.795992140254, 5852.946469635506, 6040.6979651018155, 4692.343071969859, 5484.478974497416, 5825.019493372104, 5853.09277062056, 5892.734826932884, 4429.955700442954, 5544.168374743821, 5684.498820518958, 5719.399463056319, 5749.597528172988, 4870.561649451566, 4991.675541089818, 5023.497650235094, 5615.241942337895, 5916.556258280732, 4845.854624361275, 5067.873303167303, 5124.666896651705, 5333.839984800462, 5765.971170144123], "value": 4218.16209704752, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [661.7187695229526, 700.5831530239506, 735.4079965627668, 738.4609235258259, 780.8555417247813, 13932.582022539327, 14328.4985025524, 14339.64150896228, 14373.784393234097, 16306.0, 15979.280932358042, 16220.51338459848, 16301.429449969253, 16383.754243686355, 16457.83542164578, 712.1454254894147, 806.5725165662209, 893.5800173918265, 911.8267529169436, 912.6668765900432, 894.8434024045798, 904.1817155474306, 904.6336233825311, 904.6924045824418, 908.2325435007441, 832.3341326938455, 898.0301274623391, 945.7210123013693, 956.7655924298557, 956.8947415784259, 907.3920473282883, 933.5099072986668, 943.2124176312752, 948.8197242523898, 950.7575568230126, 673.5554534007531, 680.4352387518397, 697.3793323941696, 701.7263267325746, 704.7815177295055, 15167.469138580096, 15297.46458873934, 15493.380264789395, 15617.609559760984, 15674.529764107096, 12342.506299748, 12524.436400362005, 12537.561185358469, 12545.74908501834, 12761.553345632858, 9955.850662240073, 9987.300888937778, 10179.491025448699, 10478.266521343508, 10574.62988795396, 3422.161570415264, 3442.845071971767, 3453.9827300863335, 3504.929901401983, 3567.6075631680496, 72158.19604509877, 72434.99999999948, 72641.27358726398, 74236.62881685558, 74380.0, 9296.39573427725, 9327.207187389013, 9434.669786557575, 9543.904560954441, 9659.0, 21113.78886211149, 21114.261000865205, 21165.835879026494, 21182.0, 21785.0, 7383.114026316876, 7391.371733402613, 7502.474826762074, 7546.735864244725, 7706.229377062249, 3540.6990405815795, 3579.7673151245085, 3611.494390785292, 3628.600853906068, 3674.559052913614, 3539.610642829287, 3593.4969104325414, 3644.2347107107803, 3646.288973650113, 3650.3064417760543, 630.8580569371946, 646.424682032991, 651.2185377546963, 655.1025095619033, 697.5152269172876, 519.7738983542172, 548.2434240747822, 566.6709822628992, 583.4632138432652, 596.0850095103995, 8201.097879233279, 8290.834183316421, 8308.815462714567, 8442.577871106543, 8455.281301089353, 555.6747158028052, 587.6591576885396, 590.1383979390052, 610.716017052073, 614.8032629558588, 3799.0104671688587, 3917.9608203917596, 3938.86213982515, 3951.6641085508054, 4010.6791456683395, 91515.54242228746, 99520.50239748755, 103092.48453757833, 106044.0, 108002.0, 69509.0, 84727.57636211779, 88082.0, 88367.0, 90841.0, 66341.0, 66757.66621166863, 66789.0, 66912.6654366725, 67671.66164169247, 7648.579328137006, 7729.768106956801, 7762.650680719494, 7801.102873169658, 7828.099768526692, 48094.0, 49350.50649493459, 49642.50357496379, 49813.501864980884, 49830.75084624554, 63297.36702632915, 67521.66239168773, 67584.6620766893, 71260.64369678119, 71651.92522112277, 10230.437325946847, 10268.794624107326, 10279.640212592523, 10383.169346452416, 10639.840402393815, 11243.49404276826, 11711.414429278673, 11820.76358472843, 12013.099017573542, 12563.309018004098, 11000.22998390105, 11058.11535077208, 11487.25332853362, 11564.479598417924, 11795.705107372385, 9420.62317507292, 11454.599089031844, 11510.53957841677, 11589.24669896454, 11850.644480665595, 11079.390633515233, 11449.370284634426, 11552.191346605992, 11659.008984236434, 11805.704857378634, 9343.252539796937, 9482.715518534455, 9535.856962145435, 9836.655717049869, 10585.417802020962, 8524.829503409774, 9432.764180895532, 9804.417091577563, 9941.552630131517, 10142.49287535635], "value": 6879.080159762831, "unit": "score"}], "name": "dromaeo_css", "value": 7040.739541616423, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 113.0, 117.0, 115.0, 117.0, 128.0, 115.0, 114.0, 142.0, 172.0], "value": 124.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 152.0, 144.0, 167.0, 132.0, 167.0, 154.0, 148.0, 205.0, 149.0], "value": 157.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 216.0, 261.0, 222.0, 263.0, 206.0, 201.0, 196.0, 196.0, 193.0], "value": 214.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 78.0, 74.0, 156.0, 47.0, 66.0, 44.0, 57.0, 143.0, 50.0], "value": 78.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 87.0, 136.0, 76.0, 79.0, 79.0, 122.0, 82.0, 82.0, 120.0], "value": 94.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 88.0, 87.0, 94.0, 131.0, 88.0, 88.0, 89.0, 73.0], "value": 91.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 122.0, 92.0, 155.0, 153.0, 120.0], "value": 111.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 89.0, 92.0, 90.0, 93.0, 89.0, 91.0, 92.0, 91.0, 126.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 57.0, 84.0, 89.0, 89.0, 53.0, 82.0, 95.0, 94.0, 73.0], "value": 76.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 77.0, 59.0, 94.0, 71.0, 66.0, 74.0, 99.0, 99.0], "value": 75.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 88.0, 110.0, 109.0, 106.0, 76.0, 109.0, 110.0, 107.0], "value": 98.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 140.0, 137.0, 137.0, 147.0, 144.0, 138.0, 150.0, 174.0, 173.0], "value": 149.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [205.0, 200.0, 202.0, 201.0, 198.0, 211.0, 253.0, 267.0, 201.0, 224.0], "value": 216.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 72.0, 73.0, 70.0, 97.0, 80.0, 68.0, 70.0, 71.0, 68.0], "value": 74.1, "unit": "score"}], "name": "kraken", "value": 1657.2999999999997, "alertThreshold": 2.0}]} 09:33:45 INFO - Return code: 0 09:33:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:33:45 ERROR - # TBPL SUCCESS # 09:33:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:33:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:33:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:33:45 INFO - 2016-04-28 09:33:45.865 screenresolution[1906:10747] starting screenresolution argv=screenresolution get 09:33:45 INFO - 2016-04-28 09:33:45.868 screenresolution[1906:10747] Display 0: 1600x1200x32@60 09:33:45 INFO - 2016-04-28 09:33:45.874 screenresolution[1907:10750] starting screenresolution argv=screenresolution list 09:33:45 INFO - Available Modes on Display 0 09:33:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:33:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:33:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:33:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:33:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:33:46 INFO - Intel Iris: 09:33:46 INFO - Chipset Model: Intel Iris 09:33:46 INFO - Type: GPU 09:33:46 INFO - Bus: Built-In 09:33:46 INFO - VRAM (Dynamic, Max): 1536 MB 09:33:46 INFO - Vendor: Intel (0x8086) 09:33:46 INFO - Device ID: 0x0a2e 09:33:46 INFO - Revision ID: 0x0009 09:33:46 INFO - Displays: 09:33:46 INFO - Display: 09:33:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:33:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:33:46 INFO - Display Serial Number: 1600x1200 60 09:33:46 INFO - Main Display: Yes 09:33:46 INFO - Mirror: Off 09:33:46 INFO - Online: Yes 09:33:46 INFO - Rotation: Supported 09:33:46 INFO - Return code: 0 09:33:46 INFO - Running post-action listener: _resource_record_post_action 09:33:46 INFO - Running post-run listener: _resource_record_post_run 09:33:47 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 32566784; Write bytes: 697762816; Read time: 327; Write time: 2334 09:33:47 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 378696192; Write bytes: 372992000; Read time: 28159; Write time: 695 09:33:47 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 31645184; Write bytes: 299420672; Read time: 314; Write time: 1588 09:33:47 INFO - Running post-run listener: _upload_blobber_files 09:33:47 INFO - Blob upload gear active. 09:33:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:33:47 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 09:33:47 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:33:47 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:33:47 INFO - (blobuploader) - INFO - Open directory for files ... 09:33:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:33:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:33:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:33:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:33:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:33:48 INFO - (blobuploader) - INFO - Done attempting. 09:33:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:33:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:33:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:33:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:33:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:33:49 INFO - (blobuploader) - INFO - Done attempting. 09:33:49 INFO - (blobuploader) - INFO - Iteration through files over. 09:33:49 INFO - Return code: 0 09:33:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:33:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:33:49 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cfc9676047b8fda05899adb028650c623f72ca1173fb4a8ed367ebc48335a93d222e482822dbd00e30cf8464c2232b715416b826dc8bd08419e680d28057dd35", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1bb413c486f03be4afcbd88f4d0c6a37060b46d65d342d37ceae4ab894208214e77090b7b4c797fe9bc242c6551ca3b6ae2db128fa4778adc482096033761e01"} 09:33:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:33:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:33:49 INFO - Contents: 09:33:49 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cfc9676047b8fda05899adb028650c623f72ca1173fb4a8ed367ebc48335a93d222e482822dbd00e30cf8464c2232b715416b826dc8bd08419e680d28057dd35", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1bb413c486f03be4afcbd88f4d0c6a37060b46d65d342d37ceae4ab894208214e77090b7b4c797fe9bc242c6551ca3b6ae2db128fa4778adc482096033761e01"} 09:33:49 INFO - Running post-run listener: copy_logs_to_upload_dir 09:33:49 INFO - Copying logs to upload dir... 09:33:49 INFO - mkdir: /builds/slave/test/build/upload/logs 09:33:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.560983 ========= master_lag: 1.42 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-04-28 09:33:51.217850) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-28 09:33:51.223075) ========= 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.1VtZhajZ2P/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.5DBc3Ljn3r/Listeners TMPDIR=/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cfc9676047b8fda05899adb028650c623f72ca1173fb4a8ed367ebc48335a93d222e482822dbd00e30cf8464c2232b715416b826dc8bd08419e680d28057dd35", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1bb413c486f03be4afcbd88f4d0c6a37060b46d65d342d37ceae4ab894208214e77090b7b4c797fe9bc242c6551ca3b6ae2db128fa4778adc482096033761e01"} build_url:https://queue.taskcluster.net/v1/task/frV0SoWYTXWfDw-ZNFBtvg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011102 build_url: 'https://queue.taskcluster.net/v1/task/frV0SoWYTXWfDw-ZNFBtvg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cfc9676047b8fda05899adb028650c623f72ca1173fb4a8ed367ebc48335a93d222e482822dbd00e30cf8464c2232b715416b826dc8bd08419e680d28057dd35", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1bb413c486f03be4afcbd88f4d0c6a37060b46d65d342d37ceae4ab894208214e77090b7b4c797fe9bc242c6551ca3b6ae2db128fa4778adc482096033761e01"}' ========= master_lag: 0.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-28 09:33:51.275340) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-04-28 09:33:51.275670) ========= 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.1VtZhajZ2P/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.5DBc3Ljn3r/Listeners TMPDIR=/var/folders/yy/pzwmkny90h5716w0lfhkq8zh00000w/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.005312 ========= master_lag: 3.17 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-04-28 09:33:54.451003) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-28 09:33:54.451361) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-28 09:33:54.468171) ========= ========= Total master_lag: 4.72 =========