builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0074 starttime: 1461294118.09 results: success (0) buildid: 20160421190312 builduid: 9459e8c0827640cf82c92739e7d3bb46 revision: 4fa5909cf1e73939ee823442f2c69b0a86474489 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-21 20:01:58.087271) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-21 20:01:58.087736) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-21 20:01:58.088009) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-21 20:01:58.106448) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 20:01:58.106747) ========= 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.ufPMW7YOtH/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.UmL14AwIJn/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-21 20:01:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.0M=0.001s 2016-04-21 20:01:58 (15.0 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.148322 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 20:01:58.272163) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 20:01:58.272487) ========= 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.ufPMW7YOtH/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.UmL14AwIJn/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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.033404 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 20:01:58.321096) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-21 20:01:58.321441) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 4fa5909cf1e73939ee823442f2c69b0a86474489 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 4fa5909cf1e73939ee823442f2c69b0a86474489 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ufPMW7YOtH/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.UmL14AwIJn/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-21 20:01:58,399 truncating revision to first 12 chars 2016-04-21 20:01:58,399 Setting DEBUG logging. 2016-04-21 20:01:58,399 attempt 1/10 2016-04-21 20:01:58,399 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/4fa5909cf1e7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-21 20:01:59,422 unpacking tar archive at: fx-team-4fa5909cf1e7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.267211 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-21 20:01:59.605239) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-21 20:01:59.605544) ========= script_repo_revision: 4fa5909cf1e73939ee823442f2c69b0a86474489 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-21 20:01:59.605935) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-21 20:01:59.606201) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-21 20:01:59.619406) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-04-21 20:01:59.619690) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ufPMW7YOtH/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.UmL14AwIJn/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 20:01:59 INFO - MultiFileLogger online at 20160421 20:01:59 in /builds/slave/test 20:01:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 20:01:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:01:59 INFO - {'append_to_log': False, 20:01:59 INFO - 'base_work_dir': '/builds/slave/test', 20:01:59 INFO - 'blob_upload_branch': 'Fx-Team', 20:01:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:01:59 INFO - 'branch': 'Fx-Team', 20:01:59 INFO - 'buildbot_json_path': 'buildprops.json', 20:01:59 INFO - 'config_files': ('talos/mac_config.py',), 20:01:59 INFO - 'default_actions': ('clobber', 20:01:59 INFO - 'read-buildbot-config', 20:01:59 INFO - 'download-and-extract', 20:01:59 INFO - 'populate-webroot', 20:01:59 INFO - 'create-virtualenv', 20:01:59 INFO - 'install', 20:01:59 INFO - 'run-tests'), 20:01:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:01:59 INFO - 'download_minidump_stackwalk': True, 20:01:59 INFO - 'download_symbols': 'ondemand', 20:01:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:01:59 INFO - 'tooltool.py': '/tools/tooltool.py', 20:01:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:01:59 INFO - '/tools/misc-python/virtualenv.py')}, 20:01:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:01:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:01:59 INFO - 'installer_path': 'installer.exe', 20:01:59 INFO - 'log_level': 'info', 20:01:59 INFO - 'log_name': 'talos', 20:01:59 INFO - 'log_to_console': True, 20:01:59 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:01:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:01:59 INFO - 'opt_config_files': (), 20:01:59 INFO - 'pip_index': False, 20:01:59 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:01:59 INFO - 'cmd': ('bash', 20:01:59 INFO - '-c', 20:01:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:01:59 INFO - 'enabled': True, 20:01:59 INFO - 'halt_on_failure': False, 20:01:59 INFO - 'name': 'check_screen_resolution'},), 20:01:59 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:01:59 INFO - 'cmd': ('bash', 20:01:59 INFO - '-c', 20:01:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:01:59 INFO - 'enabled': True, 20:01:59 INFO - 'halt_on_failure': False, 20:01:59 INFO - 'name': 'check_screen_resolution'},), 20:01:59 INFO - 'run_cmd_checks_enabled': True, 20:01:59 INFO - 'sps_profile': False, 20:01:59 INFO - 'sps_profile_interval': 0, 20:01:59 INFO - 'suite': 'dromaeojs', 20:01:59 INFO - 'system_bits': '32', 20:01:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:01:59 INFO - 'title': 't-yosemite-r7-0074', 20:01:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:01:59 INFO - 'use_talos_json': True, 20:01:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:01:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:01:59 INFO - 'work_dir': 'build'} 20:01:59 INFO - ##### 20:01:59 INFO - ##### Running clobber step. 20:01:59 INFO - ##### 20:01:59 INFO - Running pre-action listener: _resource_record_pre_action 20:01:59 INFO - Running main action method: clobber 20:01:59 INFO - rmtree: /builds/slave/test/build 20:01:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:02:01 INFO - Running post-action listener: _resource_record_post_action 20:02:01 INFO - ##### 20:02:01 INFO - ##### Running read-buildbot-config step. 20:02:01 INFO - ##### 20:02:01 INFO - Running pre-action listener: _resource_record_pre_action 20:02:01 INFO - Running main action method: read_buildbot_config 20:02:01 INFO - Using buildbot properties: 20:02:01 INFO - { 20:02:01 INFO - "project": "", 20:02:01 INFO - "product": "firefox", 20:02:01 INFO - "script_repo_revision": "production", 20:02:01 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 20:02:01 INFO - "repository": "", 20:02:01 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 20:02:01 INFO - "buildid": "20160421190312", 20:02:01 INFO - "pgo_build": "False", 20:02:01 INFO - "basedir": "/builds/slave/test", 20:02:01 INFO - "buildnumber": 693, 20:02:01 INFO - "slavename": "t-yosemite-r7-0074", 20:02:01 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 20:02:01 INFO - "platform": "yosemite_r7", 20:02:01 INFO - "scheduler": "tests-fx-team-macosx64-talos", 20:02:01 INFO - "branch": "fx-team", 20:02:01 INFO - "repo_path": "integration/fx-team", 20:02:01 INFO - "revision": "4fa5909cf1e73939ee823442f2c69b0a86474489", 20:02:01 INFO - "stage_platform": "macosx64", 20:02:01 INFO - "builduid": "9459e8c0827640cf82c92739e7d3bb46", 20:02:01 INFO - "slavebuilddir": "test" 20:02:01 INFO - } 20:02:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/diEbyJz1TDWrCMVoKM_gaw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 20:02:01 INFO - Running post-action listener: _resource_record_post_action 20:02:01 INFO - ##### 20:02:01 INFO - ##### Running download-and-extract step. 20:02:01 INFO - ##### 20:02:01 INFO - Running pre-action listener: _resource_record_pre_action 20:02:01 INFO - Running main action method: download_and_extract 20:02:01 INFO - mkdir: /builds/slave/test/build/tests 20:02:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:02:01 INFO - https://queue.taskcluster.net/v1/task/diEbyJz1TDWrCMVoKM_gaw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 20:02:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diEbyJz1TDWrCMVoKM_gaw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 20:02:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diEbyJz1TDWrCMVoKM_gaw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 20:02:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diEbyJz1TDWrCMVoKM_gaw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 20:02:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diEbyJz1TDWrCMVoKM_gaw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 20:02:02 INFO - Downloaded 1199 bytes. 20:02:02 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 20:02:02 INFO - Using the following test package requirements: 20:02:02 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 20:02:02 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 20:02:02 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 20:02:02 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 20:02:02 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 20:02:02 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 20:02:02 INFO - u'jsshell-mac.zip'], 20:02:02 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 20:02:02 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 20:02:02 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 20:02:02 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 20:02:02 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 20:02:02 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 20:02:02 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 20:02:02 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 20:02:02 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 20:02:02 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 20:02:02 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 20:02:02 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 20:02:02 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')]} 20:02:02 INFO - https://queue.taskcluster.net/v1/task/diEbyJz1TDWrCMVoKM_gaw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 20:02:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diEbyJz1TDWrCMVoKM_gaw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 20:02:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diEbyJz1TDWrCMVoKM_gaw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 20:02:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diEbyJz1TDWrCMVoKM_gaw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 20:02:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diEbyJz1TDWrCMVoKM_gaw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:02:03 INFO - Downloaded 18105795 bytes. 20:02:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 20:02:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 20:02:06 INFO - Return code: 0 20:02:06 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 20:02:06 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')]} 20:02:06 INFO - https://queue.taskcluster.net/v1/task/diEbyJz1TDWrCMVoKM_gaw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 20:02:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diEbyJz1TDWrCMVoKM_gaw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 20:02:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diEbyJz1TDWrCMVoKM_gaw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 20:02:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diEbyJz1TDWrCMVoKM_gaw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 20:02:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diEbyJz1TDWrCMVoKM_gaw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:02:08 INFO - Downloaded 18105795 bytes. 20:02:08 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 20:02:08 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 20:02:11 INFO - Return code: 0 20:02:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:02:11 INFO - https://queue.taskcluster.net/v1/task/diEbyJz1TDWrCMVoKM_gaw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 20:02:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diEbyJz1TDWrCMVoKM_gaw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 20:02:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diEbyJz1TDWrCMVoKM_gaw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 20:02:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diEbyJz1TDWrCMVoKM_gaw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 20:02:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diEbyJz1TDWrCMVoKM_gaw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 20:02:13 INFO - Downloaded 10845952 bytes. 20:02:13 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 20:02:13 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 20:02:13 INFO - Return code: 0 20:02:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:02:13 INFO - https://queue.taskcluster.net/v1/task/diEbyJz1TDWrCMVoKM_gaw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 20:02:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diEbyJz1TDWrCMVoKM_gaw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 20:02:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diEbyJz1TDWrCMVoKM_gaw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 20:02:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diEbyJz1TDWrCMVoKM_gaw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 20:02:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diEbyJz1TDWrCMVoKM_gaw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 20:02:16 INFO - Downloaded 110032103 bytes. 20:02:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/diEbyJz1TDWrCMVoKM_gaw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 20:02:16 INFO - mkdir: /builds/slave/test/properties 20:02:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:02:16 INFO - Writing to file /builds/slave/test/properties/build_url 20:02:16 INFO - Contents: 20:02:16 INFO - build_url:https://queue.taskcluster.net/v1/task/diEbyJz1TDWrCMVoKM_gaw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 20:02:17 INFO - Running post-action listener: _resource_record_post_action 20:02:17 INFO - Running post-action listener: set_extra_try_arguments 20:02:17 INFO - ##### 20:02:17 INFO - ##### Running populate-webroot step. 20:02:17 INFO - ##### 20:02:17 INFO - Running pre-action listener: _resource_record_pre_action 20:02:17 INFO - Running main action method: populate_webroot 20:02:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:02:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:02:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:02:17 INFO - '--tppagecycles', 20:02:17 INFO - '7'], 20:02:17 INFO - 'tests': ['tsvgm']}}, 20:02:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:02:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:02:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:02:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:02:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:02:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:02:17 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:02:17 INFO - 'talos_options': ['--disable-e10s'], 20:02:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:02:17 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:02:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:02:17 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:02:17 INFO - 'talos_options': ['--disable-e10s'], 20:02:17 INFO - 'tests': ['damp', 'tps']}, 20:02:17 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:02:17 INFO - 'tests': ['damp', 'tps']}, 20:02:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:02:17 INFO - 'tests': ['dromaeo_dom']}, 20:02:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:02:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:02:17 INFO - 'tests': ['a11yr', 20:02:17 INFO - 'ts_paint', 20:02:17 INFO - 'tpaint', 20:02:17 INFO - 'sessionrestore', 20:02:17 INFO - 'sessionrestore_no_auto_restore', 20:02:17 INFO - 'tabpaint']}, 20:02:17 INFO - 'other-e10s': {'tests': ['a11yr', 20:02:17 INFO - 'ts_paint', 20:02:17 INFO - 'tpaint', 20:02:17 INFO - 'sessionrestore', 20:02:17 INFO - 'sessionrestore_no_auto_restore', 20:02:17 INFO - 'tabpaint']}, 20:02:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:02:17 INFO - 'ts_paint', 20:02:17 INFO - 'tpaint', 20:02:17 INFO - 'sessionrestore', 20:02:17 INFO - 'sessionrestore_no_auto_restore', 20:02:17 INFO - 'tabpaint']}, 20:02:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:02:17 INFO - 'ts_paint', 20:02:17 INFO - 'tpaint', 20:02:17 INFO - 'sessionrestore', 20:02:17 INFO - 'sessionrestore_no_auto_restore', 20:02:17 INFO - 'tabpaint']}, 20:02:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:02:17 INFO - 'tests': ['a11yr', 20:02:17 INFO - 'ts_paint', 20:02:17 INFO - 'tpaint', 20:02:17 INFO - 'sessionrestore', 20:02:17 INFO - 'sessionrestore_no_auto_restore', 20:02:17 INFO - 'tabpaint']}, 20:02:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:02:17 INFO - 'tests': ['a11yr', 20:02:17 INFO - 'ts_paint', 20:02:17 INFO - 'tpaint', 20:02:17 INFO - 'sessionrestore', 20:02:17 INFO - 'sessionrestore_no_auto_restore', 20:02:17 INFO - 'tabpaint']}, 20:02:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:02:17 INFO - 'tests': ['tsvgx', 20:02:17 INFO - 'tsvgr_opacity', 20:02:17 INFO - 'tart', 20:02:17 INFO - 'tscrollx', 20:02:17 INFO - 'cart']}, 20:02:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:02:17 INFO - 'tsvgr_opacity', 20:02:17 INFO - 'tart', 20:02:17 INFO - 'tscrollx', 20:02:17 INFO - 'cart']}, 20:02:17 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:02:17 INFO - 'talos_options': ['--disable-e10s'], 20:02:17 INFO - 'tests': ['tp5o']}, 20:02:17 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:02:17 INFO - 'tests': ['tp5o']}, 20:02:17 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:02:17 INFO - 'talos_options': ['--disable-e10s', 20:02:17 INFO - '--xperf_path', 20:02:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:02:17 INFO - 'tests': ['tp5n']}, 20:02:17 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:02:17 INFO - 'talos_options': ['--xperf_path', 20:02:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:02:17 INFO - 'tests': ['tp5n']}}, 20:02:17 INFO - 'talos.zip': {'path': '', 20:02:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:02:17 INFO - Running post-action listener: _resource_record_post_action 20:02:17 INFO - ##### 20:02:17 INFO - ##### Running create-virtualenv step. 20:02:17 INFO - ##### 20:02:17 INFO - Running pre-action listener: _resource_record_pre_action 20:02:17 INFO - Running main action method: create_virtualenv 20:02:17 INFO - Creating virtualenv /builds/slave/test/build/venv 20:02:17 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 20:02:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:02:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:02:17 INFO - Using real prefix '/tools/python27' 20:02:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:02:18 INFO - Installing distribute.............................................................................................................................................................................................done. 20:02:20 INFO - Installing pip.................done. 20:02:20 INFO - Return code: 0 20:02:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:02:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:02:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:02:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:02:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:02:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bbbaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbca420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80c0d79a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba59e00>, '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 0x10b882db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba54b20>, '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.ufPMW7YOtH/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.UmL14AwIJn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 20:02:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 20:02:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 20:02:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ufPMW7YOtH/Render', 20:02:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:02:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:02:20 INFO - 'HOME': '/Users/cltbld', 20:02:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:02:20 INFO - 'LOGNAME': 'cltbld', 20:02:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:02:20 INFO - 'MOZ_NO_REMOTE': '1', 20:02:20 INFO - 'NO_EM_RESTART': '1', 20:02:20 INFO - 'PAGER': '/bin/cat', 20:02:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:02:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:02:20 INFO - 'PWD': '/builds/slave/test', 20:02:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:02:20 INFO - 'SHELL': '/bin/bash', 20:02:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UmL14AwIJn/Listeners', 20:02:20 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 20:02:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:02:20 INFO - 'USER': 'cltbld', 20:02:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:02:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:02:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:02:20 INFO - 'XPC_FLAGS': '0x0', 20:02:20 INFO - 'XPC_SERVICE_NAME': '0', 20:02:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:02:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:02:21 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 20:02:21 INFO - Cleaning up... 20:02:21 INFO - Return code: 0 20:02:21 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 20:02:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:02:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:02:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:02:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:02:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bbbaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbca420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80c0d79a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba59e00>, '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 0x10b882db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba54b20>, '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.ufPMW7YOtH/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.UmL14AwIJn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 20:02:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 20:02:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 20:02:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ufPMW7YOtH/Render', 20:02:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:02:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:02:21 INFO - 'HOME': '/Users/cltbld', 20:02:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:02:21 INFO - 'LOGNAME': 'cltbld', 20:02:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:02:21 INFO - 'MOZ_NO_REMOTE': '1', 20:02:21 INFO - 'NO_EM_RESTART': '1', 20:02:21 INFO - 'PAGER': '/bin/cat', 20:02:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:02:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:02:21 INFO - 'PWD': '/builds/slave/test', 20:02:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:02:21 INFO - 'SHELL': '/bin/bash', 20:02:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UmL14AwIJn/Listeners', 20:02:21 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 20:02:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:02:21 INFO - 'USER': 'cltbld', 20:02:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:02:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:02:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:02:21 INFO - 'XPC_FLAGS': '0x0', 20:02:21 INFO - 'XPC_SERVICE_NAME': '0', 20:02:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:02:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:02:21 INFO - Downloading/unpacking psutil>=0.7.1 20:02:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:02:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:02:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:02:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:02:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:02:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:02:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:02:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:02:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:02:24 INFO - Installing collected packages: psutil 20:02:24 INFO - Running setup.py install for psutil 20:02:24 INFO - building 'psutil._psutil_osx' extension 20:02:24 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 20:02:25 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 20:02:25 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 20:02:25 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 20:02:25 INFO - building 'psutil._psutil_posix' extension 20:02:25 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 20:02:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:02:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:02:25 INFO - ^ 20:02:25 INFO - 1 warning generated. 20:02:25 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 20:02:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:02:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:02:25 INFO - Successfully installed psutil 20:02:25 INFO - Cleaning up... 20:02:25 INFO - Return code: 0 20:02:25 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 20:02:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:02:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:02:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:02:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:02:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:25 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 0x10bbbaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbca420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80c0d79a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba59e00>, '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 0x10b882db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba54b20>, '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.ufPMW7YOtH/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.UmL14AwIJn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 20:02:25 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 20:02:25 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 20:02:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ufPMW7YOtH/Render', 20:02:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:02:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:02:25 INFO - 'HOME': '/Users/cltbld', 20:02:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:02:25 INFO - 'LOGNAME': 'cltbld', 20:02:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:02:25 INFO - 'MOZ_NO_REMOTE': '1', 20:02:25 INFO - 'NO_EM_RESTART': '1', 20:02:25 INFO - 'PAGER': '/bin/cat', 20:02:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:02:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:02:25 INFO - 'PWD': '/builds/slave/test', 20:02:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:02:25 INFO - 'SHELL': '/bin/bash', 20:02:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UmL14AwIJn/Listeners', 20:02:25 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 20:02:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:02:25 INFO - 'USER': 'cltbld', 20:02:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:02:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:02:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:02:25 INFO - 'XPC_FLAGS': '0x0', 20:02:25 INFO - 'XPC_SERVICE_NAME': '0', 20:02:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:02:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:02:25 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 20:02:25 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 20:02:25 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 20:02:25 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 20:02:25 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 20:02:25 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 20:02:25 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 20:02:28 INFO - Downloading mozsystemmonitor-0.0.tar.gz 20:02:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:02:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 20:02:28 INFO - Installing collected packages: mozsystemmonitor 20:02:28 INFO - Running setup.py install for mozsystemmonitor 20:02:28 INFO - Successfully installed mozsystemmonitor 20:02:28 INFO - Cleaning up... 20:02:28 INFO - Return code: 0 20:02:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:02: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')]} 20:02:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:02:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02: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')]} 20:02:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:02:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bbbaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbca420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80c0d79a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba59e00>, '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 0x10b882db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba54b20>, '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.ufPMW7YOtH/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.UmL14AwIJn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 20:02:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:02:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 20:02:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ufPMW7YOtH/Render', 20:02:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:02:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:02:28 INFO - 'HOME': '/Users/cltbld', 20:02:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:02:28 INFO - 'LOGNAME': 'cltbld', 20:02:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:02:28 INFO - 'MOZ_NO_REMOTE': '1', 20:02:28 INFO - 'NO_EM_RESTART': '1', 20:02:28 INFO - 'PAGER': '/bin/cat', 20:02:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:02:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:02:28 INFO - 'PWD': '/builds/slave/test', 20:02:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:02:28 INFO - 'SHELL': '/bin/bash', 20:02:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UmL14AwIJn/Listeners', 20:02:28 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 20:02:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:02:28 INFO - 'USER': 'cltbld', 20:02:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:02:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:02:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:02:28 INFO - 'XPC_FLAGS': '0x0', 20:02:28 INFO - 'XPC_SERVICE_NAME': '0', 20:02:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:02:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:02:28 INFO - Downloading/unpacking blobuploader==1.2.4 20:02: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 20:02: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 20:02: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 20:02: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 20:02: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 20:02: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 20:02:31 INFO - Downloading blobuploader-1.2.4.tar.gz 20:02:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:02:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:02: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 20:02: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 20:02: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 20:02: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 20:02: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 20:02: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 20:02:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:02:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:02: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 20:02: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 20:02: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 20:02: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 20:02: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 20:02: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 20:02:32 INFO - Downloading docopt-0.6.1.tar.gz 20:02:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:02:32 INFO - Installing collected packages: blobuploader, requests, docopt 20:02:32 INFO - Running setup.py install for blobuploader 20:02:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:02:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:02:32 INFO - Running setup.py install for requests 20:02:33 INFO - Running setup.py install for docopt 20:02:33 INFO - Successfully installed blobuploader requests docopt 20:02:33 INFO - Cleaning up... 20:02:33 INFO - Return code: 0 20:02:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:02:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:02:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:02:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:02:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:02:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bbbaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbca420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80c0d79a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba59e00>, '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 0x10b882db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba54b20>, '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.ufPMW7YOtH/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.UmL14AwIJn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 20:02:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 20:02:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:02:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ufPMW7YOtH/Render', 20:02:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:02:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:02:33 INFO - 'HOME': '/Users/cltbld', 20:02:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:02:33 INFO - 'LOGNAME': 'cltbld', 20:02:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:02:33 INFO - 'MOZ_NO_REMOTE': '1', 20:02:33 INFO - 'NO_EM_RESTART': '1', 20:02:33 INFO - 'PAGER': '/bin/cat', 20:02:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:02:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:02:33 INFO - 'PWD': '/builds/slave/test', 20:02:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:02:33 INFO - 'SHELL': '/bin/bash', 20:02:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UmL14AwIJn/Listeners', 20:02:33 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 20:02:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:02:33 INFO - 'USER': 'cltbld', 20:02:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:02:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:02:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:02:33 INFO - 'XPC_FLAGS': '0x0', 20:02:33 INFO - 'XPC_SERVICE_NAME': '0', 20:02:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:02:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:02:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:02:33 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-i9O3Xw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:02:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:02:33 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-YboD6V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:02:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:02:33 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-wMx_CX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:02:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:02:33 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-GRHDAb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:02:34 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-NZgq8X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:02:34 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-D7Ya4Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:02:34 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-NPtvVy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:02:34 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-5fYbTx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:02:34 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-dnQdjX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:02:34 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-7t4ROc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:02:34 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-sLDcFK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:02:34 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-Cb1OVi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:02:34 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-pJTKMx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:02:34 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-UB0xJ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:02:35 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-_hMpOA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:02:35 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-e2VALP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:02:35 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-TMy2hM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:02:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:02:35 INFO - Running setup.py install for manifestparser 20:02:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:02:35 INFO - Running setup.py install for mozcrash 20:02:35 INFO - Running setup.py install for mozdebug 20:02:35 INFO - Running setup.py install for mozdevice 20:02:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:02:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:02:35 INFO - Running setup.py install for mozfile 20:02:36 INFO - Running setup.py install for mozhttpd 20:02:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:02:36 INFO - Running setup.py install for mozinfo 20:02:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:02:36 INFO - Running setup.py install for mozInstall 20:02:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:02:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:02:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:02:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:02:36 INFO - Running setup.py install for mozleak 20:02:36 INFO - Running setup.py install for mozlog 20:02:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:02:36 INFO - Running setup.py install for moznetwork 20:02:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:02:36 INFO - Running setup.py install for mozprocess 20:02:37 INFO - Running setup.py install for mozprofile 20:02:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:02:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:02:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:02:37 INFO - Running setup.py install for mozrunner 20:02:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:02:37 INFO - Running setup.py install for mozscreenshot 20:02:37 INFO - Running setup.py install for moztest 20:02:37 INFO - Running setup.py install for mozversion 20:02:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:02:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:02:37 INFO - Cleaning up... 20:02:37 INFO - Return code: 0 20:02:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:02:37 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')]} 20:02:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:02:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:37 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')]} 20:02:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:02:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:37 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 0x10bbbaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbca420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80c0d79a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba59e00>, '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 0x10b882db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba54b20>, '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.ufPMW7YOtH/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.UmL14AwIJn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 20:02:37 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 20:02:37 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 20:02:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ufPMW7YOtH/Render', 20:02:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:02:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:02:37 INFO - 'HOME': '/Users/cltbld', 20:02:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:02:37 INFO - 'LOGNAME': 'cltbld', 20:02:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:02:37 INFO - 'MOZ_NO_REMOTE': '1', 20:02:37 INFO - 'NO_EM_RESTART': '1', 20:02:37 INFO - 'PAGER': '/bin/cat', 20:02:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:02:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:02:37 INFO - 'PWD': '/builds/slave/test', 20:02:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:02:37 INFO - 'SHELL': '/bin/bash', 20:02:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UmL14AwIJn/Listeners', 20:02:37 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 20:02:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:02:37 INFO - 'USER': 'cltbld', 20:02:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:02:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:02:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:02:37 INFO - 'XPC_FLAGS': '0x0', 20:02:37 INFO - 'XPC_SERVICE_NAME': '0', 20:02:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:02:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:02:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:02:38 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-5Rxr49-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:02:38 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 20:02:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:02:38 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-y5rHMJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:02:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:02:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:02:38 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-p20WwO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:02:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:02:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:02:38 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-fyl6PF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:02:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:02:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:02:38 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-ihYbr1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:02:38 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)) 20:02:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:02:38 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-N8HjHO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:02:38 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)) 20:02:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:02:38 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-wu08xg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:02:38 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)) 20:02:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:02:38 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-NtUbRc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:02:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 20:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:02:39 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-BCgMeZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:02:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 20:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:02:39 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-eExGoy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:02:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:02:39 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-NZExQ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:02:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 20:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:02:39 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-WicAKP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:02:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 20:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:02:39 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-HLI5hw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:02:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 20:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:02:39 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-esJHFQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:02:39 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)) 20:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:02:39 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-hvAGNN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:02:39 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)) 20:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:02:39 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-24ktCC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:02:39 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)) 20:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:02:39 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-eWxhIA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:02:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 20:02:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:02:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:02:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:02:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:02:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:02:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:02:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:02:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:02:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:02:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:02:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:02:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:02:42 INFO - Downloading blessings-1.6.tar.gz 20:02:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:02:42 INFO - Installing collected packages: blessings 20:02:42 INFO - Running setup.py install for blessings 20:02:42 INFO - Successfully installed blessings 20:02:42 INFO - Cleaning up... 20:02:42 INFO - Return code: 0 20:02:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:02:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:02:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:02:43 INFO - Reading from file tmpfile_stdout 20:02:43 INFO - Current package versions: 20:02:43 INFO - blessings == 1.6 20:02:43 INFO - blobuploader == 1.2.4 20:02:43 INFO - docopt == 0.6.1 20:02:43 INFO - manifestparser == 1.1 20:02:43 INFO - mozInstall == 1.12 20:02:43 INFO - mozcrash == 0.17 20:02:43 INFO - mozdebug == 0.1 20:02:43 INFO - mozdevice == 0.48 20:02:43 INFO - mozfile == 1.2 20:02:43 INFO - mozhttpd == 0.7 20:02:43 INFO - mozinfo == 0.9 20:02:43 INFO - mozleak == 0.1 20:02:43 INFO - mozlog == 3.1 20:02:43 INFO - moznetwork == 0.27 20:02:43 INFO - mozprocess == 0.22 20:02:43 INFO - mozprofile == 0.28 20:02:43 INFO - mozrunner == 6.11 20:02:43 INFO - mozscreenshot == 0.1 20:02:43 INFO - mozsystemmonitor == 0.0 20:02:43 INFO - moztest == 0.7 20:02:43 INFO - mozversion == 1.4 20:02:43 INFO - psutil == 3.1.1 20:02:43 INFO - requests == 1.2.3 20:02:43 INFO - wsgiref == 0.1.2 20:02:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:02:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:02:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:02:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:02:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:02:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bbbaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbca420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80c0d79a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba59e00>, '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 0x10b882db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba54b20>, '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.ufPMW7YOtH/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.UmL14AwIJn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 20:02:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 20:02:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:02:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ufPMW7YOtH/Render', 20:02:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:02:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:02:43 INFO - 'HOME': '/Users/cltbld', 20:02:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:02:43 INFO - 'LOGNAME': 'cltbld', 20:02:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:02:43 INFO - 'MOZ_NO_REMOTE': '1', 20:02:43 INFO - 'NO_EM_RESTART': '1', 20:02:43 INFO - 'PAGER': '/bin/cat', 20:02:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:02:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:02:43 INFO - 'PWD': '/builds/slave/test', 20:02:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:02:43 INFO - 'SHELL': '/bin/bash', 20:02:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UmL14AwIJn/Listeners', 20:02:43 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 20:02:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:02:43 INFO - 'USER': 'cltbld', 20:02:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:02:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:02:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:02:43 INFO - 'XPC_FLAGS': '0x0', 20:02:43 INFO - 'XPC_SERVICE_NAME': '0', 20:02:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:02:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:02:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 20:02:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 20:02:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 20:02:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 20:02:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 20:02:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 20:02:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 20:02:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 20:02:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 20:02:43 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 20:02:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 20:02:43 INFO - Cleaning up... 20:02:43 INFO - Return code: 0 20:02:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:02:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:02:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:02:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:02:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:02:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bbbaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbca420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80c0d79a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba59e00>, '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 0x10b882db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba54b20>, '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.ufPMW7YOtH/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.UmL14AwIJn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 20:02:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 20:02:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 20:02:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ufPMW7YOtH/Render', 20:02:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:02:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:02:43 INFO - 'HOME': '/Users/cltbld', 20:02:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:02:43 INFO - 'LOGNAME': 'cltbld', 20:02:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:02:43 INFO - 'MOZ_NO_REMOTE': '1', 20:02:43 INFO - 'NO_EM_RESTART': '1', 20:02:43 INFO - 'PAGER': '/bin/cat', 20:02:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:02:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:02:43 INFO - 'PWD': '/builds/slave/test', 20:02:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:02:43 INFO - 'SHELL': '/bin/bash', 20:02:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UmL14AwIJn/Listeners', 20:02:43 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 20:02:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:02:43 INFO - 'USER': 'cltbld', 20:02:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:02:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:02:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:02:43 INFO - 'XPC_FLAGS': '0x0', 20:02:43 INFO - 'XPC_SERVICE_NAME': '0', 20:02:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:02:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:02:43 INFO - Downloading/unpacking jsonschema 20:02:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:02:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:02:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:02:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:02:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:02:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:02:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:02:46 INFO - Downloading/unpacking functools32 (from jsonschema) 20:02: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 20:02: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 20:02: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 20:02: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 20:02: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 20:02: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 20:02:46 INFO - Downloading functools32-3.2.3-2.tar.gz 20:02:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:02:46 INFO - warning: no files found matching '*.txt' 20:02:46 INFO - no previously-included directories found matching 'build' 20:02:46 INFO - no previously-included directories found matching 'dist' 20:02:46 INFO - no previously-included directories found matching '.git*' 20:02:46 INFO - Installing collected packages: jsonschema, functools32 20:02:46 INFO - Running setup.py install for functools32 20:02:46 INFO - warning: no files found matching '*.txt' 20:02:46 INFO - no previously-included directories found matching 'build' 20:02:46 INFO - no previously-included directories found matching 'dist' 20:02:46 INFO - no previously-included directories found matching '.git*' 20:02:46 INFO - Successfully installed jsonschema functools32 20:02:46 INFO - Cleaning up... 20:02:47 INFO - Return code: 0 20:02:47 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 20:02:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:02:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:02:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:02:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:02:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:02:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bbbaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbca420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80c0d79a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba59e00>, '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 0x10b882db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba54b20>, '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.ufPMW7YOtH/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.UmL14AwIJn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 20:02:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 20:02:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 20:02:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ufPMW7YOtH/Render', 20:02:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:02:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:02:47 INFO - 'HOME': '/Users/cltbld', 20:02:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:02:47 INFO - 'LOGNAME': 'cltbld', 20:02:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:02:47 INFO - 'MOZ_NO_REMOTE': '1', 20:02:47 INFO - 'NO_EM_RESTART': '1', 20:02:47 INFO - 'PAGER': '/bin/cat', 20:02:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:02:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:02:47 INFO - 'PWD': '/builds/slave/test', 20:02:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:02:47 INFO - 'SHELL': '/bin/bash', 20:02:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UmL14AwIJn/Listeners', 20:02:47 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 20:02:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:02:47 INFO - 'USER': 'cltbld', 20:02:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:02:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:02:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:02:47 INFO - 'XPC_FLAGS': '0x0', 20:02:47 INFO - 'XPC_SERVICE_NAME': '0', 20:02:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:02:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:02:47 INFO - Downloading/unpacking flake8 20:02:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:02:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:02:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:02:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:02:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:02:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:02:49 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 20:02:49 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 20:02: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 20:02: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 20:02: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 20:02: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 20:02: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 20:02: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 20:02:50 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 20:02:50 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 20:02:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:02:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:02:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:02:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:02:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:02:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:02:50 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 20:02:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:02:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:02:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:02:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:02:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:02:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:02:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 20:02:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 20:02:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 20:02:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 20:02:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 20:02:50 INFO - no previously-included directories found matching 'docs/_build' 20:02:50 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 20:02:51 INFO - Running setup.py install for pep8 20:02:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 20:02:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 20:02:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 20:02:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 20:02:51 INFO - no previously-included directories found matching 'docs/_build' 20:02:51 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 20:02:51 INFO - Successfully installed flake8 mccabe pyflakes pep8 20:02:51 INFO - Cleaning up... 20:02:51 INFO - Return code: 0 20:02:51 INFO - Running post-action listener: _resource_record_post_action 20:02:51 INFO - Running post-action listener: _start_resource_monitoring 20:02:51 INFO - Starting resource monitoring. 20:02:51 INFO - ##### 20:02:51 INFO - ##### Running install step. 20:02:51 INFO - ##### 20:02:51 INFO - Running pre-action listener: _resource_record_pre_action 20:02:51 INFO - Running main action method: install 20:02:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:02:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:02:51 INFO - Reading from file tmpfile_stdout 20:02:51 INFO - Detecting whether we're running mozinstall >=1.0... 20:02:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:02:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:02:51 INFO - Reading from file tmpfile_stdout 20:02:51 INFO - Output received: 20:02:51 INFO - Usage: mozinstall [options] installer 20:02:51 INFO - Options: 20:02:51 INFO - -h, --help show this help message and exit 20:02:51 INFO - -d DEST, --destination=DEST 20:02:51 INFO - Directory to install application into. [default: 20:02:51 INFO - "/builds/slave/test"] 20:02:51 INFO - --app=APP Application being installed. [default: firefox] 20:02:51 INFO - mkdir: /builds/slave/test/build/application 20:02:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 20:02:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 20:03:24 INFO - Reading from file tmpfile_stdout 20:03:24 INFO - Output received: 20:03:24 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 20:03:24 INFO - Running post-action listener: _resource_record_post_action 20:03:24 INFO - ##### 20:03:24 INFO - ##### Running run-tests step. 20:03:24 INFO - ##### 20:03:24 INFO - Running pre-action listener: _resource_record_pre_action 20:03:24 INFO - Running main action method: run_tests 20:03:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:03:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:03:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:03:24 INFO - 2016-04-21 20:03:24.873 screenresolution[1641:7534] starting screenresolution argv=screenresolution get 20:03:24 INFO - 2016-04-21 20:03:24.875 screenresolution[1641:7534] Display 0: 1600x1200x32@60 20:03:24 INFO - 2016-04-21 20:03:24.881 screenresolution[1642:7536] starting screenresolution argv=screenresolution list 20:03:24 INFO - Available Modes on Display 0 20:03:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:03:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:03:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:03:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:03:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:03:25 INFO - Intel Iris: 20:03:25 INFO - Chipset Model: Intel Iris 20:03:25 INFO - Type: GPU 20:03:25 INFO - Bus: Built-In 20:03:25 INFO - VRAM (Dynamic, Max): 1536 MB 20:03:25 INFO - Vendor: Intel (0x8086) 20:03:25 INFO - Device ID: 0x0a2e 20:03:25 INFO - Revision ID: 0x0009 20:03:25 INFO - Displays: 20:03:25 INFO - Display: 20:03:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:03:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:03:25 INFO - Display Serial Number: 1600x1200 60 20:03:25 INFO - Main Display: Yes 20:03:25 INFO - Mirror: Off 20:03:25 INFO - Online: Yes 20:03:25 INFO - Rotation: Supported 20:03:25 INFO - Return code: 0 20:03:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:03:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:03:25 INFO - Python 2.7.3 20:03:25 INFO - Return code: 0 20:03:25 INFO - grabbing minidump binary from tooltool 20:03:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:03:25 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 0x10ba59e00>, '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 0x10b882db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba54b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:03:25 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 20:03:25 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 20:03:25 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:03:25 INFO - Return code: 0 20:03:25 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:03:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:03:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:03:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:03:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:03:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 20:03:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 20:03:26 INFO - Return code: 0 20:03:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/diEbyJz1TDWrCMVoKM_gaw/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0074', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 20:03:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/diEbyJz1TDWrCMVoKM_gaw/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0074 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 20:03:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ufPMW7YOtH/Render', 20:03:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:03:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:03:26 INFO - 'HOME': '/Users/cltbld', 20:03:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:03:26 INFO - 'LOGNAME': 'cltbld', 20:03:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:03:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:03:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:03:26 INFO - 'MOZ_NO_REMOTE': '1', 20:03:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:03:26 INFO - 'NO_EM_RESTART': '1', 20:03:26 INFO - 'PAGER': '/bin/cat', 20:03:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:03:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:03:26 INFO - 'PWD': '/builds/slave/test', 20:03:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:03:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:03:26 INFO - 'SHELL': '/bin/bash', 20:03:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UmL14AwIJn/Listeners', 20:03:26 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 20:03:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:03:26 INFO - 'USER': 'cltbld', 20:03:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:03:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:03:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:03:26 INFO - 'XPC_FLAGS': '0x0', 20:03:26 INFO - 'XPC_SERVICE_NAME': '0', 20:03:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:03:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/diEbyJz1TDWrCMVoKM_gaw/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0074', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 20:03:26 INFO - mozversion application_buildid: 20160421190312 20:03:26 INFO - mozversion application_changeset: 4fa5909cf1e73939ee823442f2c69b0a86474489 20:03:26 INFO - mozversion application_display_name: Nightly 20:03:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:03:26 INFO - mozversion application_name: Firefox 20:03:26 INFO - mozversion application_remotingname: firefox 20:03:26 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 20:03:26 INFO - mozversion application_vendor: Mozilla 20:03:26 INFO - mozversion application_version: 48.0a1 20:03:26 INFO - mozversion platform_buildid: 20160421190312 20:03:26 INFO - mozversion platform_changeset: 4fa5909cf1e73939ee823442f2c69b0a86474489 20:03:26 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 20:03:26 INFO - mozversion platform_version: 48.0a1 20:03:26 INFO - using testdate: 1461294206 20:03:26 INFO - actual date: 1461294206 20:03:26 INFO - starting webserver on 'localhost:49270' 20:03:26 INFO - SUITE-START | Running 2 tests 20:03:26 INFO - TEST-START | dromaeo_css 20:03:26 INFO - operating with platform_type : mac_ 20:03:26 INFO - Initialising browser for dromaeo_css test... 20:03:26 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/tmp2zpIN_/profile http://localhost:49270/getInfo.html) 20:03:28 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 20:03:28 INFO - PROCESS | 1660 | colorDepth:24 20:03:28 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 20:03:28 INFO - PROCESS | 1660 | __metrics 20:03:29 INFO - PROCESS | 1660 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:03:29 ERROR - PROCESS | 1660 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 20:03:29 INFO - TEST-INFO | 1660: exit 0 20:03:29 INFO - Browser initialized. 20:03:29 INFO - Running cycle 1/1 for dromaeo_css test... 20:03:29 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/tmp2zpIN_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:03:41 INFO - PROCESS | 1662 | RSS: Main: 238755840 20:03:41 INFO - PROCESS | 1662 | 20:04:33 INFO - PROCESS | 1662 | 1461294273423 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] 20:06:44 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 20:06:45 INFO - PROCESS | 1662 | RSS: Main: 371503104 20:06:45 INFO - PROCESS | 1662 | 20:09:48 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 20:09:48 INFO - PROCESS | 1662 | RSS: Main: 378343424 20:09:48 INFO - PROCESS | 1662 | 20:12:51 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 20:12:51 INFO - PROCESS | 1662 | RSS: Main: 375726080 20:12:51 INFO - PROCESS | 1662 | 20:15:53 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 20:15:54 INFO - PROCESS | 1662 | RSS: Main: 377978880 20:15:54 INFO - PROCESS | 1662 | 20:18:57 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 20:18:58 INFO - PROCESS | 1662 | RSS: Main: 341151744 20:18:58 INFO - PROCESS | 1662 | 20:22:01 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 20:22:01 INFO - PROCESS | 1662 | RSS: Main: 386965504 20:22:01 INFO - PROCESS | 1662 | 20:22:01 INFO - PROCESS | 1662 | __start_tp_report 20:22:01 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 20:22:01 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 20:22:01 INFO - PROCESS | 1662 | |i|pagename|runs| 20:22:01 INFO - PROCESS | 1662 | |0;dojo.html;3368.6462921393254;3417.4190387634085;3443.3629778490995;3473.461613449915;3486.62278400032;5591.916121258174;5662.292213473316;5684.687342196177;5692.288463942002;5696.458836410545;5689.2319536862515;5725.398833122515;5755.25181726375;5849.9707501462435;5857.824265272038;5101.38783345999;5138.794448222076;5149.201873709581;5174.689518628894;5263.105272103732;4468.151051300242;5238.371395432553;5241.659292146017;5274.103402421598;5321.281626980366;5051.191809310511;5051.2423136529815;5131.230315452674;5139.74301284938;5166.819161329373;5164.715940623264;5170.638055336128;5192.1173400522;5202.06362854686;5203.687778733288;5131.332926719502;5137.922931156036;5191.247269145991;5194.220866869962;5221.138512145491;5549.223108764776;5570.498655121059;5632.098864181711;5668.43315668434;5732.914006289909;5089.770960306795;6153.169322141498;6163.383661633846;6174.475169610589;6195.0397688357925;6170.691465426711;6223.408776166257;6224.221972253468;6224.937750622526;6227.190465239535;5709.942900571024;5783.929972955017;5805;5873.4126587340925;5885.411458854081;6247.2815626202655;6259.405356491126;6268.216472940882;6274.686265686789;6290.905636415458;6139.324674285825;6146.108814221912;6152.723127459275;6155.599886007485;6156.138140660311;6086.117512960596;6169.938300616936;6194.008958566607;6275.560710750204;6293.402126797947;6137.754489820447;6157.476614487729;6163.7842675506145;6167.84580385485;6170.81487555365;6144;6187.969060154671;6189.1954045974635;6198.039303907902;6216;4831.444383895827;4863.683860549053;4906.288588154697;4914.926276105862;4920.926186107211;4319.719218250803;4459.1304695584695;4481.641468682563;4487.259602165672;4497.8200871964755;5653.236813030188;5673.517750991212;5677.630953987978;5682.971585142048;5683;6240.843978900564;6241.469475094577;6286.931221692324;6298.433141017331;6309.810705678837;5556.749946252428;5599.244102046253;5621.8594535136135;5627;5629.521490673339;4499.505054444008;5365.651232669864;5367.570594352443;5368.516833485005;5371.543418809445;5300.337457817773;6221.253449586078;6283.6858157091965;6285.5600107992;6303.936960630427;6239.376062393724;6250.812475625738;6281.497480201481;6287.308396076336;6307.022411526221;6182.196314479089;6280.434760871543;6290.0879372491645;6313.084602732486;6316.368363163647;6138.508919286535;6180.042093475518;6211.65835879031;6223.0354295084335;6224;6101.908471372844;6235.75056997715;6259.280182779039;6278.654673992792;6307.96846015767;5894.410558944071;6102.4507794298725;6105.84735381619;6130;6148.415900489446;6028.638281703112;6049.879002420015;6106.491003209229;6110.194261626952;6121.602095863755;4898.510148985216;5953.28560572734;5964.642121472552;5980.850478738066;5984.521238300838;5951.285845698544;6108.214598423894;6108.645631960441;6114.593862501314;6115.205023346936;5893.616914900518;5954.10688396744;6000.759969601167;6015.909761353496;6067.393260673897 20:22:01 INFO - PROCESS | 1662 | |1;ext.html;491.4962163782123;517.9715115668638;523.6282239609877;525.7975679363444;540.4865377891003;30286.84856575717;31026;31995.36009279813;38179.23641527168;39026;41692.37461438081;41985.37021944673;42160;42167;42215;9415.105564971316;9638.518074096302;9650.227981761442;9653.420794752303;9661.71014869555;9522.476263805487;9654.613815447374;9672.90327096727;9680.661176858812;9685.854712179287;9686.951565242163;9791.412515249072;9792.853107203362;9802.656907008257;9839.360441571274;9703.981081986396;9771.462569558671;9773.41359518431;9775.169110625606;9786.26603004774;9592.66425675105;9711.90288097117;9713.90286097137;9718.902810971871;9720.173785228264;39745.40381894274;40186.79439616787;40238;40249.798751006354;40290.791276261465;2555.5527782637964;3101.5967924169713;3119.610048743907;3351.916202094943;3910;27455.588166177324;28383.290417739514;28401.431971360664;28501;28513.572296415572;2593.9092131775487;2646.3119588907025;2767.4741799058115;2778.527650299454;2779;364677.353226467;364820;366640.16679916694;370506;371293;58663.41336586665;61638.69180654068;62564;62613.37386626167;62635.06047409293;65925;66013.33986660167;66242;66304.33695662982;66409.6679516609;16728.66542669164;16945.915270423568;16952;16969.23638436273;16983.23575439108;12900.741985160244;12987.870121298665;13000.674983125498;13007.609771706864;13021.544245951347;18606.81393186078;19555.8044419554;19561;19588.21647134127;19605.607887842165;6407.679616019181;7794.3764498839955;8025.518468891885;8029.156938521488;8099.311558517474;899.820035992791;926.333040211047;974.3861367338532;976.4385478349977;1089.7057794395628;24334.148304809245;24532.018719251388;27517.449651006867;28186;28561.57157642637;1055.7941201465794;1105.2263415609107;1121.6747143328344;1139.316410153901;1141.937193454368;14443.133411995314;15085.849141508665;15110.54668359951;15277.923610381877;15492.612684682974;647693;654211;659707;660735;660800;58380.41619583835;59055.40944590499;60038.699806500685;60048;62643.373566264665;1310.711643438442;1369.0211498778422;1434.8565143485569;1447.1027962663215;1469.9338529766187;27694.169174924784;29460.55809162907;29996.85001574978;30167.396652067273;30862.765489380174;51185.48814511807;51467.71330716612;52097.218541722665;52255.73872130767;52304.73847630737;336329;402914;403565;407562;407795;23423.648645269994;25887.22338329853;26231.34421639398;26955;27301.726982729917;42593;43156.352654709575;43278.350824738285;43395.783021084695;43588.782056090786;33554.83222583871;36131.041219375205;36773;36995;37685.4347184787;36461;36522.634773652986;36942.815285923396;37153.442698360086;37280.254394912496;36067.63932360748;36433.81783091067;36730.081747956516;37465;37474.43788343122;31179.165421770558;32096.67903320937;32409.18977025499;32743.853965111106;32780.67219327872;23605;23895.40261493407;24086.638700419157;24265.393365165306;24427.755722442547 20:22:01 INFO - PROCESS | 1662 | |2;jquery.html;396.7559950630362;396.7956502401266;399.39092883352885;401.66661670813227;413.76208680009;14832.48086316979;19978.50053748655;21094.89452552737;21099.367018989436;21149.788502114974;15583.376664933417;15970;18332.633347333045;20431.080601372938;20837;4746.5490778376;5988.520918326523;5992.400759924017;5992.910106348409;6044.395560443964;5793.768249270024;5878.323992740826;5882.794102206402;5926.288845338544;5940.52475801937;5659.490645841874;5872.9706351468185;5890;5890.2931648202275;5900.675462849542;5801.767929282844;5854.6194497357965;5879;5937.524998000171;5943.494802941756;5035.1188542005;5197.740112994373;5214.973925130388;5215.3741551013745;5228.058949389108;25253.737313134454;26499.47001059968;26868.05961791328;26870.86564567184;26956.191314260603;2151.236311109549;2174.913003479859;2261.8529795563236;2288.084766093559;2669.7864170866283;9463.290253231035;9637.084476974676;10119.494025298705;12353.258804471761;15570.455034073866;1676.48029110976;1679.7648329233916;1747.842694157532;1765.4262364731514;1872.7097299918535;91421.99999999933;104366;111806;114866;118158;9577.712668619952;11650.184487085991;11666.241694289844;11722.58970936013;11722.94138529302;15109.848901511064;15195.772063419057;15247.923760381127;15260.542183734506;15262.236888155549;7637.656305466267;7693.4999225051315;8185.754427367188;8707.303415726854;8830.42602230853;7070.575765454089;8681.782955426039;8684;8786.956065219632;8795.340349473816;4575.336576196433;4625.814967401334;4634.420697412824;4682.227432473672;4698.976505117453;1904.457549171871;1959.1086055844557;1965.1058768260407;2003.1086166655698;2019;847.4788005376772;918.4443411735804;952.7618095476131;958.6596758150896;975.1272611013104;19693.719908206207;20611.175552977715;20629;20797.272095476586;20837.062332195088;933.1881263300836;943.4197968249478;951.4481600671594;962.5379817687549;964.6816550538306;7753;10751;10914.56341746337;10939;10985.725356865982;537871;546017.668144847;575269;584344;592390;135524;136072.639273606;136608.37803676407;137157;138186;977.6236149082512;979.0552117206929;993.4635296939716;995.2436148527172;1011.7521207304111;18202.635947280716;18262.99553524569;18305.450836474927;18334.633307334047;18374;49191.016179676924;50272.245916312015;50590.711603745636;52049.479505204465;52173.7391313041;70420;71528.64235678788;78658;80098;81812.59093704494;7769.92230077692;7848.725294614661;7936.166702496154;8166.346692264486;8339.291160251447;11968.102392320438;12237.816432753694;12240.08199385032;12600.432980515716;12715.93642031784;10840.67477975662;10903.563857445613;11259.549618015188;11282.153838461983;11282.984531392214;10337.328073675188;10561.471926403803;10764.138868890626;10912.399818010086;11054.336739795637;11356.318620882774;11398.08815294758;11436.94281528587;11587.710307242387;11668;7773.766786996399;8127.83744325122;8217.630206640595;8425.157484251526;8704.042555319036;8005.719799806979;9859.654912078044;10947.34315941046;11279.943600281946;11299.717507062389 20:22:01 INFO - PROCESS | 1662 | |3;mootools.html;1407.8310602727674;1565.3660267591622;1580.5890468478196;1836.9448916532501;1988.5227545389112;7204.315590018947;7863.921360786398;8804.383693141483;8821.955890220539;8830.955845220766;8451.408401411903;8694.000189978155;8699.434101861661;8709.564521773902;8715.21563059326;2060.268604645352;2123.8300935925167;2348.1311914591583;2356.39911822485;2412.1798588479915;2404.290734233405;2439.2682195341417;2440;2440.2801173653725;2444.828861979662;2365.9881700591473;2392.0073169634666;2406.302172370011;2410.0600765701392;2413.51729654069;2113.651247544153;2328.685627440291;2376.120835290936;2390.426297688542;2397.2808157552663;2388.773066558674;2416.4683769570843;2418.4195793009726;2418.5767490689236;2419.322589674894;8549.187827156411;8558.272546833527;8579.957100214522;8588.69939552119;8590.055093939662;5072.036313100499;5124.641275110706;5238.633295669305;5248.317718696583;5285.709285989269;3368.6799754023327;3451.5685539307588;3532.9293414131575;3534.540509733718;3572.6427357264324;1023.1098943918838;1574.2679653960963;1627.7476991066403;1654.7186978213733;1661.4268077513238;11351.318920864775;11507.539698412054;11512.53949841997;11512.654620361403;11528.193026488148;4462.174497717932;4550.522195169526;4629.51390104041;4657.883552911137;4704.458987216514;9149.771255718528;9156.267498600097;9160.541972901328;9162.358634895627;9196.218321442619;5259.8422047338645;5267.762950667229;5270.683758974474;5271.314729085195;5304.761285742151;4432.667549933768;4533.093381323749;4841.878953026132;4911.803527858917;4912.508749125059;3554.831552752819;3634.8000859952426;3659.3779057560278;3691.464737613031;3712.276106159273;1192.5885569478517;1204.0608325506118;1206.5596057438972;1214.908881833866;1216.2641601830942;861.6510313323114;905.103947092387;914.8764916736155;954.9427034377959;968.8595153702814;4486.371907932892;5843.795467158629;5880.911786323209;6006.309274433409;6042.667653279112;912.5163663258484;917.7797328641078;922.9123233292827;961.7162936933621;964.8070385922703;3443.6900678939014;3836.4245363195187;3928.46965659638;4007.1384652299894;4069.959300407017;10351.689449316533;10569.841452378221;10867.45662716861;10895;11016.394098324508;10830;10969.341839489656;10980.396078215776;10981.945090274497;10994.230403871812;8065.032196136502;8079.515229086273;8283.544405057659;8355.331573474108;8371.623276952552;5056.924146137737;5695.8860822784145;5759.798407055733;5881.7647294107755;6004.159417681615;9636.566354514203;9779.41323520591;10075.899241007495;10113.5448904798;10217.23370747182;9973;10314.948425257826;10318.380897146195;10326.793464130826;10330.225233107698;6873.347032031949;6910.689018994258;6945.930540694529;7003.474739394667;7010.263922288086;8050.27547520726;8058.597070146587;8139.75580732579;8142.8778568322705;8160.347172226089;7119.501634885715;7304.050473438419;7322.780316590511;7419.035525381666;7464.888026679496;7101.502894797314;7192.1369435665965;7202.53183543068;7382.852342953003;7411;6862.176538815374;6926.58440493572;6937.236903940663;6953.721851126101;6984.476164287597;4317.460317460317;4698.130845793539;4731.219348807408;4963.329950456642;4983.925241121458;4707.835225767081;4964.826231081895;4979.800807967786;4992.425871024879;4996.225585034325 20:22:01 INFO - PROCESS | 1662 | |4;prototype.html;318.1251558214909;320.5736370627066;327.8475508888366;332.31377206069465;337.80913783712185;5882.647041177533;5915.1718759373725;5925.940740592593;5934.347221805598;5979.641221526701;4871.806407430176;5030.496950304968;5855.243426971912;5871.207387002753;5917.260342457193;4173.749575025508;4229.809658565357;4309.245881970658;4337.696361254698;4414.779261036935;3836.405357169643;3973.721839471238;4303.440552728141;4417.447819022622;4428.424304840366;4408.71343362682;4409.86770396887;4430.224710675635;4439.711418757786;4474.261746811789;3419.675130862564;4141.855035073773;4404.801783919699;4406.356186288233;4410.58099480552;3105.8602362893726;3803.3724435468116;3804.1821008483307;3811.294910441577;3815.1797363566966;5623.831285061455;5655.1517272409055;5661.942551022443;5663.621279062803;5665.951798917214;4912.287718280829;5081.822136225252;5087.79648814047;5106.361704786902;5154.4587818278915;6140.3552626974415;6194.473469755077;6213.751449941997;6218.5025197984505;6220.160278362454;4123.525794533607;4249.065205654751;4264.272969447347;4286.76422796746;4293.248681480728;6485.578437401554;6529.608223506605;6547.705353259114;6564.704588293491;6566.63883486408;4308.202982448257;4317.503487098961;4326.891827704332;4347.478302603708;4358.978205108954;3797.2974999625158;4152.356384760367;4399.978000109979;4416.933745993812;4442.622377097983;4340.131973605228;5000;5065.974670126626;5079.847604571869;5097.617678674114;5883.264591926009;6214.5960512566535;6224.408681175281;6234.314225435199;6252.124702541648;1250.7060840702427;1462.795208670787;1517.0745845034521;1520.5210358736963;1520.612243877804;879.8372301124313;930.06993006993;984.5864736810556;1004.6989149051996;1017.2523195451338;5135.820246291362;5400.324959380077;5504.843982763585;5879.412058794086;5881.911771323434;5870.530357571384;6148.538859585549;6533.216014078341;6540.70566824494;6568.277489476154;5570.693611851387;5831.941680583224;5849.795257165979;5861.648301101947;5877.617954832922;3348.8325583720716;3379.172102834821;3393.8642454301553;3401.914952126167;3404.5403870477166;6498.967505162444;6689.397954184049;6729.798106056826;6730.899036514456;6741.426978706767;5225;6598.3401659833635;6647.302033286532;6663.433608143362;6679.933200668028;5536.335639723259;5540.972295138498;6422.325655806073;6706.161729783777;6715.597064176165;4787.162246606899;5827.79602713903;6424.10062591247;6428.389303016206;6445.838854028687;5545.667259964415;6354.23749150105;6354.364563543609;6360.141380913517;6381.457576106083;6365.2311244608745;6736.461083113437;6805.251422343439;6831.555948863308;6868.9313106870295;4227.32362821947;4231.767252801125;4255.87232383029;4269.188854117708;4291.163223171577;5441.782328706808;6316.936830631635;6533.379328963741;6535.57518761279;6564.208128066442;6094.049904030668;6287.528435367457;6301.936980630318;6302.84242893913;6324.430801227728;6309.432151106423;6312.5265605078885;6313.916407585394;6323.114763933145;6326.177596912372;5291.142782897193;5917.260342457193;5950.464458198783;6008.918394688998;6193.969030154821;5174.586033117266;6229.844253893508;6284.780032699016;6305.306416294296;6308;4897.804087836446;5166.276721259102;6211.875762484635;6215.440610345091;6215.782447614311 20:22:01 INFO - PROCESS | 1662 | |5;yui.html;706.9858602827943;708.9078419805426;723.226148021617;758.893754874318;767.7504810936447;12995.48018079278;14852.554423367303;14971.625709357271;15266.236688165603;15280.770788438182;13499.12255703381;14041.368138433769;14045.227512486808;14062.648433789134;14071.366788494517;748.2143749063462;770.4760762681402;920.6731610278355;925.6482536636069;934.3272843552631;771.4368510986955;899.1278459893892;927.7773334399762;929.8512238041915;932.8227636748996;927.8608208768705;978.280963491833;985.2315194148575;985.7929834734714;987.9061489158519;784.5684873319651;806.3508875355336;814.9592520373958;926.4395041000199;971.7230589282092;581.9902469215907;659.9671514080496;703.8979347994564;717.3579646216659;746.31711983535;15072.623184420367;15090.17004064776;15114;15154.393824247018;15199.620009499851;12338.691532711755;12388.81416778749;12453.188195935505;12617.053720970964;12734.872651273461;9775.804483910282;9974.152197063258;10078.992100789936;10091.545880435398;10637.893621063768;3487.250241198129;3514.659306400853;3565.73257005723;3637.4725664778457;3652.415613501827;63443.99999999954;81009.59495202545;81334.59332703298;85050;85887.5705621468;9384.62461501546;9450.385724927857;9456.148946594702;9689.806203875884;9946.751331216778;22173.778262217173;22211.44471388196;22247.22134725277;22254.221102261345;22269.66595501069;6343.841403964938;7430.777076687708;7471.514351567131;7479.401647868158;7851.450398472052;4014.6788256939376;4065.57311482296;4084.9183016340103;4088.4071809587444;4088.5298190707854;3544.556930383702;3624.655657712513;3627.147620309225;3629.673329400314;3630.128769095451;570.589175793428;649.0621052578978;649.5810202419508;651.495091304243;669.2537820330297;511.27654369068097;517.1105698199092;541.3720084701727;556.8190338140121;609.3145211636909;7744.457887947902;7848.9215107848195;7942.523448592988;8017.118117007125;8028.718994835152;546.2516352596967;551.4760977071797;578.2730303195863;579.0814295948456;611.6941529235382;3634.9273014539563;3760.3983362661843;3815.6756675682323;3827.3110840048503;3848.499695039627;91636;93323;93395.59906601407;94597;94841;77763.61118194372;78063;78207;78301.60849195717;79139.20860791318;66602.66698666476;67316.66341668359;68252;68419.65790171018;68727;5892.528597712087;7408.370288525536;7440;7603.657835397531;7704.884426733492;43160.03398740856;43272.783636081615;43397.56602433935;43502.56497434985;44006.339904900815;59415.405845940986;63825.36174638194;63914.36085639084;64128;64417;10076.294659373774;10092.293539452461;10111.494425278559;10351.534180961724;10710.785784284426;10673.06161439239;10749.355038697704;10792.920707929145;10952.123830093733;11210.49552770111;10304.690859273935;10309.845352319571;10376.740581485523;10521.894781052397;10635.89364106349;10222.693319200436;10240.078392944672;10301.278910476194;10491.160707143563;10580.735481613021;10251.7437064074;10445.791084178425;10461.790764184521;10466.215033872642;10602.840957385799;8704.956475217583;8736.606852691517;9034.322425817958;9051;9232.861507077265;8733.170348816851;8739.300855931384;8800.295976321751;8807.163319484638;8815.250703690259 20:22:01 INFO - PROCESS | 1662 | __end_tp_report 20:22:01 INFO - PROCESS | 1662 | __start_cc_report 20:22:01 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,1221 20:22:01 INFO - PROCESS | 1662 | __end_cc_report 20:22:01 INFO - PROCESS | 1662 | __startTimestamp1461295321970__endTimestamp 20:22:01 INFO - PROCESS | 1662 | 20:22:01 INFO - PROCESS | 1662 | ------- Summary: start ------- 20:22:01 INFO - PROCESS | 1662 | Number of tests: 6 20:22:01 INFO - PROCESS | 1662 | 20:22:01 INFO - PROCESS | 1662 | [#0] dojo.html Cycles:165 Average:5707.06 Median:5954.11 stddev:646.52 (10.9%) stddev-sans-first:621.93 20:22:01 INFO - PROCESS | 1662 | Values: 3368.6 3417.4 3443.4 3473.5 3486.6 5591.9 5662.3 5684.7 5692.3 5696.5 5689.2 5725.4 5755.3 5850.0 5857.8 5101.4 5138.8 5149.2 5174.7 5263.1 4468.2 5238.4 5241.7 5274.1 5321.3 5051.2 5051.2 5131.2 5139.7 5166.8 5164.7 5170.6 5192.1 5202.1 5203.7 5131.3 5137.9 5191.2 5194.2 5221.1 5549.2 5570.5 5632.1 5668.4 5732.9 5089.8 6153.2 6163.4 6174.5 6195.0 6170.7 6223.4 6224.2 6224.9 6227.2 5709.9 5783.9 5805.0 5873.4 5885.4 6247.3 6259.4 6268.2 6274.7 6290.9 6139.3 6146.1 6152.7 6155.6 6156.1 6086.1 6169.9 6194.0 6275.6 6293.4 6137.8 6157.5 6163.8 6167.8 6170.8 6144.0 6188.0 6189.2 6198.0 6216.0 4831.4 4863.7 4906.3 4914.9 4920.9 4319.7 4459.1 4481.6 4487.3 4497.8 5653.2 5673.5 5677.6 5683.0 5683.0 6240.8 6241.5 6286.9 6298.4 6309.8 5556.7 5599.2 5621.9 5627.0 5629.5 4499.5 5365.7 5367.6 5368.5 5371.5 5300.3 6221.3 6283.7 6285.6 6303.9 6239.4 6250.8 6281.5 6287.3 6307.0 6182.2 6280.4 6290.1 6313.1 6316.4 6138.5 6180.0 6211.7 6223.0 6224.0 6101.9 6235.8 6259.3 6278.7 6308.0 5894.4 6102.5 6105.8 6130.0 6148.4 6028.6 6049.9 6106.5 6110.2 6121.6 4898.5 5953.3 5964.6 5980.9 5984.5 5951.3 6108.2 6108.6 6114.6 6115.2 5893.6 5954.1 6000.8 6015.9 6067.4 20:22:01 INFO - PROCESS | 1662 | 20:22:01 INFO - PROCESS | 1662 | [#1] ext.html Cycles:180 Average:61727.66 Median:37372.63 stddev:131015.98 (350.6%) stddev-sans-first:131302.84 20:22:01 INFO - PROCESS | 1662 | Values: 491.5 518.0 523.6 525.8 540.5 30286.8 31026.0 31995.4 38179.2 39026.0 41692.4 41985.4 42160.0 42167.0 42215.0 9415.1 9638.5 9650.2 9653.4 9661.7 9522.5 9654.6 9672.9 9680.7 9685.9 9687.0 9791.4 9792.9 9802.7 9839.4 9704.0 9771.5 9773.4 9775.2 9786.3 9592.7 9711.9 9713.9 9718.9 9720.2 39745.4 40186.8 40238.0 40249.8 40290.8 2555.6 3101.6 3119.6 3351.9 3910.0 27455.6 28383.3 28401.4 28501.0 28513.6 2593.9 2646.3 2767.5 2778.5 2779.0 364677.4 364820.0 366640.2 370506.0 371293.0 58663.4 61638.7 62564.0 62613.4 62635.1 65925.0 66013.3 66242.0 66304.3 66409.7 16728.7 16945.9 16952.0 16969.2 16983.2 12900.7 12987.9 13000.7 13007.6 13021.5 18606.8 19555.8 19561.0 19588.2 19605.6 6407.7 7794.4 8025.5 8029.2 8099.3 899.8 926.3 974.4 976.4 1089.7 24334.1 24532.0 27517.4 28186.0 28561.6 1055.8 1105.2 1121.7 1139.3 1141.9 14443.1 15085.8 15110.5 15277.9 15492.6 647693.0 654211.0 659707.0 660735.0 660800.0 58380.4 59055.4 60038.7 60048.0 62643.4 1310.7 1369.0 1434.9 1447.1 1469.9 27694.2 29460.6 29996.9 30167.4 30862.8 51185.5 51467.7 52097.2 52255.7 52304.7 336329.0 402914.0 403565.0 407562.0 407795.0 23423.6 25887.2 26231.3 26955.0 27301.7 42593.0 43156.4 43278.4 43395.8 43588.8 33554.8 36131.0 36773.0 36995.0 37685.4 36461.0 36522.6 36942.8 37153.4 37280.3 36067.6 36433.8 36730.1 37465.0 37474.4 31179.2 32096.7 32409.2 32743.9 32780.7 23605.0 23895.4 24086.6 24265.4 24427.8 20:22:01 INFO - PROCESS | 1662 | 20:22:01 INFO - PROCESS | 1662 | [#2] jquery.html Cycles:180 Average:33945.44 Median:398.09 stddev:95058.52 (23878.5%) stddev-sans-first:95291.81 20:22:01 INFO - PROCESS | 1662 | Values: 396.8 396.8 399.4 401.7 413.8 14832.5 19978.5 21094.9 21099.4 21149.8 15583.4 15970.0 18332.6 20431.1 20837.0 4746.5 5988.5 5992.4 5992.9 6044.4 5793.8 5878.3 5882.8 5926.3 5940.5 5659.5 5873.0 5890.0 5890.3 5900.7 5801.8 5854.6 5879.0 5937.5 5943.5 5035.1 5197.7 5215.0 5215.4 5228.1 25253.7 26499.5 26868.1 26870.9 26956.2 2151.2 2174.9 2261.9 2288.1 2669.8 9463.3 9637.1 10119.5 12353.3 15570.5 1676.5 1679.8 1747.8 1765.4 1872.7 91422.0 104366.0 111806.0 114866.0 118158.0 9577.7 11650.2 11666.2 11722.6 11722.9 15109.8 15195.8 15247.9 15260.5 15262.2 7637.7 7693.5 8185.8 8707.3 8830.4 7070.6 8681.8 8684.0 8787.0 8795.3 4575.3 4625.8 4634.4 4682.2 4699.0 1904.5 1959.1 1965.1 2003.1 2019.0 847.5 918.4 952.8 958.7 975.1 19693.7 20611.2 20629.0 20797.3 20837.1 933.2 943.4 951.4 962.5 964.7 7753.0 10751.0 10914.6 10939.0 10985.7 537871.0 546017.7 575269.0 584344.0 592390.0 135524.0 136072.6 136608.4 137157.0 138186.0 977.6 979.1 993.5 995.2 1011.8 18202.6 18263.0 18305.5 18334.6 18374.0 49191.0 50272.2 50590.7 52049.5 52173.7 70420.0 71528.6 78658.0 80098.0 81812.6 7769.9 7848.7 7936.2 8166.3 8339.3 11968.1 12237.8 12240.1 12600.4 12715.9 10840.7 10903.6 11259.5 11282.2 11283.0 10337.3 10561.5 10764.1 10912.4 11054.3 11356.3 11398.1 11436.9 11587.7 11668.0 7773.8 8127.8 8217.6 8425.2 8704.0 8005.7 9859.7 10947.3 11279.9 11299.7 20:22:01 INFO - PROCESS | 1662 | 20:22:01 INFO - PROCESS | 1662 | [#3] mootools.html Cycles:180 Average:5632.30 Median:4912.16 stddev:3146.42 (64.1%) stddev-sans-first:3139.23 20:22:01 INFO - PROCESS | 1662 | Values: 1407.8 1565.4 1580.6 1836.9 1988.5 7204.3 7863.9 8804.4 8822.0 8831.0 8451.4 8694.0 8699.4 8709.6 8715.2 2060.3 2123.8 2348.1 2356.4 2412.2 2404.3 2439.3 2440.0 2440.3 2444.8 2366.0 2392.0 2406.3 2410.1 2413.5 2113.7 2328.7 2376.1 2390.4 2397.3 2388.8 2416.5 2418.4 2418.6 2419.3 8549.2 8558.3 8580.0 8588.7 8590.1 5072.0 5124.6 5238.6 5248.3 5285.7 3368.7 3451.6 3532.9 3534.5 3572.6 1023.1 1574.3 1627.7 1654.7 1661.4 11351.3 11507.5 11512.5 11512.7 11528.2 4462.2 4550.5 4629.5 4657.9 4704.5 9149.8 9156.3 9160.5 9162.4 9196.2 5259.8 5267.8 5270.7 5271.3 5304.8 4432.7 4533.1 4841.9 4911.8 4912.5 3554.8 3634.8 3659.4 3691.5 3712.3 1192.6 1204.1 1206.6 1214.9 1216.3 861.7 905.1 914.9 954.9 968.9 4486.4 5843.8 5880.9 6006.3 6042.7 912.5 917.8 922.9 961.7 964.8 3443.7 3836.4 3928.5 4007.1 4070.0 10351.7 10569.8 10867.5 10895.0 11016.4 10830.0 10969.3 10980.4 10981.9 10994.2 8065.0 8079.5 8283.5 8355.3 8371.6 5056.9 5695.9 5759.8 5881.8 6004.2 9636.6 9779.4 10075.9 10113.5 10217.2 9973.0 10314.9 10318.4 10326.8 10330.2 6873.3 6910.7 6945.9 7003.5 7010.3 8050.3 8058.6 8139.8 8142.9 8160.3 7119.5 7304.1 7322.8 7419.0 7464.9 7101.5 7192.1 7202.5 7382.9 7411.0 6862.2 6926.6 6937.2 6953.7 6984.5 4317.5 4698.1 4731.2 4963.3 4983.9 4707.8 4964.8 4979.8 4992.4 4996.2 20:22:01 INFO - PROCESS | 1662 | 20:22:01 INFO - PROCESS | 1662 | [#4] prototype.html Cycles:180 Average:5063.07 Median:5662.78 stddev:1589.21 (28.1%) stddev-sans-first:1553.25 20:22:01 INFO - PROCESS | 1662 | Values: 318.1 320.6 327.8 332.3 337.8 5882.6 5915.2 5925.9 5934.3 5979.6 4871.8 5030.5 5855.2 5871.2 5917.3 4173.7 4229.8 4309.2 4337.7 4414.8 3836.4 3973.7 4303.4 4417.4 4428.4 4408.7 4409.9 4430.2 4439.7 4474.3 3419.7 4141.9 4404.8 4406.4 4410.6 3105.9 3803.4 3804.2 3811.3 3815.2 5623.8 5655.2 5661.9 5663.6 5666.0 4912.3 5081.8 5087.8 5106.4 5154.5 6140.4 6194.5 6213.8 6218.5 6220.2 4123.5 4249.1 4264.3 4286.8 4293.2 6485.6 6529.6 6547.7 6564.7 6566.6 4308.2 4317.5 4326.9 4347.5 4359.0 3797.3 4152.4 4400.0 4416.9 4442.6 4340.1 5000.0 5066.0 5079.8 5097.6 5883.3 6214.6 6224.4 6234.3 6252.1 1250.7 1462.8 1517.1 1520.5 1520.6 879.8 930.1 984.6 1004.7 1017.3 5135.8 5400.3 5504.8 5879.4 5881.9 5870.5 6148.5 6533.2 6540.7 6568.3 5570.7 5831.9 5849.8 5861.6 5877.6 3348.8 3379.2 3393.9 3401.9 3404.5 6499.0 6689.4 6729.8 6730.9 6741.4 5225.0 6598.3 6647.3 6663.4 6679.9 5536.3 5541.0 6422.3 6706.2 6715.6 4787.2 5827.8 6424.1 6428.4 6445.8 5545.7 6354.2 6354.4 6360.1 6381.5 6365.2 6736.5 6805.3 6831.6 6868.9 4227.3 4231.8 4255.9 4269.2 4291.2 5441.8 6316.9 6533.4 6535.6 6564.2 6094.0 6287.5 6301.9 6302.8 6324.4 6309.4 6312.5 6313.9 6323.1 6326.2 5291.1 5917.3 5950.5 6008.9 6194.0 5174.6 6229.8 6284.8 6305.3 6308.0 4897.8 5166.3 6211.9 6215.4 6215.8 20:22:01 INFO - PROCESS | 1662 | 20:22:01 INFO - PROCESS | 1662 | [#5] yui.html Cycles:180 Average:17727.66 Median:610.50 stddev:25259.02 (4137.4%) stddev-sans-first:25297.54 20:22:01 INFO - PROCESS | 1662 | Values: 707.0 708.9 723.2 758.9 767.8 12995.5 14852.6 14971.6 15266.2 15280.8 13499.1 14041.4 14045.2 14062.6 14071.4 748.2 770.5 920.7 925.6 934.3 771.4 899.1 927.8 929.9 932.8 927.9 978.3 985.2 985.8 987.9 784.6 806.4 815.0 926.4 971.7 582.0 660.0 703.9 717.4 746.3 15072.6 15090.2 15114.0 15154.4 15199.6 12338.7 12388.8 12453.2 12617.1 12734.9 9775.8 9974.2 10079.0 10091.5 10637.9 3487.3 3514.7 3565.7 3637.5 3652.4 63444.0 81009.6 81334.6 85050.0 85887.6 9384.6 9450.4 9456.1 9689.8 9946.8 22173.8 22211.4 22247.2 22254.2 22269.7 6343.8 7430.8 7471.5 7479.4 7851.5 4014.7 4065.6 4084.9 4088.4 4088.5 3544.6 3624.7 3627.1 3629.7 3630.1 570.6 649.1 649.6 651.5 669.3 511.3 517.1 541.4 556.8 609.3 7744.5 7848.9 7942.5 8017.1 8028.7 546.3 551.5 578.3 579.1 611.7 3634.9 3760.4 3815.7 3827.3 3848.5 91636.0 93323.0 93395.6 94597.0 94841.0 77763.6 78063.0 78207.0 78301.6 79139.2 66602.7 67316.7 68252.0 68419.7 68727.0 5892.5 7408.4 7440.0 7603.7 7704.9 43160.0 43272.8 43397.6 43502.6 44006.3 59415.4 63825.4 63914.4 64128.0 64417.0 10076.3 10092.3 10111.5 10351.5 10710.8 10673.1 10749.4 10792.9 10952.1 11210.5 10304.7 10309.8 10376.7 10521.9 10635.9 10222.7 10240.1 10301.3 10491.2 10580.7 10251.7 10445.8 10461.8 10466.2 10602.8 8705.0 8736.6 9034.3 9051.0 9232.9 8733.2 8739.3 8800.3 8807.2 8815.3 20:22:01 INFO - PROCESS | 1662 | -------- Summary: end -------- 20:22:01 INFO - PROCESS | 1662 | 20:22:02 INFO - TEST-INFO | 1662: exit 0 20:22:02 INFO - TEST-OK | dromaeo_css | took 1115912ms 20:22:02 INFO - TEST-START | kraken 20:22:02 INFO - operating with platform_type : mac_ 20:22:02 INFO - Initialising browser for kraken test... 20:22:02 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/tmpTo8u6J/profile http://localhost:49270/getInfo.html) 20:22:04 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 20:22:04 INFO - PROCESS | 1668 | colorDepth:24 20:22:04 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 20:22:04 INFO - PROCESS | 1668 | __metrics 20:22:04 INFO - PROCESS | 1668 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:22:04 ERROR - PROCESS | 1668 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 20:22:05 INFO - TEST-INFO | 1668: exit 0 20:22:05 INFO - Browser initialized. 20:22:05 INFO - Running cycle 1/1 for kraken test... 20:22:05 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/tmpTo8u6J/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:22:17 INFO - PROCESS | 1670 | RSS: Main: 238981120 20:22:17 INFO - PROCESS | 1670 | 20:22:19 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 20:22:20 INFO - PROCESS | 1670 | RSS: Main: 285724672 20:22:20 INFO - PROCESS | 1670 | 20:22:23 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 20:22:24 INFO - PROCESS | 1670 | RSS: Main: 300982272 20:22:24 INFO - PROCESS | 1670 | 20:22:27 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 20:22:28 INFO - PROCESS | 1670 | RSS: Main: 387985408 20:22:28 INFO - PROCESS | 1670 | 20:22:30 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 20:22:30 INFO - PROCESS | 1670 | RSS: Main: 286228480 20:22:30 INFO - PROCESS | 1670 | 20:22:33 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:22:33 INFO - PROCESS | 1670 | RSS: Main: 290037760 20:22:33 INFO - PROCESS | 1670 | 20:22:36 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 20:22:37 INFO - PROCESS | 1670 | RSS: Main: 329707520 20:22:37 INFO - PROCESS | 1670 | 20:22:39 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 20:22:40 INFO - PROCESS | 1670 | RSS: Main: 331010048 20:22:40 INFO - PROCESS | 1670 | 20:22:43 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 20:22:43 INFO - PROCESS | 1670 | RSS: Main: 329146368 20:22:43 INFO - PROCESS | 1670 | 20:22:45 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:22:45 INFO - PROCESS | 1670 | RSS: Main: 319799296 20:22:45 INFO - PROCESS | 1670 | 20:22:47 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:22:48 INFO - PROCESS | 1670 | RSS: Main: 326512640 20:22:48 INFO - PROCESS | 1670 | 20:22:50 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:22:51 INFO - PROCESS | 1670 | RSS: Main: 329842688 20:22:51 INFO - PROCESS | 1670 | 20:22:54 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:22:54 INFO - PROCESS | 1670 | RSS: Main: 331984896 20:22:54 INFO - PROCESS | 1670 | 20:22:57 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:22:58 INFO - PROCESS | 1670 | RSS: Main: 332247040 20:22:58 INFO - PROCESS | 1670 | 20:22:59 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 20:23:00 INFO - PROCESS | 1670 | RSS: Main: 396800000 20:23:00 INFO - PROCESS | 1670 | 20:23:00 INFO - PROCESS | 1670 | __start_tp_report 20:23:00 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 20:23:00 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 20:23:00 INFO - PROCESS | 1670 | |i|pagename|runs| 20:23:00 INFO - PROCESS | 1670 | |0;ai-astar;112;110;113;113;112;111;120;111;112;113 20:23:00 INFO - PROCESS | 1670 | |1;audio-beat-detection;196;108;129;196;150;171;157;120;172;118 20:23:00 INFO - PROCESS | 1670 | |2;audio-dft;186;187;189;183;188;185;184;186;187;183 20:23:00 INFO - PROCESS | 1670 | |3;audio-fft;74;77;84;89;57;56;59;76;84;55 20:23:00 INFO - PROCESS | 1670 | |4;audio-oscillator;89;98;136;79;76;74;125;81;79;128 20:23:00 INFO - PROCESS | 1670 | |5;imaging-gaussian-blur;91;84;86;84;87;83;87;84;86;83 20:23:00 INFO - PROCESS | 1670 | |6;imaging-darkroom;92;91;92;92;97;92;92;93;92;92 20:23:00 INFO - PROCESS | 1670 | |7;imaging-desaturate;86;87;87;86;85;88;85;83;87;85 20:23:00 INFO - PROCESS | 1670 | |8;json-parse-financial;60;60;60;58;61;57;62;80;59;58 20:23:00 INFO - PROCESS | 1670 | |9;json-stringify-tinderbox;64;63;71;98;70;70;66;67;69;68 20:23:00 INFO - PROCESS | 1670 | |10;stanford-crypto-aes;82;82;82;82;82;81;80;80;83;79 20:23:00 INFO - PROCESS | 1670 | |11;stanford-crypto-ccm;140;139;140;139;135;136;137;140;225;139 20:23:00 INFO - PROCESS | 1670 | |12;stanford-crypto-pbkdf2;171;175;172;167;169;172;164;169;175;171 20:23:00 INFO - PROCESS | 1670 | |13;stanford-crypto-sha256-iterative;68;66;69;67;69;67;68;71;66;68 20:23:00 INFO - PROCESS | 1670 | __end_tp_report 20:23:00 INFO - PROCESS | 1670 | __start_cc_report 20:23:00 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,1934 20:23:00 INFO - PROCESS | 1670 | __end_cc_report 20:23:00 INFO - PROCESS | 1670 | __startTimestamp1461295380176__endTimestamp 20:23:00 INFO - PROCESS | 1670 | 20:23:00 INFO - PROCESS | 1670 | ------- Summary: start ------- 20:23:00 INFO - PROCESS | 1670 | Number of tests: 14 20:23:00 INFO - PROCESS | 1670 | 20:23:00 INFO - PROCESS | 1670 | [#0] ai-astar Cycles:10 Average:112.70 Median:112.50 stddev:2.75 (2.4%) stddev-sans-first:2.91 20:23:00 INFO - PROCESS | 1670 | Values: 112.0 110.0 113.0 113.0 112.0 111.0 120.0 111.0 112.0 113.0 20:23:00 INFO - PROCESS | 1670 | 20:23:00 INFO - PROCESS | 1670 | [#1] audio-beat-detection Cycles:10 Average:151.70 Median:164.00 stddev:32.16 (19.6%) stddev-sans-first:29.84 20:23:00 INFO - PROCESS | 1670 | Values: 196.0 108.0 129.0 196.0 150.0 171.0 157.0 120.0 172.0 118.0 20:23:00 INFO - PROCESS | 1670 | 20:23:00 INFO - PROCESS | 1670 | [#2] audio-dft Cycles:10 Average:185.80 Median:186.50 stddev:2.04 (1.1%) stddev-sans-first:2.17 20:23:00 INFO - PROCESS | 1670 | Values: 186.0 187.0 189.0 183.0 188.0 185.0 184.0 186.0 187.0 183.0 20:23:00 INFO - PROCESS | 1670 | 20:23:00 INFO - PROCESS | 1670 | [#3] audio-fft Cycles:10 Average:71.10 Median:76.50 stddev:13.14 (17.2%) stddev-sans-first:13.89 20:23:00 INFO - PROCESS | 1670 | Values: 74.0 77.0 84.0 89.0 57.0 56.0 59.0 76.0 84.0 55.0 20:23:00 INFO - PROCESS | 1670 | 20:23:00 INFO - PROCESS | 1670 | [#4] audio-oscillator Cycles:10 Average:96.50 Median:79.00 stddev:24.04 (30.4%) stddev-sans-first:25.35 20:23:00 INFO - PROCESS | 1670 | Values: 89.0 98.0 136.0 79.0 76.0 74.0 125.0 81.0 79.0 128.0 20:23:00 INFO - PROCESS | 1670 | 20:23:00 INFO - PROCESS | 1670 | [#5] imaging-gaussian-blur Cycles:10 Average:85.50 Median:86.00 stddev:2.46 (2.9%) stddev-sans-first:1.62 20:23:00 INFO - PROCESS | 1670 | Values: 91.0 84.0 86.0 84.0 87.0 83.0 87.0 84.0 86.0 83.0 20:23:00 INFO - PROCESS | 1670 | 20:23:00 INFO - PROCESS | 1670 | [#6] imaging-darkroom Cycles:10 Average:92.50 Median:92.00 stddev:1.65 (1.8%) stddev-sans-first:1.74 20:23:00 INFO - PROCESS | 1670 | Values: 92.0 91.0 92.0 92.0 97.0 92.0 92.0 93.0 92.0 92.0 20:23:00 INFO - PROCESS | 1670 | 20:23:00 INFO - PROCESS | 1670 | [#7] imaging-desaturate Cycles:10 Average:85.90 Median:86.50 stddev:1.45 (1.7%) stddev-sans-first:1.54 20:23:00 INFO - PROCESS | 1670 | Values: 86.0 87.0 87.0 86.0 85.0 88.0 85.0 83.0 87.0 85.0 20:23:00 INFO - PROCESS | 1670 | 20:23:00 INFO - PROCESS | 1670 | [#8] json-parse-financial Cycles:10 Average:61.50 Median:60.00 stddev:6.67 (11.1%) stddev-sans-first:7.05 20:23:00 INFO - PROCESS | 1670 | Values: 60.0 60.0 60.0 58.0 61.0 57.0 62.0 80.0 59.0 58.0 20:23:00 INFO - PROCESS | 1670 | 20:23:00 INFO - PROCESS | 1670 | [#9] json-stringify-tinderbox Cycles:10 Average:70.60 Median:69.50 stddev:9.98 (14.4%) stddev-sans-first:10.30 20:23:00 INFO - PROCESS | 1670 | Values: 64.0 63.0 71.0 98.0 70.0 70.0 66.0 67.0 69.0 68.0 20:23:00 INFO - PROCESS | 1670 | 20:23:00 INFO - PROCESS | 1670 | [#10] stanford-crypto-aes Cycles:10 Average:81.30 Median:82.00 stddev:1.25 (1.5%) stddev-sans-first:1.30 20:23:00 INFO - PROCESS | 1670 | Values: 82.0 82.0 82.0 82.0 82.0 81.0 80.0 80.0 83.0 79.0 20:23:00 INFO - PROCESS | 1670 | 20:23:00 INFO - PROCESS | 1670 | [#11] stanford-crypto-ccm Cycles:10 Average:147.00 Median:139.50 stddev:27.46 (19.7%) stddev-sans-first:29.01 20:23:00 INFO - PROCESS | 1670 | Values: 140.0 139.0 140.0 139.0 135.0 136.0 137.0 140.0 225.0 139.0 20:23:00 INFO - PROCESS | 1670 | 20:23:00 INFO - PROCESS | 1670 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.50 Median:171.50 stddev:3.41 (2.0%) stddev-sans-first:3.61 20:23:00 INFO - PROCESS | 1670 | Values: 171.0 175.0 172.0 167.0 169.0 172.0 164.0 169.0 175.0 171.0 20:23:00 INFO - PROCESS | 1670 | 20:23:00 INFO - PROCESS | 1670 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.90 Median:68.00 stddev:1.52 (2.2%) stddev-sans-first:1.62 20:23:00 INFO - PROCESS | 1670 | Values: 68.0 66.0 69.0 67.0 69.0 67.0 68.0 71.0 66.0 68.0 20:23:00 INFO - PROCESS | 1670 | -------- Summary: end -------- 20:23:00 INFO - PROCESS | 1670 | 20:23:00 INFO - TEST-INFO | 1670: exit 0 20:23:00 INFO - TEST-OK | kraken | took 58406ms 20:23:00 INFO - SUITE-END | took 1174s 20:23:01 INFO - Completed test suite (00:19:34) 20:23:01 INFO - javascript benchmark 20:23:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3368.6462921393254, 3417.4190387634085, 3443.3629778490995, 3473.461613449915, 3486.62278400032, 5591.916121258174, 5662.292213473316, 5684.687342196177, 5692.288463942002, 5696.458836410545, 5689.2319536862515, 5725.398833122515, 5755.25181726375, 5849.9707501462435, 5857.824265272038, 5101.38783345999, 5138.794448222076, 5149.201873709581, 5174.689518628894, 5263.105272103732, 4468.151051300242, 5238.371395432553, 5241.659292146017, 5274.103402421598, 5321.281626980366, 5051.191809310511, 5051.2423136529815, 5131.230315452674, 5139.74301284938, 5166.819161329373, 5164.715940623264, 5170.638055336128, 5192.1173400522, 5202.06362854686, 5203.687778733288, 5131.332926719502, 5137.922931156036, 5191.247269145991, 5194.220866869962, 5221.138512145491, 5549.223108764776, 5570.498655121059, 5632.098864181711, 5668.43315668434, 5732.914006289909, 5089.770960306795, 6153.169322141498, 6163.383661633846, 6174.475169610589, 6195.0397688357925, 6170.691465426711, 6223.408776166257, 6224.221972253468, 6224.937750622526, 6227.190465239535, 5709.942900571024, 5783.929972955017, 5805.0, 5873.4126587340925, 5885.411458854081, 6247.2815626202655, 6259.405356491126, 6268.216472940882, 6274.686265686789, 6290.905636415458, 6139.324674285825, 6146.108814221912, 6152.723127459275, 6155.599886007485, 6156.138140660311, 6086.117512960596, 6169.938300616936, 6194.008958566607, 6275.560710750204, 6293.402126797947, 6137.754489820447, 6157.476614487729, 6163.7842675506145, 6167.84580385485, 6170.81487555365, 6144.0, 6187.969060154671, 6189.1954045974635, 6198.039303907902, 6216.0, 4831.444383895827, 4863.683860549053, 4906.288588154697, 4914.926276105862, 4920.926186107211, 4319.719218250803, 4459.1304695584695, 4481.641468682563, 4487.259602165672, 4497.8200871964755, 5653.236813030188, 5673.517750991212, 5677.630953987978, 5682.971585142048, 5683.0, 6240.843978900564, 6241.469475094577, 6286.931221692324, 6298.433141017331, 6309.810705678837, 5556.749946252428, 5599.244102046253, 5621.8594535136135, 5627.0, 5629.521490673339, 4499.505054444008, 5365.651232669864, 5367.570594352443, 5368.516833485005, 5371.543418809445, 5300.337457817773, 6221.253449586078, 6283.6858157091965, 6285.5600107992, 6303.936960630427, 6239.376062393724, 6250.812475625738, 6281.497480201481, 6287.308396076336, 6307.022411526221, 6182.196314479089, 6280.434760871543, 6290.0879372491645, 6313.084602732486, 6316.368363163647, 6138.508919286535, 6180.042093475518, 6211.65835879031, 6223.0354295084335, 6224.0, 6101.908471372844, 6235.75056997715, 6259.280182779039, 6278.654673992792, 6307.96846015767, 5894.410558944071, 6102.4507794298725, 6105.84735381619, 6130.0, 6148.415900489446, 6028.638281703112, 6049.879002420015, 6106.491003209229, 6110.194261626952, 6121.602095863755, 4898.510148985216, 5953.28560572734, 5964.642121472552, 5980.850478738066, 5984.521238300838, 5951.285845698544, 6108.214598423894, 6108.645631960441, 6114.593862501314, 6115.205023346936, 5893.616914900518, 5954.10688396744, 6000.759969601167, 6015.909761353496, 6067.393260673897], "value": 5666.994287094022, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [491.4962163782123, 517.9715115668638, 523.6282239609877, 525.7975679363444, 540.4865377891003, 30286.84856575717, 31026.0, 31995.36009279813, 38179.23641527168, 39026.0, 41692.37461438081, 41985.37021944673, 42160.0, 42167.0, 42215.0, 9415.105564971316, 9638.518074096302, 9650.227981761442, 9653.420794752303, 9661.71014869555, 9522.476263805487, 9654.613815447374, 9672.90327096727, 9680.661176858812, 9685.854712179287, 9686.951565242163, 9791.412515249072, 9792.853107203362, 9802.656907008257, 9839.360441571274, 9703.981081986396, 9771.462569558671, 9773.41359518431, 9775.169110625606, 9786.26603004774, 9592.66425675105, 9711.90288097117, 9713.90286097137, 9718.902810971871, 9720.173785228264, 39745.40381894274, 40186.79439616787, 40238.0, 40249.798751006354, 40290.791276261465, 2555.5527782637964, 3101.5967924169713, 3119.610048743907, 3351.916202094943, 3910.0, 27455.588166177324, 28383.290417739514, 28401.431971360664, 28501.0, 28513.572296415572, 2593.9092131775487, 2646.3119588907025, 2767.4741799058115, 2778.527650299454, 2779.0, 364677.353226467, 364820.0, 366640.16679916694, 370506.0, 371293.0, 58663.41336586665, 61638.69180654068, 62564.0, 62613.37386626167, 62635.06047409293, 65925.0, 66013.33986660167, 66242.0, 66304.33695662982, 66409.6679516609, 16728.66542669164, 16945.915270423568, 16952.0, 16969.23638436273, 16983.23575439108, 12900.741985160244, 12987.870121298665, 13000.674983125498, 13007.609771706864, 13021.544245951347, 18606.81393186078, 19555.8044419554, 19561.0, 19588.21647134127, 19605.607887842165, 6407.679616019181, 7794.3764498839955, 8025.518468891885, 8029.156938521488, 8099.311558517474, 899.820035992791, 926.333040211047, 974.3861367338532, 976.4385478349977, 1089.7057794395628, 24334.148304809245, 24532.018719251388, 27517.449651006867, 28186.0, 28561.57157642637, 1055.7941201465794, 1105.2263415609107, 1121.6747143328344, 1139.316410153901, 1141.937193454368, 14443.133411995314, 15085.849141508665, 15110.54668359951, 15277.923610381877, 15492.612684682974, 647693.0, 654211.0, 659707.0, 660735.0, 660800.0, 58380.41619583835, 59055.40944590499, 60038.699806500685, 60048.0, 62643.373566264665, 1310.711643438442, 1369.0211498778422, 1434.8565143485569, 1447.1027962663215, 1469.9338529766187, 27694.169174924784, 29460.55809162907, 29996.85001574978, 30167.396652067273, 30862.765489380174, 51185.48814511807, 51467.71330716612, 52097.218541722665, 52255.73872130767, 52304.73847630737, 336329.0, 402914.0, 403565.0, 407562.0, 407795.0, 23423.648645269994, 25887.22338329853, 26231.34421639398, 26955.0, 27301.726982729917, 42593.0, 43156.352654709575, 43278.350824738285, 43395.783021084695, 43588.782056090786, 33554.83222583871, 36131.041219375205, 36773.0, 36995.0, 37685.4347184787, 36461.0, 36522.634773652986, 36942.815285923396, 37153.442698360086, 37280.254394912496, 36067.63932360748, 36433.81783091067, 36730.081747956516, 37465.0, 37474.43788343122, 31179.165421770558, 32096.67903320937, 32409.18977025499, 32743.853965111106, 32780.67219327872, 23605.0, 23895.40261493407, 24086.638700419157, 24265.393365165306, 24427.755722442547], "value": 19305.913609900224, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.7559950630362, 396.7956502401266, 399.39092883352885, 401.66661670813227, 413.76208680009, 14832.48086316979, 19978.50053748655, 21094.89452552737, 21099.367018989436, 21149.788502114974, 15583.376664933417, 15970.0, 18332.633347333045, 20431.080601372938, 20837.0, 4746.5490778376, 5988.520918326523, 5992.400759924017, 5992.910106348409, 6044.395560443964, 5793.768249270024, 5878.323992740826, 5882.794102206402, 5926.288845338544, 5940.52475801937, 5659.490645841874, 5872.9706351468185, 5890.0, 5890.2931648202275, 5900.675462849542, 5801.767929282844, 5854.6194497357965, 5879.0, 5937.524998000171, 5943.494802941756, 5035.1188542005, 5197.740112994373, 5214.973925130388, 5215.3741551013745, 5228.058949389108, 25253.737313134454, 26499.47001059968, 26868.05961791328, 26870.86564567184, 26956.191314260603, 2151.236311109549, 2174.913003479859, 2261.8529795563236, 2288.084766093559, 2669.7864170866283, 9463.290253231035, 9637.084476974676, 10119.494025298705, 12353.258804471761, 15570.455034073866, 1676.48029110976, 1679.7648329233916, 1747.842694157532, 1765.4262364731514, 1872.7097299918535, 91421.99999999933, 104366.0, 111806.0, 114866.0, 118158.0, 9577.712668619952, 11650.184487085991, 11666.241694289844, 11722.58970936013, 11722.94138529302, 15109.848901511064, 15195.772063419057, 15247.923760381127, 15260.542183734506, 15262.236888155549, 7637.656305466267, 7693.4999225051315, 8185.754427367188, 8707.303415726854, 8830.42602230853, 7070.575765454089, 8681.782955426039, 8684.0, 8786.956065219632, 8795.340349473816, 4575.336576196433, 4625.814967401334, 4634.420697412824, 4682.227432473672, 4698.976505117453, 1904.457549171871, 1959.1086055844557, 1965.1058768260407, 2003.1086166655698, 2019.0, 847.4788005376772, 918.4443411735804, 952.7618095476131, 958.6596758150896, 975.1272611013104, 19693.719908206207, 20611.175552977715, 20629.0, 20797.272095476586, 20837.062332195088, 933.1881263300836, 943.4197968249478, 951.4481600671594, 962.5379817687549, 964.6816550538306, 7753.0, 10751.0, 10914.56341746337, 10939.0, 10985.725356865982, 537871.0, 546017.668144847, 575269.0, 584344.0, 592390.0, 135524.0, 136072.639273606, 136608.37803676407, 137157.0, 138186.0, 977.6236149082512, 979.0552117206929, 993.4635296939716, 995.2436148527172, 1011.7521207304111, 18202.635947280716, 18262.99553524569, 18305.450836474927, 18334.633307334047, 18374.0, 49191.016179676924, 50272.245916312015, 50590.711603745636, 52049.479505204465, 52173.7391313041, 70420.0, 71528.64235678788, 78658.0, 80098.0, 81812.59093704494, 7769.92230077692, 7848.725294614661, 7936.166702496154, 8166.346692264486, 8339.291160251447, 11968.102392320438, 12237.816432753694, 12240.08199385032, 12600.432980515716, 12715.93642031784, 10840.67477975662, 10903.563857445613, 11259.549618015188, 11282.153838461983, 11282.984531392214, 10337.328073675188, 10561.471926403803, 10764.138868890626, 10912.399818010086, 11054.336739795637, 11356.318620882774, 11398.08815294758, 11436.94281528587, 11587.710307242387, 11668.0, 7773.766786996399, 8127.83744325122, 8217.630206640595, 8425.157484251526, 8704.042555319036, 8005.719799806979, 9859.654912078044, 10947.34315941046, 11279.943600281946, 11299.717507062389], "value": 9479.785588796916, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1407.8310602727674, 1565.3660267591622, 1580.5890468478196, 1836.9448916532501, 1988.5227545389112, 7204.315590018947, 7863.921360786398, 8804.383693141483, 8821.955890220539, 8830.955845220766, 8451.408401411903, 8694.000189978155, 8699.434101861661, 8709.564521773902, 8715.21563059326, 2060.268604645352, 2123.8300935925167, 2348.1311914591583, 2356.39911822485, 2412.1798588479915, 2404.290734233405, 2439.2682195341417, 2440.0, 2440.2801173653725, 2444.828861979662, 2365.9881700591473, 2392.0073169634666, 2406.302172370011, 2410.0600765701392, 2413.51729654069, 2113.651247544153, 2328.685627440291, 2376.120835290936, 2390.426297688542, 2397.2808157552663, 2388.773066558674, 2416.4683769570843, 2418.4195793009726, 2418.5767490689236, 2419.322589674894, 8549.187827156411, 8558.272546833527, 8579.957100214522, 8588.69939552119, 8590.055093939662, 5072.036313100499, 5124.641275110706, 5238.633295669305, 5248.317718696583, 5285.709285989269, 3368.6799754023327, 3451.5685539307588, 3532.9293414131575, 3534.540509733718, 3572.6427357264324, 1023.1098943918838, 1574.2679653960963, 1627.7476991066403, 1654.7186978213733, 1661.4268077513238, 11351.318920864775, 11507.539698412054, 11512.53949841997, 11512.654620361403, 11528.193026488148, 4462.174497717932, 4550.522195169526, 4629.51390104041, 4657.883552911137, 4704.458987216514, 9149.771255718528, 9156.267498600097, 9160.541972901328, 9162.358634895627, 9196.218321442619, 5259.8422047338645, 5267.762950667229, 5270.683758974474, 5271.314729085195, 5304.761285742151, 4432.667549933768, 4533.093381323749, 4841.878953026132, 4911.803527858917, 4912.508749125059, 3554.831552752819, 3634.8000859952426, 3659.3779057560278, 3691.464737613031, 3712.276106159273, 1192.5885569478517, 1204.0608325506118, 1206.5596057438972, 1214.908881833866, 1216.2641601830942, 861.6510313323114, 905.103947092387, 914.8764916736155, 954.9427034377959, 968.8595153702814, 4486.371907932892, 5843.795467158629, 5880.911786323209, 6006.309274433409, 6042.667653279112, 912.5163663258484, 917.7797328641078, 922.9123233292827, 961.7162936933621, 964.8070385922703, 3443.6900678939014, 3836.4245363195187, 3928.46965659638, 4007.1384652299894, 4069.959300407017, 10351.689449316533, 10569.841452378221, 10867.45662716861, 10895.0, 11016.394098324508, 10830.0, 10969.341839489656, 10980.396078215776, 10981.945090274497, 10994.230403871812, 8065.032196136502, 8079.515229086273, 8283.544405057659, 8355.331573474108, 8371.623276952552, 5056.924146137737, 5695.8860822784145, 5759.798407055733, 5881.7647294107755, 6004.159417681615, 9636.566354514203, 9779.41323520591, 10075.899241007495, 10113.5448904798, 10217.23370747182, 9973.0, 10314.948425257826, 10318.380897146195, 10326.793464130826, 10330.225233107698, 6873.347032031949, 6910.689018994258, 6945.930540694529, 7003.474739394667, 7010.263922288086, 8050.27547520726, 8058.597070146587, 8139.75580732579, 8142.8778568322705, 8160.347172226089, 7119.501634885715, 7304.050473438419, 7322.780316590511, 7419.035525381666, 7464.888026679496, 7101.502894797314, 7192.1369435665965, 7202.53183543068, 7382.852342953003, 7411.0, 6862.176538815374, 6926.58440493572, 6937.236903940663, 6953.721851126101, 6984.476164287597, 4317.460317460317, 4698.130845793539, 4731.219348807408, 4963.329950456642, 4983.925241121458, 4707.835225767081, 4964.826231081895, 4979.800807967786, 4992.425871024879, 4996.225585034325], "value": 4569.62495038752, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.1251558214909, 320.5736370627066, 327.8475508888366, 332.31377206069465, 337.80913783712185, 5882.647041177533, 5915.1718759373725, 5925.940740592593, 5934.347221805598, 5979.641221526701, 4871.806407430176, 5030.496950304968, 5855.243426971912, 5871.207387002753, 5917.260342457193, 4173.749575025508, 4229.809658565357, 4309.245881970658, 4337.696361254698, 4414.779261036935, 3836.405357169643, 3973.721839471238, 4303.440552728141, 4417.447819022622, 4428.424304840366, 4408.71343362682, 4409.86770396887, 4430.224710675635, 4439.711418757786, 4474.261746811789, 3419.675130862564, 4141.855035073773, 4404.801783919699, 4406.356186288233, 4410.58099480552, 3105.8602362893726, 3803.3724435468116, 3804.1821008483307, 3811.294910441577, 3815.1797363566966, 5623.831285061455, 5655.1517272409055, 5661.942551022443, 5663.621279062803, 5665.951798917214, 4912.287718280829, 5081.822136225252, 5087.79648814047, 5106.361704786902, 5154.4587818278915, 6140.3552626974415, 6194.473469755077, 6213.751449941997, 6218.5025197984505, 6220.160278362454, 4123.525794533607, 4249.065205654751, 4264.272969447347, 4286.76422796746, 4293.248681480728, 6485.578437401554, 6529.608223506605, 6547.705353259114, 6564.704588293491, 6566.63883486408, 4308.202982448257, 4317.503487098961, 4326.891827704332, 4347.478302603708, 4358.978205108954, 3797.2974999625158, 4152.356384760367, 4399.978000109979, 4416.933745993812, 4442.622377097983, 4340.131973605228, 5000.0, 5065.974670126626, 5079.847604571869, 5097.617678674114, 5883.264591926009, 6214.5960512566535, 6224.408681175281, 6234.314225435199, 6252.124702541648, 1250.7060840702427, 1462.795208670787, 1517.0745845034521, 1520.5210358736963, 1520.612243877804, 879.8372301124313, 930.06993006993, 984.5864736810556, 1004.6989149051996, 1017.2523195451338, 5135.820246291362, 5400.324959380077, 5504.843982763585, 5879.412058794086, 5881.911771323434, 5870.530357571384, 6148.538859585549, 6533.216014078341, 6540.70566824494, 6568.277489476154, 5570.693611851387, 5831.941680583224, 5849.795257165979, 5861.648301101947, 5877.617954832922, 3348.8325583720716, 3379.172102834821, 3393.8642454301553, 3401.914952126167, 3404.5403870477166, 6498.967505162444, 6689.397954184049, 6729.798106056826, 6730.899036514456, 6741.426978706767, 5225.0, 6598.3401659833635, 6647.302033286532, 6663.433608143362, 6679.933200668028, 5536.335639723259, 5540.972295138498, 6422.325655806073, 6706.161729783777, 6715.597064176165, 4787.162246606899, 5827.79602713903, 6424.10062591247, 6428.389303016206, 6445.838854028687, 5545.667259964415, 6354.23749150105, 6354.364563543609, 6360.141380913517, 6381.457576106083, 6365.2311244608745, 6736.461083113437, 6805.251422343439, 6831.555948863308, 6868.9313106870295, 4227.32362821947, 4231.767252801125, 4255.87232383029, 4269.188854117708, 4291.163223171577, 5441.782328706808, 6316.936830631635, 6533.379328963741, 6535.57518761279, 6564.208128066442, 6094.049904030668, 6287.528435367457, 6301.936980630318, 6302.84242893913, 6324.430801227728, 6309.432151106423, 6312.5265605078885, 6313.916407585394, 6323.114763933145, 6326.177596912372, 5291.142782897193, 5917.260342457193, 5950.464458198783, 6008.918394688998, 6193.969030154821, 5174.586033117266, 6229.844253893508, 6284.780032699016, 6305.306416294296, 6308.0, 4897.804087836446, 5166.276721259102, 6211.875762484635, 6215.440610345091, 6215.782447614311], "value": 4550.536984497051, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [706.9858602827943, 708.9078419805426, 723.226148021617, 758.893754874318, 767.7504810936447, 12995.48018079278, 14852.554423367303, 14971.625709357271, 15266.236688165603, 15280.770788438182, 13499.12255703381, 14041.368138433769, 14045.227512486808, 14062.648433789134, 14071.366788494517, 748.2143749063462, 770.4760762681402, 920.6731610278355, 925.6482536636069, 934.3272843552631, 771.4368510986955, 899.1278459893892, 927.7773334399762, 929.8512238041915, 932.8227636748996, 927.8608208768705, 978.280963491833, 985.2315194148575, 985.7929834734714, 987.9061489158519, 784.5684873319651, 806.3508875355336, 814.9592520373958, 926.4395041000199, 971.7230589282092, 581.9902469215907, 659.9671514080496, 703.8979347994564, 717.3579646216659, 746.31711983535, 15072.623184420367, 15090.17004064776, 15114.0, 15154.393824247018, 15199.620009499851, 12338.691532711755, 12388.81416778749, 12453.188195935505, 12617.053720970964, 12734.872651273461, 9775.804483910282, 9974.152197063258, 10078.992100789936, 10091.545880435398, 10637.893621063768, 3487.250241198129, 3514.659306400853, 3565.73257005723, 3637.4725664778457, 3652.415613501827, 63443.99999999954, 81009.59495202545, 81334.59332703298, 85050.0, 85887.5705621468, 9384.62461501546, 9450.385724927857, 9456.148946594702, 9689.806203875884, 9946.751331216778, 22173.778262217173, 22211.44471388196, 22247.22134725277, 22254.221102261345, 22269.66595501069, 6343.841403964938, 7430.777076687708, 7471.514351567131, 7479.401647868158, 7851.450398472052, 4014.6788256939376, 4065.57311482296, 4084.9183016340103, 4088.4071809587444, 4088.5298190707854, 3544.556930383702, 3624.655657712513, 3627.147620309225, 3629.673329400314, 3630.128769095451, 570.589175793428, 649.0621052578978, 649.5810202419508, 651.495091304243, 669.2537820330297, 511.27654369068097, 517.1105698199092, 541.3720084701727, 556.8190338140121, 609.3145211636909, 7744.457887947902, 7848.9215107848195, 7942.523448592988, 8017.118117007125, 8028.718994835152, 546.2516352596967, 551.4760977071797, 578.2730303195863, 579.0814295948456, 611.6941529235382, 3634.9273014539563, 3760.3983362661843, 3815.6756675682323, 3827.3110840048503, 3848.499695039627, 91636.0, 93323.0, 93395.59906601407, 94597.0, 94841.0, 77763.61118194372, 78063.0, 78207.0, 78301.60849195717, 79139.20860791318, 66602.66698666476, 67316.66341668359, 68252.0, 68419.65790171018, 68727.0, 5892.528597712087, 7408.370288525536, 7440.0, 7603.657835397531, 7704.884426733492, 43160.03398740856, 43272.783636081615, 43397.56602433935, 43502.56497434985, 44006.339904900815, 59415.405845940986, 63825.36174638194, 63914.36085639084, 64128.0, 64417.0, 10076.294659373774, 10092.293539452461, 10111.494425278559, 10351.534180961724, 10710.785784284426, 10673.06161439239, 10749.355038697704, 10792.920707929145, 10952.123830093733, 11210.49552770111, 10304.690859273935, 10309.845352319571, 10376.740581485523, 10521.894781052397, 10635.89364106349, 10222.693319200436, 10240.078392944672, 10301.278910476194, 10491.160707143563, 10580.735481613021, 10251.7437064074, 10445.791084178425, 10461.790764184521, 10466.215033872642, 10602.840957385799, 8704.956475217583, 8736.606852691517, 9034.322425817958, 9051.0, 9232.861507077265, 8733.170348816851, 8739.300855931384, 8800.295976321751, 8807.163319484638, 8815.250703690259], "value": 6675.802362004954, "unit": "score"}], "name": "dromaeo_css", "value": 7239.71512020113, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 110.0, 113.0, 113.0, 112.0, 111.0, 120.0, 111.0, 112.0, 113.0], "value": 112.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 108.0, 129.0, 196.0, 150.0, 171.0, 157.0, 120.0, 172.0, 118.0], "value": 151.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 187.0, 189.0, 183.0, 188.0, 185.0, 184.0, 186.0, 187.0, 183.0], "value": 185.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 77.0, 84.0, 89.0, 57.0, 56.0, 59.0, 76.0, 84.0, 55.0], "value": 71.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 98.0, 136.0, 79.0, 76.0, 74.0, 125.0, 81.0, 79.0, 128.0], "value": 96.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 84.0, 86.0, 84.0, 87.0, 83.0, 87.0, 84.0, 86.0, 83.0], "value": 85.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 92.0, 92.0, 97.0, 92.0, 92.0, 93.0, 92.0, 92.0], "value": 92.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 87.0, 86.0, 85.0, 88.0, 85.0, 83.0, 87.0, 85.0], "value": 85.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 60.0, 58.0, 61.0, 57.0, 62.0, 80.0, 59.0, 58.0], "value": 61.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 71.0, 98.0, 70.0, 70.0, 66.0, 67.0, 69.0, 68.0], "value": 70.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 82.0, 82.0, 82.0, 81.0, 80.0, 80.0, 83.0, 79.0], "value": 81.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 139.0, 140.0, 139.0, 135.0, 136.0, 137.0, 140.0, 225.0, 139.0], "value": 147.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 175.0, 172.0, 167.0, 169.0, 172.0, 164.0, 169.0, 175.0, 171.0], "value": 170.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 69.0, 67.0, 69.0, 67.0, 68.0, 71.0, 66.0, 68.0], "value": 67.9, "unit": "score"}], "name": "kraken", "value": 1480.5, "alertThreshold": 2.0}]} 20:23:01 INFO - Return code: 0 20:23:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 20:23:01 ERROR - # TBPL SUCCESS # 20:23:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:23:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:23:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:23:01 INFO - 2016-04-21 20:23:01.494 screenresolution[1677:9862] starting screenresolution argv=screenresolution get 20:23:01 INFO - 2016-04-21 20:23:01.496 screenresolution[1677:9862] Display 0: 1600x1200x32@60 20:23:01 INFO - 2016-04-21 20:23:01.502 screenresolution[1678:9864] starting screenresolution argv=screenresolution list 20:23:01 INFO - Available Modes on Display 0 20:23:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:23:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:23:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:23:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:23:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:23:01 INFO - Intel Iris: 20:23:01 INFO - Chipset Model: Intel Iris 20:23:01 INFO - Type: GPU 20:23:01 INFO - Bus: Built-In 20:23:01 INFO - VRAM (Dynamic, Max): 1536 MB 20:23:01 INFO - Vendor: Intel (0x8086) 20:23:01 INFO - Device ID: 0x0a2e 20:23:01 INFO - Revision ID: 0x0009 20:23:01 INFO - Displays: 20:23:01 INFO - Display: 20:23:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:23:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:23:01 INFO - Display Serial Number: 1600x1200 60 20:23:01 INFO - Main Display: Yes 20:23:01 INFO - Mirror: Off 20:23:01 INFO - Online: Yes 20:23:01 INFO - Rotation: Supported 20:23:01 INFO - Return code: 0 20:23:01 INFO - Running post-action listener: _resource_record_post_action 20:23:01 INFO - Running post-run listener: _resource_record_post_run 20:23:02 INFO - Total resource usage - Wall time: 1209s; CPU: 53.0%; Read bytes: 38049280; Write bytes: 693780992; Read time: 460; Write time: 1896 20:23:02 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 386546176; Write bytes: 380519936; Read time: 28447; Write time: 711 20:23:02 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 36038144; Write bytes: 295578624; Read time: 434; Write time: 1146 20:23:02 INFO - Running post-run listener: _upload_blobber_files 20:23:02 INFO - Blob upload gear active. 20:23:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:23:02 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 20:23:02 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:23:02 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:23:03 INFO - (blobuploader) - INFO - Open directory for files ... 20:23:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:23:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:23:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:23:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:23:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:23:04 INFO - (blobuploader) - INFO - Done attempting. 20:23:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:23:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:23:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:23:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:23:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:23:05 INFO - (blobuploader) - INFO - Done attempting. 20:23:05 INFO - (blobuploader) - INFO - Iteration through files over. 20:23:05 INFO - Return code: 0 20:23:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:23:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:23:05 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f1ac0958bec8ec4dc316a89d9a7f7626d54465009a6ff09afc3817d3e76d98b186ae80fbf1e0dabc7c146ecd18c114eccc7f93f80d1db772154ea9e0d4842cf2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/68f8ffd9fd2058fbf830c8c26f5b4cf008eeeeb7aa09e0f3dfbeb6bf79bc6d1785e819ef25c86e53f584141c1756978a63438fbebfb6ee836c85d346e9dfdb08"} 20:23:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:23:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:23:05 INFO - Contents: 20:23:05 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f1ac0958bec8ec4dc316a89d9a7f7626d54465009a6ff09afc3817d3e76d98b186ae80fbf1e0dabc7c146ecd18c114eccc7f93f80d1db772154ea9e0d4842cf2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/68f8ffd9fd2058fbf830c8c26f5b4cf008eeeeb7aa09e0f3dfbeb6bf79bc6d1785e819ef25c86e53f584141c1756978a63438fbebfb6ee836c85d346e9dfdb08"} 20:23:05 INFO - Running post-run listener: copy_logs_to_upload_dir 20:23:05 INFO - Copying logs to upload dir... 20:23:05 INFO - mkdir: /builds/slave/test/build/upload/logs 20:23:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.115797 ========= master_lag: 3.29 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-04-21 20:23:09.029386) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-21 20:23:09.033071) ========= 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.ufPMW7YOtH/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.UmL14AwIJn/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f1ac0958bec8ec4dc316a89d9a7f7626d54465009a6ff09afc3817d3e76d98b186ae80fbf1e0dabc7c146ecd18c114eccc7f93f80d1db772154ea9e0d4842cf2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/68f8ffd9fd2058fbf830c8c26f5b4cf008eeeeb7aa09e0f3dfbeb6bf79bc6d1785e819ef25c86e53f584141c1756978a63438fbebfb6ee836c85d346e9dfdb08"} build_url:https://queue.taskcluster.net/v1/task/diEbyJz1TDWrCMVoKM_gaw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011013 build_url: 'https://queue.taskcluster.net/v1/task/diEbyJz1TDWrCMVoKM_gaw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f1ac0958bec8ec4dc316a89d9a7f7626d54465009a6ff09afc3817d3e76d98b186ae80fbf1e0dabc7c146ecd18c114eccc7f93f80d1db772154ea9e0d4842cf2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/68f8ffd9fd2058fbf830c8c26f5b4cf008eeeeb7aa09e0f3dfbeb6bf79bc6d1785e819ef25c86e53f584141c1756978a63438fbebfb6ee836c85d346e9dfdb08"}' ========= master_lag: 0.30 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-21 20:23:09.346124) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 20:23:09.346463) ========= 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.ufPMW7YOtH/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.UmL14AwIJn/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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.005105 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 20:23:09.403325) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-21 20:23:09.403641) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-21 20:23:09.404158) ========= ========= Total master_lag: 3.70 =========