builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0043 starttime: 1462547356.16 results: success (0) buildid: 20160506053348 builduid: 341c06341a424d86b2ff77b5ca842180 revision: 9d202f109312b37f42224c55724ea845a4b86c23 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 08:09:16.156797) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 08:09:16.157201) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 08:09:16.157486) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 08:09:16.198830) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 08:09:16.199107) ========= 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.73Bqw6ErkE/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.UQrJ1X9zHF/Listeners TMPDIR=/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-06 08:09:16-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.60M=0.001s 2016-05-06 08:09:16 (9.60 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.118273 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 08:09:16.334398) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 08:09:16.334703) ========= 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.73Bqw6ErkE/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.UQrJ1X9zHF/Listeners TMPDIR=/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/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.034158 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 08:09:16.383638) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 08:09:16.383985) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 9d202f109312b37f42224c55724ea845a4b86c23 --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 9d202f109312b37f42224c55724ea845a4b86c23 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.73Bqw6ErkE/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.UQrJ1X9zHF/Listeners TMPDIR=/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-06 08:09:16,449 truncating revision to first 12 chars 2016-05-06 08:09:16,450 Setting DEBUG logging. 2016-05-06 08:09:16,450 attempt 1/10 2016-05-06 08:09:16,450 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/9d202f109312?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-06 08:09:17,064 unpacking tar archive at: fx-team-9d202f109312/testing/mozharness/ program finished with exit code 0 elapsedTime=0.855325 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 08:09:17.255300) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 08:09:17.255614) ========= script_repo_revision: 9d202f109312b37f42224c55724ea845a4b86c23 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 08:09:17.256026) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 08:09:17.256302) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 08:09:17.269432) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-05-06 08:09:17.269696) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.73Bqw6ErkE/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.UQrJ1X9zHF/Listeners TMPDIR=/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/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 08:09:17 INFO - MultiFileLogger online at 20160506 08:09:17 in /builds/slave/test 08:09:17 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 08:09:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:09:17 INFO - {'append_to_log': False, 08:09:17 INFO - 'base_work_dir': '/builds/slave/test', 08:09:17 INFO - 'blob_upload_branch': 'Fx-Team', 08:09:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:09:17 INFO - 'branch': 'Fx-Team', 08:09:17 INFO - 'buildbot_json_path': 'buildprops.json', 08:09:17 INFO - 'config_files': ('talos/mac_config.py',), 08:09:17 INFO - 'default_actions': ('clobber', 08:09:17 INFO - 'read-buildbot-config', 08:09:17 INFO - 'download-and-extract', 08:09:17 INFO - 'populate-webroot', 08:09:17 INFO - 'create-virtualenv', 08:09:17 INFO - 'install', 08:09:17 INFO - 'run-tests'), 08:09:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:09:17 INFO - 'download_minidump_stackwalk': True, 08:09:17 INFO - 'download_symbols': 'ondemand', 08:09:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:09:17 INFO - 'tooltool.py': '/tools/tooltool.py', 08:09:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:09:17 INFO - '/tools/misc-python/virtualenv.py')}, 08:09:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:09:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:09:17 INFO - 'installer_path': 'installer.exe', 08:09:17 INFO - 'log_level': 'info', 08:09:17 INFO - 'log_name': 'talos', 08:09:17 INFO - 'log_to_console': True, 08:09:17 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:09:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:09:17 INFO - 'opt_config_files': (), 08:09:17 INFO - 'pip_index': False, 08:09:17 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:09:17 INFO - 'cmd': ('bash', 08:09:17 INFO - '-c', 08:09:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:09:17 INFO - 'enabled': True, 08:09:17 INFO - 'halt_on_failure': False, 08:09:17 INFO - 'name': 'check_screen_resolution'},), 08:09:17 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:09:17 INFO - 'cmd': ('bash', 08:09:17 INFO - '-c', 08:09:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:09:17 INFO - 'enabled': True, 08:09:17 INFO - 'halt_on_failure': False, 08:09:17 INFO - 'name': 'check_screen_resolution'},), 08:09:17 INFO - 'run_cmd_checks_enabled': True, 08:09:17 INFO - 'sps_profile': False, 08:09:17 INFO - 'sps_profile_interval': 0, 08:09:17 INFO - 'suite': 'dromaeojs-e10s', 08:09:17 INFO - 'system_bits': '32', 08:09:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:09:17 INFO - 'title': 't-yosemite-r7-0043', 08:09:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:09:17 INFO - 'use_talos_json': True, 08:09:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:09:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:09:17 INFO - 'work_dir': 'build'} 08:09:17 INFO - ##### 08:09:17 INFO - ##### Running clobber step. 08:09:17 INFO - ##### 08:09:17 INFO - Running pre-action listener: _resource_record_pre_action 08:09:17 INFO - Running main action method: clobber 08:09:17 INFO - rmtree: /builds/slave/test/build 08:09:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:09:19 INFO - Running post-action listener: _resource_record_post_action 08:09:19 INFO - ##### 08:09:19 INFO - ##### Finished clobber step (success) 08:09:19 INFO - ##### 08:09:19 INFO - ##### 08:09:19 INFO - ##### Running read-buildbot-config step. 08:09:19 INFO - ##### 08:09:19 INFO - Running pre-action listener: _resource_record_pre_action 08:09:19 INFO - Running main action method: read_buildbot_config 08:09:19 INFO - Using buildbot properties: 08:09:19 INFO - { 08:09:19 INFO - "project": "", 08:09:19 INFO - "product": "firefox", 08:09:19 INFO - "script_repo_revision": "production", 08:09:19 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 08:09:19 INFO - "repository": "", 08:09:19 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 08:09:19 INFO - "buildid": "20160506053348", 08:09:19 INFO - "pgo_build": "False", 08:09:19 INFO - "basedir": "/builds/slave/test", 08:09:19 INFO - "buildnumber": 2, 08:09:19 INFO - "slavename": "t-yosemite-r7-0043", 08:09:19 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 08:09:19 INFO - "platform": "yosemite_r7", 08:09:19 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 08:09:19 INFO - "branch": "fx-team", 08:09:19 INFO - "repo_path": "integration/fx-team", 08:09:19 INFO - "revision": "9d202f109312b37f42224c55724ea845a4b86c23", 08:09:19 INFO - "stage_platform": "macosx64", 08:09:19 INFO - "builduid": "341c06341a424d86b2ff77b5ca842180", 08:09:19 INFO - "slavebuilddir": "test" 08:09:19 INFO - } 08:09:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/fYTw48n9Rsu4LSGDbhEtbg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 08:09:19 INFO - Running post-action listener: _resource_record_post_action 08:09:19 INFO - ##### 08:09:19 INFO - ##### Finished read-buildbot-config step (success) 08:09:19 INFO - ##### 08:09:19 INFO - ##### 08:09:19 INFO - ##### Running download-and-extract step. 08:09:19 INFO - ##### 08:09:19 INFO - Running pre-action listener: _resource_record_pre_action 08:09:19 INFO - Running main action method: download_and_extract 08:09:19 INFO - mkdir: /builds/slave/test/build/tests 08:09:19 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')]} 08:09:19 INFO - https://queue.taskcluster.net/v1/task/fYTw48n9Rsu4LSGDbhEtbg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 08:09:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fYTw48n9Rsu4LSGDbhEtbg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 08:09:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fYTw48n9Rsu4LSGDbhEtbg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 08:09:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fYTw48n9Rsu4LSGDbhEtbg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 08:09:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fYTw48n9Rsu4LSGDbhEtbg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 08:09:21 INFO - Downloaded 1199 bytes. 08:09:21 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 08:09:21 INFO - Using the following test package requirements: 08:09:21 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 08:09:21 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:09:21 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 08:09:21 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:09:21 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 08:09:21 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:09:21 INFO - u'jsshell-mac.zip'], 08:09:21 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:09:21 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 08:09:21 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 08:09:21 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:09:21 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 08:09:21 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:09:21 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 08:09:21 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:09:21 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 08:09:21 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:09:21 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 08:09:21 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:09: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')]} 08:09:21 INFO - https://queue.taskcluster.net/v1/task/fYTw48n9Rsu4LSGDbhEtbg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:09:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fYTw48n9Rsu4LSGDbhEtbg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:09:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fYTw48n9Rsu4LSGDbhEtbg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:09:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fYTw48n9Rsu4LSGDbhEtbg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:09:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fYTw48n9Rsu4LSGDbhEtbg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:09:24 INFO - Downloaded 18144950 bytes. 08:09:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:09:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 08:09:27 INFO - Return code: 0 08:09:27 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:09:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:09:27 INFO - https://queue.taskcluster.net/v1/task/fYTw48n9Rsu4LSGDbhEtbg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:09:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fYTw48n9Rsu4LSGDbhEtbg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:09:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fYTw48n9Rsu4LSGDbhEtbg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:09:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fYTw48n9Rsu4LSGDbhEtbg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:09:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fYTw48n9Rsu4LSGDbhEtbg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:09:28 INFO - Downloaded 18144950 bytes. 08:09:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:09:28 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 08:09:32 INFO - Return code: 0 08:09:32 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')]} 08:09:32 INFO - https://queue.taskcluster.net/v1/task/fYTw48n9Rsu4LSGDbhEtbg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 08:09:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fYTw48n9Rsu4LSGDbhEtbg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 08:09:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fYTw48n9Rsu4LSGDbhEtbg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 08:09:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fYTw48n9Rsu4LSGDbhEtbg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 08:09:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fYTw48n9Rsu4LSGDbhEtbg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:09:33 INFO - Downloaded 10840152 bytes. 08:09:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:09:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 08:09:33 INFO - Return code: 0 08:09: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')]} 08:09:33 INFO - https://queue.taskcluster.net/v1/task/fYTw48n9Rsu4LSGDbhEtbg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 08:09:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fYTw48n9Rsu4LSGDbhEtbg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:09:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fYTw48n9Rsu4LSGDbhEtbg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:09:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fYTw48n9Rsu4LSGDbhEtbg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 08:09:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fYTw48n9Rsu4LSGDbhEtbg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 08:09:36 INFO - Downloaded 110451611 bytes. 08:09:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fYTw48n9Rsu4LSGDbhEtbg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:09:36 INFO - mkdir: /builds/slave/test/properties 08:09:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:09:36 INFO - Writing to file /builds/slave/test/properties/build_url 08:09:36 INFO - Contents: 08:09:36 INFO - build_url:https://queue.taskcluster.net/v1/task/fYTw48n9Rsu4LSGDbhEtbg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:09:37 INFO - Running post-action listener: _resource_record_post_action 08:09:37 INFO - Running post-action listener: set_extra_try_arguments 08:09:37 INFO - ##### 08:09:37 INFO - ##### Finished download-and-extract step (success) 08:09:37 INFO - ##### 08:09:37 INFO - ##### 08:09:37 INFO - ##### Running populate-webroot step. 08:09:37 INFO - ##### 08:09:37 INFO - Running pre-action listener: _resource_record_pre_action 08:09:37 INFO - Running main action method: populate_webroot 08:09:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:09:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:09:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:09:37 INFO - '--tppagecycles', 08:09:37 INFO - '7'], 08:09:37 INFO - 'tests': ['tsvgm']}}, 08:09:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:09:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:09:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:09:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:09:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:09:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:09:37 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:09:37 INFO - 'talos_options': ['--disable-e10s'], 08:09:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:09:37 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:09:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:09:37 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:09:37 INFO - 'talos_options': ['--disable-e10s'], 08:09:37 INFO - 'tests': ['damp', 'tps']}, 08:09:37 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:09:37 INFO - 'tests': ['damp', 'tps']}, 08:09:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:09:37 INFO - 'tests': ['dromaeo_dom']}, 08:09:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:09:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:09:37 INFO - 'tests': ['a11yr', 08:09:37 INFO - 'ts_paint', 08:09:37 INFO - 'tpaint', 08:09:37 INFO - 'sessionrestore', 08:09:37 INFO - 'sessionrestore_no_auto_restore', 08:09:37 INFO - 'tabpaint']}, 08:09:37 INFO - 'other-e10s': {'tests': ['a11yr', 08:09:37 INFO - 'ts_paint', 08:09:37 INFO - 'tpaint', 08:09:37 INFO - 'sessionrestore', 08:09:37 INFO - 'sessionrestore_no_auto_restore', 08:09:37 INFO - 'tabpaint']}, 08:09:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:09:37 INFO - 'ts_paint', 08:09:37 INFO - 'tpaint', 08:09:37 INFO - 'sessionrestore', 08:09:37 INFO - 'sessionrestore_no_auto_restore', 08:09:37 INFO - 'tabpaint']}, 08:09:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:09:37 INFO - 'ts_paint', 08:09:37 INFO - 'tpaint', 08:09:37 INFO - 'sessionrestore', 08:09:37 INFO - 'sessionrestore_no_auto_restore', 08:09:37 INFO - 'tabpaint']}, 08:09:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:09:37 INFO - 'tests': ['a11yr', 08:09:37 INFO - 'ts_paint', 08:09:37 INFO - 'tpaint', 08:09:37 INFO - 'sessionrestore', 08:09:37 INFO - 'sessionrestore_no_auto_restore', 08:09:37 INFO - 'tabpaint']}, 08:09:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:09:37 INFO - 'tests': ['a11yr', 08:09:37 INFO - 'ts_paint', 08:09:37 INFO - 'tpaint', 08:09:37 INFO - 'sessionrestore', 08:09:37 INFO - 'sessionrestore_no_auto_restore', 08:09:37 INFO - 'tabpaint']}, 08:09:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:09:37 INFO - 'tests': ['tsvgx', 08:09:37 INFO - 'tsvgr_opacity', 08:09:37 INFO - 'tart', 08:09:37 INFO - 'tscrollx', 08:09:37 INFO - 'cart']}, 08:09:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:09:37 INFO - 'tsvgr_opacity', 08:09:37 INFO - 'tart', 08:09:37 INFO - 'tscrollx', 08:09:37 INFO - 'cart']}, 08:09:37 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:09:37 INFO - 'talos_options': ['--disable-e10s'], 08:09:37 INFO - 'tests': ['tp5o']}, 08:09:37 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:09:37 INFO - 'tests': ['tp5o']}, 08:09:37 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:09:37 INFO - 'talos_options': ['--disable-e10s', 08:09:37 INFO - '--xperf_path', 08:09:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:09:37 INFO - 'tests': ['tp5n']}, 08:09:37 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:09:37 INFO - 'talos_options': ['--xperf_path', 08:09:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:09:37 INFO - 'tests': ['tp5n']}}, 08:09:37 INFO - 'talos.zip': {'path': '', 08:09:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:09:37 INFO - Running post-action listener: _resource_record_post_action 08:09:37 INFO - ##### 08:09:37 INFO - ##### Finished populate-webroot step (success) 08:09:37 INFO - ##### 08:09:37 INFO - ##### 08:09:37 INFO - ##### Running create-virtualenv step. 08:09:37 INFO - ##### 08:09:37 INFO - Running pre-action listener: _resource_record_pre_action 08:09:37 INFO - Running main action method: create_virtualenv 08:09:37 INFO - Creating virtualenv /builds/slave/test/build/venv 08:09:37 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 08:09:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:09:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:09:37 INFO - Using real prefix '/tools/python27' 08:09:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:09:38 INFO - Installing distribute.............................................................................................................................................................................................done. 08:09:41 INFO - Installing pip.................done. 08:09:41 INFO - Return code: 0 08:09:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:09:41 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')]} 08:09:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:41 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')]} 08:09:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:41 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 0x109f49a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f59420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc091c8ad40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109de8e00>, '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 0x109c51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109de3b20>, '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.73Bqw6ErkE/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.UQrJ1X9zHF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/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 08:09:41 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 08:09:41 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 08:09:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.73Bqw6ErkE/Render', 08:09:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:41 INFO - 'HOME': '/Users/cltbld', 08:09:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:41 INFO - 'LOGNAME': 'cltbld', 08:09:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:41 INFO - 'MOZ_NO_REMOTE': '1', 08:09:41 INFO - 'NO_EM_RESTART': '1', 08:09:41 INFO - 'PAGER': '/bin/cat', 08:09:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:41 INFO - 'PWD': '/builds/slave/test', 08:09:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:41 INFO - 'SHELL': '/bin/bash', 08:09:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UQrJ1X9zHF/Listeners', 08:09:41 INFO - 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', 08:09:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:41 INFO - 'USER': 'cltbld', 08:09:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:41 INFO - 'XPC_FLAGS': '0x0', 08:09:41 INFO - 'XPC_SERVICE_NAME': '0', 08:09:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:41 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 08:09:41 INFO - Cleaning up... 08:09:41 INFO - Return code: 0 08:09:41 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 08:09:41 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')]} 08:09:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:41 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')]} 08:09:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:41 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 0x109f49a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f59420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc091c8ad40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109de8e00>, '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 0x109c51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109de3b20>, '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.73Bqw6ErkE/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.UQrJ1X9zHF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/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 08:09:41 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 08:09:41 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 08:09:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.73Bqw6ErkE/Render', 08:09:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:41 INFO - 'HOME': '/Users/cltbld', 08:09:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:41 INFO - 'LOGNAME': 'cltbld', 08:09:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:41 INFO - 'MOZ_NO_REMOTE': '1', 08:09:41 INFO - 'NO_EM_RESTART': '1', 08:09:41 INFO - 'PAGER': '/bin/cat', 08:09:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:41 INFO - 'PWD': '/builds/slave/test', 08:09:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:41 INFO - 'SHELL': '/bin/bash', 08:09:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UQrJ1X9zHF/Listeners', 08:09:41 INFO - 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', 08:09:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:41 INFO - 'USER': 'cltbld', 08:09:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:41 INFO - 'XPC_FLAGS': '0x0', 08:09:41 INFO - 'XPC_SERVICE_NAME': '0', 08:09:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:41 INFO - Downloading/unpacking psutil>=0.7.1 08:09:41 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 08:09:41 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 08:09:41 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 08:09:41 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 08:09:41 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 08:09:41 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 08:09:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:09:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:09:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:09:44 INFO - Installing collected packages: psutil 08:09:44 INFO - Running setup.py install for psutil 08:09:44 INFO - building 'psutil._psutil_osx' extension 08:09:44 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 08:09:45 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 08:09:45 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 08:09:45 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 08:09:45 INFO - building 'psutil._psutil_posix' extension 08:09:45 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 08:09:45 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:09:45 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:09:45 INFO - ^ 08:09:45 INFO - 1 warning generated. 08:09:45 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 08:09:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:09:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:09:45 INFO - Successfully installed psutil 08:09:45 INFO - Cleaning up... 08:09:45 INFO - Return code: 0 08:09:45 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 08:09:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:09:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:09:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:45 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 0x109f49a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f59420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc091c8ad40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109de8e00>, '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 0x109c51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109de3b20>, '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.73Bqw6ErkE/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.UQrJ1X9zHF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/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 08:09:45 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 08:09:45 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 08:09:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.73Bqw6ErkE/Render', 08:09:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:45 INFO - 'HOME': '/Users/cltbld', 08:09:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:45 INFO - 'LOGNAME': 'cltbld', 08:09:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:45 INFO - 'MOZ_NO_REMOTE': '1', 08:09:45 INFO - 'NO_EM_RESTART': '1', 08:09:45 INFO - 'PAGER': '/bin/cat', 08:09:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:45 INFO - 'PWD': '/builds/slave/test', 08:09:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:45 INFO - 'SHELL': '/bin/bash', 08:09:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UQrJ1X9zHF/Listeners', 08:09:45 INFO - 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', 08:09:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:45 INFO - 'USER': 'cltbld', 08:09:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:45 INFO - 'XPC_FLAGS': '0x0', 08:09:45 INFO - 'XPC_SERVICE_NAME': '0', 08:09:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:46 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 08:09: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 08:09: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 08:09: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 08:09: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 08:09: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 08:09: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 08:09:48 INFO - Downloading mozsystemmonitor-0.0.tar.gz 08:09:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:09:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 08:09:48 INFO - Installing collected packages: mozsystemmonitor 08:09:48 INFO - Running setup.py install for mozsystemmonitor 08:09:48 INFO - Successfully installed mozsystemmonitor 08:09:48 INFO - Cleaning up... 08:09:48 INFO - Return code: 0 08:09:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:09:48 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')]} 08:09:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:09:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109f49a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f59420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc091c8ad40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109de8e00>, '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 0x109c51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109de3b20>, '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.73Bqw6ErkE/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.UQrJ1X9zHF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/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 08:09:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:09:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:09:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.73Bqw6ErkE/Render', 08:09:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:49 INFO - 'HOME': '/Users/cltbld', 08:09:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:49 INFO - 'LOGNAME': 'cltbld', 08:09:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:49 INFO - 'MOZ_NO_REMOTE': '1', 08:09:49 INFO - 'NO_EM_RESTART': '1', 08:09:49 INFO - 'PAGER': '/bin/cat', 08:09:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:49 INFO - 'PWD': '/builds/slave/test', 08:09:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:49 INFO - 'SHELL': '/bin/bash', 08:09:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UQrJ1X9zHF/Listeners', 08:09:49 INFO - 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', 08:09:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:49 INFO - 'USER': 'cltbld', 08:09:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:49 INFO - 'XPC_FLAGS': '0x0', 08:09:49 INFO - 'XPC_SERVICE_NAME': '0', 08:09:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:49 INFO - Downloading/unpacking blobuploader==1.2.4 08:09: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 08:09: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 08:09: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 08:09: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 08:09: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 08:09: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 08:09:52 INFO - Downloading blobuploader-1.2.4.tar.gz 08:09:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:09:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:09:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:09:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:09:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:53 INFO - Downloading docopt-0.6.1.tar.gz 08:09:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:09:53 INFO - Installing collected packages: blobuploader, requests, docopt 08:09:53 INFO - Running setup.py install for blobuploader 08:09:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:09:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:09:53 INFO - Running setup.py install for requests 08:09:54 INFO - Running setup.py install for docopt 08:09:54 INFO - Successfully installed blobuploader requests docopt 08:09:54 INFO - Cleaning up... 08:09:54 INFO - Return code: 0 08:09:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:09:54 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')]} 08:09:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:54 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')]} 08:09:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:54 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 0x109f49a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f59420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc091c8ad40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109de8e00>, '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 0x109c51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109de3b20>, '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.73Bqw6ErkE/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.UQrJ1X9zHF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/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 08:09:54 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 08:09:54 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 08:09:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.73Bqw6ErkE/Render', 08:09:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:54 INFO - 'HOME': '/Users/cltbld', 08:09:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:54 INFO - 'LOGNAME': 'cltbld', 08:09:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:54 INFO - 'MOZ_NO_REMOTE': '1', 08:09:54 INFO - 'NO_EM_RESTART': '1', 08:09:54 INFO - 'PAGER': '/bin/cat', 08:09:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:54 INFO - 'PWD': '/builds/slave/test', 08:09:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:54 INFO - 'SHELL': '/bin/bash', 08:09:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UQrJ1X9zHF/Listeners', 08:09:54 INFO - 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', 08:09:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:54 INFO - 'USER': 'cltbld', 08:09:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:54 INFO - 'XPC_FLAGS': '0x0', 08:09:54 INFO - 'XPC_SERVICE_NAME': '0', 08:09:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:09:54 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-CSJLa6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:09:54 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-VT9FEb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:09:55 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-o7vZ3M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:09:55 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-BLyVXF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:09:55 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-DY2GHN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:09:55 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-bYHmDX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:09:55 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-dQ2Sp7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:09:55 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-yWBvMc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:09:55 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-B6Fe3G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:09:55 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-h9GoHN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:09:55 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-WgDjWy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:09:55 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-nE7uEQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:09:56 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-J0yZsg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:09:56 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-Bx3OdG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:09:56 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-kEYX0z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:09:56 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-Th3E97-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:09:56 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-m5F4ZN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:09:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:09:56 INFO - Running setup.py install for manifestparser 08:09:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:09:56 INFO - Running setup.py install for mozcrash 08:09:56 INFO - Running setup.py install for mozdebug 08:09:57 INFO - Running setup.py install for mozdevice 08:09:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:09:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:09:57 INFO - Running setup.py install for mozfile 08:09:57 INFO - Running setup.py install for mozhttpd 08:09:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:09:57 INFO - Running setup.py install for mozinfo 08:09:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:09:57 INFO - Running setup.py install for mozInstall 08:09:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:09:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:09:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:09:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:09:57 INFO - Running setup.py install for mozleak 08:09:57 INFO - Running setup.py install for mozlog 08:09:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:09:58 INFO - Running setup.py install for moznetwork 08:09:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:09:58 INFO - Running setup.py install for mozprocess 08:09:58 INFO - Running setup.py install for mozprofile 08:09:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:09:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:09:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:09:58 INFO - Running setup.py install for mozrunner 08:09:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:09:58 INFO - Running setup.py install for mozscreenshot 08:09:58 INFO - Running setup.py install for moztest 08:09:59 INFO - Running setup.py install for mozversion 08:09:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:09:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:09:59 INFO - Cleaning up... 08:09:59 INFO - Return code: 0 08:09:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:09:59 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')]} 08:09:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:59 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')]} 08:09:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:59 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 0x109f49a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f59420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc091c8ad40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109de8e00>, '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 0x109c51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109de3b20>, '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.73Bqw6ErkE/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.UQrJ1X9zHF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/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 08:09:59 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 08:09:59 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 08:09:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.73Bqw6ErkE/Render', 08:09:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:59 INFO - 'HOME': '/Users/cltbld', 08:09:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:59 INFO - 'LOGNAME': 'cltbld', 08:09:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:59 INFO - 'MOZ_NO_REMOTE': '1', 08:09:59 INFO - 'NO_EM_RESTART': '1', 08:09:59 INFO - 'PAGER': '/bin/cat', 08:09:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:59 INFO - 'PWD': '/builds/slave/test', 08:09:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:59 INFO - 'SHELL': '/bin/bash', 08:09:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UQrJ1X9zHF/Listeners', 08:09:59 INFO - 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', 08:09:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:59 INFO - 'USER': 'cltbld', 08:09:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:59 INFO - 'XPC_FLAGS': '0x0', 08:09:59 INFO - 'XPC_SERVICE_NAME': '0', 08:09:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:09:59 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-3d8bT6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:09:59 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)) 08:09:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:09:59 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-bmGfyU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:09:59 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)) 08:09:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:09:59 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-pGN8Vz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:09:59 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)) 08:09:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:09:59 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-o4QGh9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:09:59 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)) 08:09:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:09:59 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-b0o9uC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:10:00 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)) 08:10:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:10:00 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-4tiHmT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:10:00 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)) 08:10:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:10:00 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-WoxlEe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:10:00 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)) 08:10:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:10:00 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-82AWtp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:10:00 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)) 08:10:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:10:00 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-mEkukp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:10:00 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)) 08:10:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:10:00 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-PeixXA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:10:00 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)) 08:10:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:10:00 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-K9ASfm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:10:00 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)) 08:10:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:10:00 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-bE0exv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:10:00 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)) 08:10:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:10:00 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-J9Gz22-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:10:00 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)) 08:10:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:10:00 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-uJ4TW8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:10:00 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)) 08:10:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:10:00 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-LDVGYg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:10:01 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)) 08:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:10:01 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-EfX8_y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:10:01 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)) 08:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:10:01 INFO - Running setup.py (path:/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/pip-VkDLHm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:10:01 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)) 08:10:01 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)) 08:10:01 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)) 08:10:01 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)) 08:10:01 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)) 08:10:01 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)) 08:10:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:10:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:03 INFO - Downloading blessings-1.6.tar.gz 08:10:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:10:03 INFO - Installing collected packages: blessings 08:10:03 INFO - Running setup.py install for blessings 08:10:03 INFO - Successfully installed blessings 08:10:03 INFO - Cleaning up... 08:10:04 INFO - Return code: 0 08:10:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:10:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:10:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:10:04 INFO - Reading from file tmpfile_stdout 08:10:04 INFO - Current package versions: 08:10:04 INFO - blessings == 1.6 08:10:04 INFO - blobuploader == 1.2.4 08:10:04 INFO - docopt == 0.6.1 08:10:04 INFO - manifestparser == 1.1 08:10:04 INFO - mozInstall == 1.12 08:10:04 INFO - mozcrash == 0.17 08:10:04 INFO - mozdebug == 0.1 08:10:04 INFO - mozdevice == 0.48 08:10:04 INFO - mozfile == 1.2 08:10:04 INFO - mozhttpd == 0.7 08:10:04 INFO - mozinfo == 0.9 08:10:04 INFO - mozleak == 0.1 08:10:04 INFO - mozlog == 3.1 08:10:04 INFO - moznetwork == 0.27 08:10:04 INFO - mozprocess == 0.22 08:10:04 INFO - mozprofile == 0.28 08:10:04 INFO - mozrunner == 6.11 08:10:04 INFO - mozscreenshot == 0.1 08:10:04 INFO - mozsystemmonitor == 0.0 08:10:04 INFO - moztest == 0.7 08:10:04 INFO - mozversion == 1.4 08:10:04 INFO - psutil == 3.1.1 08:10:04 INFO - requests == 1.2.3 08:10:04 INFO - wsgiref == 0.1.2 08:10:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:10:04 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')]} 08:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:04 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')]} 08:10:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:04 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 0x109f49a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f59420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc091c8ad40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109de8e00>, '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 0x109c51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109de3b20>, '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.73Bqw6ErkE/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.UQrJ1X9zHF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/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 08:10:04 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 08:10:04 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 08:10:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.73Bqw6ErkE/Render', 08:10:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:04 INFO - 'HOME': '/Users/cltbld', 08:10:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:04 INFO - 'LOGNAME': 'cltbld', 08:10:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:04 INFO - 'MOZ_NO_REMOTE': '1', 08:10:04 INFO - 'NO_EM_RESTART': '1', 08:10:04 INFO - 'PAGER': '/bin/cat', 08:10:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:04 INFO - 'PWD': '/builds/slave/test', 08:10:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:04 INFO - 'SHELL': '/bin/bash', 08:10:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UQrJ1X9zHF/Listeners', 08:10:04 INFO - 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', 08:10:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:04 INFO - 'USER': 'cltbld', 08:10:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:04 INFO - 'XPC_FLAGS': '0x0', 08:10:04 INFO - 'XPC_SERVICE_NAME': '0', 08:10:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:04 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)) 08:10:04 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)) 08:10:04 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)) 08:10:04 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)) 08:10:04 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)) 08:10:04 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)) 08:10:04 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)) 08:10:04 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)) 08:10:04 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)) 08:10:04 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)) 08:10:04 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)) 08:10:04 INFO - Cleaning up... 08:10:04 INFO - Return code: 0 08:10:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:10:04 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')]} 08:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:04 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')]} 08:10:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:04 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 0x109f49a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f59420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc091c8ad40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109de8e00>, '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 0x109c51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109de3b20>, '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.73Bqw6ErkE/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.UQrJ1X9zHF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/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 08:10:04 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 08:10:04 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 08:10:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.73Bqw6ErkE/Render', 08:10:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:04 INFO - 'HOME': '/Users/cltbld', 08:10:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:04 INFO - 'LOGNAME': 'cltbld', 08:10:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:04 INFO - 'MOZ_NO_REMOTE': '1', 08:10:04 INFO - 'NO_EM_RESTART': '1', 08:10:04 INFO - 'PAGER': '/bin/cat', 08:10:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:04 INFO - 'PWD': '/builds/slave/test', 08:10:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:04 INFO - 'SHELL': '/bin/bash', 08:10:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UQrJ1X9zHF/Listeners', 08:10:04 INFO - 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', 08:10:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:04 INFO - 'USER': 'cltbld', 08:10:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:04 INFO - 'XPC_FLAGS': '0x0', 08:10:04 INFO - 'XPC_SERVICE_NAME': '0', 08:10:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:04 INFO - Downloading/unpacking jsonschema 08:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:10:07 INFO - Downloading/unpacking functools32 (from jsonschema) 08:10:07 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 08:10:07 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 08:10:07 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 08:10:07 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 08:10:07 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 08:10:07 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 08:10:07 INFO - Downloading functools32-3.2.3-2.tar.gz 08:10:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:10:07 INFO - warning: no files found matching '*.txt' 08:10:07 INFO - no previously-included directories found matching 'build' 08:10:07 INFO - no previously-included directories found matching 'dist' 08:10:07 INFO - no previously-included directories found matching '.git*' 08:10:07 INFO - Installing collected packages: jsonschema, functools32 08:10:07 INFO - Running setup.py install for functools32 08:10:07 INFO - warning: no files found matching '*.txt' 08:10:07 INFO - no previously-included directories found matching 'build' 08:10:07 INFO - no previously-included directories found matching 'dist' 08:10:07 INFO - no previously-included directories found matching '.git*' 08:10:07 INFO - Successfully installed jsonschema functools32 08:10:07 INFO - Cleaning up... 08:10:08 INFO - Return code: 0 08:10:08 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 08:10:08 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')]} 08:10:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:08 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')]} 08:10:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:08 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 0x109f49a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109f59420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc091c8ad40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109de8e00>, '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 0x109c51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109de3b20>, '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.73Bqw6ErkE/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.UQrJ1X9zHF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/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 08:10:08 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 08:10:08 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 08:10:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.73Bqw6ErkE/Render', 08:10:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:08 INFO - 'HOME': '/Users/cltbld', 08:10:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:08 INFO - 'LOGNAME': 'cltbld', 08:10:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:08 INFO - 'MOZ_NO_REMOTE': '1', 08:10:08 INFO - 'NO_EM_RESTART': '1', 08:10:08 INFO - 'PAGER': '/bin/cat', 08:10:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:08 INFO - 'PWD': '/builds/slave/test', 08:10:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:08 INFO - 'SHELL': '/bin/bash', 08:10:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UQrJ1X9zHF/Listeners', 08:10:08 INFO - 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', 08:10:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:08 INFO - 'USER': 'cltbld', 08:10:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:08 INFO - 'XPC_FLAGS': '0x0', 08:10:08 INFO - 'XPC_SERVICE_NAME': '0', 08:10:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:08 INFO - Downloading/unpacking flake8 08:10:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:10 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:10:10 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:10:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:11 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:10:11 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:10:11 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 08:10:11 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 08:10:11 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 08:10:11 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 08:10:11 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 08:10:11 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 08:10:11 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:10:11 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 08:10:11 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 08:10:11 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 08:10:11 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 08:10:11 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 08:10:11 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 08:10:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 08:10:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:10:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:10:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:10:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:10:11 INFO - no previously-included directories found matching 'docs/_build' 08:10:11 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:10:12 INFO - Running setup.py install for pep8 08:10:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:10:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:10:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:10:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:10:12 INFO - no previously-included directories found matching 'docs/_build' 08:10:12 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 08:10:12 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:10:12 INFO - Cleaning up... 08:10:12 INFO - Return code: 0 08:10:12 INFO - Running post-action listener: _resource_record_post_action 08:10:12 INFO - Running post-action listener: _start_resource_monitoring 08:10:12 INFO - Starting resource monitoring. 08:10:12 INFO - ##### 08:10:12 INFO - ##### Finished create-virtualenv step (success) 08:10:12 INFO - ##### 08:10:12 INFO - ##### 08:10:12 INFO - ##### Running install step. 08:10:12 INFO - ##### 08:10:12 INFO - Running pre-action listener: _resource_record_pre_action 08:10:12 INFO - Running main action method: install 08:10:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:10:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:10:12 INFO - Reading from file tmpfile_stdout 08:10:12 INFO - Detecting whether we're running mozinstall >=1.0... 08:10:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:10:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:10:12 INFO - Reading from file tmpfile_stdout 08:10:12 INFO - Output received: 08:10:12 INFO - Usage: mozinstall [options] installer 08:10:12 INFO - Options: 08:10:12 INFO - -h, --help show this help message and exit 08:10:12 INFO - -d DEST, --destination=DEST 08:10:12 INFO - Directory to install application into. [default: 08:10:12 INFO - "/builds/slave/test"] 08:10:12 INFO - --app=APP Application being installed. [default: firefox] 08:10:12 INFO - mkdir: /builds/slave/test/build/application 08:10:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:10:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:10:46 INFO - Reading from file tmpfile_stdout 08:10:46 INFO - Output received: 08:10:46 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:10:46 INFO - Running post-action listener: _resource_record_post_action 08:10:46 INFO - ##### 08:10:46 INFO - ##### Finished install step (success) 08:10:46 INFO - ##### 08:10:46 INFO - ##### 08:10:46 INFO - ##### Running run-tests step. 08:10:46 INFO - ##### 08:10:46 INFO - Running pre-action listener: _resource_record_pre_action 08:10:46 INFO - Running main action method: run_tests 08:10:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:10:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:10:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:10:46 INFO - 2016-05-06 08:10:46.193 screenresolution[1649:7670] starting screenresolution argv=screenresolution get 08:10:46 INFO - 2016-05-06 08:10:46.196 screenresolution[1649:7670] Display 0: 1600x1200x32@60 08:10:46 INFO - 2016-05-06 08:10:46.202 screenresolution[1650:7672] starting screenresolution argv=screenresolution list 08:10:46 INFO - Available Modes on Display 0 08:10:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:10:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:10:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:10:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:10:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:10:46 INFO - Intel Iris: 08:10:46 INFO - Chipset Model: Intel Iris 08:10:46 INFO - Type: GPU 08:10:46 INFO - Bus: Built-In 08:10:46 INFO - VRAM (Dynamic, Max): 1536 MB 08:10:46 INFO - Vendor: Intel (0x8086) 08:10:46 INFO - Device ID: 0x0a2e 08:10:46 INFO - Revision ID: 0x0009 08:10:46 INFO - Displays: 08:10:46 INFO - Display: 08:10:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:10:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:10:46 INFO - Display Serial Number: 1600x1200 60 08:10:46 INFO - Main Display: Yes 08:10:46 INFO - Mirror: Off 08:10:46 INFO - Online: Yes 08:10:46 INFO - Rotation: Supported 08:10:46 INFO - Return code: 0 08:10:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:10:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:10:46 INFO - Python 2.7.3 08:10:46 INFO - Return code: 0 08:10:46 INFO - grabbing minidump binary from tooltool 08:10:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:46 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 0x109de8e00>, '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 0x109c51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109de3b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:10:46 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 08:10:46 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 08:10:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:10:46 INFO - Return code: 0 08:10:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:10:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:10:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:10:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:10:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:10:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 08:10:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 08:10:47 INFO - Return code: 0 08:10:47 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-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fYTw48n9Rsu4LSGDbhEtbg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0043', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 08:10:47 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-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fYTw48n9Rsu4LSGDbhEtbg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0043 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 08:10:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.73Bqw6ErkE/Render', 08:10:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:47 INFO - 'HOME': '/Users/cltbld', 08:10:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:47 INFO - 'LOGNAME': 'cltbld', 08:10:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:10:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:10:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:47 INFO - 'MOZ_NO_REMOTE': '1', 08:10:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:10:47 INFO - 'NO_EM_RESTART': '1', 08:10:47 INFO - 'PAGER': '/bin/cat', 08:10:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:47 INFO - 'PWD': '/builds/slave/test', 08:10:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:10:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:47 INFO - 'SHELL': '/bin/bash', 08:10:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UQrJ1X9zHF/Listeners', 08:10:47 INFO - 'TMPDIR': '/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/', 08:10:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:47 INFO - 'USER': 'cltbld', 08:10:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:47 INFO - 'XPC_FLAGS': '0x0', 08:10:47 INFO - 'XPC_SERVICE_NAME': '0', 08:10:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fYTw48n9Rsu4LSGDbhEtbg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0043', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 08:10:47 INFO - mozversion application_buildid: 20160506053348 08:10:47 INFO - mozversion application_changeset: 9d202f109312b37f42224c55724ea845a4b86c23 08:10:47 INFO - mozversion application_display_name: Nightly 08:10:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:10:47 INFO - mozversion application_name: Firefox 08:10:47 INFO - mozversion application_remotingname: firefox 08:10:47 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 08:10:47 INFO - mozversion application_vendor: Mozilla 08:10:47 INFO - mozversion application_version: 49.0a1 08:10:47 INFO - mozversion platform_buildid: 20160506053348 08:10:47 INFO - mozversion platform_changeset: 9d202f109312b37f42224c55724ea845a4b86c23 08:10:47 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 08:10:47 INFO - mozversion platform_version: 49.0a1 08:10:47 INFO - using testdate: 1462547447 08:10:47 INFO - actual date: 1462547447 08:10:47 INFO - starting webserver on 'localhost:49277' 08:10:47 INFO - SUITE-START | Running 2 tests 08:10:47 INFO - TEST-START | dromaeo_css 08:10:47 INFO - operating with platform_type : mac_ 08:10:47 INFO - Initialising browser for dromaeo_css test... 08:10:47 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/tmp_3T2JV/profile http://localhost:49277/getInfo.html) 08:10:51 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 08:10:51 INFO - PROCESS | 1668 | colorDepth:24 08:10:51 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 08:10:51 INFO - PROCESS | 1668 | __metrics 08:10:51 ERROR - PROCESS | 1668 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 08:10:51 INFO - TEST-INFO | 1668: exit 0 08:10:51 INFO - Browser initialized. 08:10:51 INFO - Running cycle 1/1 for dromaeo_css test... 08:10:51 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/tmp_3T2JV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:11:03 INFO - PROCESS | 1671 | RSS: Main: 250109952 08:11:03 INFO - PROCESS | 1671 | 08:11:53 INFO - PROCESS | 1671 | 1462547513487 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] 08:14:04 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 08:14:05 INFO - PROCESS | 1671 | RSS: Main: 262283264 08:14:05 INFO - PROCESS | 1671 | 08:17:07 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 08:17:08 INFO - PROCESS | 1671 | RSS: Main: 274092032 08:17:08 INFO - PROCESS | 1671 | 08:20:10 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 08:20:10 INFO - PROCESS | 1671 | RSS: Main: 276082688 08:20:10 INFO - PROCESS | 1671 | 08:23:12 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 08:23:13 INFO - PROCESS | 1671 | RSS: Main: 277655552 08:23:13 INFO - PROCESS | 1671 | 08:26:16 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 08:26:16 INFO - PROCESS | 1671 | RSS: Main: 280510464 08:26:16 INFO - PROCESS | 1671 | 08:29:19 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 08:29:19 INFO - PROCESS | 1671 | RSS: Main: 281681920 08:29:19 INFO - PROCESS | 1671 | 08:29:19 INFO - PROCESS | 1671 | __start_tp_report 08:29:19 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 08:29:19 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 08:29:19 INFO - PROCESS | 1671 | |i|pagename|runs| 08:29:19 INFO - PROCESS | 1671 | |0;dojo.html;3490.1623610333495;3492.6856582907535;3531.2937412517485;3537.8584856605735;3553.342631613151;5725.599208055438;5878.662962631058;5928.2886053673565;5936.406359364072;5961.254843144607;5774.913376299349;5816.418358164182;5820.883582328351;5823.039198532248;5885.764569417229;5194.92207616886;5243.082460569404;5285.656432331886;5326.147816349385;5336.866578335534;435.69939847305517;5245.868853278679;5294.391145018314;5304.734763261841;5319.388270348902;5285.154375299952;5288.2596436498925;5302.072137375943;5325.854941187644;5350.224217488481;5306.257124002643;5348.973255133699;5363.704996225206;5385.111456609694;5401.081816091274;4973.477784832613;5163.66436181647;5193.039287731782;5229.712365819878;5247.396549396792;4762.4285085789925;5646.378898321182;5676.659400435987;5710.971445142789;5799.985002624566;5466.480684334982;6077.24034495688;6102.428659372087;6320.399562041599;6323.93676063238;6238.344973777779;6251.093591429263;6314.431701146918;6332.05019247112;6341.112244285804;6018.740509869686;6027.186329845458;6030.005049166838;6032.095185722133;6048.123022161806;5687.1753595729215;5971.104334349882;6032.728527216286;6346.714397852108;6389.968050159812;6149.84625384369;6159.4456498915315;6174.938250617526;6186.1648677428875;6254.6872656366995;6271.96864015677;6291.245050593958;6306.369363063749;6340.239171299474;6343.777967771198;6087.4521293083835;6109.358517355702;6130.693465326716;6275.435210831047;6348.77779277723;5164.561012313995;6049.758009679564;6120.968863631887;6140.109684095781;6194.225721784777;3894.6494815466745;4757.881052973703;4866.367372241586;4867.221244600847;4896.853094407174;3625.4380571011534;4380.496242932104;4432.3794668746405;4501.932471012938;4522.997147524465;5601.383847776741;5877.853053673693;5940.079287710412;5997.820065398045;6003.129546215774;6291.5281353898645;6294.370562943669;6315.147455093595;6330.493560515148;6335.936640633535;5871.3541510433815;5948.196993405835;5980.132880732356;5995.100734889801;6034.969825150846;5455.618106732491;5504.366997795305;5509.035918714289;5511.834644960658;5539.750711217925;6306.527010474141;6517.869642607121;6517.934820651828;6532.477401807939;6540.9345906541275;5249;6196.690165491797;6227.470664993345;6273.843153921006;6283.2460104787715;6442.452391546771;6443.6133831968355;6457.321981191907;6485.44873685742;6525.347465253437;6148.846278843064;6187.102870083902;6259.405356491126;6331.113644089924;6341.524385671001;6208.348123446973;6238.75044998195;6321.905171422341;6346.4605508532295;6356.9046464304;5296.708681022426;6233.594816336924;6268.153799237227;6268.216472940882;6274.59215151032;6066.15073889647;6119.654885717031;6131.325554188947;6223.066540018853;6224.128621993015;4802.327674125555;4860.465348811698;5886.378508524434;6027.397260273938;6120.479759220516;6190.81427557156;6225.315215326401;6251.187345645037;6251.781187658592;6263.060540918898;5884.852878677896;5986.371430999682;6008.579399441997;6066.848328791815;6098 08:29:19 INFO - PROCESS | 1671 | |1;ext.html;476.8188088526359;507.40633458853137;538.6714104396317;540.7296351824087;554.7974989128968;38501.84494465167;40740;40765.7961710191;40772.59227407725;40792.592074079286;41596.376054359134;41788.791056044756;42108.36837447433;42350.57649423505;42467;9513.476758778264;9640.228781697482;9703.22374210065;9721.70834874955;9724.75688107796;9511.619535218617;9527.571259293349;9561.474118923456;9565.234781217487;9581.808363832719;9552.522373881313;9563.617455301779;9568.712938611818;9659.951700241489;9677.661281855137;8749.606267717905;9171.85351831021;9546.140847323704;9624.951875240615;9679.564419601134;9467.715968520955;9484.383515071568;9487.288453365956;9492.857607135898;9613.423194608346;38496.230075398336;38520.80739596312;38865.222695545934;38890.833275001794;38901.610983890365;2792.8463934483593;2810.9578356324673;3102.30198205402;3344.66553344666;3374.4432168692138;26208.86895565529;27214;27564.448711025867;27702.5844612331;27868.581971270447;2598.662173917379;2621.5936529837713;2628.54000549903;2654.349684327333;2707.6344693466326;500679;504300;507464;508536;509186.454067731;61824.07263891045;63311;63355.36644633495;63440.73118537603;63457;35416.93749187477;35892.82053589715;36246.91259262226;38087.23825523474;38119;17171.570710732332;17233.965962042068;17352.219150138273;17379.913100434416;17381.565460863327;13223.73552528944;13236.669083272995;13309.866901331057;13347.199168049949;13376.264305463294;19107.426777196706;19224.903875480533;19379.224831006883;19517.571199888396;19563.51091222731;8225.753227403187;8315.251627353568;8336.20771534123;8370.79073023167;8394.538300393537;926.4811705444972;1032.143321043532;1038.200585549138;1056.4612047855496;1099.8845121262152;28481.715182847907;28844.423111537653;29313.853430733136;29625.11307581209;29811.254718631775;929.846575315079;1091.47063674118;1101.1631160318216;1113.1095123900848;1175.4945373489481;12421.44103515344;14496.565103046925;15057.698846023237;15174.620634484227;15214.847851521565;645128;649240;662089;662993;663327;57129;58535.707321463124;58614.70692646509;59106.70446647739;59330.11004834931;1445.7253121907015;1453.651123730297;1462.7805829125716;1472.425753955958;1482.5181815909873;28899.277518062216;29331.41337173202;29684.406311874074;29752.851235744547;29830.8508457465;41833;50784.492155077976;57356.13965790433;59014;59200.407995919486;626888;634014;634153;640332;642107;38649.80675096607;39806.80096599498;41934.370984434645;42349.78825105855;42700;39895.80052099721;45889.770551147034;45965;46402;47337.34319298946;37183.628163718015;38265.043373915876;38576.614233858425;38582;39446.60553394429;35150.824245878604;37296.44055339118;37496.43755343727;38190.42714359342;38281;36432.27135457329;36959.44560831643;37077.44383834299;37607.43588846224;37722.62277377191;22085.779142208372;32796.1800954978;33359.83240586665;33530.49704254487;33545.32909341751;24814.503709926063;24986;25044.248672539852;25105.74894251034;25124.371890702878 08:29:19 INFO - PROCESS | 1671 | |2;jquery.html;352.418509459392;394.3650722337037;398.83234619841147;401.3417994489038;417.94148819165304;14721.55835324942;19929.40211793645;20620.1751929923;21122.943852807373;21158.365249042534;21117.788822111776;21196.57606847866;21210.939453027324;21895.890520547415;21923.232686855965;5536.584756143286;5949.88100237995;6013.669248191348;6019.307779605358;6024.1566180734735;5647.152927060933;5846.327672317695;5997.49021333185;5998.520118390518;6018.3078945921125;5789.4500022498005;6061.636301821904;6079.270487541501;6084;6086.208792856923;6087.2999605045325;6107.236595425572;6139.324674285825;6144.8771024579255;6148.569600127993;5668.461496157858;5889.646621202742;5920.97039514804;5943.851403714899;5952.672603006833;13110.344482775918;13250.204987700672;13251.536196233086;13348.466061357534;23486.177983770485;2038.8063134002393;2092.665173572221;2126.51090249242;2238.0488292475634;2296.9655455168186;8587;9703.563339649732;9898.802023959552;10197.439140847251;13511.594652160353;1700.948971530856;1738.6609611125837;1743.834335738103;1769.840714335703;1851.1392202625843;97278;130073.34963325123;130626;133006.33496832455;133614;9236.815263694689;11523.596674116367;11899.583514576949;11952.58165964187;11968.581099661471;15200;15249.313780879887;15269.00751451152;15280.235988200546;15296.311665974836;6977.581345119309;8797.252233560092;8896.555172241491;8963.372563920462;9011.63953441868;8823.02946675878;8830.690925817566;8846.15961483658;8911.598978046004;8927.196552310194;4612.492625811158;4707.388039554904;4727.810887564528;4751.429828420612;4767.498238055002;1957.2953736654792;2023.3221870673194;2032.085561497332;2049.8872562009233;2086.603545326383;775.3680750188578;870.3472395703222;901.6123067081218;998.605550807427;1049.3651340938775;18912.621747564972;20273.79726202719;20368.98155092269;20470.488237793877;20583.691244631344;914.8124634449922;929.2240978782792;951.5147274889717;963.5808423335872;1016.2987538598344;8093.899130710367;10747.978942000498;10786.460676966277;10818.837717434088;10837.67486975392;604664;621411;625065;640655;642353;84609;135219;142662;143433;143755.9999999979;935.9812803743887;987.5309228116731;991.7355371900884;1061.532925512772;1083.2958576924905;17626.942383457033;19418.8058119417;19486;19495.139091804645;19596.314129005415;46251;47607.28589071098;49455.25817112818;51341.71645708887;54738.45261547333;74101.2589874116;74396.25603743893;74671;74671.25328746643;76986.6150669243;7242.782716518513;7841.5687137208;8129.821175929339;8189.22202390772;8511.616977236163;9059.864102038342;11903.10726695484;12419.689507762017;12770.744585108432;12907.031972601904;9490.952545237229;10787.946060269649;10837.295575787548;10937.179711521509;11070.225084244026;9981.900180998096;10672.83990740155;10832.295900766425;11105.944470277596;11234.3259404436;10996.914713147586;11115.88841115882;11128.38793866345;11145;11215.383153926612;8106.47307924983;8108.472949258279;8213.260806527442;8367.121452247426;8501.574921254036;10688.839667405151;10941.945290273497;11205.04757095656;11216.046636035702;11227.38249396291 08:29:19 INFO - PROCESS | 1671 | |3;mootools.html;1466.6406730351066;1594.7687585300134;1823.2524664887399;1925.7689077310715;1932.5844943337179;7789.766307010785;8578.571071446433;8613.741587752369;9159.038300978402;9167.587458564356;7547.018887544626;8717.782055448617;8734.5632718364;8906.86639700405;8910.866337004934;2475.319287196022;2505.8621775802326;2509.5231905937903;2515.949681006379;2520.785733212679;2458.3485376375274;2487.9751202488014;2496.4133428644254;2498.7376325485834;2526.418923647562;2485.3165379520597;2492.2772396005225;2522.8899284314843;2523.671922650053;2525.2045605634257;2431.1612493689736;2437.8659173745436;2439.890204940782;2449.4121410861444;2450.742672019439;2422.1643532981093;2427.5751743445003;2452.583060879637;2452.901883924641;2480.218731099698;8853.955730221373;8972.8654070189;8974.416662916954;8998.685046023424;9003.954980225122;4964.87587810304;5437.945620543823;5459.645123066988;5474.151506516497;5507.339119305709;3630.5643322801434;3671.596124426311;3685.2261025184744;3735.3089678409583;3735.9439608405896;925.3753716241511;1010.7523656704154;1052.2690174662637;1063.6596289187464;1071.8928107189297;9807.31348805584;9972.351797133164;11699.590514331958;11860.64418067468;11884.465199065977;4865.9026819464125;4894.3147959285725;5011.949880501221;5019.121653710586;5046.646734728607;9997;10013.29906906524;10028.949855250678;10050.396976181453;10058.748531286776;5392.487713667196;5426.158945363475;5469.917951230735;5478.753456094485;5510.669359838423;4229.13302772937;4448.421705178371;4824.710517368969;4957.90084198314;4992.15133427311;3517.402041652874;4066.7356621820077;4073.103917138225;4150.232207041707;4186.995121170897;1358.864113588653;1372.025861638248;1379.2069560003038;1385.8752712255946;1388.3682924269433;786.9842603147905;911.7355966769695;950.3917492804611;952.3524003677566;984.0258144437099;5298.682079075268;5967.731452084667;6116.908246376308;6131.601445906002;6172.475339596095;478.23004962011134;479.4917387569188;482.99275510866664;486.5159166629227;488.36024807502287;3971.185906889081;4079.817742161119;4099.282625540519;4144.5026596807975;4158.438610787565;8460.703875364456;10678.786424271471;10836.945815270874;10871.619493317854;10923.3992130432;10717.892821071846;10753.247272690993;10839.078678312431;10866.348019118877;10894.455277236106;7993.72021979228;8232.765085237263;8262.793430164174;8320.791980200544;8370.916290837014;5790.160426738184;5949.940500594938;5950.077737950624;5962.165296858357;6019.578629496068;8963.148500892405;9963.950180249052;10103.595856165672;10110.292279540656;10132.696019119143;9998.400095994264;10032.046955539212;10149.441780701836;10319.226058045826;10371.636992705218;6365.395287447685;6910.343517365842;7328.047353843966;7538;7568;8433;8460.534670593177;8468.44955077918;8550.657973680984;8558.144185581392;7284.599347035964;7298.197198308288;7414.295641914009;7535.849283014419;7556.433267504849;7295;7412;7414.518056326321;7447.851042979001;7497.887531687138;7061.8940715888275;7122.074130363019;7143.107111611049;7155.892661609976;7166.964165179141;4924.852254432372;5069.822556210515;5110.770015349392;5117.667351622133;5194.636375453682;4939.48135445773;5051.974740126276;5073.3911930568565;5112.9744351278005;5298.523132918056 08:29:19 INFO - PROCESS | 1671 | |4;prototype.html;306.287880677425;309.5202436223854;324.9236429439082;328.5662924939081;331.8208167589502;4514.458265008199;5276.7573781142955;5345.732713364336;5436.537894278986;5574.526165275946;4626.86119416418;4823.348847905531;5076.64463487556;5455.0453670607685;5513.889722205554;3650.3064417760543;4191.1617676464675;4200.348945913388;4232.8359701082145;4274.807633656477;3827.655511004009;4095.28332541804;4214.494260688722;4220.324748040314;4244.1511697660435;3349.363620912019;4033.3143365627916;4048.6761059115347;4072.2873497137884;4094.795260236991;3461.3077384523194;4069.4302797608357;4076.388541718736;4109.321961876287;4123.876283711494;3058.7247147756584;3145.575347328127;3284;3493.965060349389;3558.5729712434672;4471.575200355961;4705.529447055302;4943.431505376891;4994.9750251248515;5174.567953062055;4683.227267500858;4694.2958556216845;4701.882452938669;4729.6452766042685;4742.103158736533;4994.55049045584;5222.0729472049115;5698.715064246771;5809.912851307234;5825.300963884319;3184.6656101109515;3576;3738.756980796267;3905.1994341159993;3915.3555506687258;5136.512031357052;5567.916481252784;5960.046392577166;5967.701614919237;6021.096835474714;3500.5099286099967;3950.6370302245678;3957.6635985941516;3973.841046358113;3979.801009949491;3746.981265093657;3774.6602805747616;3831.662685761683;4039.555648878621;4071;4450.755208463501;4669;4695.178343789823;4699.906001880012;4701.811927522861;4772.949951010772;4812.037592481518;5039.546440820343;5430.294061771944;5560.694161821139;1158.9420528973517;1199.0287866827898;1280.737448822989;1295.9049603085377;1298.3832679477173;749.1907044487501;877.5827039331434;887.2236792806294;894.0389081737159;894.4722613657843;4292.978535107367;4377.163234625757;4824.589909857701;5032.521910418504;5106.208537676592;4881.658283920163;4921.26181072842;4964.553190212898;5020.974895125501;5086.4659210783075;4177.057668184309;4562.634989200856;4574.627611716472;4781.52184781526;4947.282644016597;2644.418227989839;2768.6816016157995;2872.6983666714696;2898.942021159565;2974.3902499987453;5192.792288308501;5262.7631756571045;5672.19962625034;5922.838164070084;5992.58051936369;5110.897782044338;5494.066008778517;5646.85882852932;5714.171445140514;5783.652980821164;5030.868054687666;5181.96360727856;5191.9751296993445;5505.201745746845;5860.501857342174;4808.3989501312335;5257.763400646903;5622.831315060391;5625.549956003591;5688.354914964561;4586.518415566317;5280.574244953764;5324.653897496651;5598.720063996865;5620.358431537171;4927.989762098833;5124.308218390619;5340.917042353408;5833.095870140135;5938.792142275;3267.6732326767133;3488.424409972326;3594.928101438009;3728.9422673407544;3756.13608869956;4956.975215123901;5033.421156567042;5281.823812663534;5425.348958124892;5658.236138121301;4679.672422930362;4903.828366007173;5802.723400851806;5814.360420353673;5819.76720931158;4872.9512704872495;5791.942080579309;5811.4479124483105;5815.59787524303;5830.4461076197695;5323.866903327448;5664.971675141598;5687.691830879004;5710.942890571041;5725.971370143123;4960.925586116284;4992.975035124801;5112.440705584832;5648.78551111513;5859.853503662443;4825.638077144298;5286;5486.875190585841;5524.06090964545;5654.660720356792 08:29:19 INFO - PROCESS | 1671 | |5;yui.html;524.4283730733499;547.8329109621563;559.9048161812492;629.1129507394573;668.7692746002627;14366.066205696636;15286.84713152868;15389.307481163347;15419.922900385496;15452.845471545295;13676.658083547916;14035;14229.501967431142;14295.927805414583;14302.856971430283;726.7274771960515;905.8505346617833;916.083916083916;923.2844545477275;934.0939288889766;931.2317338195982;934.0146145816161;934.2059249637822;938.052566907425;939.7321763297462;939.0750111140521;939.492673956063;939.8402271613842;939.9624015039391;984.3257368702461;935.7613808478828;971.4414211828192;980.240313756837;981.11209355533;982.40564458503;711.8469529051227;718.0916141081536;718.5042320798684;731.5171986488892;735.3859527294662;14848.925755371261;14884.62788430287;14966.47617333399;14972.47596334134;15189.696206075927;12824.551140710124;12904;12982.415791289415;12998.350082495837;13336.199828010253;10137.290389672726;10148.89851101487;10285.897141028569;10434.530446129942;10643.57425702971;2823.7228373908156;3250.5859950921426;3475.739319551044;3501.527293815328;3536.840842162109;69018;79743.8038429424;83125.58437207836;85149.14850851474;88111.67832482517;9654.613815447303;9664;9676.371035882645;9748.951255243679;9911.80176396468;18518.796278241873;19822.306219282254;20303.086361113787;21365.46586335323;21618;7483.214262502468;7615.504992175491;7701.499402538817;7836.059672839182;7976.162502937224;3680.0799800049986;4188.979055104766;4218.514870789837;4229.513605935295;4392.626626736763;3417.743669224818;3639.1993761372987;3642.6357364263363;3643.9796856880116;3660.139867131222;646.2245305633165;650.869826034795;656.3666062249952;656.8029591122644;659.6009414304373;513.7970501651827;558.4917724870348;578.6759414727766;604.9062395328731;610.2676787854592;7501.549907005488;7675.961620191975;8076.515409075474;8266.586670666442;8304.418690691593;524.9448807875194;543.234040003593;543.5054100768282;609.740860134441;610.9038894214283;3795.525559305087;3811.6378944000276;3849.1531862990096;3858.6141385861188;4040.070783719761;87500;89372.5531372352;90635;91091.54454227819;91378;62060.68969655213;75827;77908.4418311642;79571;80525;67630;68790.31209687839;69716.30283697098;69831.30168698248;70055.64972175208;7659.195784442776;7669.81277276043;7684.46208765381;7753.4960227585025;7820.765377038698;39501.604983949794;41051.56319528802;42007.1598568033;42800.5719942809;43107.568924310355;55731.72134139304;56572.15141772959;57674.84650306887;58076.41923580879;59026;10151.543180556744;10968.780624387628;11029.283096598696;11140.387278699749;11506.252093613888;9764.267679923893;12371.072169587496;12458.127931044739;12508;12728;10553.63062292816;11226;11475.254108482923;11797.35114568707;11960.162788604714;11185.496652650487;11606.88393116058;11730;11956.641300760992;12131;11057.06014988735;11320.37737924422;11438.828417573577;11606.94196529012;11674.299542027165;7382.298681625237;9331.860022099538;9603.567839447382;9654.27592930519;9896.158826499828;9419.905800942177;9577.856332154885;9728.075832795874;9776.560054797408;9871.25965552572 08:29:19 INFO - PROCESS | 1671 | __end_tp_report 08:29:19 INFO - PROCESS | 1671 | __start_cc_report 08:29:19 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,412 08:29:19 INFO - PROCESS | 1671 | __end_cc_report 08:29:19 INFO - PROCESS | 1671 | __startTimestamp1462548559570__endTimestamp 08:29:19 INFO - PROCESS | 1671 | 08:29:19 INFO - PROCESS | 1671 | ------- Summary: start ------- 08:29:19 INFO - PROCESS | 1671 | Number of tests: 6 08:29:19 INFO - PROCESS | 1671 | 08:29:19 INFO - PROCESS | 1671 | [#0] dojo.html Cycles:165 Average:5740.66 Median:6027.19 stddev:792.92 (13.2%) stddev-sans-first:775.44 08:29:19 INFO - PROCESS | 1671 | Values: 3490.2 3492.7 3531.3 3537.9 3553.3 5725.6 5878.7 5928.3 5936.4 5961.3 5774.9 5816.4 5820.9 5823.0 5885.8 5194.9 5243.1 5285.7 5326.1 5336.9 435.7 5245.9 5294.4 5304.7 5319.4 5285.2 5288.3 5302.1 5325.9 5350.2 5306.3 5349.0 5363.7 5385.1 5401.1 4973.5 5163.7 5193.0 5229.7 5247.4 4762.4 5646.4 5676.7 5711.0 5800.0 5466.5 6077.2 6102.4 6320.4 6323.9 6238.3 6251.1 6314.4 6332.1 6341.1 6018.7 6027.2 6030.0 6032.1 6048.1 5687.2 5971.1 6032.7 6346.7 6390.0 6149.8 6159.4 6174.9 6186.2 6254.7 6272.0 6291.2 6306.4 6340.2 6343.8 6087.5 6109.4 6130.7 6275.4 6348.8 5164.6 6049.8 6121.0 6140.1 6194.2 3894.6 4757.9 4866.4 4867.2 4896.9 3625.4 4380.5 4432.4 4501.9 4523.0 5601.4 5877.9 5940.1 5997.8 6003.1 6291.5 6294.4 6315.1 6330.5 6335.9 5871.4 5948.2 5980.1 5995.1 6035.0 5455.6 5504.4 5509.0 5511.8 5539.8 6306.5 6517.9 6517.9 6532.5 6540.9 5249.0 6196.7 6227.5 6273.8 6283.2 6442.5 6443.6 6457.3 6485.4 6525.3 6148.8 6187.1 6259.4 6331.1 6341.5 6208.3 6238.8 6321.9 6346.5 6356.9 5296.7 6233.6 6268.2 6268.2 6274.6 6066.2 6119.7 6131.3 6223.1 6224.1 4802.3 4860.5 5886.4 6027.4 6120.5 6190.8 6225.3 6251.2 6251.8 6263.1 5884.9 5986.4 6008.6 6066.8 6098.0 08:29:19 INFO - PROCESS | 1671 | 08:29:19 INFO - PROCESS | 1671 | [#1] ext.html Cycles:180 Average:72295.09 Median:38757.51 stddev:161451.19 (416.6%) stddev-sans-first:161814.06 08:29:19 INFO - PROCESS | 1671 | Values: 476.8 507.4 538.7 540.7 554.8 38501.8 40740.0 40765.8 40772.6 40792.6 41596.4 41788.8 42108.4 42350.6 42467.0 9513.5 9640.2 9703.2 9721.7 9724.8 9511.6 9527.6 9561.5 9565.2 9581.8 9552.5 9563.6 9568.7 9660.0 9677.7 8749.6 9171.9 9546.1 9625.0 9679.6 9467.7 9484.4 9487.3 9492.9 9613.4 38496.2 38520.8 38865.2 38890.8 38901.6 2792.8 2811.0 3102.3 3344.7 3374.4 26208.9 27214.0 27564.4 27702.6 27868.6 2598.7 2621.6 2628.5 2654.3 2707.6 500679.0 504300.0 507464.0 508536.0 509186.5 61824.1 63311.0 63355.4 63440.7 63457.0 35416.9 35892.8 36246.9 38087.2 38119.0 17171.6 17234.0 17352.2 17379.9 17381.6 13223.7 13236.7 13309.9 13347.2 13376.3 19107.4 19224.9 19379.2 19517.6 19563.5 8225.8 8315.3 8336.2 8370.8 8394.5 926.5 1032.1 1038.2 1056.5 1099.9 28481.7 28844.4 29313.9 29625.1 29811.3 929.8 1091.5 1101.2 1113.1 1175.5 12421.4 14496.6 15057.7 15174.6 15214.8 645128.0 649240.0 662089.0 662993.0 663327.0 57129.0 58535.7 58614.7 59106.7 59330.1 1445.7 1453.7 1462.8 1472.4 1482.5 28899.3 29331.4 29684.4 29752.9 29830.9 41833.0 50784.5 57356.1 59014.0 59200.4 626888.0 634014.0 634153.0 640332.0 642107.0 38649.8 39806.8 41934.4 42349.8 42700.0 39895.8 45889.8 45965.0 46402.0 47337.3 37183.6 38265.0 38576.6 38582.0 39446.6 35150.8 37296.4 37496.4 38190.4 38281.0 36432.3 36959.4 37077.4 37607.4 37722.6 22085.8 32796.2 33359.8 33530.5 33545.3 24814.5 24986.0 25044.2 25105.7 25124.4 08:29:19 INFO - PROCESS | 1671 | 08:29:19 INFO - PROCESS | 1671 | [#2] jquery.html Cycles:180 Average:35612.41 Median:396.60 stddev:104685.84 (26395.9%) stddev-sans-first:104946.03 08:29:19 INFO - PROCESS | 1671 | Values: 352.4 394.4 398.8 401.3 417.9 14721.6 19929.4 20620.2 21122.9 21158.4 21117.8 21196.6 21210.9 21895.9 21923.2 5536.6 5949.9 6013.7 6019.3 6024.2 5647.2 5846.3 5997.5 5998.5 6018.3 5789.5 6061.6 6079.3 6084.0 6086.2 6087.3 6107.2 6139.3 6144.9 6148.6 5668.5 5889.6 5921.0 5943.9 5952.7 13110.3 13250.2 13251.5 13348.5 23486.2 2038.8 2092.7 2126.5 2238.0 2297.0 8587.0 9703.6 9898.8 10197.4 13511.6 1700.9 1738.7 1743.8 1769.8 1851.1 97278.0 130073.3 130626.0 133006.3 133614.0 9236.8 11523.6 11899.6 11952.6 11968.6 15200.0 15249.3 15269.0 15280.2 15296.3 6977.6 8797.3 8896.6 8963.4 9011.6 8823.0 8830.7 8846.2 8911.6 8927.2 4612.5 4707.4 4727.8 4751.4 4767.5 1957.3 2023.3 2032.1 2049.9 2086.6 775.4 870.3 901.6 998.6 1049.4 18912.6 20273.8 20369.0 20470.5 20583.7 914.8 929.2 951.5 963.6 1016.3 8093.9 10748.0 10786.5 10818.8 10837.7 604664.0 621411.0 625065.0 640655.0 642353.0 84609.0 135219.0 142662.0 143433.0 143756.0 936.0 987.5 991.7 1061.5 1083.3 17626.9 19418.8 19486.0 19495.1 19596.3 46251.0 47607.3 49455.3 51341.7 54738.5 74101.3 74396.3 74671.0 74671.3 76986.6 7242.8 7841.6 8129.8 8189.2 8511.6 9059.9 11903.1 12419.7 12770.7 12907.0 9491.0 10787.9 10837.3 10937.2 11070.2 9981.9 10672.8 10832.3 11105.9 11234.3 10996.9 11115.9 11128.4 11145.0 11215.4 8106.5 8108.5 8213.3 8367.1 8501.6 10688.8 10941.9 11205.0 11216.0 11227.4 08:29:19 INFO - PROCESS | 1671 | 08:29:19 INFO - PROCESS | 1671 | [#3] mootools.html Cycles:180 Average:5737.62 Median:4978.51 stddev:3160.53 (63.5%) stddev-sans-first:3153.10 08:29:19 INFO - PROCESS | 1671 | Values: 1466.6 1594.8 1823.3 1925.8 1932.6 7789.8 8578.6 8613.7 9159.0 9167.6 7547.0 8717.8 8734.6 8906.9 8910.9 2475.3 2505.9 2509.5 2515.9 2520.8 2458.3 2488.0 2496.4 2498.7 2526.4 2485.3 2492.3 2522.9 2523.7 2525.2 2431.2 2437.9 2439.9 2449.4 2450.7 2422.2 2427.6 2452.6 2452.9 2480.2 8854.0 8972.9 8974.4 8998.7 9004.0 4964.9 5437.9 5459.6 5474.2 5507.3 3630.6 3671.6 3685.2 3735.3 3735.9 925.4 1010.8 1052.3 1063.7 1071.9 9807.3 9972.4 11699.6 11860.6 11884.5 4865.9 4894.3 5011.9 5019.1 5046.6 9997.0 10013.3 10028.9 10050.4 10058.7 5392.5 5426.2 5469.9 5478.8 5510.7 4229.1 4448.4 4824.7 4957.9 4992.2 3517.4 4066.7 4073.1 4150.2 4187.0 1358.9 1372.0 1379.2 1385.9 1388.4 787.0 911.7 950.4 952.4 984.0 5298.7 5967.7 6116.9 6131.6 6172.5 478.2 479.5 483.0 486.5 488.4 3971.2 4079.8 4099.3 4144.5 4158.4 8460.7 10678.8 10836.9 10871.6 10923.4 10717.9 10753.2 10839.1 10866.3 10894.5 7993.7 8232.8 8262.8 8320.8 8370.9 5790.2 5949.9 5950.1 5962.2 6019.6 8963.1 9964.0 10103.6 10110.3 10132.7 9998.4 10032.0 10149.4 10319.2 10371.6 6365.4 6910.3 7328.0 7538.0 7568.0 8433.0 8460.5 8468.4 8550.7 8558.1 7284.6 7298.2 7414.3 7535.8 7556.4 7295.0 7412.0 7414.5 7447.9 7497.9 7061.9 7122.1 7143.1 7155.9 7167.0 4924.9 5069.8 5110.8 5117.7 5194.6 4939.5 5052.0 5073.4 5113.0 5298.5 08:29:19 INFO - PROCESS | 1671 | 08:29:19 INFO - PROCESS | 1671 | [#4] prototype.html Cycles:180 Average:4447.71 Median:4912.55 stddev:1360.68 (27.7%) stddev-sans-first:1328.52 08:29:19 INFO - PROCESS | 1671 | Values: 306.3 309.5 324.9 328.6 331.8 4514.5 5276.8 5345.7 5436.5 5574.5 4626.9 4823.3 5076.6 5455.0 5513.9 3650.3 4191.2 4200.3 4232.8 4274.8 3827.7 4095.3 4214.5 4220.3 4244.2 3349.4 4033.3 4048.7 4072.3 4094.8 3461.3 4069.4 4076.4 4109.3 4123.9 3058.7 3145.6 3284.0 3494.0 3558.6 4471.6 4705.5 4943.4 4995.0 5174.6 4683.2 4694.3 4701.9 4729.6 4742.1 4994.6 5222.1 5698.7 5809.9 5825.3 3184.7 3576.0 3738.8 3905.2 3915.4 5136.5 5567.9 5960.0 5967.7 6021.1 3500.5 3950.6 3957.7 3973.8 3979.8 3747.0 3774.7 3831.7 4039.6 4071.0 4450.8 4669.0 4695.2 4699.9 4701.8 4772.9 4812.0 5039.5 5430.3 5560.7 1158.9 1199.0 1280.7 1295.9 1298.4 749.2 877.6 887.2 894.0 894.5 4293.0 4377.2 4824.6 5032.5 5106.2 4881.7 4921.3 4964.6 5021.0 5086.5 4177.1 4562.6 4574.6 4781.5 4947.3 2644.4 2768.7 2872.7 2898.9 2974.4 5192.8 5262.8 5672.2 5922.8 5992.6 5110.9 5494.1 5646.9 5714.2 5783.7 5030.9 5182.0 5192.0 5505.2 5860.5 4808.4 5257.8 5622.8 5625.5 5688.4 4586.5 5280.6 5324.7 5598.7 5620.4 4928.0 5124.3 5340.9 5833.1 5938.8 3267.7 3488.4 3594.9 3728.9 3756.1 4957.0 5033.4 5281.8 5425.3 5658.2 4679.7 4903.8 5802.7 5814.4 5819.8 4873.0 5791.9 5811.4 5815.6 5830.4 5323.9 5665.0 5687.7 5710.9 5726.0 4960.9 4993.0 5112.4 5648.8 5859.9 4825.6 5286.0 5486.9 5524.1 5654.7 08:29:19 INFO - PROCESS | 1671 | 08:29:19 INFO - PROCESS | 1671 | [#5] yui.html Cycles:180 Average:17601.93 Median:29327.55 stddev:24567.36 (83.8%) stddev-sans-first:24602.82 08:29:19 INFO - PROCESS | 1671 | Values: 524.4 547.8 559.9 629.1 668.8 14366.1 15286.8 15389.3 15419.9 15452.8 13676.7 14035.0 14229.5 14295.9 14302.9 726.7 905.9 916.1 923.3 934.1 931.2 934.0 934.2 938.1 939.7 939.1 939.5 939.8 940.0 984.3 935.8 971.4 980.2 981.1 982.4 711.8 718.1 718.5 731.5 735.4 14848.9 14884.6 14966.5 14972.5 15189.7 12824.6 12904.0 12982.4 12998.4 13336.2 10137.3 10148.9 10285.9 10434.5 10643.6 2823.7 3250.6 3475.7 3501.5 3536.8 69018.0 79743.8 83125.6 85149.1 88111.7 9654.6 9664.0 9676.4 9749.0 9911.8 18518.8 19822.3 20303.1 21365.5 21618.0 7483.2 7615.5 7701.5 7836.1 7976.2 3680.1 4189.0 4218.5 4229.5 4392.6 3417.7 3639.2 3642.6 3644.0 3660.1 646.2 650.9 656.4 656.8 659.6 513.8 558.5 578.7 604.9 610.3 7501.5 7676.0 8076.5 8266.6 8304.4 524.9 543.2 543.5 609.7 610.9 3795.5 3811.6 3849.2 3858.6 4040.1 87500.0 89372.6 90635.0 91091.5 91378.0 62060.7 75827.0 77908.4 79571.0 80525.0 67630.0 68790.3 69716.3 69831.3 70055.6 7659.2 7669.8 7684.5 7753.5 7820.8 39501.6 41051.6 42007.2 42800.6 43107.6 55731.7 56572.2 57674.8 58076.4 59026.0 10151.5 10968.8 11029.3 11140.4 11506.3 9764.3 12371.1 12458.1 12508.0 12728.0 10553.6 11226.0 11475.3 11797.4 11960.2 11185.5 11606.9 11730.0 11956.6 12131.0 11057.1 11320.4 11438.8 11606.9 11674.3 7382.3 9331.9 9603.6 9654.3 9896.2 9419.9 9577.9 9728.1 9776.6 9871.3 08:29:19 INFO - PROCESS | 1671 | -------- Summary: end -------- 08:29:19 INFO - PROCESS | 1671 | 08:29:20 INFO - TEST-INFO | 1671: exit 0 08:29:20 INFO - TEST-OK | dromaeo_css | took 1112260ms 08:29:20 INFO - TEST-START | kraken 08:29:20 INFO - operating with platform_type : mac_ 08:29:20 INFO - Initialising browser for kraken test... 08:29:20 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/tmpgTp6Op/profile http://localhost:49277/getInfo.html) 08:29:23 INFO - PROCESS | 1680 | __metrics Screen width/height:1600/1200 08:29:23 INFO - PROCESS | 1680 | colorDepth:24 08:29:23 INFO - PROCESS | 1680 | Browser inner width/height: 1024/689 08:29:23 INFO - PROCESS | 1680 | __metrics 08:29:23 ERROR - PROCESS | 1680 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 08:29:23 INFO - TEST-INFO | 1680: exit 0 08:29:23 INFO - Browser initialized. 08:29:23 INFO - Running cycle 1/1 for kraken test... 08:29:23 INFO - TEST-INFO | started process 1683 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/tmpgTp6Op/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:29:35 INFO - PROCESS | 1683 | RSS: Main: 250015744 08:29:35 INFO - PROCESS | 1683 | 08:29:38 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 08:29:39 INFO - PROCESS | 1683 | RSS: Main: 261758976 08:29:39 INFO - PROCESS | 1683 | 08:29:42 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 08:29:43 INFO - PROCESS | 1683 | RSS: Main: 266432512 08:29:43 INFO - PROCESS | 1683 | 08:29:46 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 08:29:47 INFO - PROCESS | 1683 | RSS: Main: 266952704 08:29:47 INFO - PROCESS | 1683 | 08:29:49 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 08:29:50 INFO - PROCESS | 1683 | RSS: Main: 268181504 08:29:50 INFO - PROCESS | 1683 | 08:29:52 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:29:52 INFO - PROCESS | 1683 | RSS: Main: 268681216 08:29:52 INFO - PROCESS | 1683 | 08:29:55 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 08:29:56 INFO - PROCESS | 1683 | RSS: Main: 268148736 08:29:56 INFO - PROCESS | 1683 | 08:29:59 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 08:29:59 INFO - PROCESS | 1683 | RSS: Main: 268369920 08:29:59 INFO - PROCESS | 1683 | 08:30:02 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 08:30:03 INFO - PROCESS | 1683 | RSS: Main: 268382208 08:30:03 INFO - PROCESS | 1683 | 08:30:05 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:30:05 INFO - PROCESS | 1683 | RSS: Main: 268292096 08:30:05 INFO - PROCESS | 1683 | 08:30:07 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:30:08 INFO - PROCESS | 1683 | RSS: Main: 268337152 08:30:08 INFO - PROCESS | 1683 | 08:30:10 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:30:11 INFO - PROCESS | 1683 | RSS: Main: 268709888 08:30:11 INFO - PROCESS | 1683 | 08:30:14 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:30:14 INFO - PROCESS | 1683 | RSS: Main: 268763136 08:30:14 INFO - PROCESS | 1683 | 08:30:17 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:30:18 INFO - PROCESS | 1683 | RSS: Main: 268746752 08:30:18 INFO - PROCESS | 1683 | 08:30:19 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 08:30:20 INFO - PROCESS | 1683 | RSS: Main: 270348288 08:30:20 INFO - PROCESS | 1683 | 08:30:20 INFO - PROCESS | 1683 | __start_tp_report 08:30:20 INFO - PROCESS | 1683 | _x_x_mozilla_page_load 08:30:20 INFO - PROCESS | 1683 | _x_x_mozilla_page_load_details 08:30:20 INFO - PROCESS | 1683 | |i|pagename|runs| 08:30:20 INFO - PROCESS | 1683 | |0;ai-astar;143;171;124;116;118;160;148;145;172;142 08:30:20 INFO - PROCESS | 1683 | |1;audio-beat-detection;154;125;145;167;128;145;165;147;199;124 08:30:20 INFO - PROCESS | 1683 | |2;audio-dft;193;242;234;286;242;211;200;292;280;193 08:30:20 INFO - PROCESS | 1683 | |3;audio-fft;72;107;84;139;59;62;69;82;167;59 08:30:20 INFO - PROCESS | 1683 | |4;audio-oscillator;83;100;134;79;81;84;120;84;83;123 08:30:20 INFO - PROCESS | 1683 | |5;imaging-gaussian-blur;89;94;136;130;89;88;89;86;89;103 08:30:20 INFO - PROCESS | 1683 | |6;imaging-darkroom;96;94;99;155;94;95;95;98;94;94 08:30:20 INFO - PROCESS | 1683 | |7;imaging-desaturate;92;95;91;93;101;151;106;92;91;91 08:30:20 INFO - PROCESS | 1683 | |8;json-parse-financial;59;62;59;61;61;62;61;77;64;61 08:30:20 INFO - PROCESS | 1683 | |9;json-stringify-tinderbox;66;69;78;94;62;77;109;103;92;69 08:30:20 INFO - PROCESS | 1683 | |10;stanford-crypto-aes;88;88;81;88;113;108;114;85;88;89 08:30:20 INFO - PROCESS | 1683 | |11;stanford-crypto-ccm;142;143;144;127;155;146;154;148;189;144 08:30:20 INFO - PROCESS | 1683 | |12;stanford-crypto-pbkdf2;166;172;171;170;172;172;172;177;172;178 08:30:20 INFO - PROCESS | 1683 | |13;stanford-crypto-sha256-iterative;67;66;69;66;70;69;67;65;86;90 08:30:20 INFO - PROCESS | 1683 | __end_tp_report 08:30:20 INFO - PROCESS | 1683 | __start_cc_report 08:30:20 INFO - PROCESS | 1683 | _x_x_mozilla_cycle_collect,1066 08:30:20 INFO - PROCESS | 1683 | __end_cc_report 08:30:20 INFO - PROCESS | 1683 | __startTimestamp1462548620203__endTimestamp 08:30:20 INFO - PROCESS | 1683 | 08:30:20 INFO - PROCESS | 1683 | ------- Summary: start ------- 08:30:20 INFO - PROCESS | 1683 | Number of tests: 14 08:30:20 INFO - PROCESS | 1683 | 08:30:20 INFO - PROCESS | 1683 | [#0] ai-astar Cycles:10 Average:143.90 Median:146.50 stddev:20.14 (13.7%) stddev-sans-first:21.36 08:30:20 INFO - PROCESS | 1683 | Values: 143.0 171.0 124.0 116.0 118.0 160.0 148.0 145.0 172.0 142.0 08:30:20 INFO - PROCESS | 1683 | 08:30:20 INFO - PROCESS | 1683 | [#1] audio-beat-detection Cycles:10 Average:149.90 Median:150.50 stddev:23.03 (15.3%) stddev-sans-first:24.38 08:30:20 INFO - PROCESS | 1683 | Values: 154.0 125.0 145.0 167.0 128.0 145.0 165.0 147.0 199.0 124.0 08:30:20 INFO - PROCESS | 1683 | 08:30:20 INFO - PROCESS | 1683 | [#2] audio-dft Cycles:10 Average:237.30 Median:242.00 stddev:38.37 (15.9%) stddev-sans-first:37.20 08:30:20 INFO - PROCESS | 1683 | Values: 193.0 242.0 234.0 286.0 242.0 211.0 200.0 292.0 280.0 193.0 08:30:20 INFO - PROCESS | 1683 | 08:30:20 INFO - PROCESS | 1683 | [#3] audio-fft Cycles:10 Average:90.00 Median:65.50 stddev:36.80 (56.2%) stddev-sans-first:38.45 08:30:20 INFO - PROCESS | 1683 | Values: 72.0 107.0 84.0 139.0 59.0 62.0 69.0 82.0 167.0 59.0 08:30:20 INFO - PROCESS | 1683 | 08:30:20 INFO - PROCESS | 1683 | [#4] audio-oscillator Cycles:10 Average:97.10 Median:82.00 stddev:20.80 (25.4%) stddev-sans-first:21.42 08:30:20 INFO - PROCESS | 1683 | Values: 83.0 100.0 134.0 79.0 81.0 84.0 120.0 84.0 83.0 123.0 08:30:20 INFO - PROCESS | 1683 | 08:30:20 INFO - PROCESS | 1683 | [#5] imaging-gaussian-blur Cycles:10 Average:99.30 Median:89.00 stddev:18.44 (20.7%) stddev-sans-first:19.18 08:30:20 INFO - PROCESS | 1683 | Values: 89.0 94.0 136.0 130.0 89.0 88.0 89.0 86.0 89.0 103.0 08:30:20 INFO - PROCESS | 1683 | 08:30:20 INFO - PROCESS | 1683 | [#6] imaging-darkroom Cycles:10 Average:101.40 Median:95.00 stddev:18.92 (19.9%) stddev-sans-first:19.96 08:30:20 INFO - PROCESS | 1683 | Values: 96.0 94.0 99.0 155.0 94.0 95.0 95.0 98.0 94.0 94.0 08:30:20 INFO - PROCESS | 1683 | 08:30:20 INFO - PROCESS | 1683 | [#7] imaging-desaturate Cycles:10 Average:100.30 Median:91.50 stddev:18.51 (20.2%) stddev-sans-first:19.38 08:30:20 INFO - PROCESS | 1683 | Values: 92.0 95.0 91.0 93.0 101.0 151.0 106.0 92.0 91.0 91.0 08:30:20 INFO - PROCESS | 1683 | 08:30:20 INFO - PROCESS | 1683 | [#8] json-parse-financial Cycles:10 Average:62.70 Median:61.50 stddev:5.23 (8.5%) stddev-sans-first:5.37 08:30:20 INFO - PROCESS | 1683 | Values: 59.0 62.0 59.0 61.0 61.0 62.0 61.0 77.0 64.0 61.0 08:30:20 INFO - PROCESS | 1683 | 08:30:20 INFO - PROCESS | 1683 | [#9] json-stringify-tinderbox Cycles:10 Average:81.90 Median:73.00 stddev:16.50 (22.6%) stddev-sans-first:16.46 08:30:20 INFO - PROCESS | 1683 | Values: 66.0 69.0 78.0 94.0 62.0 77.0 109.0 103.0 92.0 69.0 08:30:20 INFO - PROCESS | 1683 | 08:30:20 INFO - PROCESS | 1683 | [#10] stanford-crypto-aes Cycles:10 Average:94.20 Median:88.00 stddev:12.36 (14.0%) stddev-sans-first:12.91 08:30:20 INFO - PROCESS | 1683 | Values: 88.0 88.0 81.0 88.0 113.0 108.0 114.0 85.0 88.0 89.0 08:30:20 INFO - PROCESS | 1683 | 08:30:20 INFO - PROCESS | 1683 | [#11] stanford-crypto-ccm Cycles:10 Average:149.20 Median:147.00 stddev:15.95 (10.9%) stddev-sans-first:16.70 08:30:20 INFO - PROCESS | 1683 | Values: 142.0 143.0 144.0 127.0 155.0 146.0 154.0 148.0 189.0 144.0 08:30:20 INFO - PROCESS | 1683 | 08:30:20 INFO - PROCESS | 1683 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.20 Median:172.00 stddev:3.36 (2.0%) stddev-sans-first:2.71 08:30:20 INFO - PROCESS | 1683 | Values: 166.0 172.0 171.0 170.0 172.0 172.0 172.0 177.0 172.0 178.0 08:30:20 INFO - PROCESS | 1683 | 08:30:20 INFO - PROCESS | 1683 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.50 Median:69.00 stddev:8.89 (12.9%) stddev-sans-first:9.27 08:30:20 INFO - PROCESS | 1683 | Values: 67.0 66.0 69.0 66.0 70.0 69.0 67.0 65.0 86.0 90.0 08:30:20 INFO - PROCESS | 1683 | -------- Summary: end -------- 08:30:20 INFO - PROCESS | 1683 | 08:30:20 INFO - TEST-INFO | 1683: exit 0 08:30:20 INFO - TEST-OK | kraken | took 60853ms 08:30:20 INFO - SUITE-END | took 1173s 08:30:21 INFO - Completed test suite (00:19:33) 08:30:21 INFO - javascript benchmark 08:30:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3490.1623610333495, 3492.6856582907535, 3531.2937412517485, 3537.8584856605735, 3553.342631613151, 5725.599208055438, 5878.662962631058, 5928.2886053673565, 5936.406359364072, 5961.254843144607, 5774.913376299349, 5816.418358164182, 5820.883582328351, 5823.039198532248, 5885.764569417229, 5194.92207616886, 5243.082460569404, 5285.656432331886, 5326.147816349385, 5336.866578335534, 435.69939847305517, 5245.868853278679, 5294.391145018314, 5304.734763261841, 5319.388270348902, 5285.154375299952, 5288.2596436498925, 5302.072137375943, 5325.854941187644, 5350.224217488481, 5306.257124002643, 5348.973255133699, 5363.704996225206, 5385.111456609694, 5401.081816091274, 4973.477784832613, 5163.66436181647, 5193.039287731782, 5229.712365819878, 5247.396549396792, 4762.4285085789925, 5646.378898321182, 5676.659400435987, 5710.971445142789, 5799.985002624566, 5466.480684334982, 6077.24034495688, 6102.428659372087, 6320.399562041599, 6323.93676063238, 6238.344973777779, 6251.093591429263, 6314.431701146918, 6332.05019247112, 6341.112244285804, 6018.740509869686, 6027.186329845458, 6030.005049166838, 6032.095185722133, 6048.123022161806, 5687.1753595729215, 5971.104334349882, 6032.728527216286, 6346.714397852108, 6389.968050159812, 6149.84625384369, 6159.4456498915315, 6174.938250617526, 6186.1648677428875, 6254.6872656366995, 6271.96864015677, 6291.245050593958, 6306.369363063749, 6340.239171299474, 6343.777967771198, 6087.4521293083835, 6109.358517355702, 6130.693465326716, 6275.435210831047, 6348.77779277723, 5164.561012313995, 6049.758009679564, 6120.968863631887, 6140.109684095781, 6194.225721784777, 3894.6494815466745, 4757.881052973703, 4866.367372241586, 4867.221244600847, 4896.853094407174, 3625.4380571011534, 4380.496242932104, 4432.3794668746405, 4501.932471012938, 4522.997147524465, 5601.383847776741, 5877.853053673693, 5940.079287710412, 5997.820065398045, 6003.129546215774, 6291.5281353898645, 6294.370562943669, 6315.147455093595, 6330.493560515148, 6335.936640633535, 5871.3541510433815, 5948.196993405835, 5980.132880732356, 5995.100734889801, 6034.969825150846, 5455.618106732491, 5504.366997795305, 5509.035918714289, 5511.834644960658, 5539.750711217925, 6306.527010474141, 6517.869642607121, 6517.934820651828, 6532.477401807939, 6540.9345906541275, 5249.0, 6196.690165491797, 6227.470664993345, 6273.843153921006, 6283.2460104787715, 6442.452391546771, 6443.6133831968355, 6457.321981191907, 6485.44873685742, 6525.347465253437, 6148.846278843064, 6187.102870083902, 6259.405356491126, 6331.113644089924, 6341.524385671001, 6208.348123446973, 6238.75044998195, 6321.905171422341, 6346.4605508532295, 6356.9046464304, 5296.708681022426, 6233.594816336924, 6268.153799237227, 6268.216472940882, 6274.59215151032, 6066.15073889647, 6119.654885717031, 6131.325554188947, 6223.066540018853, 6224.128621993015, 4802.327674125555, 4860.465348811698, 5886.378508524434, 6027.397260273938, 6120.479759220516, 6190.81427557156, 6225.315215326401, 6251.187345645037, 6251.781187658592, 6263.060540918898, 5884.852878677896, 5986.371430999682, 6008.579399441997, 6066.848328791815, 6098.0], "value": 5693.285349456462, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [476.8188088526359, 507.40633458853137, 538.6714104396317, 540.7296351824087, 554.7974989128968, 38501.84494465167, 40740.0, 40765.7961710191, 40772.59227407725, 40792.592074079286, 41596.376054359134, 41788.791056044756, 42108.36837447433, 42350.57649423505, 42467.0, 9513.476758778264, 9640.228781697482, 9703.22374210065, 9721.70834874955, 9724.75688107796, 9511.619535218617, 9527.571259293349, 9561.474118923456, 9565.234781217487, 9581.808363832719, 9552.522373881313, 9563.617455301779, 9568.712938611818, 9659.951700241489, 9677.661281855137, 8749.606267717905, 9171.85351831021, 9546.140847323704, 9624.951875240615, 9679.564419601134, 9467.715968520955, 9484.383515071568, 9487.288453365956, 9492.857607135898, 9613.423194608346, 38496.230075398336, 38520.80739596312, 38865.222695545934, 38890.833275001794, 38901.610983890365, 2792.8463934483593, 2810.9578356324673, 3102.30198205402, 3344.66553344666, 3374.4432168692138, 26208.86895565529, 27214.0, 27564.448711025867, 27702.5844612331, 27868.581971270447, 2598.662173917379, 2621.5936529837713, 2628.54000549903, 2654.349684327333, 2707.6344693466326, 500679.0, 504300.0, 507464.0, 508536.0, 509186.454067731, 61824.07263891045, 63311.0, 63355.36644633495, 63440.73118537603, 63457.0, 35416.93749187477, 35892.82053589715, 36246.91259262226, 38087.23825523474, 38119.0, 17171.570710732332, 17233.965962042068, 17352.219150138273, 17379.913100434416, 17381.565460863327, 13223.73552528944, 13236.669083272995, 13309.866901331057, 13347.199168049949, 13376.264305463294, 19107.426777196706, 19224.903875480533, 19379.224831006883, 19517.571199888396, 19563.51091222731, 8225.753227403187, 8315.251627353568, 8336.20771534123, 8370.79073023167, 8394.538300393537, 926.4811705444972, 1032.143321043532, 1038.200585549138, 1056.4612047855496, 1099.8845121262152, 28481.715182847907, 28844.423111537653, 29313.853430733136, 29625.11307581209, 29811.254718631775, 929.846575315079, 1091.47063674118, 1101.1631160318216, 1113.1095123900848, 1175.4945373489481, 12421.44103515344, 14496.565103046925, 15057.698846023237, 15174.620634484227, 15214.847851521565, 645128.0, 649240.0, 662089.0, 662993.0, 663327.0, 57129.0, 58535.707321463124, 58614.70692646509, 59106.70446647739, 59330.11004834931, 1445.7253121907015, 1453.651123730297, 1462.7805829125716, 1472.425753955958, 1482.5181815909873, 28899.277518062216, 29331.41337173202, 29684.406311874074, 29752.851235744547, 29830.8508457465, 41833.0, 50784.492155077976, 57356.13965790433, 59014.0, 59200.407995919486, 626888.0, 634014.0, 634153.0, 640332.0, 642107.0, 38649.80675096607, 39806.80096599498, 41934.370984434645, 42349.78825105855, 42700.0, 39895.80052099721, 45889.770551147034, 45965.0, 46402.0, 47337.34319298946, 37183.628163718015, 38265.043373915876, 38576.614233858425, 38582.0, 39446.60553394429, 35150.824245878604, 37296.44055339118, 37496.43755343727, 38190.42714359342, 38281.0, 36432.27135457329, 36959.44560831643, 37077.44383834299, 37607.43588846224, 37722.62277377191, 22085.779142208372, 32796.1800954978, 33359.83240586665, 33530.49704254487, 33545.32909341751, 24814.503709926063, 24986.0, 25044.248672539852, 25105.74894251034, 25124.371890702878], "value": 19866.005681640112, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.418509459392, 394.3650722337037, 398.83234619841147, 401.3417994489038, 417.94148819165304, 14721.55835324942, 19929.40211793645, 20620.1751929923, 21122.943852807373, 21158.365249042534, 21117.788822111776, 21196.57606847866, 21210.939453027324, 21895.890520547415, 21923.232686855965, 5536.584756143286, 5949.88100237995, 6013.669248191348, 6019.307779605358, 6024.1566180734735, 5647.152927060933, 5846.327672317695, 5997.49021333185, 5998.520118390518, 6018.3078945921125, 5789.4500022498005, 6061.636301821904, 6079.270487541501, 6084.0, 6086.208792856923, 6087.2999605045325, 6107.236595425572, 6139.324674285825, 6144.8771024579255, 6148.569600127993, 5668.461496157858, 5889.646621202742, 5920.97039514804, 5943.851403714899, 5952.672603006833, 13110.344482775918, 13250.204987700672, 13251.536196233086, 13348.466061357534, 23486.177983770485, 2038.8063134002393, 2092.665173572221, 2126.51090249242, 2238.0488292475634, 2296.9655455168186, 8587.0, 9703.563339649732, 9898.802023959552, 10197.439140847251, 13511.594652160353, 1700.948971530856, 1738.6609611125837, 1743.834335738103, 1769.840714335703, 1851.1392202625843, 97278.0, 130073.34963325123, 130626.0, 133006.33496832455, 133614.0, 9236.815263694689, 11523.596674116367, 11899.583514576949, 11952.58165964187, 11968.581099661471, 15200.0, 15249.313780879887, 15269.00751451152, 15280.235988200546, 15296.311665974836, 6977.581345119309, 8797.252233560092, 8896.555172241491, 8963.372563920462, 9011.63953441868, 8823.02946675878, 8830.690925817566, 8846.15961483658, 8911.598978046004, 8927.196552310194, 4612.492625811158, 4707.388039554904, 4727.810887564528, 4751.429828420612, 4767.498238055002, 1957.2953736654792, 2023.3221870673194, 2032.085561497332, 2049.8872562009233, 2086.603545326383, 775.3680750188578, 870.3472395703222, 901.6123067081218, 998.605550807427, 1049.3651340938775, 18912.621747564972, 20273.79726202719, 20368.98155092269, 20470.488237793877, 20583.691244631344, 914.8124634449922, 929.2240978782792, 951.5147274889717, 963.5808423335872, 1016.2987538598344, 8093.899130710367, 10747.978942000498, 10786.460676966277, 10818.837717434088, 10837.67486975392, 604664.0, 621411.0, 625065.0, 640655.0, 642353.0, 84609.0, 135219.0, 142662.0, 143433.0, 143755.9999999979, 935.9812803743887, 987.5309228116731, 991.7355371900884, 1061.532925512772, 1083.2958576924905, 17626.942383457033, 19418.8058119417, 19486.0, 19495.139091804645, 19596.314129005415, 46251.0, 47607.28589071098, 49455.25817112818, 51341.71645708887, 54738.45261547333, 74101.2589874116, 74396.25603743893, 74671.0, 74671.25328746643, 76986.6150669243, 7242.782716518513, 7841.5687137208, 8129.821175929339, 8189.22202390772, 8511.616977236163, 9059.864102038342, 11903.10726695484, 12419.689507762017, 12770.744585108432, 12907.031972601904, 9490.952545237229, 10787.946060269649, 10837.295575787548, 10937.179711521509, 11070.225084244026, 9981.900180998096, 10672.83990740155, 10832.295900766425, 11105.944470277596, 11234.3259404436, 10996.914713147586, 11115.88841115882, 11128.38793866345, 11145.0, 11215.383153926612, 8106.47307924983, 8108.472949258279, 8213.260806527442, 8367.121452247426, 8501.574921254036, 10688.839667405151, 10941.945290273497, 11205.04757095656, 11216.046636035702, 11227.38249396291], "value": 9464.272949496146, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1466.6406730351066, 1594.7687585300134, 1823.2524664887399, 1925.7689077310715, 1932.5844943337179, 7789.766307010785, 8578.571071446433, 8613.741587752369, 9159.038300978402, 9167.587458564356, 7547.018887544626, 8717.782055448617, 8734.5632718364, 8906.86639700405, 8910.866337004934, 2475.319287196022, 2505.8621775802326, 2509.5231905937903, 2515.949681006379, 2520.785733212679, 2458.3485376375274, 2487.9751202488014, 2496.4133428644254, 2498.7376325485834, 2526.418923647562, 2485.3165379520597, 2492.2772396005225, 2522.8899284314843, 2523.671922650053, 2525.2045605634257, 2431.1612493689736, 2437.8659173745436, 2439.890204940782, 2449.4121410861444, 2450.742672019439, 2422.1643532981093, 2427.5751743445003, 2452.583060879637, 2452.901883924641, 2480.218731099698, 8853.955730221373, 8972.8654070189, 8974.416662916954, 8998.685046023424, 9003.954980225122, 4964.87587810304, 5437.945620543823, 5459.645123066988, 5474.151506516497, 5507.339119305709, 3630.5643322801434, 3671.596124426311, 3685.2261025184744, 3735.3089678409583, 3735.9439608405896, 925.3753716241511, 1010.7523656704154, 1052.2690174662637, 1063.6596289187464, 1071.8928107189297, 9807.31348805584, 9972.351797133164, 11699.590514331958, 11860.64418067468, 11884.465199065977, 4865.9026819464125, 4894.3147959285725, 5011.949880501221, 5019.121653710586, 5046.646734728607, 9997.0, 10013.29906906524, 10028.949855250678, 10050.396976181453, 10058.748531286776, 5392.487713667196, 5426.158945363475, 5469.917951230735, 5478.753456094485, 5510.669359838423, 4229.13302772937, 4448.421705178371, 4824.710517368969, 4957.90084198314, 4992.15133427311, 3517.402041652874, 4066.7356621820077, 4073.103917138225, 4150.232207041707, 4186.995121170897, 1358.864113588653, 1372.025861638248, 1379.2069560003038, 1385.8752712255946, 1388.3682924269433, 786.9842603147905, 911.7355966769695, 950.3917492804611, 952.3524003677566, 984.0258144437099, 5298.682079075268, 5967.731452084667, 6116.908246376308, 6131.601445906002, 6172.475339596095, 478.23004962011134, 479.4917387569188, 482.99275510866664, 486.5159166629227, 488.36024807502287, 3971.185906889081, 4079.817742161119, 4099.282625540519, 4144.5026596807975, 4158.438610787565, 8460.703875364456, 10678.786424271471, 10836.945815270874, 10871.619493317854, 10923.3992130432, 10717.892821071846, 10753.247272690993, 10839.078678312431, 10866.348019118877, 10894.455277236106, 7993.72021979228, 8232.765085237263, 8262.793430164174, 8320.791980200544, 8370.916290837014, 5790.160426738184, 5949.940500594938, 5950.077737950624, 5962.165296858357, 6019.578629496068, 8963.148500892405, 9963.950180249052, 10103.595856165672, 10110.292279540656, 10132.696019119143, 9998.400095994264, 10032.046955539212, 10149.441780701836, 10319.226058045826, 10371.636992705218, 6365.395287447685, 6910.343517365842, 7328.047353843966, 7538.0, 7568.0, 8433.0, 8460.534670593177, 8468.44955077918, 8550.657973680984, 8558.144185581392, 7284.599347035964, 7298.197198308288, 7414.295641914009, 7535.849283014419, 7556.433267504849, 7295.0, 7412.0, 7414.518056326321, 7447.851042979001, 7497.887531687138, 7061.8940715888275, 7122.074130363019, 7143.107111611049, 7155.892661609976, 7166.964165179141, 4924.852254432372, 5069.822556210515, 5110.770015349392, 5117.667351622133, 5194.636375453682, 4939.48135445773, 5051.974740126276, 5073.3911930568565, 5112.9744351278005, 5298.523132918056], "value": 4581.61886863392, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.287880677425, 309.5202436223854, 324.9236429439082, 328.5662924939081, 331.8208167589502, 4514.458265008199, 5276.7573781142955, 5345.732713364336, 5436.537894278986, 5574.526165275946, 4626.86119416418, 4823.348847905531, 5076.64463487556, 5455.0453670607685, 5513.889722205554, 3650.3064417760543, 4191.1617676464675, 4200.348945913388, 4232.8359701082145, 4274.807633656477, 3827.655511004009, 4095.28332541804, 4214.494260688722, 4220.324748040314, 4244.1511697660435, 3349.363620912019, 4033.3143365627916, 4048.6761059115347, 4072.2873497137884, 4094.795260236991, 3461.3077384523194, 4069.4302797608357, 4076.388541718736, 4109.321961876287, 4123.876283711494, 3058.7247147756584, 3145.575347328127, 3284.0, 3493.965060349389, 3558.5729712434672, 4471.575200355961, 4705.529447055302, 4943.431505376891, 4994.9750251248515, 5174.567953062055, 4683.227267500858, 4694.2958556216845, 4701.882452938669, 4729.6452766042685, 4742.103158736533, 4994.55049045584, 5222.0729472049115, 5698.715064246771, 5809.912851307234, 5825.300963884319, 3184.6656101109515, 3576.0, 3738.756980796267, 3905.1994341159993, 3915.3555506687258, 5136.512031357052, 5567.916481252784, 5960.046392577166, 5967.701614919237, 6021.096835474714, 3500.5099286099967, 3950.6370302245678, 3957.6635985941516, 3973.841046358113, 3979.801009949491, 3746.981265093657, 3774.6602805747616, 3831.662685761683, 4039.555648878621, 4071.0, 4450.755208463501, 4669.0, 4695.178343789823, 4699.906001880012, 4701.811927522861, 4772.949951010772, 4812.037592481518, 5039.546440820343, 5430.294061771944, 5560.694161821139, 1158.9420528973517, 1199.0287866827898, 1280.737448822989, 1295.9049603085377, 1298.3832679477173, 749.1907044487501, 877.5827039331434, 887.2236792806294, 894.0389081737159, 894.4722613657843, 4292.978535107367, 4377.163234625757, 4824.589909857701, 5032.521910418504, 5106.208537676592, 4881.658283920163, 4921.26181072842, 4964.553190212898, 5020.974895125501, 5086.4659210783075, 4177.057668184309, 4562.634989200856, 4574.627611716472, 4781.52184781526, 4947.282644016597, 2644.418227989839, 2768.6816016157995, 2872.6983666714696, 2898.942021159565, 2974.3902499987453, 5192.792288308501, 5262.7631756571045, 5672.19962625034, 5922.838164070084, 5992.58051936369, 5110.897782044338, 5494.066008778517, 5646.85882852932, 5714.171445140514, 5783.652980821164, 5030.868054687666, 5181.96360727856, 5191.9751296993445, 5505.201745746845, 5860.501857342174, 4808.3989501312335, 5257.763400646903, 5622.831315060391, 5625.549956003591, 5688.354914964561, 4586.518415566317, 5280.574244953764, 5324.653897496651, 5598.720063996865, 5620.358431537171, 4927.989762098833, 5124.308218390619, 5340.917042353408, 5833.095870140135, 5938.792142275, 3267.6732326767133, 3488.424409972326, 3594.928101438009, 3728.9422673407544, 3756.13608869956, 4956.975215123901, 5033.421156567042, 5281.823812663534, 5425.348958124892, 5658.236138121301, 4679.672422930362, 4903.828366007173, 5802.723400851806, 5814.360420353673, 5819.76720931158, 4872.9512704872495, 5791.942080579309, 5811.4479124483105, 5815.59787524303, 5830.4461076197695, 5323.866903327448, 5664.971675141598, 5687.691830879004, 5710.942890571041, 5725.971370143123, 4960.925586116284, 4992.975035124801, 5112.440705584832, 5648.78551111513, 5859.853503662443, 4825.638077144298, 5286.0, 5486.875190585841, 5524.06090964545, 5654.660720356792], "value": 4017.219968137077, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [524.4283730733499, 547.8329109621563, 559.9048161812492, 629.1129507394573, 668.7692746002627, 14366.066205696636, 15286.84713152868, 15389.307481163347, 15419.922900385496, 15452.845471545295, 13676.658083547916, 14035.0, 14229.501967431142, 14295.927805414583, 14302.856971430283, 726.7274771960515, 905.8505346617833, 916.083916083916, 923.2844545477275, 934.0939288889766, 931.2317338195982, 934.0146145816161, 934.2059249637822, 938.052566907425, 939.7321763297462, 939.0750111140521, 939.492673956063, 939.8402271613842, 939.9624015039391, 984.3257368702461, 935.7613808478828, 971.4414211828192, 980.240313756837, 981.11209355533, 982.40564458503, 711.8469529051227, 718.0916141081536, 718.5042320798684, 731.5171986488892, 735.3859527294662, 14848.925755371261, 14884.62788430287, 14966.47617333399, 14972.47596334134, 15189.696206075927, 12824.551140710124, 12904.0, 12982.415791289415, 12998.350082495837, 13336.199828010253, 10137.290389672726, 10148.89851101487, 10285.897141028569, 10434.530446129942, 10643.57425702971, 2823.7228373908156, 3250.5859950921426, 3475.739319551044, 3501.527293815328, 3536.840842162109, 69018.0, 79743.8038429424, 83125.58437207836, 85149.14850851474, 88111.67832482517, 9654.613815447303, 9664.0, 9676.371035882645, 9748.951255243679, 9911.80176396468, 18518.796278241873, 19822.306219282254, 20303.086361113787, 21365.46586335323, 21618.0, 7483.214262502468, 7615.504992175491, 7701.499402538817, 7836.059672839182, 7976.162502937224, 3680.0799800049986, 4188.979055104766, 4218.514870789837, 4229.513605935295, 4392.626626736763, 3417.743669224818, 3639.1993761372987, 3642.6357364263363, 3643.9796856880116, 3660.139867131222, 646.2245305633165, 650.869826034795, 656.3666062249952, 656.8029591122644, 659.6009414304373, 513.7970501651827, 558.4917724870348, 578.6759414727766, 604.9062395328731, 610.2676787854592, 7501.549907005488, 7675.961620191975, 8076.515409075474, 8266.586670666442, 8304.418690691593, 524.9448807875194, 543.234040003593, 543.5054100768282, 609.740860134441, 610.9038894214283, 3795.525559305087, 3811.6378944000276, 3849.1531862990096, 3858.6141385861188, 4040.070783719761, 87500.0, 89372.5531372352, 90635.0, 91091.54454227819, 91378.0, 62060.68969655213, 75827.0, 77908.4418311642, 79571.0, 80525.0, 67630.0, 68790.31209687839, 69716.30283697098, 69831.30168698248, 70055.64972175208, 7659.195784442776, 7669.81277276043, 7684.46208765381, 7753.4960227585025, 7820.765377038698, 39501.604983949794, 41051.56319528802, 42007.1598568033, 42800.5719942809, 43107.568924310355, 55731.72134139304, 56572.15141772959, 57674.84650306887, 58076.41923580879, 59026.0, 10151.543180556744, 10968.780624387628, 11029.283096598696, 11140.387278699749, 11506.252093613888, 9764.267679923893, 12371.072169587496, 12458.127931044739, 12508.0, 12728.0, 10553.63062292816, 11226.0, 11475.254108482923, 11797.35114568707, 11960.162788604714, 11185.496652650487, 11606.88393116058, 11730.0, 11956.641300760992, 12131.0, 11057.06014988735, 11320.37737924422, 11438.828417573577, 11606.94196529012, 11674.299542027165, 7382.298681625237, 9331.860022099538, 9603.567839447382, 9654.27592930519, 9896.158826499828, 9419.905800942177, 9577.856332154885, 9728.075832795874, 9776.560054797408, 9871.25965552572], "value": 6784.884102388141, "unit": "score"}], "name": "dromaeo_css", "value": 7150.708066282592, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 171.0, 124.0, 116.0, 118.0, 160.0, 148.0, 145.0, 172.0, 142.0], "value": 143.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 125.0, 145.0, 167.0, 128.0, 145.0, 165.0, 147.0, 199.0, 124.0], "value": 149.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 242.0, 234.0, 286.0, 242.0, 211.0, 200.0, 292.0, 280.0, 193.0], "value": 237.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 107.0, 84.0, 139.0, 59.0, 62.0, 69.0, 82.0, 167.0, 59.0], "value": 90.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 100.0, 134.0, 79.0, 81.0, 84.0, 120.0, 84.0, 83.0, 123.0], "value": 97.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 94.0, 136.0, 130.0, 89.0, 88.0, 89.0, 86.0, 89.0, 103.0], "value": 99.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 94.0, 99.0, 155.0, 94.0, 95.0, 95.0, 98.0, 94.0, 94.0], "value": 101.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 95.0, 91.0, 93.0, 101.0, 151.0, 106.0, 92.0, 91.0, 91.0], "value": 100.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 62.0, 59.0, 61.0, 61.0, 62.0, 61.0, 77.0, 64.0, 61.0], "value": 62.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 78.0, 94.0, 62.0, 77.0, 109.0, 103.0, 92.0, 69.0], "value": 81.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 81.0, 88.0, 113.0, 108.0, 114.0, 85.0, 88.0, 89.0], "value": 94.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 143.0, 144.0, 127.0, 155.0, 146.0, 154.0, 148.0, 189.0, 144.0], "value": 149.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 172.0, 171.0, 170.0, 172.0, 172.0, 172.0, 177.0, 172.0, 178.0], "value": 172.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 69.0, 66.0, 70.0, 69.0, 67.0, 65.0, 86.0, 90.0], "value": 71.5, "unit": "score"}], "name": "kraken", "value": 1650.9, "alertThreshold": 2.0}]} 08:30:21 INFO - Return code: 0 08:30:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 08:30:21 ERROR - # TBPL SUCCESS # 08:30:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:30:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:30:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:30:21 INFO - 2016-05-06 08:30:21.484 screenresolution[1689:10473] starting screenresolution argv=screenresolution get 08:30:21 INFO - 2016-05-06 08:30:21.486 screenresolution[1689:10473] Display 0: 1600x1200x32@60 08:30:21 INFO - 2016-05-06 08:30:21.492 screenresolution[1690:10476] starting screenresolution argv=screenresolution list 08:30:21 INFO - Available Modes on Display 0 08:30:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:30:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:30:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:30:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:30:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:30:21 INFO - Intel Iris: 08:30:21 INFO - Chipset Model: Intel Iris 08:30:21 INFO - Type: GPU 08:30:21 INFO - Bus: Built-In 08:30:21 INFO - VRAM (Dynamic, Max): 1536 MB 08:30:21 INFO - Vendor: Intel (0x8086) 08:30:21 INFO - Device ID: 0x0a2e 08:30:21 INFO - Revision ID: 0x0009 08:30:21 INFO - Displays: 08:30:21 INFO - Display: 08:30:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:30:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:30:21 INFO - Display Serial Number: 1600x1200 60 08:30:21 INFO - Main Display: Yes 08:30:21 INFO - Mirror: Off 08:30:21 INFO - Online: Yes 08:30:21 INFO - Rotation: Supported 08:30:21 INFO - Return code: 0 08:30:21 INFO - Running post-action listener: _resource_record_post_action 08:30:21 INFO - ##### 08:30:21 INFO - ##### Finished run-tests step (success) 08:30:21 INFO - ##### 08:30:21 INFO - Running post-run listener: _resource_record_post_run 08:30:22 INFO - Total resource usage - Wall time: 1208s; CPU: 55.0%; Read bytes: 38090240; Write bytes: 698693632; Read time: 394; Write time: 1923 08:30:22 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 383328768; Write bytes: 372701184; Read time: 28352; Write time: 761 08:30:22 INFO - run-tests - Wall time: 1176s; CPU: 55.0%; Read bytes: 31829504; Write bytes: 298962944; Read time: 311; Write time: 1106 08:30:22 INFO - Running post-run listener: _upload_blobber_files 08:30:22 INFO - Blob upload gear active. 08:30:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:30:22 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 08:30:22 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'] 08:30:22 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 08:30:23 INFO - (blobuploader) - INFO - Open directory for files ... 08:30:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:30:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:30:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:30:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:30:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:30:24 INFO - (blobuploader) - INFO - Done attempting. 08:30:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:30:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:30:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:30:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:30:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:30:25 INFO - (blobuploader) - INFO - Done attempting. 08:30:25 INFO - (blobuploader) - INFO - Iteration through files over. 08:30:25 INFO - Return code: 0 08:30:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:30:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:30:25 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6f88340ef8dd947466be5da213ad33ac46c69f2d9e133227135ffe67e1398ac2375e005124cb942f2c8d54290385709d3cfa41e88ebf241a8c39fe2c7fa5de3a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/067edfd4cde3bf523425c7520692c425c293c627987056579af8c03ad9ab7f1eb074830d586db5baa5953bb9a77b2422e0975f63dda8168054b9f1b9988d244f"} 08:30:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:30:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:30:25 INFO - Contents: 08:30:25 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6f88340ef8dd947466be5da213ad33ac46c69f2d9e133227135ffe67e1398ac2375e005124cb942f2c8d54290385709d3cfa41e88ebf241a8c39fe2c7fa5de3a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/067edfd4cde3bf523425c7520692c425c293c627987056579af8c03ad9ab7f1eb074830d586db5baa5953bb9a77b2422e0975f63dda8168054b9f1b9988d244f"} 08:30:25 INFO - Running post-run listener: copy_logs_to_upload_dir 08:30:25 INFO - Copying logs to upload dir... 08:30:25 INFO - mkdir: /builds/slave/test/build/upload/logs 08:30:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.969706 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-05-06 08:30:25.287248) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 08:30:25.290960) ========= 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.73Bqw6ErkE/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.UQrJ1X9zHF/Listeners TMPDIR=/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6f88340ef8dd947466be5da213ad33ac46c69f2d9e133227135ffe67e1398ac2375e005124cb942f2c8d54290385709d3cfa41e88ebf241a8c39fe2c7fa5de3a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/067edfd4cde3bf523425c7520692c425c293c627987056579af8c03ad9ab7f1eb074830d586db5baa5953bb9a77b2422e0975f63dda8168054b9f1b9988d244f"} build_url:https://queue.taskcluster.net/v1/task/fYTw48n9Rsu4LSGDbhEtbg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011291 build_url: 'https://queue.taskcluster.net/v1/task/fYTw48n9Rsu4LSGDbhEtbg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6f88340ef8dd947466be5da213ad33ac46c69f2d9e133227135ffe67e1398ac2375e005124cb942f2c8d54290385709d3cfa41e88ebf241a8c39fe2c7fa5de3a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/067edfd4cde3bf523425c7520692c425c293c627987056579af8c03ad9ab7f1eb074830d586db5baa5953bb9a77b2422e0975f63dda8168054b9f1b9988d244f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 08:30:25.320846) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 08:30:25.321327) ========= 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.73Bqw6ErkE/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.UQrJ1X9zHF/Listeners TMPDIR=/var/folders/_w/3j13m0p51h30tjlqbj_z4n9800000w/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.004753 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 08:30:25.370187) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 08:30:25.370529) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 08:30:25.370832) ========= ========= Total master_lag: 0.16 =========