builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0342 starttime: 1463431679.62 results: success (0) buildid: 20160516124143 builduid: 3a25a15ff16c4b0cb6e450f554c3ebd7 revision: 17724486e1591858eea09dc327475a25883854a4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-16 13:47:59.616640) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-16 13:47:59.617064) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-16 13:47:59.617354) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-16 13:47:59.639083) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 13:47:59.639482) ========= 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.qqoY3GEJJl/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.0bA9dSrB1C/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-16 13:47:59-- 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% 7.98M=0.001s 2016-05-16 13:47:59 (7.98 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.099490 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 13:47:59.756311) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 13:47:59.756643) ========= 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.qqoY3GEJJl/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.0bA9dSrB1C/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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.020013 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 13:47:59.809224) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-16 13:47:59.813694) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 17724486e1591858eea09dc327475a25883854a4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 17724486e1591858eea09dc327475a25883854a4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qqoY3GEJJl/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.0bA9dSrB1C/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-16 13:47:59,819 truncating revision to first 12 chars 2016-05-16 13:47:59,819 Setting DEBUG logging. 2016-05-16 13:47:59,819 attempt 1/10 2016-05-16 13:47:59,819 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/17724486e159?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-16 13:48:01,708 unpacking tar archive at: mozilla-aurora-17724486e159/testing/mozharness/ program finished with exit code 0 elapsedTime=2.136055 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-16 13:48:01.967300) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-16 13:48:01.967681) ========= script_repo_revision: 17724486e1591858eea09dc327475a25883854a4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-16 13:48:01.968076) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-16 13:48:01.970384) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-16 13:48:01.987994) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-05-16 13:48:01.988646) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qqoY3GEJJl/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.0bA9dSrB1C/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 13:48:02 INFO - MultiFileLogger online at 20160516 13:48:02 in /builds/slave/test 13:48:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 13:48:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:48:02 INFO - {'append_to_log': False, 13:48:02 INFO - 'base_work_dir': '/builds/slave/test', 13:48:02 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 13:48:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:48:02 INFO - 'branch': 'Mozilla-Aurora', 13:48:02 INFO - 'buildbot_json_path': 'buildprops.json', 13:48:02 INFO - 'config_files': ('talos/mac_config.py',), 13:48:02 INFO - 'default_actions': ('clobber', 13:48:02 INFO - 'read-buildbot-config', 13:48:02 INFO - 'download-and-extract', 13:48:02 INFO - 'populate-webroot', 13:48:02 INFO - 'create-virtualenv', 13:48:02 INFO - 'install', 13:48:02 INFO - 'run-tests'), 13:48:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:48:02 INFO - 'download_minidump_stackwalk': True, 13:48:02 INFO - 'download_symbols': 'ondemand', 13:48:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:48:02 INFO - 'tooltool.py': '/tools/tooltool.py', 13:48:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:48:02 INFO - '/tools/misc-python/virtualenv.py')}, 13:48:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:48:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:48:02 INFO - 'installer_path': 'installer.exe', 13:48:02 INFO - 'log_level': 'info', 13:48:02 INFO - 'log_name': 'talos', 13:48:02 INFO - 'log_to_console': True, 13:48:02 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:48:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:48:02 INFO - 'opt_config_files': (), 13:48:02 INFO - 'pip_index': False, 13:48:02 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:48:02 INFO - 'cmd': ('bash', 13:48:02 INFO - '-c', 13:48:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:48:02 INFO - 'enabled': True, 13:48:02 INFO - 'halt_on_failure': False, 13:48:02 INFO - 'name': 'check_screen_resolution'},), 13:48:02 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:48:02 INFO - 'cmd': ('bash', 13:48:02 INFO - '-c', 13:48:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:48:02 INFO - 'enabled': True, 13:48:02 INFO - 'halt_on_failure': False, 13:48:02 INFO - 'name': 'check_screen_resolution'},), 13:48:02 INFO - 'run_cmd_checks_enabled': True, 13:48:02 INFO - 'sps_profile': False, 13:48:02 INFO - 'sps_profile_interval': 0, 13:48:02 INFO - 'suite': 'dromaeojs-e10s', 13:48:02 INFO - 'system_bits': '32', 13:48:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:48:02 INFO - 'title': 't-yosemite-r7-0342', 13:48:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:48:02 INFO - 'use_talos_json': True, 13:48:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:48:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:48:02 INFO - 'work_dir': 'build'} 13:48:02 INFO - ##### 13:48:02 INFO - ##### Running clobber step. 13:48:02 INFO - ##### 13:48:02 INFO - Running pre-action listener: _resource_record_pre_action 13:48:02 INFO - Running main action method: clobber 13:48:02 INFO - rmtree: /builds/slave/test/build 13:48:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:48:03 INFO - Running post-action listener: _resource_record_post_action 13:48:03 INFO - ##### 13:48:03 INFO - ##### Running read-buildbot-config step. 13:48:03 INFO - ##### 13:48:03 INFO - Running pre-action listener: _resource_record_pre_action 13:48:03 INFO - Running main action method: read_buildbot_config 13:48:03 INFO - Using buildbot properties: 13:48:03 INFO - { 13:48:03 INFO - "project": "", 13:48:03 INFO - "product": "firefox", 13:48:03 INFO - "script_repo_revision": "production", 13:48:03 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 13:48:03 INFO - "repository": "", 13:48:03 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 13:48:03 INFO - "buildid": "20160516124143", 13:48:03 INFO - "pgo_build": "False", 13:48:03 INFO - "basedir": "/builds/slave/test", 13:48:03 INFO - "buildnumber": 293, 13:48:03 INFO - "slavename": "t-yosemite-r7-0342", 13:48:03 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 13:48:03 INFO - "platform": "yosemite_r7", 13:48:03 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 13:48:03 INFO - "branch": "mozilla-aurora", 13:48:03 INFO - "repo_path": "releases/mozilla-aurora", 13:48:03 INFO - "revision": "17724486e1591858eea09dc327475a25883854a4", 13:48:03 INFO - "stage_platform": "macosx64", 13:48:03 INFO - "builduid": "3a25a15ff16c4b0cb6e450f554c3ebd7", 13:48:03 INFO - "slavebuilddir": "test" 13:48:03 INFO - } 13:48:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/BHfYKtMzTuy974xb02XETA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 13:48:03 INFO - Running post-action listener: _resource_record_post_action 13:48:03 INFO - ##### 13:48:03 INFO - ##### Running download-and-extract step. 13:48:03 INFO - ##### 13:48:03 INFO - Running pre-action listener: _resource_record_pre_action 13:48:03 INFO - Running main action method: download_and_extract 13:48:03 INFO - mkdir: /builds/slave/test/build/tests 13:48:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:48:03 INFO - https://queue.taskcluster.net/v1/task/BHfYKtMzTuy974xb02XETA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 13:48:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BHfYKtMzTuy974xb02XETA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 13:48:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BHfYKtMzTuy974xb02XETA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 13:48:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BHfYKtMzTuy974xb02XETA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 13:48:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BHfYKtMzTuy974xb02XETA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 13:48:05 INFO - Downloaded 1199 bytes. 13:48:05 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 13:48:05 INFO - Using the following test package requirements: 13:48:05 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 13:48:05 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:48:05 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 13:48:05 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:48:05 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 13:48:05 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:48:05 INFO - u'jsshell-mac.zip'], 13:48:05 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:48:05 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 13:48:05 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 13:48:05 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:48:05 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 13:48:05 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:48:05 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 13:48:05 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:48:05 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 13:48:05 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:48:05 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 13:48:05 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 13:48:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:48:05 INFO - https://queue.taskcluster.net/v1/task/BHfYKtMzTuy974xb02XETA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:48:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BHfYKtMzTuy974xb02XETA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:48:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BHfYKtMzTuy974xb02XETA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:48:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BHfYKtMzTuy974xb02XETA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:48:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BHfYKtMzTuy974xb02XETA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 13:48:06 INFO - Downloaded 18102374 bytes. 13:48:06 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:48:06 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 13:48:09 INFO - Return code: 0 13:48:09 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 13:48:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:48:09 INFO - https://queue.taskcluster.net/v1/task/BHfYKtMzTuy974xb02XETA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:48:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BHfYKtMzTuy974xb02XETA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:48:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BHfYKtMzTuy974xb02XETA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:48:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BHfYKtMzTuy974xb02XETA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:48:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BHfYKtMzTuy974xb02XETA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 13:48:10 INFO - Downloaded 18102374 bytes. 13:48:10 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:48:10 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 13:48:14 INFO - Return code: 0 13:48:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:48:14 INFO - https://queue.taskcluster.net/v1/task/BHfYKtMzTuy974xb02XETA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 13:48:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BHfYKtMzTuy974xb02XETA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 13:48:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BHfYKtMzTuy974xb02XETA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 13:48:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BHfYKtMzTuy974xb02XETA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 13:48:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BHfYKtMzTuy974xb02XETA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 13:48:15 INFO - Downloaded 10840145 bytes. 13:48:15 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:48:15 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 13:48:15 INFO - Return code: 0 13:48:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:48:15 INFO - https://queue.taskcluster.net/v1/task/BHfYKtMzTuy974xb02XETA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 13:48:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BHfYKtMzTuy974xb02XETA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 13:48:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BHfYKtMzTuy974xb02XETA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 13:48:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BHfYKtMzTuy974xb02XETA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 13:48:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BHfYKtMzTuy974xb02XETA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 13:48:18 INFO - Downloaded 114126469 bytes. 13:48:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BHfYKtMzTuy974xb02XETA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 13:48:18 INFO - mkdir: /builds/slave/test/properties 13:48:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:48:18 INFO - Writing to file /builds/slave/test/properties/build_url 13:48:18 INFO - Contents: 13:48:18 INFO - build_url:https://queue.taskcluster.net/v1/task/BHfYKtMzTuy974xb02XETA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 13:48:20 INFO - Running post-action listener: _resource_record_post_action 13:48:20 INFO - Running post-action listener: set_extra_try_arguments 13:48:20 INFO - ##### 13:48:20 INFO - ##### Running populate-webroot step. 13:48:20 INFO - ##### 13:48:20 INFO - Running pre-action listener: _resource_record_pre_action 13:48:20 INFO - Running main action method: populate_webroot 13:48:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:48:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:48:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:48:20 INFO - '--tppagecycles', 13:48:20 INFO - '7'], 13:48:20 INFO - 'tests': ['tsvgm']}}, 13:48:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:48:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:48:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:48:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:48:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:48:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:48:20 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:48:20 INFO - 'talos_options': ['--disable-e10s'], 13:48:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:48:20 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:48:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:48:20 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:48:20 INFO - 'talos_options': ['--disable-e10s'], 13:48:20 INFO - 'tests': ['damp', 'tps']}, 13:48:20 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:48:20 INFO - 'tests': ['damp', 'tps']}, 13:48:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:48:20 INFO - 'tests': ['dromaeo_dom']}, 13:48:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:48:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:48:20 INFO - 'tests': ['a11yr', 13:48:20 INFO - 'ts_paint', 13:48:20 INFO - 'tpaint', 13:48:20 INFO - 'sessionrestore', 13:48:20 INFO - 'sessionrestore_no_auto_restore', 13:48:20 INFO - 'tabpaint']}, 13:48:20 INFO - 'other-e10s': {'tests': ['a11yr', 13:48:20 INFO - 'ts_paint', 13:48:20 INFO - 'tpaint', 13:48:20 INFO - 'sessionrestore', 13:48:20 INFO - 'sessionrestore_no_auto_restore', 13:48:20 INFO - 'tabpaint']}, 13:48:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:48:20 INFO - 'ts_paint', 13:48:20 INFO - 'tpaint', 13:48:20 INFO - 'sessionrestore', 13:48:20 INFO - 'sessionrestore_no_auto_restore', 13:48:20 INFO - 'tabpaint']}, 13:48:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:48:20 INFO - 'ts_paint', 13:48:20 INFO - 'tpaint', 13:48:20 INFO - 'sessionrestore', 13:48:20 INFO - 'sessionrestore_no_auto_restore', 13:48:20 INFO - 'tabpaint']}, 13:48:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:48:20 INFO - 'tests': ['a11yr', 13:48:20 INFO - 'ts_paint', 13:48:20 INFO - 'tpaint', 13:48:20 INFO - 'sessionrestore', 13:48:20 INFO - 'sessionrestore_no_auto_restore', 13:48:20 INFO - 'tabpaint']}, 13:48:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:48:20 INFO - 'tests': ['a11yr', 13:48:20 INFO - 'ts_paint', 13:48:20 INFO - 'tpaint', 13:48:20 INFO - 'sessionrestore', 13:48:20 INFO - 'sessionrestore_no_auto_restore', 13:48:20 INFO - 'tabpaint']}, 13:48:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:48:20 INFO - 'tests': ['tsvgx', 13:48:20 INFO - 'tsvgr_opacity', 13:48:20 INFO - 'tart', 13:48:20 INFO - 'tscrollx', 13:48:20 INFO - 'cart']}, 13:48:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:48:20 INFO - 'tsvgr_opacity', 13:48:20 INFO - 'tart', 13:48:20 INFO - 'tscrollx', 13:48:20 INFO - 'cart']}, 13:48:20 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:48:20 INFO - 'talos_options': ['--disable-e10s'], 13:48:20 INFO - 'tests': ['tp5o']}, 13:48:20 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:48:20 INFO - 'tests': ['tp5o']}, 13:48:20 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:48:20 INFO - 'talos_options': ['--disable-e10s', 13:48:20 INFO - '--xperf_path', 13:48:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:48:20 INFO - 'tests': ['tp5n']}, 13:48:20 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:48:20 INFO - 'talos_options': ['--xperf_path', 13:48:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:48:20 INFO - 'tests': ['tp5n']}}, 13:48:20 INFO - 'talos.zip': {'path': '', 13:48:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:48:20 INFO - Running post-action listener: _resource_record_post_action 13:48:20 INFO - ##### 13:48:20 INFO - ##### Running create-virtualenv step. 13:48:20 INFO - ##### 13:48:20 INFO - Running pre-action listener: _resource_record_pre_action 13:48:20 INFO - Running main action method: create_virtualenv 13:48:20 INFO - Creating virtualenv /builds/slave/test/build/venv 13:48:20 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 13:48:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:48:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:48:20 INFO - Using real prefix '/tools/python27' 13:48:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:48:21 INFO - Installing distribute.............................................................................................................................................................................................done. 13:48:24 INFO - Installing pip.................done. 13:48:24 INFO - Return code: 0 13:48:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:48:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:48:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:48:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:48:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:48:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:24 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 0x101d3aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d4a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9fa497c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bd9e00>, '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 0x101a42db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101bd4b20>, '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.qqoY3GEJJl/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.0bA9dSrB1C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:48:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 13:48:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 13:48:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qqoY3GEJJl/Render', 13:48:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:48:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:48:24 INFO - 'HOME': '/Users/cltbld', 13:48:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:48:24 INFO - 'LOGNAME': 'cltbld', 13:48:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:48:24 INFO - 'MOZ_NO_REMOTE': '1', 13:48:24 INFO - 'NO_EM_RESTART': '1', 13:48:24 INFO - 'PAGER': '/bin/cat', 13:48:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:48:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:48:24 INFO - 'PWD': '/builds/slave/test', 13:48:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:48:24 INFO - 'SHELL': '/bin/bash', 13:48:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0bA9dSrB1C/Listeners', 13:48:24 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 13:48:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:48:24 INFO - 'USER': 'cltbld', 13:48:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:48:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:48:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:48:24 INFO - 'XPC_FLAGS': '0x0', 13:48:24 INFO - 'XPC_SERVICE_NAME': '0', 13:48:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:48:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:48:24 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 13:48:24 INFO - Cleaning up... 13:48:24 INFO - Return code: 0 13:48:24 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 13:48:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:48:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:48:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:48:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:48:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:24 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 0x101d3aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d4a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9fa497c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bd9e00>, '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 0x101a42db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101bd4b20>, '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.qqoY3GEJJl/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.0bA9dSrB1C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:48:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 13:48:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 13:48:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qqoY3GEJJl/Render', 13:48:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:48:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:48:24 INFO - 'HOME': '/Users/cltbld', 13:48:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:48:24 INFO - 'LOGNAME': 'cltbld', 13:48:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:48:24 INFO - 'MOZ_NO_REMOTE': '1', 13:48:24 INFO - 'NO_EM_RESTART': '1', 13:48:24 INFO - 'PAGER': '/bin/cat', 13:48:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:48:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:48:24 INFO - 'PWD': '/builds/slave/test', 13:48:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:48:24 INFO - 'SHELL': '/bin/bash', 13:48:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0bA9dSrB1C/Listeners', 13:48:24 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 13:48:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:48:24 INFO - 'USER': 'cltbld', 13:48:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:48:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:48:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:48:24 INFO - 'XPC_FLAGS': '0x0', 13:48:24 INFO - 'XPC_SERVICE_NAME': '0', 13:48:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:48:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:48:24 INFO - Downloading/unpacking psutil>=0.7.1 13:48:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:48:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:48:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:48:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:48:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:48:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:48:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:48:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:48:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:48:27 INFO - Installing collected packages: psutil 13:48:27 INFO - Running setup.py install for psutil 13:48:27 INFO - building 'psutil._psutil_osx' extension 13:48:27 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 13:48:27 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 13:48:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 13:48:28 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 13:48:28 INFO - building 'psutil._psutil_posix' extension 13:48:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 13:48:28 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:48:28 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:48:28 INFO - ^ 13:48:28 INFO - 1 warning generated. 13:48:28 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 13:48:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:48:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:48:28 INFO - Successfully installed psutil 13:48:28 INFO - Cleaning up... 13:48:28 INFO - Return code: 0 13:48:28 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 13:48:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:48:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:48:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:48:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:48:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48: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', '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 0x101d3aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d4a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9fa497c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bd9e00>, '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 0x101a42db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101bd4b20>, '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.qqoY3GEJJl/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.0bA9dSrB1C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:48: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 13:48: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 mozsystemmonitor==0.0.0 13:48:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qqoY3GEJJl/Render', 13:48:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:48:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:48:28 INFO - 'HOME': '/Users/cltbld', 13:48:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:48:28 INFO - 'LOGNAME': 'cltbld', 13:48:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:48:28 INFO - 'MOZ_NO_REMOTE': '1', 13:48:28 INFO - 'NO_EM_RESTART': '1', 13:48:28 INFO - 'PAGER': '/bin/cat', 13:48:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:48:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:48:28 INFO - 'PWD': '/builds/slave/test', 13:48:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:48:28 INFO - 'SHELL': '/bin/bash', 13:48:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0bA9dSrB1C/Listeners', 13:48:28 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 13:48:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:48:28 INFO - 'USER': 'cltbld', 13:48:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:48:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:48:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:48:28 INFO - 'XPC_FLAGS': '0x0', 13:48:28 INFO - 'XPC_SERVICE_NAME': '0', 13:48:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:48:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:48:28 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 13:48: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 13:48: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 13:48: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 13:48: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 13:48: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 13:48: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 13:48:31 INFO - Downloading mozsystemmonitor-0.0.tar.gz 13:48:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 13:48:31 INFO - Installing collected packages: mozsystemmonitor 13:48:31 INFO - Running setup.py install for mozsystemmonitor 13:48:31 INFO - Successfully installed mozsystemmonitor 13:48:31 INFO - Cleaning up... 13:48:31 INFO - Return code: 0 13:48:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:48:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:48:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:48:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:48:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:48:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101d3aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d4a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9fa497c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bd9e00>, '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 0x101a42db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101bd4b20>, '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.qqoY3GEJJl/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.0bA9dSrB1C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:48:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 13:48:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 13:48:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qqoY3GEJJl/Render', 13:48:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:48:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:48:31 INFO - 'HOME': '/Users/cltbld', 13:48:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:48:31 INFO - 'LOGNAME': 'cltbld', 13:48:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:48:31 INFO - 'MOZ_NO_REMOTE': '1', 13:48:31 INFO - 'NO_EM_RESTART': '1', 13:48:31 INFO - 'PAGER': '/bin/cat', 13:48:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:48:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:48:31 INFO - 'PWD': '/builds/slave/test', 13:48:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:48:31 INFO - 'SHELL': '/bin/bash', 13:48:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0bA9dSrB1C/Listeners', 13:48:31 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 13:48:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:48:31 INFO - 'USER': 'cltbld', 13:48:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:48:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:48:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:48:31 INFO - 'XPC_FLAGS': '0x0', 13:48:31 INFO - 'XPC_SERVICE_NAME': '0', 13:48:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:48:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:48:31 INFO - Downloading/unpacking blobuploader==1.2.4 13:48:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:48:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:48:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:48:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:48:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:48:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:48:33 INFO - Downloading blobuploader-1.2.4.tar.gz 13:48:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:48:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:48:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:48:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:48:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:48:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:48:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:48:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:48:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:48:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:48:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:48:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:48:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:48:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:48:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:48:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:48:35 INFO - Downloading docopt-0.6.1.tar.gz 13:48:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:48:35 INFO - Installing collected packages: blobuploader, requests, docopt 13:48:35 INFO - Running setup.py install for blobuploader 13:48:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:48:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:48:35 INFO - Running setup.py install for requests 13:48:35 INFO - Running setup.py install for docopt 13:48:36 INFO - Successfully installed blobuploader requests docopt 13:48:36 INFO - Cleaning up... 13:48:36 INFO - Return code: 0 13:48:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:48:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:48:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:48:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:48:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:48:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:36 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 0x101d3aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d4a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9fa497c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bd9e00>, '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 0x101a42db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101bd4b20>, '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.qqoY3GEJJl/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.0bA9dSrB1C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:48:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 13:48:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:48:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qqoY3GEJJl/Render', 13:48:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:48:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:48:36 INFO - 'HOME': '/Users/cltbld', 13:48:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:48:36 INFO - 'LOGNAME': 'cltbld', 13:48:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:48:36 INFO - 'MOZ_NO_REMOTE': '1', 13:48:36 INFO - 'NO_EM_RESTART': '1', 13:48:36 INFO - 'PAGER': '/bin/cat', 13:48:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:48:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:48:36 INFO - 'PWD': '/builds/slave/test', 13:48:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:48:36 INFO - 'SHELL': '/bin/bash', 13:48:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0bA9dSrB1C/Listeners', 13:48:36 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 13:48:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:48:36 INFO - 'USER': 'cltbld', 13:48:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:48:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:48:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:48:36 INFO - 'XPC_FLAGS': '0x0', 13:48:36 INFO - 'XPC_SERVICE_NAME': '0', 13:48:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:48:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:48:36 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-SL1Upl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:48:36 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-ee7FTU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:48:36 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-LYeY7P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:48:36 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-tWH1wS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:48:36 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-bYLpyz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:48:36 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-1kKb8y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:48:37 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-MzDVIm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:48:37 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-q_F1Bn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:48:37 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-VEr3MN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:48:37 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-mYmskg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:48:37 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-ck8o76-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:48:37 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-nCvaFB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:48:37 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-lHs20_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:48:37 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-ysDNjZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:48:37 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-iHFU69-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:48:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:48:37 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-hQ5xNj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:48:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:48:38 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-NUc_QF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:48:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:48:38 INFO - Running setup.py install for manifestparser 13:48:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:48:38 INFO - Running setup.py install for mozcrash 13:48:38 INFO - Running setup.py install for mozdebug 13:48:38 INFO - Running setup.py install for mozdevice 13:48:38 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:48:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:48:38 INFO - Running setup.py install for mozfile 13:48:38 INFO - Running setup.py install for mozhttpd 13:48:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:48:39 INFO - Running setup.py install for mozinfo 13:48:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:48:39 INFO - Running setup.py install for mozInstall 13:48:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:48:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:48:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:48:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:48:39 INFO - Running setup.py install for mozleak 13:48:39 INFO - Running setup.py install for mozlog 13:48:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:48:39 INFO - Running setup.py install for moznetwork 13:48:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:48:39 INFO - Running setup.py install for mozprocess 13:48:39 INFO - Running setup.py install for mozprofile 13:48:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:48:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:48:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:48:40 INFO - Running setup.py install for mozrunner 13:48:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:48:40 INFO - Running setup.py install for mozscreenshot 13:48:40 INFO - Running setup.py install for moztest 13:48:40 INFO - Running setup.py install for mozversion 13:48:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:48:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:48:40 INFO - Cleaning up... 13:48:40 INFO - Return code: 0 13:48:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:48:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:48:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:48:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:48:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:48:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:40 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 0x101d3aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d4a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9fa497c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bd9e00>, '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 0x101a42db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101bd4b20>, '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.qqoY3GEJJl/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.0bA9dSrB1C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:48:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 13:48:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:48:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qqoY3GEJJl/Render', 13:48:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:48:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:48:40 INFO - 'HOME': '/Users/cltbld', 13:48:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:48:40 INFO - 'LOGNAME': 'cltbld', 13:48:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:48:40 INFO - 'MOZ_NO_REMOTE': '1', 13:48:40 INFO - 'NO_EM_RESTART': '1', 13:48:40 INFO - 'PAGER': '/bin/cat', 13:48:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:48:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:48:40 INFO - 'PWD': '/builds/slave/test', 13:48:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:48:40 INFO - 'SHELL': '/bin/bash', 13:48:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0bA9dSrB1C/Listeners', 13:48:40 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 13:48:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:48:40 INFO - 'USER': 'cltbld', 13:48:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:48:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:48:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:48:40 INFO - 'XPC_FLAGS': '0x0', 13:48:40 INFO - 'XPC_SERVICE_NAME': '0', 13:48:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:48:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:48:41 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-u_wCuo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:48:41 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 13:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:48:41 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-0oyNfj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:48:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:48:41 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-_K4D69-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:48:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 13:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:48:41 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-3vmJ5A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:48:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:48:41 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-I3TLd1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:48:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 13:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:48:41 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-CNsRM1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:48:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 13:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:48:41 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-XNr9py-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:48:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 13:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:48:41 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-xrakCm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:48:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 13:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:48:41 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-3KkHbP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:48:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 13:48:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:48:41 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-Xz2rtC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:48:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:48:42 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-ziQ1T7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:48:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 13:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:48:42 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-YVAEp2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:48:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 13:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:48:42 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-803upw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:48:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 13:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:48:42 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-xWCK0L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:48:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 13:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:48:42 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-RzEHlm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:48:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 13:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:48:42 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-8mEEX1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:48:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 13:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:48:42 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-CyxVDd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:48:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 13:48:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:48:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:48:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 13:48:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:48:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:48:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:48:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:48:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:48:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:48:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:48:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:48:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:48:45 INFO - Downloading blessings-1.6.tar.gz 13:48:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:48:45 INFO - Installing collected packages: blessings 13:48:45 INFO - Running setup.py install for blessings 13:48:45 INFO - Successfully installed blessings 13:48:45 INFO - Cleaning up... 13:48:45 INFO - Return code: 0 13:48:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:48:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:48:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:48:45 INFO - Reading from file tmpfile_stdout 13:48:45 INFO - Current package versions: 13:48:45 INFO - blessings == 1.6 13:48:45 INFO - blobuploader == 1.2.4 13:48:45 INFO - docopt == 0.6.1 13:48:45 INFO - manifestparser == 1.1 13:48:45 INFO - mozInstall == 1.12 13:48:45 INFO - mozcrash == 0.17 13:48:45 INFO - mozdebug == 0.1 13:48:45 INFO - mozdevice == 0.48 13:48:45 INFO - mozfile == 1.2 13:48:45 INFO - mozhttpd == 0.7 13:48:45 INFO - mozinfo == 0.9 13:48:45 INFO - mozleak == 0.1 13:48:45 INFO - mozlog == 3.1 13:48:45 INFO - moznetwork == 0.27 13:48:45 INFO - mozprocess == 0.22 13:48:45 INFO - mozprofile == 0.28 13:48:45 INFO - mozrunner == 6.11 13:48:45 INFO - mozscreenshot == 0.1 13:48:45 INFO - mozsystemmonitor == 0.0 13:48:45 INFO - moztest == 0.7 13:48:45 INFO - mozversion == 1.4 13:48:45 INFO - psutil == 3.1.1 13:48:45 INFO - requests == 1.2.3 13:48:45 INFO - wsgiref == 0.1.2 13:48:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:48: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')]} 13:48:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:48:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48: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')]} 13:48:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:48:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:45 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 0x101d3aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d4a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9fa497c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bd9e00>, '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 0x101a42db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101bd4b20>, '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.qqoY3GEJJl/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.0bA9dSrB1C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:48:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 13:48:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:48:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qqoY3GEJJl/Render', 13:48:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:48:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:48:45 INFO - 'HOME': '/Users/cltbld', 13:48:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:48:45 INFO - 'LOGNAME': 'cltbld', 13:48:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:48:45 INFO - 'MOZ_NO_REMOTE': '1', 13:48:45 INFO - 'NO_EM_RESTART': '1', 13:48:45 INFO - 'PAGER': '/bin/cat', 13:48:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:48:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:48:45 INFO - 'PWD': '/builds/slave/test', 13:48:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:48:45 INFO - 'SHELL': '/bin/bash', 13:48:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0bA9dSrB1C/Listeners', 13:48:45 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 13:48:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:48:45 INFO - 'USER': 'cltbld', 13:48:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:48:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:48:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:48:45 INFO - 'XPC_FLAGS': '0x0', 13:48:45 INFO - 'XPC_SERVICE_NAME': '0', 13:48:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:48:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:48:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 13:48:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 13:48:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 13:48:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 13:48:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 13:48:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 13:48:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 13:48:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 13:48:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 13:48:46 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 13:48:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 13:48:46 INFO - Cleaning up... 13:48:46 INFO - Return code: 0 13:48:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:48: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')]} 13:48:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:48:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48: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')]} 13:48:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:48:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101d3aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d4a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9fa497c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bd9e00>, '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 0x101a42db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101bd4b20>, '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.qqoY3GEJJl/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.0bA9dSrB1C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:48:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 13:48:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 13:48:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qqoY3GEJJl/Render', 13:48:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:48:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:48:46 INFO - 'HOME': '/Users/cltbld', 13:48:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:48:46 INFO - 'LOGNAME': 'cltbld', 13:48:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:48:46 INFO - 'MOZ_NO_REMOTE': '1', 13:48:46 INFO - 'NO_EM_RESTART': '1', 13:48:46 INFO - 'PAGER': '/bin/cat', 13:48:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:48:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:48:46 INFO - 'PWD': '/builds/slave/test', 13:48:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:48:46 INFO - 'SHELL': '/bin/bash', 13:48:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0bA9dSrB1C/Listeners', 13:48:46 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 13:48:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:48:46 INFO - 'USER': 'cltbld', 13:48:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:48:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:48:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:48:46 INFO - 'XPC_FLAGS': '0x0', 13:48:46 INFO - 'XPC_SERVICE_NAME': '0', 13:48:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:48:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:48:46 INFO - Downloading/unpacking jsonschema 13:48:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:48:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:48:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:48:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:48:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:48:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:48:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:48:48 INFO - Downloading/unpacking functools32 (from jsonschema) 13:48:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:48:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:48:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:48:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:48:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:48:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:48:49 INFO - Downloading functools32-3.2.3-2.tar.gz 13:48:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:48:49 INFO - warning: no files found matching '*.txt' 13:48:49 INFO - no previously-included directories found matching 'build' 13:48:49 INFO - no previously-included directories found matching 'dist' 13:48:49 INFO - no previously-included directories found matching '.git*' 13:48:49 INFO - Installing collected packages: jsonschema, functools32 13:48:49 INFO - Running setup.py install for functools32 13:48:49 INFO - warning: no files found matching '*.txt' 13:48:49 INFO - no previously-included directories found matching 'build' 13:48:49 INFO - no previously-included directories found matching 'dist' 13:48:49 INFO - no previously-included directories found matching '.git*' 13:48:49 INFO - Successfully installed jsonschema functools32 13:48:49 INFO - Cleaning up... 13:48:49 INFO - Return code: 0 13:48:49 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 13:48:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:48:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:48:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:48:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:48:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:49 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 0x101d3aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d4a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9fa497c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101bd9e00>, '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 0x101a42db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101bd4b20>, '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.qqoY3GEJJl/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.0bA9dSrB1C/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:48:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 13:48:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 13:48:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qqoY3GEJJl/Render', 13:48:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:48:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:48:49 INFO - 'HOME': '/Users/cltbld', 13:48:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:48:49 INFO - 'LOGNAME': 'cltbld', 13:48:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:48:49 INFO - 'MOZ_NO_REMOTE': '1', 13:48:49 INFO - 'NO_EM_RESTART': '1', 13:48:49 INFO - 'PAGER': '/bin/cat', 13:48:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:48:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:48:49 INFO - 'PWD': '/builds/slave/test', 13:48:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:48:49 INFO - 'SHELL': '/bin/bash', 13:48:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0bA9dSrB1C/Listeners', 13:48:49 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 13:48:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:48:49 INFO - 'USER': 'cltbld', 13:48:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:48:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:48:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:48:49 INFO - 'XPC_FLAGS': '0x0', 13:48:49 INFO - 'XPC_SERVICE_NAME': '0', 13:48:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:48:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:48:49 INFO - Downloading/unpacking flake8 13:48:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:48:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:48:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:48:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:48:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:48:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:48:52 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 13:48:52 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 13:48:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:48:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:48:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:48:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:48:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:48:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:48:52 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 13:48:52 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 13:48:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:48:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:48:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:48:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:48:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:48:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:48:53 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 13:48:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:48:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:48:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:48:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:48:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:48:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:48:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 13:48:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:48:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:48:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:48:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:48:53 INFO - no previously-included directories found matching 'docs/_build' 13:48:53 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 13:48:53 INFO - Running setup.py install for pep8 13:48:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:48:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:48:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:48:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:48:53 INFO - no previously-included directories found matching 'docs/_build' 13:48:54 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 13:48:54 INFO - Successfully installed flake8 mccabe pyflakes pep8 13:48:54 INFO - Cleaning up... 13:48:54 INFO - Return code: 0 13:48:54 INFO - Running post-action listener: _resource_record_post_action 13:48:54 INFO - Running post-action listener: _start_resource_monitoring 13:48:54 INFO - Starting resource monitoring. 13:48:54 INFO - ##### 13:48:54 INFO - ##### Running install step. 13:48:54 INFO - ##### 13:48:54 INFO - Running pre-action listener: _resource_record_pre_action 13:48:54 INFO - Running main action method: install 13:48:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:48:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:48:54 INFO - Reading from file tmpfile_stdout 13:48:54 INFO - Detecting whether we're running mozinstall >=1.0... 13:48:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:48:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:48:54 INFO - Reading from file tmpfile_stdout 13:48:54 INFO - Output received: 13:48:54 INFO - Usage: mozinstall [options] installer 13:48:54 INFO - Options: 13:48:54 INFO - -h, --help show this help message and exit 13:48:54 INFO - -d DEST, --destination=DEST 13:48:54 INFO - Directory to install application into. [default: 13:48:54 INFO - "/builds/slave/test"] 13:48:54 INFO - --app=APP Application being installed. [default: firefox] 13:48:54 INFO - mkdir: /builds/slave/test/build/application 13:48:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 13:48:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 13:49:26 INFO - Reading from file tmpfile_stdout 13:49:26 INFO - Output received: 13:49:26 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 13:49:26 INFO - Running post-action listener: _resource_record_post_action 13:49:26 INFO - ##### 13:49:26 INFO - ##### Running run-tests step. 13:49:26 INFO - ##### 13:49:26 INFO - Running pre-action listener: _resource_record_pre_action 13:49:26 INFO - Running main action method: run_tests 13:49:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:49:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:49:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:49:26 INFO - 2016-05-16 13:49:26.030 screenresolution[1943:12359] starting screenresolution argv=screenresolution get 13:49:26 INFO - 2016-05-16 13:49:26.032 screenresolution[1943:12359] Display 0: 1600x1200x32@60 13:49:26 INFO - 2016-05-16 13:49:26.037 screenresolution[1944:12361] starting screenresolution argv=screenresolution list 13:49:26 INFO - Available Modes on Display 0 13:49:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:49:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:49:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:49:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:49:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:49:26 INFO - Intel Iris: 13:49:26 INFO - Chipset Model: Intel Iris 13:49:26 INFO - Type: GPU 13:49:26 INFO - Bus: Built-In 13:49:26 INFO - VRAM (Dynamic, Max): 1536 MB 13:49:26 INFO - Vendor: Intel (0x8086) 13:49:26 INFO - Device ID: 0x0a2e 13:49:26 INFO - Revision ID: 0x0009 13:49:26 INFO - Displays: 13:49:26 INFO - Display: 13:49:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:49:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:49:26 INFO - Display Serial Number: 1600x1200 60 13:49:26 INFO - Main Display: Yes 13:49:26 INFO - Mirror: Off 13:49:26 INFO - Online: Yes 13:49:26 INFO - Rotation: Supported 13:49:26 INFO - Return code: 0 13:49:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:49:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:49:26 INFO - Python 2.7.3 13:49:26 INFO - Return code: 0 13:49:26 INFO - grabbing minidump binary from tooltool 13:49:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:49:26 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 0x101bd9e00>, '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 0x101a42db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101bd4b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:49:26 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 13:49:26 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 13:49:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:49:26 INFO - Return code: 0 13:49:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:49:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:49:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:49:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:49:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:49:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 13:49:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 13:49:27 INFO - Return code: 0 13:49:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BHfYKtMzTuy974xb02XETA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0342', '--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 13:49:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BHfYKtMzTuy974xb02XETA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0342 --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 13:49:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qqoY3GEJJl/Render', 13:49:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:49:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:49:27 INFO - 'HOME': '/Users/cltbld', 13:49:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:49:27 INFO - 'LOGNAME': 'cltbld', 13:49:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:49:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:49:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:49:27 INFO - 'MOZ_NO_REMOTE': '1', 13:49:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:49:27 INFO - 'NO_EM_RESTART': '1', 13:49:27 INFO - 'PAGER': '/bin/cat', 13:49:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:49:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:49:27 INFO - 'PWD': '/builds/slave/test', 13:49:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:49:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:49:27 INFO - 'SHELL': '/bin/bash', 13:49:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0bA9dSrB1C/Listeners', 13:49:27 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 13:49:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:49:27 INFO - 'USER': 'cltbld', 13:49:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:49:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:49:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:49:27 INFO - 'XPC_FLAGS': '0x0', 13:49:27 INFO - 'XPC_SERVICE_NAME': '0', 13:49:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:49:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BHfYKtMzTuy974xb02XETA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0342', '--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 13:49:27 INFO - mozversion application_buildid: 20160516124143 13:49:27 INFO - mozversion application_changeset: 17724486e1591858eea09dc327475a25883854a4 13:49:27 INFO - mozversion application_display_name: FirefoxDeveloperEdition 13:49:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:49:27 INFO - mozversion application_name: Firefox 13:49:27 INFO - mozversion application_remotingname: firefox-dev 13:49:27 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:49:27 INFO - mozversion application_vendor: Mozilla 13:49:27 INFO - mozversion application_version: 48.0a2 13:49:27 INFO - mozversion platform_buildid: 20160516124143 13:49:27 INFO - mozversion platform_changeset: 17724486e1591858eea09dc327475a25883854a4 13:49:27 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:49:27 INFO - mozversion platform_version: 48.0a2 13:49:27 INFO - using testdate: 1463431767 13:49:27 INFO - actual date: 1463431767 13:49:27 INFO - starting webserver on 'localhost:51330' 13:49:27 INFO - SUITE-START | Running 2 tests 13:49:27 INFO - TEST-START | dromaeo_css 13:49:27 INFO - operating with platform_type : mac_ 13:49:27 INFO - Initialising browser for dromaeo_css test... 13:49:27 INFO - TEST-INFO | started process 1962 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/tmpPwUXAt/profile http://localhost:51330/getInfo.html) 13:49:30 INFO - PROCESS | 1962 | __metrics Screen width/height:1600/1200 13:49:30 INFO - PROCESS | 1962 | colorDepth:24 13:49:30 INFO - PROCESS | 1962 | Browser inner width/height: 1024/689 13:49:30 INFO - PROCESS | 1962 | __metrics 13:49:30 ERROR - PROCESS | 1962 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 13:49:31 INFO - TEST-INFO | 1962: exit 0 13:49:31 INFO - Browser initialized. 13:49:31 INFO - Running cycle 1/1 for dromaeo_css test... 13:49:31 INFO - TEST-INFO | started process 1965 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/tmpPwUXAt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:49:43 INFO - PROCESS | 1965 | RSS: Main: 221294592 13:49:43 INFO - PROCESS | 1965 | 13:50:32 INFO - PROCESS | 1965 | 1463431832750 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 13:52:43 INFO - PROCESS | 1965 | Cycle 1(1): loaded http://localhost:51330/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51330/tests/dromaeo/cssquery-ext.html) 13:52:45 INFO - PROCESS | 1965 | RSS: Main: 232124416 13:52:45 INFO - PROCESS | 1965 | 13:55:47 INFO - PROCESS | 1965 | Cycle 1(1): loaded http://localhost:51330/tests/dromaeo/cssquery-ext.html (next: http://localhost:51330/tests/dromaeo/cssquery-jquery.html) 13:55:47 INFO - PROCESS | 1965 | RSS: Main: 243990528 13:55:47 INFO - PROCESS | 1965 | 13:58:49 INFO - PROCESS | 1965 | Cycle 1(1): loaded http://localhost:51330/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51330/tests/dromaeo/cssquery-mootools.html) 13:58:50 INFO - PROCESS | 1965 | RSS: Main: 245977088 13:58:50 INFO - PROCESS | 1965 | 14:01:52 INFO - PROCESS | 1965 | Cycle 1(1): loaded http://localhost:51330/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51330/tests/dromaeo/cssquery-prototype.html) 14:01:52 INFO - PROCESS | 1965 | RSS: Main: 247660544 14:01:52 INFO - PROCESS | 1965 | 14:04:55 INFO - PROCESS | 1965 | Cycle 1(1): loaded http://localhost:51330/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51330/tests/dromaeo/cssquery-yui.html) 14:04:56 INFO - PROCESS | 1965 | RSS: Main: 250494976 14:04:56 INFO - PROCESS | 1965 | 14:07:58 INFO - PROCESS | 1965 | Cycle 1(1): loaded http://localhost:51330/tests/dromaeo/cssquery-yui.html (next: http://localhost:51330/tests/dromaeo/cssquery-dojo.html) 14:07:58 INFO - PROCESS | 1965 | RSS: Main: 251179008 14:07:58 INFO - PROCESS | 1965 | 14:07:58 INFO - PROCESS | 1965 | __start_tp_report 14:07:58 INFO - PROCESS | 1965 | _x_x_mozilla_page_load 14:07:58 INFO - PROCESS | 1965 | _x_x_mozilla_page_load_details 14:07:58 INFO - PROCESS | 1965 | |i|pagename|runs| 14:07:58 INFO - PROCESS | 1965 | |0;dojo.html;2770.709075547067;3450.3444345574353;3476.669716376943;3478.7738796978197;3527.2063785648234;5786.566007549435;5815.912761308584;5823.475887170154;5834.416558344169;5843.4156584341545;5779.017567013607;5794.884102317962;5805.941940580593;5841.707914604263;5858.355580886099;5206.713630750317;5277.736113194344;5289.100852855024;5296.364436267634;5313.20301954708;5194.584433245332;5287.365516138049;5309.522143007129;5313.388960269561;5313.468653134694;5248.71132087735;5252.737363131847;5285.286486324355;5288.15389537674;5310.8141215057485;5261.657992230493;5300.92048619271;5304.6286759926825;5305.0450918834595;5312.415634280264;5213.572218183263;5280.445553216934;5284;5292.867678308073;5311.49540793624;5556.16657501374;5609.775608975677;5688.4311568843195;5710.514606258473;5865.120231965197;5112.258722485256;6104.572679912408;6133.509319254449;6140.263168419773;6232.127502149703;6141.907871381933;6198.690065496708;6207.099970504297;6221.377862213788;6226.315105338409;4917.852464426073;5764.510016648548;5791.652500849962;5802.332731735863;5916.822495325147;6149.938500614937;6196.287426945959;6205.162303089115;6205.379462053849;6214.5960512566535;6202.658853763086;6211.875762484816;6218.751249949952;6223.533235007392;6252.437280644764;6119.441623354662;6158;6206.658633775186;6224.284207316126;6224.657643829542;5025.346704928336;6224.751009959551;6233.594816336924;6234.096056071935;6239.220097487814;6049;6182.072689096672;6210;6210.068489726577;6231.376862313823;4894.290327902434;4938.111139994836;4943.65394422387;4955.331030310934;4990.426100998359;4417.3815665806815;4419.535948725337;4464;4467.061916997467;4470.150671372428;5704.8859022819315;5734.283214598176;5784.3926387729525;5784.971075144597;5793.188953546507;6094.542909281821;6129.603311142676;6138.388398386059;6184.134221209034;6209.192804935329;5772;5820.76716931318;5843.912341314884;5850.239468869105;5853.560982926212;5071.797128114834;5106.183010718267;5383.838484845461;5419.187121931663;5465.4261302563455;5179.300794392709;6178.227721534809;6209.9379006210265;6224.284207316126;6242.375762423721;5192.013517431676;6178.5366097543765;6180.629162250099;6181.567290289637;6199.814005579839;6193.473554747717;6221.844453888689;6253.530985176039;6310.368963103653;6358.205224346957;6114.7536090578;6123.8761238761235;6140.022089286603;6142.007694998287;6158.261008678808;5154.974225128851;6109.732506502054;6191.473724733448;6199.690015499117;6200.627962322094;5960.1357803117435;6025.397460253939;6096.603720758137;6126.387361263838;6167.321594624498;6129.1112788644505;6145.600535965148;6202.7208775606105;6208.348123446973;6210.441060304594;5205.193195054772;5743.10981797822;5995.91006134917;6058.0912863069125;6086.178365920721;6029.758809647565;6060.727267273071;6103.206583144163;6107.295156111712;6142.437386315837;5996.070609055603;6035.849103772441;6037.63774173551;6049.334573196857;6054.8183554493435 14:07:58 INFO - PROCESS | 1965 | |1;ext.html;498.32725820142787;535.8606762241817;561.2899681902392;575.2033433694332;577.9077543442894;30009.399812003747;36536.81731591342;37549.436758448646;37656.05859853505;37725.43411848821;38273.61726382736;38768.418473722915;38860.417093743614;38929.610703892955;38942;9413.952930235338;9636.18092462138;9652.420854748703;9666.323357364987;9692.982236865133;9537.427754334727;9767.120959113678;9792.265580081486;9802.117809397152;9833.213342932548;9732.65935692251;9828.459434731087;9832.508374581279;9872.90127098727;9880.505974701273;9761.609535618567;9805.558749856273;9868.703938881845;9871.506424678773;9939.65211217604;9598.472084035375;9717.902820971842;9723.90276097237;9724.17344525709;9737.026297370207;32214.516782248284;38384.23231535382;38787.83636490881;38805.02987425337;38812.611873881186;2665.920022399331;3245.334706385185;3284.9343013139605;3322.933541329184;3563.5367402237803;27423.725762742317;27572;28581.71418285811;28686.856565717037;28927.56608650872;2772.4316515114556;2813.2263627502603;2821.2946763309174;2833.4049849531625;2841.701621329751;485106;491065;491433;492452.5377373126;494662;33523.4971475428;35871.82064089663;53161.468385316424;59882.70058649766;60395.79208415895;34784.65215347814;34795.65204347924;34911.65088349135;34972.47541286883;35057.82471087679;15235.466758663393;15336.846631533543;15385;15428.537143885702;15461.304241309168;12920;13013.804792928113;13074.869251307366;13089.280089595164;13102.082854200306;8177.9591102044105;8208.548529830916;8237.752867413987;8238.588070596446;8283.213094756109;3341.983290083534;3484.3728128936527;3489.8537399499123;3496.265784185299;3517.2965406918206;1027.2501074215716;1036.5957276662107;1074.8172810622214;1095.6220104064084;1152.7233463968587;28194.154175374773;29018.12945611635;29090.27274318159;29556.408871822874;29829;1104.5857803323754;1150.4995327032768;1161.297415063891;1171.6133675887106;1265.2661456355293;15124.924375378052;15239.695206096038;15256.847431525543;15395.384184632714;15522.068675879484;650558.7472062609;652736;664005;664548;668274;58526.70736646289;58901.11648325279;58966;59153.704231478565;59304.70347648234;1494.7384207763598;1520.323456062064;1529.3118096856458;1539.1534655939188;1609.7302803047646;24158.751526936827;29369.85315073411;29584.704152958195;29972;30111.548326775555;51108;51328;51357.7432112852;51486.48513514817;51654.22518662148;578894;580205;583933;587366;589692.0515397396;26546.469070618867;41404.378934315406;42461.36307955445;43398.78300608477;43524;45223.547764521936;46297;46986;47864;48179.75910120427;29338.559921600765;34191.31617367688;35429.82285088558;36255.63744362522;37640.43539346857;35815.10462238465;35818.28363432769;35877.461838071926;35960.28079438344;36416.81791591131;37159;37251.88244352674;38514.614853851104;38526.229475410895;38809.6119038806;24920.750792491843;29420.558691619215;30574;31074.378512430078;31562.526562101128;24727.38181545404;24897.75102249027;24992.62511062299;25314;25365.365865853502 14:07:58 INFO - PROCESS | 1965 | |2;jquery.html;371.6580745713943;402.5049189496919;403.2479425870752;409.83196889275393;417.1157146848681;15366.001209921347;15633;16638.41765538205;19788.109535070922;20611.690824637622;19599.70600440991;19995.900020499877;20032.49918752032;20304.18783248672;20674.173033078696;5895.498882594985;5992.191054207691;6156.138140660289;6186.133941248228;6192.59748116371;6051.1830902828215;6136.447719705226;6152.630842149463;6156.907646385308;6159.784407545737;4689.296605509184;6093.908591371133;6100.450959413652;6124.969375153118;6125.081237814341;5861.5603829712945;5966.37353077927;6020.458158765732;6025.728842202111;6039.909401358983;5707.914381284242;5814.331351894544;5827.155062515953;5828.795992140297;5849.444302791228;12938.48246070156;13406.46374145033;18333;24825.5034899301;24931;2026.98986505068;2095.1200495791804;2150.53763440861;2346.6480027995767;2777.388974425623;9540.475273859935;9931.304808663397;10213.285070045025;10307.072363487247;10383.42891140987;1697.7198762204346;1714.41709818661;1716.948491545243;1737.5569229846308;1783.9018853963028;114663;117611;120303.79696203013;121196.39401803023;121959;9826.606935722633;11649.59226427071;11879.524819007316;11947.880521194676;11948;14983;15224.314905829264;15231.543053708407;15264.007839490398;15288.38846446152;7383.372413344819;8948.328875334377;9045.231155351737;9127.58925848325;9161.404508707046;8988.865167022499;8998.460092394478;9047.32145089121;9147.954260228656;9163.541822908828;3963.048868271652;4635.582797548237;4664;4710.929336059893;4775.928361074587;1713.6230029393514;1956.923691969411;2024.453397582674;2026.1490174126902;2066.5866826634733;848.733337294757;928.382625553999;987.6790043235977;1006.942710154335;1024.5594394410325;18293;20146.496337591678;20217.393478195678;20497.897510512652;20550.38348849537;965.4496936745987;995.5470261031213;996.8604395384652;1005.3113617172261;1014.3812274512542;10605.098566621924;10799;10817.729556761144;10839.566417343376;10998.285111467729;566529;584872;594999;596551;597191;112479;116549.4172529149;118833.40583297201;119925.40037299758;120229.39885300517;1009.5412129472841;1012.417859730643;1012.9797404052025;1057.440992797432;1082.2370228988543;17948.37180698669;18149.092545372416;18164.636707265516;18259.908700456413;18672;51467.74266128645;52395;52657;52870.942581148935;54760.45239547713;74360.25639743534;78260.60869695614;80991;81735.59132204301;82560.17439825764;7961.641726122222;8199.139090395423;8276.0896301408;8338.332933365438;8419.747407577783;8445;9284.02517735628;12026.797320267904;12582.433790479634;12920.676983075498;10869.456527173768;11081.667549973514;11104.722381940515;11296.774064518828;11442.542298307975;10706.625268115578;10828.891711083104;10872.782544349227;10997.61508347204;11186.440677966231;11264.58026325662;11319.717007074889;11443.313401195956;11451.02666273376;11495.252808567417;7817.335526480313;8152.347812174893;8244.670213191645;8321.16788321163;8561.82876342482;9397.29520285995;11180;11403.315801051964;11464;11525.596604118815 14:07:58 INFO - PROCESS | 1965 | |3;mootools.html;1551.8525740054695;1565.6712090461003;1777.9555511112221;1870.4482177757552;1959.8530110241736;7996.600169991506;8618;8793.340499462542;8998.19016288534;9000.594973226203;7350.411967042637;8776.64893404263;8910.019897811251;8917.643294268239;8931.64273429062;2479.429731161834;2502.1367628168346;2504.524140413325;2509.3224829296078;2518.1186584695392;2463.224084413413;2532.936676583082;2540.0855691950883;2540.8348457350307;2550.591905295153;2510.8493490390633;2517.2070797698666;2519.685039370079;2527.9620805687928;2538.5557527432716;2518.433352495683;2529.5699731045675;2531.3291977625945;2534.7465253474597;2536.69559652841;2508.8745562721792;2522.7729504344516;2522.785563227128;2541.733118022618;2545.5163518931345;7748.496347737379;9011.369204155711;9078.818423631556;9081.772955676095;9086.363954523185;4825.782839772219;4915.3364295819965;5167.069927413064;5537.861553461155;5578.135389014709;3561.732870034732;3626.5285512883156;3673.430618254175;3691.169486865461;3697.8335974880924;1067.6583493282153;1084.0622861224983;1086.5056399338362;1087.912966962649;1091.4106382553412;12248.44881980313;12348.135630505869;12364;12400.62798116058;12417.317047562381;4863.2461968394955;4891.6575839691595;4957.107720610325;4957.281194226817;4987.077390682735;10350.844737328945;10356.482175891175;10375.16998640107;10403.323783954018;10412.323198992193;5549.556035517149;5550.972245138749;5586.888262234814;5589.301337332833;5594.356648985337;4486.237339652267;4881.341018962395;5013.373328333958;5063.87340316495;5092.185250359925;4119.546849846514;4125.8968525787095;4145.834166633361;4182.749035057906;4193.161367726467;1366.8906487480979;1395.260511928659;1397.6925076483158;1405.501047128275;1415.1226239731432;916.5829547555846;918.7151982885327;937.4609599480326;940.4169414962766;950.2635457520477;5874.706264686834;5916.674582897896;5949.375315591887;6038;6141.539384546177;465.7182404645209;472.8061494787121;472.90069085492433;479.398355064392;486.77851577532135;3821.6942644588366;3857.5756666766633;3869.7678139311734;3920.2355540669864;4004.5594984551676;9314.627414903329;10675.359478431319;11107.777844443066;11116.444177791078;11153.10775137987;10819.459027048617;10868.782624347468;10945;10966.177536684781;10984.231103822816;7857.017872765904;8319.750407487785;8429.072801991775;8555.486670799772;8634.956825215833;4800.519948005312;5293.5765138789575;5601.103823388238;5629.549636029026;5698.1452782083015;9688.12806847387;9833.704988850346;9855.35940163887;9943.403395796275;10046.799064018825;9757.56090975922;9958.55186516623;10141.340812847142;10156.441395723306;10365;6784;7197.640117994184;7268.709251629875;7339.522931009467;7443.962780186064;8285.212904774036;8285.83428331442;8325.375596830141;8345.290650294794;8368.497890126611;6383.744650213939;7138.179109402277;7336.853262934818;7395.445341599294;7515.436342274242;7173.10336207974;7197.676104575202;7304.598247096248;7335.596542190231;7343.559386436831;6847.109875716125;6859.17689877198;6864.897026544506;6894.24163342022;6911.5853048817235;4103.958960410477;4721.126591580569;4785.162596545521;4788.186008378653;4802.135615589227;4115.0741083256025;4194.412782210432;4857.878553036061;4882.023595280887;4926.482719314564 14:07:58 INFO - PROCESS | 1965 | |4;prototype.html;290.9810862293951;330.8130906038088;333.5208350963477;336.16630755725816;337.0629649574184;5475.589330800191;6872;6897.6206308653;6924.757633482829;6928.099347084872;5449.455054494549;6833;6909.861802763954;6922.826929326757;6930;4803.231482962718;4865.002674451746;5003.549680528752;5006.424261209971;5010.398752149747;5004.324416203803;5069.2649565812935;5076.695398276097;5077.33994580704;5089.109405853979;4051.4530538377235;5023.572996295319;5027.120253955562;5049;5052.696838189702;5022.42242142151;5032.974835125838;5035.395752509704;5040.571551418116;5060.392752869642;4311.935320970188;4327.6105150536605;4330.588594083589;4331.830405790451;4339.262325404688;6406.967965160191;6686.598804071723;6691.966540167268;6698.5980841149685;6714.529982901199;5906.025505791539;5936.376680448534;5943.673097979609;5949;5957.761689532414;7402.666879990414;7426.702931882718;7431.554106753612;7450.888236676454;7467.327940485382;4718.386609740746;4725.298892398734;4740.217864052427;4743.146233677937;4832.492588278251;7511.661975211128;7764.689412423496;7882.487638303492;7886.093099293597;7892.526448413114;4734.976325118352;4748.477667456577;4786.832460863853;4795.184818580849;4810.470848206694;4851.078295123915;4871.14754917888;4887.047025829929;4891.975540122347;4901.411830580282;5902.527797776168;5904.763809447574;5906.940930590725;5928.347881733006;5964.135200396005;5989.670568118795;6374;7424.368928641018;7457.328840404281;7468.962655186798;1675.3801093595448;1697.558634754948;1698.8555972742208;1699.226851782436;1710.3500669745417;1043.3062013760764;1074.7259448840653;1082.3397727386289;1086;1088.0968795899385;6861.284678830292;6934.791956241321;6974.860502789916;6977.069630754993;6978.546394484342;6261;6509.804705858832;7372.189059203483;7384.926150738531;7612.619369031526;6921.757738479129;6939.097917270722;6939.410150137194;6945.652717364213;7003.894941575881;3186.5538824564114;3572.4105522589;3921.568627450978;4085.1216988347646;4094.3653733671326;7965.566198084401;8147.185281471805;8157.8368432632205;8159.184081591793;8164.877526837102;7495.850082998419;7708.691652333844;7940;7989.480683755537;8111.878321825177;7470.439717021137;7568.735094271674;8010.076322142376;8146;8156.918430815735;7759.961200193963;7787.5716835574585;7809.765707028798;7827.373810095292;7827.904093427039;7672.501287416523;7678.61606919663;7691.269329413697;7697.191794861683;7698.846023079619;7675.846483070419;7957.8408431832195;7958.801029974297;7976.760697179094;7977.162397948364;4690.108879312919;4781.641376896677;4785.736784476887;4788.9760551198415;4793.760311984456;7613.467057306157;7641.35048520882;7693.192214817587;7697.114831794415;7704.152543220353;7181.35367816899;7356;7387.298206670358;7394;7396.9260307396235;7368.668409921674;7388.039554857834;7402.037735094404;7412.295831895961;7419.851602968019;7198.424126070006;7210.603416812126;7235.2764723527225;7260.745873894389;7315.524490908074;7481.66332515049;7496.8500629986;7497.175310715707;7509.399248060251;7512.962435187789;7090.4327653788605;7308.926910730825;7324.670389832364;7339.302766237199;7356.080489938758 14:07:58 INFO - PROCESS | 1965 | |5;yui.html;668.0580381661857;697.8569393274379;736.6869080640727;740.1857956248124;756.1757684124306;14712.485063022796;14844.109353438802;14956.551303460887;14985.400583976629;14994.850051499496;13764.655883602914;13774.380152893118;13865.792013119812;13866.37601307941;13901.791473127885;717.8636059148771;895.9552022398886;899.4378513429107;904.9773755656096;907.1110311894347;751.6429695894461;901.324006994754;905.8686490458913;907.1518130547926;908.2869947091534;945.9101385368414;952.2334520710825;969.8642190093393;971.5142428785607;972.8735264415616;939.9577019034126;953.4374718915873;954.2985905359556;955.8279509688218;965.8164948659768;691.5332150798191;691.8443350246205;700.3976580141075;716.4447553146354;716.6918225163178;14433.134011959211;14445.855541444556;14463.927680361636;14490.058146220568;14611.78082328766;12703.364831758376;12873.098883078188;13028.80456793149;13045.217286962717;13146.079774415795;9861.112499874975;9917.603295868157;10074.848877266846;10265.538050787733;10325.122364599018;3537.2394935088823;3553.1650062235353;3553.538040054802;3554.9466757998653;3622.4023036198996;55765.44234557643;73623.63188184079;73732;73761.89357159648;74141.88787168197;7863.528188308605;7947.324477419368;9528.094830991045;9639.855402168972;9695.66065187715;21021;21058.47353816167;21085;21103.577928441344;21132.049057792436;7515.624218789038;7523.172451030382;7586.848263034819;7616;7915.643796029192;4056.1684854604737;4101.651359634405;4108.342665173558;4157.958420415817;4211.873643790691;3623.7825730456257;3691.90770230741;3701.648343407372;3701.8704345347783;3710.05393624624;616.1681729664916;671.7715976567893;673.71367168953;681.9863602727918;685.0306815855508;503.8387715930867;542.2192043457409;542.7883125581027;568.3861429656044;572.7165053298644;8121.89289222736;8221.218984196612;8240;8512.489250644981;8521.39086836892;531.2695044314069;567.9748054761196;601.8164459839703;605.6275390634728;619.8419403052193;3839.040239940015;3866.2460820140363;3907;3913.354296541039;4117.279476091672;71295.64352178205;94764;94839.52580237055;95329;95474;71570.9264361035;74216;74402.62798685972;74540.62729686426;76049;70654;70864.64567677231;71175.64412178009;71535.64232178805;71843;7661.502002369828;7745.419093568117;7826.882596760939;7938.404619653434;8146.67413303485;50145.498545015544;50683.98632027265;50693;50918.745406272734;51593;68738.65630671814;69210.65394672994;69245.26886827667;69797.65101174462;69885.30114698788;9759.951200244237;9894.604215831287;10053.648122315684;10087.344322619007;10102.949485252526;10579.206559507913;10593.841092383775;10792.56829726802;10834.03362389172;11140;10192.444406882212;10244.078032977068;10274.383536987805;10512.737181570523;10541.945805419396;8306.584670766559;9928.056834600702;10310.175185985254;10310.793784124426;10542.26204165701;9330.300227483101;9415.62337506492;9437.669681561112;9597.90402095998;9607.327487075838;8744.825103498022;8760.693375731818;8905.287576993955;8993.235574976201;9128.041555636562;8790.38467307308;8791.736247912573;8883.111688831064;8928.241099506355;8945.284377249935 14:07:58 INFO - PROCESS | 1965 | __end_tp_report 14:07:58 INFO - PROCESS | 1965 | __start_cc_report 14:07:58 INFO - PROCESS | 1965 | _x_x_mozilla_cycle_collect,415 14:07:58 INFO - PROCESS | 1965 | __end_cc_report 14:07:58 INFO - PROCESS | 1965 | __startTimestamp1463432878408__endTimestamp 14:07:58 INFO - PROCESS | 1965 | 14:07:58 INFO - PROCESS | 1965 | ------- Summary: start ------- 14:07:58 INFO - PROCESS | 1965 | Number of tests: 6 14:07:58 INFO - PROCESS | 1965 | 14:07:58 INFO - PROCESS | 1965 | [#0] dojo.html Cycles:165 Average:5715.50 Median:5960.14 stddev:631.44 (10.6%) stddev-sans-first:589.61 14:07:58 INFO - PROCESS | 1965 | Values: 2770.7 3450.3 3476.7 3478.8 3527.2 5786.6 5815.9 5823.5 5834.4 5843.4 5779.0 5794.9 5805.9 5841.7 5858.4 5206.7 5277.7 5289.1 5296.4 5313.2 5194.6 5287.4 5309.5 5313.4 5313.5 5248.7 5252.7 5285.3 5288.2 5310.8 5261.7 5300.9 5304.6 5305.0 5312.4 5213.6 5280.4 5284.0 5292.9 5311.5 5556.2 5609.8 5688.4 5710.5 5865.1 5112.3 6104.6 6133.5 6140.3 6232.1 6141.9 6198.7 6207.1 6221.4 6226.3 4917.9 5764.5 5791.7 5802.3 5916.8 6149.9 6196.3 6205.2 6205.4 6214.6 6202.7 6211.9 6218.8 6223.5 6252.4 6119.4 6158.0 6206.7 6224.3 6224.7 5025.3 6224.8 6233.6 6234.1 6239.2 6049.0 6182.1 6210.0 6210.1 6231.4 4894.3 4938.1 4943.7 4955.3 4990.4 4417.4 4419.5 4464.0 4467.1 4470.2 5704.9 5734.3 5784.4 5785.0 5793.2 6094.5 6129.6 6138.4 6184.1 6209.2 5772.0 5820.8 5843.9 5850.2 5853.6 5071.8 5106.2 5383.8 5419.2 5465.4 5179.3 6178.2 6209.9 6224.3 6242.4 5192.0 6178.5 6180.6 6181.6 6199.8 6193.5 6221.8 6253.5 6310.4 6358.2 6114.8 6123.9 6140.0 6142.0 6158.3 5155.0 6109.7 6191.5 6199.7 6200.6 5960.1 6025.4 6096.6 6126.4 6167.3 6129.1 6145.6 6202.7 6208.3 6210.4 5205.2 5743.1 5995.9 6058.1 6086.2 6029.8 6060.7 6103.2 6107.3 6142.4 5996.1 6035.8 6037.6 6049.3 6054.8 14:07:58 INFO - PROCESS | 1965 | 14:07:58 INFO - PROCESS | 1965 | [#1] ext.html Cycles:180 Average:69193.03 Median:37205.44 stddev:156107.90 (419.6%) stddev-sans-first:156460.62 14:07:58 INFO - PROCESS | 1965 | Values: 498.3 535.9 561.3 575.2 577.9 30009.4 36536.8 37549.4 37656.1 37725.4 38273.6 38768.4 38860.4 38929.6 38942.0 9414.0 9636.2 9652.4 9666.3 9693.0 9537.4 9767.1 9792.3 9802.1 9833.2 9732.7 9828.5 9832.5 9872.9 9880.5 9761.6 9805.6 9868.7 9871.5 9939.7 9598.5 9717.9 9723.9 9724.2 9737.0 32214.5 38384.2 38787.8 38805.0 38812.6 2665.9 3245.3 3284.9 3322.9 3563.5 27423.7 27572.0 28581.7 28686.9 28927.6 2772.4 2813.2 2821.3 2833.4 2841.7 485106.0 491065.0 491433.0 492452.5 494662.0 33523.5 35871.8 53161.5 59882.7 60395.8 34784.7 34795.7 34911.7 34972.5 35057.8 15235.5 15336.8 15385.0 15428.5 15461.3 12920.0 13013.8 13074.9 13089.3 13102.1 8178.0 8208.5 8237.8 8238.6 8283.2 3342.0 3484.4 3489.9 3496.3 3517.3 1027.3 1036.6 1074.8 1095.6 1152.7 28194.2 29018.1 29090.3 29556.4 29829.0 1104.6 1150.5 1161.3 1171.6 1265.3 15124.9 15239.7 15256.8 15395.4 15522.1 650558.7 652736.0 664005.0 664548.0 668274.0 58526.7 58901.1 58966.0 59153.7 59304.7 1494.7 1520.3 1529.3 1539.2 1609.7 24158.8 29369.9 29584.7 29972.0 30111.5 51108.0 51328.0 51357.7 51486.5 51654.2 578894.0 580205.0 583933.0 587366.0 589692.1 26546.5 41404.4 42461.4 43398.8 43524.0 45223.5 46297.0 46986.0 47864.0 48179.8 29338.6 34191.3 35429.8 36255.6 37640.4 35815.1 35818.3 35877.5 35960.3 36416.8 37159.0 37251.9 38514.6 38526.2 38809.6 24920.8 29420.6 30574.0 31074.4 31562.5 24727.4 24897.8 24992.6 25314.0 25365.4 14:07:58 INFO - PROCESS | 1965 | 14:07:58 INFO - PROCESS | 1965 | [#2] jquery.html Cycles:180 Average:34251.07 Median:11370.38 stddev:98082.47 (862.6%) stddev-sans-first:98324.63 14:07:58 INFO - PROCESS | 1965 | Values: 371.7 402.5 403.2 409.8 417.1 15366.0 15633.0 16638.4 19788.1 20611.7 19599.7 19995.9 20032.5 20304.2 20674.2 5895.5 5992.2 6156.1 6186.1 6192.6 6051.2 6136.4 6152.6 6156.9 6159.8 4689.3 6093.9 6100.5 6125.0 6125.1 5861.6 5966.4 6020.5 6025.7 6039.9 5707.9 5814.3 5827.2 5828.8 5849.4 12938.5 13406.5 18333.0 24825.5 24931.0 2027.0 2095.1 2150.5 2346.6 2777.4 9540.5 9931.3 10213.3 10307.1 10383.4 1697.7 1714.4 1716.9 1737.6 1783.9 114663.0 117611.0 120303.8 121196.4 121959.0 9826.6 11649.6 11879.5 11947.9 11948.0 14983.0 15224.3 15231.5 15264.0 15288.4 7383.4 8948.3 9045.2 9127.6 9161.4 8988.9 8998.5 9047.3 9148.0 9163.5 3963.0 4635.6 4664.0 4710.9 4775.9 1713.6 1956.9 2024.5 2026.1 2066.6 848.7 928.4 987.7 1006.9 1024.6 18293.0 20146.5 20217.4 20497.9 20550.4 965.4 995.5 996.9 1005.3 1014.4 10605.1 10799.0 10817.7 10839.6 10998.3 566529.0 584872.0 594999.0 596551.0 597191.0 112479.0 116549.4 118833.4 119925.4 120229.4 1009.5 1012.4 1013.0 1057.4 1082.2 17948.4 18149.1 18164.6 18259.9 18672.0 51467.7 52395.0 52657.0 52870.9 54760.5 74360.3 78260.6 80991.0 81735.6 82560.2 7961.6 8199.1 8276.1 8338.3 8419.7 8445.0 9284.0 12026.8 12582.4 12920.7 10869.5 11081.7 11104.7 11296.8 11442.5 10706.6 10828.9 10872.8 10997.6 11186.4 11264.6 11319.7 11443.3 11451.0 11495.3 7817.3 8152.3 8244.7 8321.2 8561.8 9397.3 11180.0 11403.3 11464.0 11525.6 14:07:58 INFO - PROCESS | 1965 | 14:07:58 INFO - PROCESS | 1965 | [#3] mootools.html Cycles:180 Average:5741.82 Median:4813.96 stddev:3239.14 (67.3%) stddev-sans-first:3232.92 14:07:58 INFO - PROCESS | 1965 | Values: 1551.9 1565.7 1778.0 1870.4 1959.9 7996.6 8618.0 8793.3 8998.2 9000.6 7350.4 8776.6 8910.0 8917.6 8931.6 2479.4 2502.1 2504.5 2509.3 2518.1 2463.2 2532.9 2540.1 2540.8 2550.6 2510.8 2517.2 2519.7 2528.0 2538.6 2518.4 2529.6 2531.3 2534.7 2536.7 2508.9 2522.8 2522.8 2541.7 2545.5 7748.5 9011.4 9078.8 9081.8 9086.4 4825.8 4915.3 5167.1 5537.9 5578.1 3561.7 3626.5 3673.4 3691.2 3697.8 1067.7 1084.1 1086.5 1087.9 1091.4 12248.4 12348.1 12364.0 12400.6 12417.3 4863.2 4891.7 4957.1 4957.3 4987.1 10350.8 10356.5 10375.2 10403.3 10412.3 5549.6 5551.0 5586.9 5589.3 5594.4 4486.2 4881.3 5013.4 5063.9 5092.2 4119.5 4125.9 4145.8 4182.7 4193.2 1366.9 1395.3 1397.7 1405.5 1415.1 916.6 918.7 937.5 940.4 950.3 5874.7 5916.7 5949.4 6038.0 6141.5 465.7 472.8 472.9 479.4 486.8 3821.7 3857.6 3869.8 3920.2 4004.6 9314.6 10675.4 11107.8 11116.4 11153.1 10819.5 10868.8 10945.0 10966.2 10984.2 7857.0 8319.8 8429.1 8555.5 8635.0 4800.5 5293.6 5601.1 5629.5 5698.1 9688.1 9833.7 9855.4 9943.4 10046.8 9757.6 9958.6 10141.3 10156.4 10365.0 6784.0 7197.6 7268.7 7339.5 7444.0 8285.2 8285.8 8325.4 8345.3 8368.5 6383.7 7138.2 7336.9 7395.4 7515.4 7173.1 7197.7 7304.6 7335.6 7343.6 6847.1 6859.2 6864.9 6894.2 6911.6 4104.0 4721.1 4785.2 4788.2 4802.1 4115.1 4194.4 4857.9 4882.0 4926.5 14:07:58 INFO - PROCESS | 1965 | 14:07:58 INFO - PROCESS | 1965 | [#4] prototype.html Cycles:180 Average:6020.77 Median:6915.81 stddev:1963.33 (28.4%) stddev-sans-first:1921.16 14:07:58 INFO - PROCESS | 1965 | Values: 291.0 330.8 333.5 336.2 337.1 5475.6 6872.0 6897.6 6924.8 6928.1 5449.5 6833.0 6909.9 6922.8 6930.0 4803.2 4865.0 5003.5 5006.4 5010.4 5004.3 5069.3 5076.7 5077.3 5089.1 4051.5 5023.6 5027.1 5049.0 5052.7 5022.4 5033.0 5035.4 5040.6 5060.4 4311.9 4327.6 4330.6 4331.8 4339.3 6407.0 6686.6 6692.0 6698.6 6714.5 5906.0 5936.4 5943.7 5949.0 5957.8 7402.7 7426.7 7431.6 7450.9 7467.3 4718.4 4725.3 4740.2 4743.1 4832.5 7511.7 7764.7 7882.5 7886.1 7892.5 4735.0 4748.5 4786.8 4795.2 4810.5 4851.1 4871.1 4887.0 4892.0 4901.4 5902.5 5904.8 5906.9 5928.3 5964.1 5989.7 6374.0 7424.4 7457.3 7469.0 1675.4 1697.6 1698.9 1699.2 1710.4 1043.3 1074.7 1082.3 1086.0 1088.1 6861.3 6934.8 6974.9 6977.1 6978.5 6261.0 6509.8 7372.2 7384.9 7612.6 6921.8 6939.1 6939.4 6945.7 7003.9 3186.6 3572.4 3921.6 4085.1 4094.4 7965.6 8147.2 8157.8 8159.2 8164.9 7495.9 7708.7 7940.0 7989.5 8111.9 7470.4 7568.7 8010.1 8146.0 8156.9 7760.0 7787.6 7809.8 7827.4 7827.9 7672.5 7678.6 7691.3 7697.2 7698.8 7675.8 7957.8 7958.8 7976.8 7977.2 4690.1 4781.6 4785.7 4789.0 4793.8 7613.5 7641.4 7693.2 7697.1 7704.2 7181.4 7356.0 7387.3 7394.0 7396.9 7368.7 7388.0 7402.0 7412.3 7419.9 7198.4 7210.6 7235.3 7260.7 7315.5 7481.7 7496.9 7497.2 7509.4 7513.0 7090.4 7308.9 7324.7 7339.3 7356.1 14:07:58 INFO - PROCESS | 1965 | 14:07:58 INFO - PROCESS | 1965 | [#5] yui.html Cycles:180 Average:17684.35 Median:34971.56 stddev:24948.62 (71.3%) stddev-sans-first:24985.89 14:07:58 INFO - PROCESS | 1965 | Values: 668.1 697.9 736.7 740.2 756.2 14712.5 14844.1 14956.6 14985.4 14994.9 13764.7 13774.4 13865.8 13866.4 13901.8 717.9 896.0 899.4 905.0 907.1 751.6 901.3 905.9 907.2 908.3 945.9 952.2 969.9 971.5 972.9 940.0 953.4 954.3 955.8 965.8 691.5 691.8 700.4 716.4 716.7 14433.1 14445.9 14463.9 14490.1 14611.8 12703.4 12873.1 13028.8 13045.2 13146.1 9861.1 9917.6 10074.8 10265.5 10325.1 3537.2 3553.2 3553.5 3554.9 3622.4 55765.4 73623.6 73732.0 73761.9 74141.9 7863.5 7947.3 9528.1 9639.9 9695.7 21021.0 21058.5 21085.0 21103.6 21132.0 7515.6 7523.2 7586.8 7616.0 7915.6 4056.2 4101.7 4108.3 4158.0 4211.9 3623.8 3691.9 3701.6 3701.9 3710.1 616.2 671.8 673.7 682.0 685.0 503.8 542.2 542.8 568.4 572.7 8121.9 8221.2 8240.0 8512.5 8521.4 531.3 568.0 601.8 605.6 619.8 3839.0 3866.2 3907.0 3913.4 4117.3 71295.6 94764.0 94839.5 95329.0 95474.0 71570.9 74216.0 74402.6 74540.6 76049.0 70654.0 70864.6 71175.6 71535.6 71843.0 7661.5 7745.4 7826.9 7938.4 8146.7 50145.5 50684.0 50693.0 50918.7 51593.0 68738.7 69210.7 69245.3 69797.7 69885.3 9760.0 9894.6 10053.6 10087.3 10102.9 10579.2 10593.8 10792.6 10834.0 11140.0 10192.4 10244.1 10274.4 10512.7 10541.9 8306.6 9928.1 10310.2 10310.8 10542.3 9330.3 9415.6 9437.7 9597.9 9607.3 8744.8 8760.7 8905.3 8993.2 9128.0 8790.4 8791.7 8883.1 8928.2 8945.3 14:07:58 INFO - PROCESS | 1965 | -------- Summary: end -------- 14:07:58 INFO - PROCESS | 1965 | 14:07:59 INFO - TEST-INFO | 1965: exit 0 14:07:59 INFO - TEST-OK | dromaeo_css | took 1111395ms 14:07:59 INFO - TEST-START | kraken 14:07:59 INFO - operating with platform_type : mac_ 14:07:59 INFO - Initialising browser for kraken test... 14:07:59 INFO - TEST-INFO | started process 1970 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/tmpcbdEjB/profile http://localhost:51330/getInfo.html) 14:08:01 INFO - PROCESS | 1970 | __metrics Screen width/height:1600/1200 14:08:01 INFO - PROCESS | 1970 | colorDepth:24 14:08:01 INFO - PROCESS | 1970 | Browser inner width/height: 1024/689 14:08:01 INFO - PROCESS | 1970 | __metrics 14:08:01 ERROR - PROCESS | 1970 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 14:08:02 INFO - TEST-INFO | 1970: exit 0 14:08:02 INFO - Browser initialized. 14:08:02 INFO - Running cycle 1/1 for kraken test... 14:08:02 INFO - TEST-INFO | started process 1973 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/tmpcbdEjB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:08:14 INFO - PROCESS | 1973 | RSS: Main: 221728768 14:08:14 INFO - PROCESS | 1973 | 14:08:17 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:51330/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51330/tests/kraken/driver.html?testName=audio-beat-detection) 14:08:18 INFO - PROCESS | 1973 | RSS: Main: 233304064 14:08:18 INFO - PROCESS | 1973 | 14:08:21 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:51330/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51330/tests/kraken/driver.html?testName=audio-dft) 14:08:21 INFO - PROCESS | 1973 | RSS: Main: 242151424 14:08:21 INFO - PROCESS | 1973 | 14:08:25 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:51330/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51330/tests/kraken/driver.html?testName=audio-fft) 14:08:25 INFO - PROCESS | 1973 | RSS: Main: 246292480 14:08:25 INFO - PROCESS | 1973 | 14:08:27 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:51330/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51330/tests/kraken/driver.html?testName=audio-oscillator) 14:08:28 INFO - PROCESS | 1973 | RSS: Main: 246906880 14:08:28 INFO - PROCESS | 1973 | 14:08:30 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:51330/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51330/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:08:31 INFO - PROCESS | 1973 | RSS: Main: 247083008 14:08:31 INFO - PROCESS | 1973 | 14:08:33 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:51330/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51330/tests/kraken/driver.html?testName=imaging-darkroom) 14:08:34 INFO - PROCESS | 1973 | RSS: Main: 246677504 14:08:34 INFO - PROCESS | 1973 | 14:08:37 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:51330/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51330/tests/kraken/driver.html?testName=imaging-desaturate) 14:08:38 INFO - PROCESS | 1973 | RSS: Main: 246824960 14:08:38 INFO - PROCESS | 1973 | 14:08:41 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:51330/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51330/tests/kraken/driver.html?testName=json-parse-financial) 14:08:41 INFO - PROCESS | 1973 | RSS: Main: 247001088 14:08:41 INFO - PROCESS | 1973 | 14:08:43 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:51330/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51330/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:08:44 INFO - PROCESS | 1973 | RSS: Main: 246272000 14:08:44 INFO - PROCESS | 1973 | 14:08:46 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:51330/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51330/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:08:46 INFO - PROCESS | 1973 | RSS: Main: 246255616 14:08:46 INFO - PROCESS | 1973 | 14:08:49 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:51330/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51330/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:08:49 INFO - PROCESS | 1973 | RSS: Main: 247844864 14:08:49 INFO - PROCESS | 1973 | 14:08:52 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:51330/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51330/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:08:53 INFO - PROCESS | 1973 | RSS: Main: 247906304 14:08:53 INFO - PROCESS | 1973 | 14:08:56 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:51330/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51330/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:08:56 INFO - PROCESS | 1973 | RSS: Main: 247779328 14:08:56 INFO - PROCESS | 1973 | 14:08:58 INFO - PROCESS | 1973 | Cycle 1(1): loaded http://localhost:51330/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51330/tests/kraken/driver.html?testName=ai-astar) 14:08:59 INFO - PROCESS | 1973 | RSS: Main: 249487360 14:08:59 INFO - PROCESS | 1973 | 14:08:59 INFO - PROCESS | 1973 | __start_tp_report 14:08:59 INFO - PROCESS | 1973 | _x_x_mozilla_page_load 14:08:59 INFO - PROCESS | 1973 | _x_x_mozilla_page_load_details 14:08:59 INFO - PROCESS | 1973 | |i|pagename|runs| 14:08:59 INFO - PROCESS | 1973 | |0;ai-astar;119;116;118;117;120;132;96;173;170;171 14:08:59 INFO - PROCESS | 1973 | |1;audio-beat-detection;149;146;148;186;156;173;125;176;188;150 14:08:59 INFO - PROCESS | 1973 | |2;audio-dft;196;199;196;199;233;197;199;171;268;190 14:08:59 INFO - PROCESS | 1973 | |3;audio-fft;65;78;75;149;59;56;60;58;142;80 14:08:59 INFO - PROCESS | 1973 | |4;audio-oscillator;77;81;126;80;80;82;117;81;81;123 14:08:59 INFO - PROCESS | 1973 | |5;imaging-gaussian-blur;88;87;87;85;89;85;90;85;88;77 14:08:59 INFO - PROCESS | 1973 | |6;imaging-darkroom;151;152;101;97;97;101;97;149;153;96 14:08:59 INFO - PROCESS | 1973 | |7;imaging-desaturate;100;141;145;120;94;94;97;94;135;94 14:08:59 INFO - PROCESS | 1973 | |8;json-parse-financial;51;73;96;95;97;93;80;79;63;62 14:08:59 INFO - PROCESS | 1973 | |9;json-stringify-tinderbox;59;68;66;68;77;74;69;69;66;71 14:08:59 INFO - PROCESS | 1973 | |10;stanford-crypto-aes;90;106;73;103;110;109;111;89;81;94 14:08:59 INFO - PROCESS | 1973 | |11;stanford-crypto-ccm;132;148;147;157;126;145;163;118;202;168 14:08:59 INFO - PROCESS | 1973 | |12;stanford-crypto-pbkdf2;209;191;167;168;168;173;166;175;177;216 14:08:59 INFO - PROCESS | 1973 | |13;stanford-crypto-sha256-iterative;67;70;81;91;58;73;98;99;101;79 14:08:59 INFO - PROCESS | 1973 | __end_tp_report 14:08:59 INFO - PROCESS | 1973 | __start_cc_report 14:08:59 INFO - PROCESS | 1973 | _x_x_mozilla_cycle_collect,1092 14:08:59 INFO - PROCESS | 1973 | __end_cc_report 14:08:59 INFO - PROCESS | 1973 | __startTimestamp1463432939082__endTimestamp 14:08:59 INFO - PROCESS | 1973 | 14:08:59 INFO - PROCESS | 1973 | ------- Summary: start ------- 14:08:59 INFO - PROCESS | 1973 | Number of tests: 14 14:08:59 INFO - PROCESS | 1973 | 14:08:59 INFO - PROCESS | 1973 | [#0] ai-astar Cycles:10 Average:133.20 Median:151.00 stddev:27.72 (18.4%) stddev-sans-first:28.93 14:08:59 INFO - PROCESS | 1973 | Values: 119.0 116.0 118.0 117.0 120.0 132.0 96.0 173.0 170.0 171.0 14:08:59 INFO - PROCESS | 1973 | 14:08:59 INFO - PROCESS | 1973 | [#1] audio-beat-detection Cycles:10 Average:159.70 Median:164.50 stddev:20.24 (12.3%) stddev-sans-first:21.09 14:08:59 INFO - PROCESS | 1973 | Values: 149.0 146.0 148.0 186.0 156.0 173.0 125.0 176.0 188.0 150.0 14:08:59 INFO - PROCESS | 1973 | 14:08:59 INFO - PROCESS | 1973 | [#2] audio-dft Cycles:10 Average:204.80 Median:199.00 stddev:26.81 (13.5%) stddev-sans-first:28.24 14:08:59 INFO - PROCESS | 1973 | Values: 196.0 199.0 196.0 199.0 233.0 197.0 199.0 171.0 268.0 190.0 14:08:59 INFO - PROCESS | 1973 | 14:08:59 INFO - PROCESS | 1973 | [#3] audio-fft Cycles:10 Average:82.20 Median:62.50 stddev:34.50 (55.2%) stddev-sans-first:36.03 14:08:59 INFO - PROCESS | 1973 | Values: 65.0 78.0 75.0 149.0 59.0 56.0 60.0 58.0 142.0 80.0 14:08:59 INFO - PROCESS | 1973 | 14:08:59 INFO - PROCESS | 1973 | [#4] audio-oscillator Cycles:10 Average:92.80 Median:80.50 stddev:20.31 (25.2%) stddev-sans-first:20.72 14:08:59 INFO - PROCESS | 1973 | Values: 77.0 81.0 126.0 80.0 80.0 82.0 117.0 81.0 81.0 123.0 14:08:59 INFO - PROCESS | 1973 | 14:08:59 INFO - PROCESS | 1973 | [#5] imaging-gaussian-blur Cycles:10 Average:86.10 Median:87.50 stddev:3.63 (4.2%) stddev-sans-first:3.79 14:08:59 INFO - PROCESS | 1973 | Values: 88.0 87.0 87.0 85.0 89.0 85.0 90.0 85.0 88.0 77.0 14:08:59 INFO - PROCESS | 1973 | 14:08:59 INFO - PROCESS | 1973 | [#6] imaging-darkroom Cycles:10 Average:119.40 Median:124.50 stddev:27.48 (22.1%) stddev-sans-first:26.66 14:08:59 INFO - PROCESS | 1973 | Values: 151.0 152.0 101.0 97.0 97.0 101.0 97.0 149.0 153.0 96.0 14:08:59 INFO - PROCESS | 1973 | 14:08:59 INFO - PROCESS | 1973 | [#7] imaging-desaturate Cycles:10 Average:111.40 Median:94.00 stddev:21.56 (22.9%) stddev-sans-first:22.47 14:08:59 INFO - PROCESS | 1973 | Values: 100.0 141.0 145.0 120.0 94.0 94.0 97.0 94.0 135.0 94.0 14:08:59 INFO - PROCESS | 1973 | 14:08:59 INFO - PROCESS | 1973 | [#8] json-parse-financial Cycles:10 Average:78.90 Median:86.50 stddev:16.43 (19.0%) stddev-sans-first:13.99 14:08:59 INFO - PROCESS | 1973 | Values: 51.0 73.0 96.0 95.0 97.0 93.0 80.0 79.0 63.0 62.0 14:08:59 INFO - PROCESS | 1973 | 14:08:59 INFO - PROCESS | 1973 | [#9] json-stringify-tinderbox Cycles:10 Average:68.70 Median:69.00 stddev:4.85 (7.0%) stddev-sans-first:3.67 14:08:59 INFO - PROCESS | 1973 | Values: 59.0 68.0 66.0 68.0 77.0 74.0 69.0 69.0 66.0 71.0 14:08:59 INFO - PROCESS | 1973 | 14:08:59 INFO - PROCESS | 1973 | [#10] stanford-crypto-aes Cycles:10 Average:96.60 Median:77.00 stddev:13.24 (17.2%) stddev-sans-first:13.83 14:08:59 INFO - PROCESS | 1973 | Values: 90.0 106.0 73.0 103.0 110.0 109.0 111.0 89.0 81.0 94.0 14:08:59 INFO - PROCESS | 1973 | 14:08:59 INFO - PROCESS | 1973 | [#11] stanford-crypto-ccm Cycles:10 Average:150.60 Median:152.50 stddev:24.05 (15.8%) stddev-sans-first:24.55 14:08:59 INFO - PROCESS | 1973 | Values: 132.0 148.0 147.0 157.0 126.0 145.0 163.0 118.0 202.0 168.0 14:08:59 INFO - PROCESS | 1973 | 14:08:59 INFO - PROCESS | 1973 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.00 Median:176.00 stddev:18.21 (10.3%) stddev-sans-first:16.25 14:08:59 INFO - PROCESS | 1973 | Values: 209.0 191.0 167.0 168.0 168.0 173.0 166.0 175.0 177.0 216.0 14:08:59 INFO - PROCESS | 1973 | 14:08:59 INFO - PROCESS | 1973 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:81.70 Median:80.00 stddev:14.99 (18.7%) stddev-sans-first:14.92 14:08:59 INFO - PROCESS | 1973 | Values: 67.0 70.0 81.0 91.0 58.0 73.0 98.0 99.0 101.0 79.0 14:08:59 INFO - PROCESS | 1973 | -------- Summary: end -------- 14:08:59 INFO - PROCESS | 1973 | 14:08:59 INFO - TEST-INFO | 1973: exit 0 14:08:59 INFO - TEST-OK | kraken | took 60793ms 14:08:59 INFO - SUITE-END | took 1172s 14:09:00 INFO - Completed test suite (00:19:32) 14:09:00 INFO - javascript benchmark 14:09:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2770.709075547067, 3450.3444345574353, 3476.669716376943, 3478.7738796978197, 3527.2063785648234, 5786.566007549435, 5815.912761308584, 5823.475887170154, 5834.416558344169, 5843.4156584341545, 5779.017567013607, 5794.884102317962, 5805.941940580593, 5841.707914604263, 5858.355580886099, 5206.713630750317, 5277.736113194344, 5289.100852855024, 5296.364436267634, 5313.20301954708, 5194.584433245332, 5287.365516138049, 5309.522143007129, 5313.388960269561, 5313.468653134694, 5248.71132087735, 5252.737363131847, 5285.286486324355, 5288.15389537674, 5310.8141215057485, 5261.657992230493, 5300.92048619271, 5304.6286759926825, 5305.0450918834595, 5312.415634280264, 5213.572218183263, 5280.445553216934, 5284.0, 5292.867678308073, 5311.49540793624, 5556.16657501374, 5609.775608975677, 5688.4311568843195, 5710.514606258473, 5865.120231965197, 5112.258722485256, 6104.572679912408, 6133.509319254449, 6140.263168419773, 6232.127502149703, 6141.907871381933, 6198.690065496708, 6207.099970504297, 6221.377862213788, 6226.315105338409, 4917.852464426073, 5764.510016648548, 5791.652500849962, 5802.332731735863, 5916.822495325147, 6149.938500614937, 6196.287426945959, 6205.162303089115, 6205.379462053849, 6214.5960512566535, 6202.658853763086, 6211.875762484816, 6218.751249949952, 6223.533235007392, 6252.437280644764, 6119.441623354662, 6158.0, 6206.658633775186, 6224.284207316126, 6224.657643829542, 5025.346704928336, 6224.751009959551, 6233.594816336924, 6234.096056071935, 6239.220097487814, 6049.0, 6182.072689096672, 6210.0, 6210.068489726577, 6231.376862313823, 4894.290327902434, 4938.111139994836, 4943.65394422387, 4955.331030310934, 4990.426100998359, 4417.3815665806815, 4419.535948725337, 4464.0, 4467.061916997467, 4470.150671372428, 5704.8859022819315, 5734.283214598176, 5784.3926387729525, 5784.971075144597, 5793.188953546507, 6094.542909281821, 6129.603311142676, 6138.388398386059, 6184.134221209034, 6209.192804935329, 5772.0, 5820.76716931318, 5843.912341314884, 5850.239468869105, 5853.560982926212, 5071.797128114834, 5106.183010718267, 5383.838484845461, 5419.187121931663, 5465.4261302563455, 5179.300794392709, 6178.227721534809, 6209.9379006210265, 6224.284207316126, 6242.375762423721, 5192.013517431676, 6178.5366097543765, 6180.629162250099, 6181.567290289637, 6199.814005579839, 6193.473554747717, 6221.844453888689, 6253.530985176039, 6310.368963103653, 6358.205224346957, 6114.7536090578, 6123.8761238761235, 6140.022089286603, 6142.007694998287, 6158.261008678808, 5154.974225128851, 6109.732506502054, 6191.473724733448, 6199.690015499117, 6200.627962322094, 5960.1357803117435, 6025.397460253939, 6096.603720758137, 6126.387361263838, 6167.321594624498, 6129.1112788644505, 6145.600535965148, 6202.7208775606105, 6208.348123446973, 6210.441060304594, 5205.193195054772, 5743.10981797822, 5995.91006134917, 6058.0912863069125, 6086.178365920721, 6029.758809647565, 6060.727267273071, 6103.206583144163, 6107.295156111712, 6142.437386315837, 5996.070609055603, 6035.849103772441, 6037.63774173551, 6049.334573196857, 6054.8183554493435], "value": 5677.597632824685, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.32725820142787, 535.8606762241817, 561.2899681902392, 575.2033433694332, 577.9077543442894, 30009.399812003747, 36536.81731591342, 37549.436758448646, 37656.05859853505, 37725.43411848821, 38273.61726382736, 38768.418473722915, 38860.417093743614, 38929.610703892955, 38942.0, 9413.952930235338, 9636.18092462138, 9652.420854748703, 9666.323357364987, 9692.982236865133, 9537.427754334727, 9767.120959113678, 9792.265580081486, 9802.117809397152, 9833.213342932548, 9732.65935692251, 9828.459434731087, 9832.508374581279, 9872.90127098727, 9880.505974701273, 9761.609535618567, 9805.558749856273, 9868.703938881845, 9871.506424678773, 9939.65211217604, 9598.472084035375, 9717.902820971842, 9723.90276097237, 9724.17344525709, 9737.026297370207, 32214.516782248284, 38384.23231535382, 38787.83636490881, 38805.02987425337, 38812.611873881186, 2665.920022399331, 3245.334706385185, 3284.9343013139605, 3322.933541329184, 3563.5367402237803, 27423.725762742317, 27572.0, 28581.71418285811, 28686.856565717037, 28927.56608650872, 2772.4316515114556, 2813.2263627502603, 2821.2946763309174, 2833.4049849531625, 2841.701621329751, 485106.0, 491065.0, 491433.0, 492452.5377373126, 494662.0, 33523.4971475428, 35871.82064089663, 53161.468385316424, 59882.70058649766, 60395.79208415895, 34784.65215347814, 34795.65204347924, 34911.65088349135, 34972.47541286883, 35057.82471087679, 15235.466758663393, 15336.846631533543, 15385.0, 15428.537143885702, 15461.304241309168, 12920.0, 13013.804792928113, 13074.869251307366, 13089.280089595164, 13102.082854200306, 8177.9591102044105, 8208.548529830916, 8237.752867413987, 8238.588070596446, 8283.213094756109, 3341.983290083534, 3484.3728128936527, 3489.8537399499123, 3496.265784185299, 3517.2965406918206, 1027.2501074215716, 1036.5957276662107, 1074.8172810622214, 1095.6220104064084, 1152.7233463968587, 28194.154175374773, 29018.12945611635, 29090.27274318159, 29556.408871822874, 29829.0, 1104.5857803323754, 1150.4995327032768, 1161.297415063891, 1171.6133675887106, 1265.2661456355293, 15124.924375378052, 15239.695206096038, 15256.847431525543, 15395.384184632714, 15522.068675879484, 650558.7472062609, 652736.0, 664005.0, 664548.0, 668274.0, 58526.70736646289, 58901.11648325279, 58966.0, 59153.704231478565, 59304.70347648234, 1494.7384207763598, 1520.323456062064, 1529.3118096856458, 1539.1534655939188, 1609.7302803047646, 24158.751526936827, 29369.85315073411, 29584.704152958195, 29972.0, 30111.548326775555, 51108.0, 51328.0, 51357.7432112852, 51486.48513514817, 51654.22518662148, 578894.0, 580205.0, 583933.0, 587366.0, 589692.0515397396, 26546.469070618867, 41404.378934315406, 42461.36307955445, 43398.78300608477, 43524.0, 45223.547764521936, 46297.0, 46986.0, 47864.0, 48179.75910120427, 29338.559921600765, 34191.31617367688, 35429.82285088558, 36255.63744362522, 37640.43539346857, 35815.10462238465, 35818.28363432769, 35877.461838071926, 35960.28079438344, 36416.81791591131, 37159.0, 37251.88244352674, 38514.614853851104, 38526.229475410895, 38809.6119038806, 24920.750792491843, 29420.558691619215, 30574.0, 31074.378512430078, 31562.526562101128, 24727.38181545404, 24897.75102249027, 24992.62511062299, 25314.0, 25365.365865853502], "value": 18670.24052540966, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [371.6580745713943, 402.5049189496919, 403.2479425870752, 409.83196889275393, 417.1157146848681, 15366.001209921347, 15633.0, 16638.41765538205, 19788.109535070922, 20611.690824637622, 19599.70600440991, 19995.900020499877, 20032.49918752032, 20304.18783248672, 20674.173033078696, 5895.498882594985, 5992.191054207691, 6156.138140660289, 6186.133941248228, 6192.59748116371, 6051.1830902828215, 6136.447719705226, 6152.630842149463, 6156.907646385308, 6159.784407545737, 4689.296605509184, 6093.908591371133, 6100.450959413652, 6124.969375153118, 6125.081237814341, 5861.5603829712945, 5966.37353077927, 6020.458158765732, 6025.728842202111, 6039.909401358983, 5707.914381284242, 5814.331351894544, 5827.155062515953, 5828.795992140297, 5849.444302791228, 12938.48246070156, 13406.46374145033, 18333.0, 24825.5034899301, 24931.0, 2026.98986505068, 2095.1200495791804, 2150.53763440861, 2346.6480027995767, 2777.388974425623, 9540.475273859935, 9931.304808663397, 10213.285070045025, 10307.072363487247, 10383.42891140987, 1697.7198762204346, 1714.41709818661, 1716.948491545243, 1737.5569229846308, 1783.9018853963028, 114663.0, 117611.0, 120303.79696203013, 121196.39401803023, 121959.0, 9826.606935722633, 11649.59226427071, 11879.524819007316, 11947.880521194676, 11948.0, 14983.0, 15224.314905829264, 15231.543053708407, 15264.007839490398, 15288.38846446152, 7383.372413344819, 8948.328875334377, 9045.231155351737, 9127.58925848325, 9161.404508707046, 8988.865167022499, 8998.460092394478, 9047.32145089121, 9147.954260228656, 9163.541822908828, 3963.048868271652, 4635.582797548237, 4664.0, 4710.929336059893, 4775.928361074587, 1713.6230029393514, 1956.923691969411, 2024.453397582674, 2026.1490174126902, 2066.5866826634733, 848.733337294757, 928.382625553999, 987.6790043235977, 1006.942710154335, 1024.5594394410325, 18293.0, 20146.496337591678, 20217.393478195678, 20497.897510512652, 20550.38348849537, 965.4496936745987, 995.5470261031213, 996.8604395384652, 1005.3113617172261, 1014.3812274512542, 10605.098566621924, 10799.0, 10817.729556761144, 10839.566417343376, 10998.285111467729, 566529.0, 584872.0, 594999.0, 596551.0, 597191.0, 112479.0, 116549.4172529149, 118833.40583297201, 119925.40037299758, 120229.39885300517, 1009.5412129472841, 1012.417859730643, 1012.9797404052025, 1057.440992797432, 1082.2370228988543, 17948.37180698669, 18149.092545372416, 18164.636707265516, 18259.908700456413, 18672.0, 51467.74266128645, 52395.0, 52657.0, 52870.942581148935, 54760.45239547713, 74360.25639743534, 78260.60869695614, 80991.0, 81735.59132204301, 82560.17439825764, 7961.641726122222, 8199.139090395423, 8276.0896301408, 8338.332933365438, 8419.747407577783, 8445.0, 9284.02517735628, 12026.797320267904, 12582.433790479634, 12920.676983075498, 10869.456527173768, 11081.667549973514, 11104.722381940515, 11296.774064518828, 11442.542298307975, 10706.625268115578, 10828.891711083104, 10872.782544349227, 10997.61508347204, 11186.440677966231, 11264.58026325662, 11319.717007074889, 11443.313401195956, 11451.02666273376, 11495.252808567417, 7817.335526480313, 8152.347812174893, 8244.670213191645, 8321.16788321163, 8561.82876342482, 9397.29520285995, 11180.0, 11403.315801051964, 11464.0, 11525.596604118815], "value": 9491.563772149704, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1551.8525740054695, 1565.6712090461003, 1777.9555511112221, 1870.4482177757552, 1959.8530110241736, 7996.600169991506, 8618.0, 8793.340499462542, 8998.19016288534, 9000.594973226203, 7350.411967042637, 8776.64893404263, 8910.019897811251, 8917.643294268239, 8931.64273429062, 2479.429731161834, 2502.1367628168346, 2504.524140413325, 2509.3224829296078, 2518.1186584695392, 2463.224084413413, 2532.936676583082, 2540.0855691950883, 2540.8348457350307, 2550.591905295153, 2510.8493490390633, 2517.2070797698666, 2519.685039370079, 2527.9620805687928, 2538.5557527432716, 2518.433352495683, 2529.5699731045675, 2531.3291977625945, 2534.7465253474597, 2536.69559652841, 2508.8745562721792, 2522.7729504344516, 2522.785563227128, 2541.733118022618, 2545.5163518931345, 7748.496347737379, 9011.369204155711, 9078.818423631556, 9081.772955676095, 9086.363954523185, 4825.782839772219, 4915.3364295819965, 5167.069927413064, 5537.861553461155, 5578.135389014709, 3561.732870034732, 3626.5285512883156, 3673.430618254175, 3691.169486865461, 3697.8335974880924, 1067.6583493282153, 1084.0622861224983, 1086.5056399338362, 1087.912966962649, 1091.4106382553412, 12248.44881980313, 12348.135630505869, 12364.0, 12400.62798116058, 12417.317047562381, 4863.2461968394955, 4891.6575839691595, 4957.107720610325, 4957.281194226817, 4987.077390682735, 10350.844737328945, 10356.482175891175, 10375.16998640107, 10403.323783954018, 10412.323198992193, 5549.556035517149, 5550.972245138749, 5586.888262234814, 5589.301337332833, 5594.356648985337, 4486.237339652267, 4881.341018962395, 5013.373328333958, 5063.87340316495, 5092.185250359925, 4119.546849846514, 4125.8968525787095, 4145.834166633361, 4182.749035057906, 4193.161367726467, 1366.8906487480979, 1395.260511928659, 1397.6925076483158, 1405.501047128275, 1415.1226239731432, 916.5829547555846, 918.7151982885327, 937.4609599480326, 940.4169414962766, 950.2635457520477, 5874.706264686834, 5916.674582897896, 5949.375315591887, 6038.0, 6141.539384546177, 465.7182404645209, 472.8061494787121, 472.90069085492433, 479.398355064392, 486.77851577532135, 3821.6942644588366, 3857.5756666766633, 3869.7678139311734, 3920.2355540669864, 4004.5594984551676, 9314.627414903329, 10675.359478431319, 11107.777844443066, 11116.444177791078, 11153.10775137987, 10819.459027048617, 10868.782624347468, 10945.0, 10966.177536684781, 10984.231103822816, 7857.017872765904, 8319.750407487785, 8429.072801991775, 8555.486670799772, 8634.956825215833, 4800.519948005312, 5293.5765138789575, 5601.103823388238, 5629.549636029026, 5698.1452782083015, 9688.12806847387, 9833.704988850346, 9855.35940163887, 9943.403395796275, 10046.799064018825, 9757.56090975922, 9958.55186516623, 10141.340812847142, 10156.441395723306, 10365.0, 6784.0, 7197.640117994184, 7268.709251629875, 7339.522931009467, 7443.962780186064, 8285.212904774036, 8285.83428331442, 8325.375596830141, 8345.290650294794, 8368.497890126611, 6383.744650213939, 7138.179109402277, 7336.853262934818, 7395.445341599294, 7515.436342274242, 7173.10336207974, 7197.676104575202, 7304.598247096248, 7335.596542190231, 7343.559386436831, 6847.109875716125, 6859.17689877198, 6864.897026544506, 6894.24163342022, 6911.5853048817235, 4103.958960410477, 4721.126591580569, 4785.162596545521, 4788.186008378653, 4802.135615589227, 4115.0741083256025, 4194.412782210432, 4857.878553036061, 4882.023595280887, 4926.482719314564], "value": 4575.885024795278, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [290.9810862293951, 330.8130906038088, 333.5208350963477, 336.16630755725816, 337.0629649574184, 5475.589330800191, 6872.0, 6897.6206308653, 6924.757633482829, 6928.099347084872, 5449.455054494549, 6833.0, 6909.861802763954, 6922.826929326757, 6930.0, 4803.231482962718, 4865.002674451746, 5003.549680528752, 5006.424261209971, 5010.398752149747, 5004.324416203803, 5069.2649565812935, 5076.695398276097, 5077.33994580704, 5089.109405853979, 4051.4530538377235, 5023.572996295319, 5027.120253955562, 5049.0, 5052.696838189702, 5022.42242142151, 5032.974835125838, 5035.395752509704, 5040.571551418116, 5060.392752869642, 4311.935320970188, 4327.6105150536605, 4330.588594083589, 4331.830405790451, 4339.262325404688, 6406.967965160191, 6686.598804071723, 6691.966540167268, 6698.5980841149685, 6714.529982901199, 5906.025505791539, 5936.376680448534, 5943.673097979609, 5949.0, 5957.761689532414, 7402.666879990414, 7426.702931882718, 7431.554106753612, 7450.888236676454, 7467.327940485382, 4718.386609740746, 4725.298892398734, 4740.217864052427, 4743.146233677937, 4832.492588278251, 7511.661975211128, 7764.689412423496, 7882.487638303492, 7886.093099293597, 7892.526448413114, 4734.976325118352, 4748.477667456577, 4786.832460863853, 4795.184818580849, 4810.470848206694, 4851.078295123915, 4871.14754917888, 4887.047025829929, 4891.975540122347, 4901.411830580282, 5902.527797776168, 5904.763809447574, 5906.940930590725, 5928.347881733006, 5964.135200396005, 5989.670568118795, 6374.0, 7424.368928641018, 7457.328840404281, 7468.962655186798, 1675.3801093595448, 1697.558634754948, 1698.8555972742208, 1699.226851782436, 1710.3500669745417, 1043.3062013760764, 1074.7259448840653, 1082.3397727386289, 1086.0, 1088.0968795899385, 6861.284678830292, 6934.791956241321, 6974.860502789916, 6977.069630754993, 6978.546394484342, 6261.0, 6509.804705858832, 7372.189059203483, 7384.926150738531, 7612.619369031526, 6921.757738479129, 6939.097917270722, 6939.410150137194, 6945.652717364213, 7003.894941575881, 3186.5538824564114, 3572.4105522589, 3921.568627450978, 4085.1216988347646, 4094.3653733671326, 7965.566198084401, 8147.185281471805, 8157.8368432632205, 8159.184081591793, 8164.877526837102, 7495.850082998419, 7708.691652333844, 7940.0, 7989.480683755537, 8111.878321825177, 7470.439717021137, 7568.735094271674, 8010.076322142376, 8146.0, 8156.918430815735, 7759.961200193963, 7787.5716835574585, 7809.765707028798, 7827.373810095292, 7827.904093427039, 7672.501287416523, 7678.61606919663, 7691.269329413697, 7697.191794861683, 7698.846023079619, 7675.846483070419, 7957.8408431832195, 7958.801029974297, 7976.760697179094, 7977.162397948364, 4690.108879312919, 4781.641376896677, 4785.736784476887, 4788.9760551198415, 4793.760311984456, 7613.467057306157, 7641.35048520882, 7693.192214817587, 7697.114831794415, 7704.152543220353, 7181.35367816899, 7356.0, 7387.298206670358, 7394.0, 7396.9260307396235, 7368.668409921674, 7388.039554857834, 7402.037735094404, 7412.295831895961, 7419.851602968019, 7198.424126070006, 7210.603416812126, 7235.2764723527225, 7260.745873894389, 7315.524490908074, 7481.66332515049, 7496.8500629986, 7497.175310715707, 7509.399248060251, 7512.962435187789, 7090.4327653788605, 7308.926910730825, 7324.670389832364, 7339.302766237199, 7356.080489938758], "value": 5354.357880180081, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [668.0580381661857, 697.8569393274379, 736.6869080640727, 740.1857956248124, 756.1757684124306, 14712.485063022796, 14844.109353438802, 14956.551303460887, 14985.400583976629, 14994.850051499496, 13764.655883602914, 13774.380152893118, 13865.792013119812, 13866.37601307941, 13901.791473127885, 717.8636059148771, 895.9552022398886, 899.4378513429107, 904.9773755656096, 907.1110311894347, 751.6429695894461, 901.324006994754, 905.8686490458913, 907.1518130547926, 908.2869947091534, 945.9101385368414, 952.2334520710825, 969.8642190093393, 971.5142428785607, 972.8735264415616, 939.9577019034126, 953.4374718915873, 954.2985905359556, 955.8279509688218, 965.8164948659768, 691.5332150798191, 691.8443350246205, 700.3976580141075, 716.4447553146354, 716.6918225163178, 14433.134011959211, 14445.855541444556, 14463.927680361636, 14490.058146220568, 14611.78082328766, 12703.364831758376, 12873.098883078188, 13028.80456793149, 13045.217286962717, 13146.079774415795, 9861.112499874975, 9917.603295868157, 10074.848877266846, 10265.538050787733, 10325.122364599018, 3537.2394935088823, 3553.1650062235353, 3553.538040054802, 3554.9466757998653, 3622.4023036198996, 55765.44234557643, 73623.63188184079, 73732.0, 73761.89357159648, 74141.88787168197, 7863.528188308605, 7947.324477419368, 9528.094830991045, 9639.855402168972, 9695.66065187715, 21021.0, 21058.47353816167, 21085.0, 21103.577928441344, 21132.049057792436, 7515.624218789038, 7523.172451030382, 7586.848263034819, 7616.0, 7915.643796029192, 4056.1684854604737, 4101.651359634405, 4108.342665173558, 4157.958420415817, 4211.873643790691, 3623.7825730456257, 3691.90770230741, 3701.648343407372, 3701.8704345347783, 3710.05393624624, 616.1681729664916, 671.7715976567893, 673.71367168953, 681.9863602727918, 685.0306815855508, 503.8387715930867, 542.2192043457409, 542.7883125581027, 568.3861429656044, 572.7165053298644, 8121.89289222736, 8221.218984196612, 8240.0, 8512.489250644981, 8521.39086836892, 531.2695044314069, 567.9748054761196, 601.8164459839703, 605.6275390634728, 619.8419403052193, 3839.040239940015, 3866.2460820140363, 3907.0, 3913.354296541039, 4117.279476091672, 71295.64352178205, 94764.0, 94839.52580237055, 95329.0, 95474.0, 71570.9264361035, 74216.0, 74402.62798685972, 74540.62729686426, 76049.0, 70654.0, 70864.64567677231, 71175.64412178009, 71535.64232178805, 71843.0, 7661.502002369828, 7745.419093568117, 7826.882596760939, 7938.404619653434, 8146.67413303485, 50145.498545015544, 50683.98632027265, 50693.0, 50918.745406272734, 51593.0, 68738.65630671814, 69210.65394672994, 69245.26886827667, 69797.65101174462, 69885.30114698788, 9759.951200244237, 9894.604215831287, 10053.648122315684, 10087.344322619007, 10102.949485252526, 10579.206559507913, 10593.841092383775, 10792.56829726802, 10834.03362389172, 11140.0, 10192.444406882212, 10244.078032977068, 10274.383536987805, 10512.737181570523, 10541.945805419396, 8306.584670766559, 9928.056834600702, 10310.175185985254, 10310.793784124426, 10542.26204165701, 9330.300227483101, 9415.62337506492, 9437.669681561112, 9597.90402095998, 9607.327487075838, 8744.825103498022, 8760.693375731818, 8905.287576993955, 8993.235574976201, 9128.041555636562, 8790.38467307308, 8791.736247912573, 8883.111688831064, 8928.241099506355, 8945.284377249935], "value": 6709.126459303438, "unit": "score"}], "name": "dromaeo_css", "value": 7408.936735269361, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 116.0, 118.0, 117.0, 120.0, 132.0, 96.0, 173.0, 170.0, 171.0], "value": 133.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 146.0, 148.0, 186.0, 156.0, 173.0, 125.0, 176.0, 188.0, 150.0], "value": 159.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 199.0, 196.0, 199.0, 233.0, 197.0, 199.0, 171.0, 268.0, 190.0], "value": 204.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 78.0, 75.0, 149.0, 59.0, 56.0, 60.0, 58.0, 142.0, 80.0], "value": 82.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 81.0, 126.0, 80.0, 80.0, 82.0, 117.0, 81.0, 81.0, 123.0], "value": 92.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 87.0, 85.0, 89.0, 85.0, 90.0, 85.0, 88.0, 77.0], "value": 86.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 152.0, 101.0, 97.0, 97.0, 101.0, 97.0, 149.0, 153.0, 96.0], "value": 119.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 141.0, 145.0, 120.0, 94.0, 94.0, 97.0, 94.0, 135.0, 94.0], "value": 111.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 73.0, 96.0, 95.0, 97.0, 93.0, 80.0, 79.0, 63.0, 62.0], "value": 78.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 68.0, 66.0, 68.0, 77.0, 74.0, 69.0, 69.0, 66.0, 71.0], "value": 68.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 106.0, 73.0, 103.0, 110.0, 109.0, 111.0, 89.0, 81.0, 94.0], "value": 96.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 148.0, 147.0, 157.0, 126.0, 145.0, 163.0, 118.0, 202.0, 168.0], "value": 150.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [209.0, 191.0, 167.0, 168.0, 168.0, 173.0, 166.0, 175.0, 177.0, 216.0], "value": 181.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 81.0, 91.0, 58.0, 73.0, 98.0, 99.0, 101.0, 79.0], "value": 81.7, "unit": "score"}], "name": "kraken", "value": 1647.1, "alertThreshold": 2.0}]} 14:09:00 INFO - Return code: 0 14:09:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 14:09:00 ERROR - # TBPL SUCCESS # 14:09:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:09:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:09:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:09:00 INFO - 2016-05-16 14:09:00.366 screenresolution[1978:14926] starting screenresolution argv=screenresolution get 14:09:00 INFO - 2016-05-16 14:09:00.368 screenresolution[1978:14926] Display 0: 1600x1200x32@60 14:09:00 INFO - 2016-05-16 14:09:00.374 screenresolution[1979:14929] starting screenresolution argv=screenresolution list 14:09:00 INFO - Available Modes on Display 0 14:09:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:09:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:09:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:09:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:09:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:09:00 INFO - Intel Iris: 14:09:00 INFO - Chipset Model: Intel Iris 14:09:00 INFO - Type: GPU 14:09:00 INFO - Bus: Built-In 14:09:00 INFO - VRAM (Dynamic, Max): 1536 MB 14:09:00 INFO - Vendor: Intel (0x8086) 14:09:00 INFO - Device ID: 0x0a2e 14:09:00 INFO - Revision ID: 0x0009 14:09:00 INFO - Displays: 14:09:00 INFO - Display: 14:09:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:09:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:09:00 INFO - Display Serial Number: 1600x1200 60 14:09:00 INFO - Main Display: Yes 14:09:00 INFO - Mirror: Off 14:09:00 INFO - Online: Yes 14:09:00 INFO - Rotation: Supported 14:09:00 INFO - Return code: 0 14:09:00 INFO - Running post-action listener: _resource_record_post_action 14:09:00 INFO - Running post-run listener: _resource_record_post_run 14:09:01 INFO - Total resource usage - Wall time: 1206s; CPU: 54.0%; Read bytes: 479232; Write bytes: 656177664; Read time: 5; Write time: 1864 14:09:01 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 344908288; Write bytes: 347199488; Read time: 26451; Write time: 703 14:09:01 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 479232; Write bytes: 299020800; Read time: 5; Write time: 1125 14:09:01 INFO - Running post-run listener: _upload_blobber_files 14:09:01 INFO - Blob upload gear active. 14:09:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:09:01 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 14:09:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:09:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:09:02 INFO - (blobuploader) - INFO - Open directory for files ... 14:09:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:09:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:09:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:09:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:09:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:09:03 INFO - (blobuploader) - INFO - Done attempting. 14:09:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:09:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:09:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:09:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:09:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:09:04 INFO - (blobuploader) - INFO - Done attempting. 14:09:04 INFO - (blobuploader) - INFO - Iteration through files over. 14:09:04 INFO - Return code: 0 14:09:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:09:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:09:04 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d64be904324e8094f3b14e816c2e0428185dc805c5f076ed11aafd313deaf9db1ba0d38acc084f49cc0786f1468841f3c09a1cde9596347d4aefd38bf7c4aceb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/12fef5808f025bbc2bd99cea9543ef75fb07fccb816a5a0bff5cc2ebcd5382da47dcc5e4a0b796b8abdf8f983ffac50c54964d151ff87a4dc2415b3d73c06631"} 14:09:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:09:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:09:04 INFO - Contents: 14:09:04 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d64be904324e8094f3b14e816c2e0428185dc805c5f076ed11aafd313deaf9db1ba0d38acc084f49cc0786f1468841f3c09a1cde9596347d4aefd38bf7c4aceb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/12fef5808f025bbc2bd99cea9543ef75fb07fccb816a5a0bff5cc2ebcd5382da47dcc5e4a0b796b8abdf8f983ffac50c54964d151ff87a4dc2415b3d73c06631"} 14:09:04 INFO - Running post-run listener: copy_logs_to_upload_dir 14:09:04 INFO - Copying logs to upload dir... 14:09:04 INFO - mkdir: /builds/slave/test/build/upload/logs 14:09:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.578021 ========= master_lag: 0.74 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-05-16 14:09:05.310892) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-16 14:09:05.317733) ========= 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.qqoY3GEJJl/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.0bA9dSrB1C/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d64be904324e8094f3b14e816c2e0428185dc805c5f076ed11aafd313deaf9db1ba0d38acc084f49cc0786f1468841f3c09a1cde9596347d4aefd38bf7c4aceb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/12fef5808f025bbc2bd99cea9543ef75fb07fccb816a5a0bff5cc2ebcd5382da47dcc5e4a0b796b8abdf8f983ffac50c54964d151ff87a4dc2415b3d73c06631"} build_url:https://queue.taskcluster.net/v1/task/BHfYKtMzTuy974xb02XETA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011242 build_url: 'https://queue.taskcluster.net/v1/task/BHfYKtMzTuy974xb02XETA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d64be904324e8094f3b14e816c2e0428185dc805c5f076ed11aafd313deaf9db1ba0d38acc084f49cc0786f1468841f3c09a1cde9596347d4aefd38bf7c4aceb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/12fef5808f025bbc2bd99cea9543ef75fb07fccb816a5a0bff5cc2ebcd5382da47dcc5e4a0b796b8abdf8f983ffac50c54964d151ff87a4dc2415b3d73c06631"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-16 14:09:05.359097) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 14:09:05.359494) ========= 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.qqoY3GEJJl/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.0bA9dSrB1C/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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.005061 ========= master_lag: 0.12 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 14:09:05.489273) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-16 14:09:05.493650) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-16 14:09:05.493990) ========= ========= Total master_lag: 0.97 =========