builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0093 starttime: 1462543931.32 results: success (0) buildid: 20160506053348 builduid: 341c06341a424d86b2ff77b5ca842180 revision: 9d202f109312b37f42224c55724ea845a4b86c23 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 07:12:11.319856) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 07:12:11.320294) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 07:12:11.320559) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 07:12:11.339389) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 07:12:11.339684) ========= 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.B71nVAXiZk/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.cWjYDGpnDD/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 07:12:11-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 36.8M=0s 2016-05-06 07:12:11 (36.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.109418 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 07:12:11.465971) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 07:12:11.466300) ========= 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.B71nVAXiZk/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.cWjYDGpnDD/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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.027227 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 07:12:11.514147) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-06 07:12:11.514496) ========= 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.B71nVAXiZk/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.cWjYDGpnDD/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 07:12:11,590 truncating revision to first 12 chars 2016-05-06 07:12:11,590 Setting DEBUG logging. 2016-05-06 07:12:11,590 attempt 1/10 2016-05-06 07:12:11,590 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 07:12:12,892 unpacking tar archive at: fx-team-9d202f109312/testing/mozharness/ program finished with exit code 0 elapsedTime=1.547326 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-06 07:12:13.091098) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 07:12:13.091422) ========= script_repo_revision: 9d202f109312b37f42224c55724ea845a4b86c23 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 07:12:13.091790) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 07:12:13.092046) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 07:12:13.177668) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-05-06 07:12:13.178050) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.B71nVAXiZk/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.cWjYDGpnDD/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 07:12:13 INFO - MultiFileLogger online at 20160506 07:12:13 in /builds/slave/test 07:12:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 07:12:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:12:13 INFO - {'append_to_log': False, 07:12:13 INFO - 'base_work_dir': '/builds/slave/test', 07:12:13 INFO - 'blob_upload_branch': 'Fx-Team', 07:12:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:12:13 INFO - 'branch': 'Fx-Team', 07:12:13 INFO - 'buildbot_json_path': 'buildprops.json', 07:12:13 INFO - 'config_files': ('talos/mac_config.py',), 07:12:13 INFO - 'default_actions': ('clobber', 07:12:13 INFO - 'read-buildbot-config', 07:12:13 INFO - 'download-and-extract', 07:12:13 INFO - 'populate-webroot', 07:12:13 INFO - 'create-virtualenv', 07:12:13 INFO - 'install', 07:12:13 INFO - 'run-tests'), 07:12:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:12:13 INFO - 'download_minidump_stackwalk': True, 07:12:13 INFO - 'download_symbols': 'ondemand', 07:12:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:12:13 INFO - 'tooltool.py': '/tools/tooltool.py', 07:12:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:12:13 INFO - '/tools/misc-python/virtualenv.py')}, 07:12:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:12:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:12:13 INFO - 'installer_path': 'installer.exe', 07:12:13 INFO - 'log_level': 'info', 07:12:13 INFO - 'log_name': 'talos', 07:12:13 INFO - 'log_to_console': True, 07:12:13 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:12:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:12:13 INFO - 'opt_config_files': (), 07:12:13 INFO - 'pip_index': False, 07:12:13 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:12:13 INFO - 'cmd': ('bash', 07:12:13 INFO - '-c', 07:12:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:12:13 INFO - 'enabled': True, 07:12:13 INFO - 'halt_on_failure': False, 07:12:13 INFO - 'name': 'check_screen_resolution'},), 07:12:13 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:12:13 INFO - 'cmd': ('bash', 07:12:13 INFO - '-c', 07:12:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:12:13 INFO - 'enabled': True, 07:12:13 INFO - 'halt_on_failure': False, 07:12:13 INFO - 'name': 'check_screen_resolution'},), 07:12:13 INFO - 'run_cmd_checks_enabled': True, 07:12:13 INFO - 'sps_profile': False, 07:12:13 INFO - 'sps_profile_interval': 0, 07:12:13 INFO - 'suite': 'dromaeojs', 07:12:13 INFO - 'system_bits': '32', 07:12:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:12:13 INFO - 'title': 't-yosemite-r7-0093', 07:12:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:12:13 INFO - 'use_talos_json': True, 07:12:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:12:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:12:13 INFO - 'work_dir': 'build'} 07:12:13 INFO - ##### 07:12:13 INFO - ##### Running clobber step. 07:12:13 INFO - ##### 07:12:13 INFO - Running pre-action listener: _resource_record_pre_action 07:12:13 INFO - Running main action method: clobber 07:12:13 INFO - rmtree: /builds/slave/test/build 07:12:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:12:15 INFO - Running post-action listener: _resource_record_post_action 07:12:15 INFO - ##### 07:12:15 INFO - ##### Finished clobber step (success) 07:12:15 INFO - ##### 07:12:15 INFO - ##### 07:12:15 INFO - ##### Running read-buildbot-config step. 07:12:15 INFO - ##### 07:12:15 INFO - Running pre-action listener: _resource_record_pre_action 07:12:15 INFO - Running main action method: read_buildbot_config 07:12:15 INFO - Using buildbot properties: 07:12:15 INFO - { 07:12:15 INFO - "project": "", 07:12:15 INFO - "product": "firefox", 07:12:15 INFO - "script_repo_revision": "production", 07:12:15 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 07:12:15 INFO - "repository": "", 07:12:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 07:12:15 INFO - "buildid": "20160506053348", 07:12:15 INFO - "pgo_build": "False", 07:12:15 INFO - "basedir": "/builds/slave/test", 07:12:15 INFO - "buildnumber": 2, 07:12:15 INFO - "slavename": "t-yosemite-r7-0093", 07:12:15 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 07:12:15 INFO - "platform": "yosemite_r7", 07:12:15 INFO - "scheduler": "tests-fx-team-macosx64-talos", 07:12:15 INFO - "branch": "fx-team", 07:12:15 INFO - "repo_path": "integration/fx-team", 07:12:15 INFO - "revision": "9d202f109312b37f42224c55724ea845a4b86c23", 07:12:15 INFO - "stage_platform": "macosx64", 07:12:15 INFO - "builduid": "341c06341a424d86b2ff77b5ca842180", 07:12:15 INFO - "slavebuilddir": "test" 07:12:15 INFO - } 07:12:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/fYTw48n9Rsu4LSGDbhEtbg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 07:12:15 INFO - Running post-action listener: _resource_record_post_action 07:12:15 INFO - ##### 07:12:15 INFO - ##### Finished read-buildbot-config step (success) 07:12:15 INFO - ##### 07:12:15 INFO - ##### 07:12:15 INFO - ##### Running download-and-extract step. 07:12:15 INFO - ##### 07:12:15 INFO - Running pre-action listener: _resource_record_pre_action 07:12:15 INFO - Running main action method: download_and_extract 07:12:15 INFO - mkdir: /builds/slave/test/build/tests 07:12:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:12:15 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 07:12:15 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 07:12:15 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 07:12:15 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 07:12:15 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 07:12:16 INFO - Downloaded 1199 bytes. 07:12:16 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 07:12:16 INFO - Using the following test package requirements: 07:12:16 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 07:12:16 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:12:16 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 07:12:16 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:12:16 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 07:12:16 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:12:16 INFO - u'jsshell-mac.zip'], 07:12:16 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:12:16 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 07:12:16 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 07:12:16 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:12:16 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 07:12:16 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:12:16 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 07:12:16 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:12:16 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 07:12:16 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 07:12:16 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 07:12:16 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:12:16 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')]} 07:12:16 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 07:12:16 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 07:12:16 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 07:12:16 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 07:12:16 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 07:12:19 INFO - Downloaded 18144950 bytes. 07:12:19 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'] 07:12:19 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 07:12:22 INFO - Return code: 0 07:12:22 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 07:12:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:12:22 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 07:12:22 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 07:12:22 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 07:12:22 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 07:12:22 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 07:12:24 INFO - Downloaded 18144950 bytes. 07:12: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'] 07:12: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 07:12:28 INFO - Return code: 0 07:12:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:12:28 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 07:12:28 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 07:12:28 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 07:12:28 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 07:12:28 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 07:12:29 INFO - Downloaded 10840152 bytes. 07:12:29 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'] 07:12:29 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 07:12:29 INFO - Return code: 0 07:12:29 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')]} 07:12:29 INFO - https://queue.taskcluster.net/v1/task/fYTw48n9Rsu4LSGDbhEtbg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 07:12:29 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 07:12:29 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 07:12:29 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 07:12:29 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 07:12:33 INFO - Downloaded 110451611 bytes. 07:12:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fYTw48n9Rsu4LSGDbhEtbg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:12:33 INFO - mkdir: /builds/slave/test/properties 07:12:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:12:33 INFO - Writing to file /builds/slave/test/properties/build_url 07:12:33 INFO - Contents: 07:12:33 INFO - build_url:https://queue.taskcluster.net/v1/task/fYTw48n9Rsu4LSGDbhEtbg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 07:12:34 INFO - Running post-action listener: _resource_record_post_action 07:12:34 INFO - Running post-action listener: set_extra_try_arguments 07:12:34 INFO - ##### 07:12:34 INFO - ##### Finished download-and-extract step (success) 07:12:34 INFO - ##### 07:12:34 INFO - ##### 07:12:34 INFO - ##### Running populate-webroot step. 07:12:34 INFO - ##### 07:12:34 INFO - Running pre-action listener: _resource_record_pre_action 07:12:34 INFO - Running main action method: populate_webroot 07:12:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:12:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:12:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:12:34 INFO - '--tppagecycles', 07:12:34 INFO - '7'], 07:12:34 INFO - 'tests': ['tsvgm']}}, 07:12:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:12:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:12:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:12:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:12:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:12:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:12:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:12:34 INFO - 'talos_options': ['--disable-e10s'], 07:12:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:12:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:12:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:12:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:12:34 INFO - 'talos_options': ['--disable-e10s'], 07:12:34 INFO - 'tests': ['damp', 'tps']}, 07:12:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:12:34 INFO - 'tests': ['damp', 'tps']}, 07:12:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:12:34 INFO - 'tests': ['dromaeo_dom']}, 07:12:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:12:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:12:34 INFO - 'tests': ['a11yr', 07:12:34 INFO - 'ts_paint', 07:12:34 INFO - 'tpaint', 07:12:34 INFO - 'sessionrestore', 07:12:34 INFO - 'sessionrestore_no_auto_restore', 07:12:34 INFO - 'tabpaint']}, 07:12:34 INFO - 'other-e10s': {'tests': ['a11yr', 07:12:34 INFO - 'ts_paint', 07:12:34 INFO - 'tpaint', 07:12:34 INFO - 'sessionrestore', 07:12:34 INFO - 'sessionrestore_no_auto_restore', 07:12:34 INFO - 'tabpaint']}, 07:12:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:12:34 INFO - 'ts_paint', 07:12:34 INFO - 'tpaint', 07:12:34 INFO - 'sessionrestore', 07:12:34 INFO - 'sessionrestore_no_auto_restore', 07:12:34 INFO - 'tabpaint']}, 07:12:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:12:34 INFO - 'ts_paint', 07:12:34 INFO - 'tpaint', 07:12:34 INFO - 'sessionrestore', 07:12:34 INFO - 'sessionrestore_no_auto_restore', 07:12:34 INFO - 'tabpaint']}, 07:12:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:12:34 INFO - 'tests': ['a11yr', 07:12:34 INFO - 'ts_paint', 07:12:34 INFO - 'tpaint', 07:12:34 INFO - 'sessionrestore', 07:12:34 INFO - 'sessionrestore_no_auto_restore', 07:12:34 INFO - 'tabpaint']}, 07:12:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:12:34 INFO - 'tests': ['a11yr', 07:12:34 INFO - 'ts_paint', 07:12:34 INFO - 'tpaint', 07:12:34 INFO - 'sessionrestore', 07:12:34 INFO - 'sessionrestore_no_auto_restore', 07:12:34 INFO - 'tabpaint']}, 07:12:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:12:34 INFO - 'tests': ['tsvgx', 07:12:34 INFO - 'tsvgr_opacity', 07:12:34 INFO - 'tart', 07:12:34 INFO - 'tscrollx', 07:12:34 INFO - 'cart']}, 07:12:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:12:34 INFO - 'tsvgr_opacity', 07:12:34 INFO - 'tart', 07:12:34 INFO - 'tscrollx', 07:12:34 INFO - 'cart']}, 07:12:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:12:34 INFO - 'talos_options': ['--disable-e10s'], 07:12:34 INFO - 'tests': ['tp5o']}, 07:12:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:12:34 INFO - 'tests': ['tp5o']}, 07:12:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:12:34 INFO - 'talos_options': ['--disable-e10s', 07:12:34 INFO - '--xperf_path', 07:12:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:12:34 INFO - 'tests': ['tp5n']}, 07:12:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:12:34 INFO - 'talos_options': ['--xperf_path', 07:12:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:12:34 INFO - 'tests': ['tp5n']}}, 07:12:34 INFO - 'talos.zip': {'path': '', 07:12:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:12:34 INFO - Running post-action listener: _resource_record_post_action 07:12:34 INFO - ##### 07:12:34 INFO - ##### Finished populate-webroot step (success) 07:12:34 INFO - ##### 07:12:34 INFO - ##### 07:12:34 INFO - ##### Running create-virtualenv step. 07:12:34 INFO - ##### 07:12:34 INFO - Running pre-action listener: _resource_record_pre_action 07:12:34 INFO - Running main action method: create_virtualenv 07:12:34 INFO - Creating virtualenv /builds/slave/test/build/venv 07:12:34 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 07:12:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:12:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:12:34 INFO - Using real prefix '/tools/python27' 07:12:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:12:35 INFO - Installing distribute.............................................................................................................................................................................................done. 07:12:37 INFO - Installing pip.................done. 07:12:37 INFO - Return code: 0 07:12:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:12:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:12:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:12:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:37 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 0x10b0d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0e3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf73478170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af72e00>, '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 0x10ad9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af6db20>, '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.B71nVAXiZk/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.cWjYDGpnDD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 07:12:37 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 07:12:37 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 07:12:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.B71nVAXiZk/Render', 07:12:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:12:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:12:37 INFO - 'HOME': '/Users/cltbld', 07:12:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:12:37 INFO - 'LOGNAME': 'cltbld', 07:12:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:37 INFO - 'MOZ_NO_REMOTE': '1', 07:12:37 INFO - 'NO_EM_RESTART': '1', 07:12:37 INFO - 'PAGER': '/bin/cat', 07:12:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:12:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:37 INFO - 'PWD': '/builds/slave/test', 07:12:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:12:37 INFO - 'SHELL': '/bin/bash', 07:12:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cWjYDGpnDD/Listeners', 07:12:37 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 07:12:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:12:37 INFO - 'USER': 'cltbld', 07:12:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:12:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:12:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:37 INFO - 'XPC_FLAGS': '0x0', 07:12:37 INFO - 'XPC_SERVICE_NAME': '0', 07:12:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:12:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:38 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 07:12:38 INFO - Cleaning up... 07:12:38 INFO - Return code: 0 07:12:38 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 07:12:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:12:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:12:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:38 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 0x10b0d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0e3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf73478170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af72e00>, '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 0x10ad9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af6db20>, '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.B71nVAXiZk/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.cWjYDGpnDD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 07:12:38 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 07:12:38 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 07:12:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.B71nVAXiZk/Render', 07:12:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:12:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:12:38 INFO - 'HOME': '/Users/cltbld', 07:12:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:12:38 INFO - 'LOGNAME': 'cltbld', 07:12:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:38 INFO - 'MOZ_NO_REMOTE': '1', 07:12:38 INFO - 'NO_EM_RESTART': '1', 07:12:38 INFO - 'PAGER': '/bin/cat', 07:12:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:12:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:38 INFO - 'PWD': '/builds/slave/test', 07:12:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:12:38 INFO - 'SHELL': '/bin/bash', 07:12:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cWjYDGpnDD/Listeners', 07:12:38 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 07:12:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:12:38 INFO - 'USER': 'cltbld', 07:12:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:12:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:12:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:38 INFO - 'XPC_FLAGS': '0x0', 07:12:38 INFO - 'XPC_SERVICE_NAME': '0', 07:12:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:12:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:38 INFO - Downloading/unpacking psutil>=0.7.1 07:12:38 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 07:12:38 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 07:12:38 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 07:12:38 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 07:12:38 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 07:12:38 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 07:12:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:12:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:12:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:12:41 INFO - Installing collected packages: psutil 07:12:41 INFO - Running setup.py install for psutil 07:12:41 INFO - building 'psutil._psutil_osx' extension 07:12:41 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 07:12:42 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 07:12:42 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 07:12:42 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 07:12:42 INFO - building 'psutil._psutil_posix' extension 07:12:42 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 07:12:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:12:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:12:42 INFO - ^ 07:12:42 INFO - 1 warning generated. 07:12:42 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 07:12:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:12:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:12:42 INFO - Successfully installed psutil 07:12:42 INFO - Cleaning up... 07:12:42 INFO - Return code: 0 07:12:42 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 07:12:42 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')]} 07:12:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:42 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')]} 07:12:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:42 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 0x10b0d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0e3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf73478170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af72e00>, '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 0x10ad9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af6db20>, '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.B71nVAXiZk/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.cWjYDGpnDD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 07:12:42 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 07:12:42 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 07:12:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.B71nVAXiZk/Render', 07:12:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:12:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:12:42 INFO - 'HOME': '/Users/cltbld', 07:12:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:12:42 INFO - 'LOGNAME': 'cltbld', 07:12:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:42 INFO - 'MOZ_NO_REMOTE': '1', 07:12:42 INFO - 'NO_EM_RESTART': '1', 07:12:42 INFO - 'PAGER': '/bin/cat', 07:12:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:12:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:42 INFO - 'PWD': '/builds/slave/test', 07:12:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:12:42 INFO - 'SHELL': '/bin/bash', 07:12:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cWjYDGpnDD/Listeners', 07:12:42 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 07:12:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:12:42 INFO - 'USER': 'cltbld', 07:12:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:12:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:12:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:42 INFO - 'XPC_FLAGS': '0x0', 07:12:42 INFO - 'XPC_SERVICE_NAME': '0', 07:12:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:12:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:42 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 07:12:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:45 INFO - Downloading mozsystemmonitor-0.0.tar.gz 07:12:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:12:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 07:12:45 INFO - Installing collected packages: mozsystemmonitor 07:12:45 INFO - Running setup.py install for mozsystemmonitor 07:12:45 INFO - Successfully installed mozsystemmonitor 07:12:45 INFO - Cleaning up... 07:12:45 INFO - Return code: 0 07:12:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:12:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:12:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:12:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12: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', '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 0x10b0d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0e3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf73478170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af72e00>, '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 0x10ad9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af6db20>, '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.B71nVAXiZk/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.cWjYDGpnDD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 07:12: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:12: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 blobuploader==1.2.4 07:12:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.B71nVAXiZk/Render', 07:12:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:12:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:12:45 INFO - 'HOME': '/Users/cltbld', 07:12:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:12:45 INFO - 'LOGNAME': 'cltbld', 07:12:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:45 INFO - 'MOZ_NO_REMOTE': '1', 07:12:45 INFO - 'NO_EM_RESTART': '1', 07:12:45 INFO - 'PAGER': '/bin/cat', 07:12:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:12:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:45 INFO - 'PWD': '/builds/slave/test', 07:12:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:12:45 INFO - 'SHELL': '/bin/bash', 07:12:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cWjYDGpnDD/Listeners', 07:12:45 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 07:12:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:12:45 INFO - 'USER': 'cltbld', 07:12:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:12:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:12:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:45 INFO - 'XPC_FLAGS': '0x0', 07:12:45 INFO - 'XPC_SERVICE_NAME': '0', 07:12:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:12:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:45 INFO - Downloading/unpacking blobuploader==1.2.4 07:12:45 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 07:12:45 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 07:12:45 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 07:12:45 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 07:12:45 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 07:12:45 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 07:12:47 INFO - Downloading blobuploader-1.2.4.tar.gz 07:12:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:12:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:12:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:12:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:12:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:49 INFO - Downloading docopt-0.6.1.tar.gz 07:12:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:12:49 INFO - Installing collected packages: blobuploader, requests, docopt 07:12:49 INFO - Running setup.py install for blobuploader 07:12:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:12:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:12:49 INFO - Running setup.py install for requests 07:12:49 INFO - Running setup.py install for docopt 07:12:49 INFO - Successfully installed blobuploader requests docopt 07:12:49 INFO - Cleaning up... 07:12:50 INFO - Return code: 0 07:12:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:12:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:12:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:12:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:50 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 0x10b0d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0e3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf73478170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af72e00>, '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 0x10ad9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af6db20>, '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.B71nVAXiZk/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.cWjYDGpnDD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 07:12:50 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 07:12:50 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 07:12:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.B71nVAXiZk/Render', 07:12:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:12:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:12:50 INFO - 'HOME': '/Users/cltbld', 07:12:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:12:50 INFO - 'LOGNAME': 'cltbld', 07:12:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:50 INFO - 'MOZ_NO_REMOTE': '1', 07:12:50 INFO - 'NO_EM_RESTART': '1', 07:12:50 INFO - 'PAGER': '/bin/cat', 07:12:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:12:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:50 INFO - 'PWD': '/builds/slave/test', 07:12:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:12:50 INFO - 'SHELL': '/bin/bash', 07:12:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cWjYDGpnDD/Listeners', 07:12:50 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 07:12:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:12:50 INFO - 'USER': 'cltbld', 07:12:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:12:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:12:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:50 INFO - 'XPC_FLAGS': '0x0', 07:12:50 INFO - 'XPC_SERVICE_NAME': '0', 07:12:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:12:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:12:50 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-PHDSNH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:12:50 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-8KInrv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:12:50 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-ot3650-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:12:50 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-GIYZ_U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:12:50 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-BmkG7w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:12:50 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-mO_Kc3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:12:50 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-3jWmtk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:12:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:12:50 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-HbrbZX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:12:51 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-TWhOb2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:12:51 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-CWq8Ci-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:12:51 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-qTqjTD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:12:51 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-JkZLMh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:12:51 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-uIHhV5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:12:51 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-H1TLgj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:12:51 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-NTVsRL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:12:51 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-zUC28K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:12:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:12:51 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-z2TCu5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:12:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:12:52 INFO - Running setup.py install for manifestparser 07:12:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:12:52 INFO - Running setup.py install for mozcrash 07:12:52 INFO - Running setup.py install for mozdebug 07:12:52 INFO - Running setup.py install for mozdevice 07:12:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:12:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:12:52 INFO - Running setup.py install for mozfile 07:12:52 INFO - Running setup.py install for mozhttpd 07:12:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:12:52 INFO - Running setup.py install for mozinfo 07:12:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:12:53 INFO - Running setup.py install for mozInstall 07:12:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:12:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:12:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:12:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:12:53 INFO - Running setup.py install for mozleak 07:12:53 INFO - Running setup.py install for mozlog 07:12:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:12:53 INFO - Running setup.py install for moznetwork 07:12:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:12:53 INFO - Running setup.py install for mozprocess 07:12:53 INFO - Running setup.py install for mozprofile 07:12:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:12:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:12:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:12:54 INFO - Running setup.py install for mozrunner 07:12:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:12:54 INFO - Running setup.py install for mozscreenshot 07:12:54 INFO - Running setup.py install for moztest 07:12:54 INFO - Running setup.py install for mozversion 07:12:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:12:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:12:54 INFO - Cleaning up... 07:12:54 INFO - Return code: 0 07:12:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:12: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')]} 07:12:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12: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')]} 07:12:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:54 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 0x10b0d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0e3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf73478170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af72e00>, '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 0x10ad9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af6db20>, '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.B71nVAXiZk/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.cWjYDGpnDD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 07:12:54 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 07:12:54 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 07:12:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.B71nVAXiZk/Render', 07:12:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:12:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:12:54 INFO - 'HOME': '/Users/cltbld', 07:12:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:12:54 INFO - 'LOGNAME': 'cltbld', 07:12:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:54 INFO - 'MOZ_NO_REMOTE': '1', 07:12:54 INFO - 'NO_EM_RESTART': '1', 07:12:54 INFO - 'PAGER': '/bin/cat', 07:12:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:12:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:54 INFO - 'PWD': '/builds/slave/test', 07:12:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:12:54 INFO - 'SHELL': '/bin/bash', 07:12:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cWjYDGpnDD/Listeners', 07:12:54 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 07:12:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:12:54 INFO - 'USER': 'cltbld', 07:12:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:12:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:12:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:54 INFO - 'XPC_FLAGS': '0x0', 07:12:54 INFO - 'XPC_SERVICE_NAME': '0', 07:12:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:12:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:12:54 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-MnNgn7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:12:55 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)) 07:12:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:12:55 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-004kde-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:12:55 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)) 07:12:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:12:55 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-SJ5BLd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:12:55 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)) 07:12:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:12:55 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-a2eN7r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:12:55 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)) 07:12:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:12:55 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-bO1iQF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:12:55 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)) 07:12:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:12:55 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-lvhK7e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:12:55 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)) 07:12:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:12:55 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-Y0JCd3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:12:55 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)) 07:12:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:12:55 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-QGV3T2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:12:55 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)) 07:12:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:12:55 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-zuD7hi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:12:55 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)) 07:12:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:12:55 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-qh2snP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:12:55 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)) 07:12:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:12:55 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-cyKCI0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:12:56 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)) 07:12:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:12:56 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-G9mECY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:12:56 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)) 07:12:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:12:56 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-JnoxIV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:12:56 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)) 07:12:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:12:56 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-rEqbs3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:12:56 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)) 07:12:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:12:56 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-sgqAIu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:12:56 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)) 07:12:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:12:56 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-YHXB5F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:12:56 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)) 07:12:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:12:56 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-ldAGnQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:12:56 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)) 07:12:56 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)) 07:12:56 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)) 07:12:56 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)) 07:12:56 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)) 07:12:56 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)) 07:12:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:12:56 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 07:12:56 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 07:12:56 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 07:12:56 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 07:12:56 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 07:12:56 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 07:12:59 INFO - Downloading blessings-1.6.tar.gz 07:12:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:12:59 INFO - Installing collected packages: blessings 07:12:59 INFO - Running setup.py install for blessings 07:12:59 INFO - Successfully installed blessings 07:12:59 INFO - Cleaning up... 07:12:59 INFO - Return code: 0 07:12:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:12:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:12:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:12:59 INFO - Reading from file tmpfile_stdout 07:12:59 INFO - Current package versions: 07:12:59 INFO - blessings == 1.6 07:12:59 INFO - blobuploader == 1.2.4 07:12:59 INFO - docopt == 0.6.1 07:12:59 INFO - manifestparser == 1.1 07:12:59 INFO - mozInstall == 1.12 07:12:59 INFO - mozcrash == 0.17 07:12:59 INFO - mozdebug == 0.1 07:12:59 INFO - mozdevice == 0.48 07:12:59 INFO - mozfile == 1.2 07:12:59 INFO - mozhttpd == 0.7 07:12:59 INFO - mozinfo == 0.9 07:12:59 INFO - mozleak == 0.1 07:12:59 INFO - mozlog == 3.1 07:12:59 INFO - moznetwork == 0.27 07:12:59 INFO - mozprocess == 0.22 07:12:59 INFO - mozprofile == 0.28 07:12:59 INFO - mozrunner == 6.11 07:12:59 INFO - mozscreenshot == 0.1 07:12:59 INFO - mozsystemmonitor == 0.0 07:12:59 INFO - moztest == 0.7 07:12:59 INFO - mozversion == 1.4 07:12:59 INFO - psutil == 3.1.1 07:12:59 INFO - requests == 1.2.3 07:12:59 INFO - wsgiref == 0.1.2 07:12:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:12: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')]} 07:12:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12: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')]} 07:12:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:59 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 0x10b0d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0e3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf73478170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af72e00>, '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 0x10ad9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af6db20>, '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.B71nVAXiZk/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.cWjYDGpnDD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 07:12:59 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 07:12:59 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 07:12:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.B71nVAXiZk/Render', 07:12:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:12:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:12:59 INFO - 'HOME': '/Users/cltbld', 07:12:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:12:59 INFO - 'LOGNAME': 'cltbld', 07:12:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:59 INFO - 'MOZ_NO_REMOTE': '1', 07:12:59 INFO - 'NO_EM_RESTART': '1', 07:12:59 INFO - 'PAGER': '/bin/cat', 07:12:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:12:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:59 INFO - 'PWD': '/builds/slave/test', 07:12:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:12:59 INFO - 'SHELL': '/bin/bash', 07:12:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cWjYDGpnDD/Listeners', 07:12:59 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 07:12:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:12:59 INFO - 'USER': 'cltbld', 07:12:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:12:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:12:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:59 INFO - 'XPC_FLAGS': '0x0', 07:12:59 INFO - 'XPC_SERVICE_NAME': '0', 07:12:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:13:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:13:00 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)) 07:13:00 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)) 07:13:00 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)) 07:13:00 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)) 07:13:00 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)) 07:13:00 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)) 07:13:00 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)) 07:13:00 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)) 07:13:00 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)) 07:13:00 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)) 07:13:00 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)) 07:13:00 INFO - Cleaning up... 07:13:00 INFO - Return code: 0 07:13:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:13:00 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')]} 07:13:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:13:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:00 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')]} 07:13:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:13:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:00 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 0x10b0d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0e3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf73478170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af72e00>, '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 0x10ad9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af6db20>, '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.B71nVAXiZk/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.cWjYDGpnDD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 07:13:00 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 07:13:00 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 07:13:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.B71nVAXiZk/Render', 07:13:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:13:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:13:00 INFO - 'HOME': '/Users/cltbld', 07:13:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:13:00 INFO - 'LOGNAME': 'cltbld', 07:13:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:13:00 INFO - 'MOZ_NO_REMOTE': '1', 07:13:00 INFO - 'NO_EM_RESTART': '1', 07:13:00 INFO - 'PAGER': '/bin/cat', 07:13:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:13:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:13:00 INFO - 'PWD': '/builds/slave/test', 07:13:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:13:00 INFO - 'SHELL': '/bin/bash', 07:13:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cWjYDGpnDD/Listeners', 07:13:00 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 07:13:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:13:00 INFO - 'USER': 'cltbld', 07:13:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:13:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:13:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:13:00 INFO - 'XPC_FLAGS': '0x0', 07:13:00 INFO - 'XPC_SERVICE_NAME': '0', 07:13:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:13:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:13:00 INFO - Downloading/unpacking jsonschema 07:13:00 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 07:13:00 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 07:13:00 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 07:13:00 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 07:13:00 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 07:13:00 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 07:13:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:13:02 INFO - Downloading/unpacking functools32 (from jsonschema) 07:13:02 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 07:13:02 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 07:13:02 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 07:13:02 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 07:13:02 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 07:13:02 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 07:13:03 INFO - Downloading functools32-3.2.3-2.tar.gz 07:13:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:13:03 INFO - warning: no files found matching '*.txt' 07:13:03 INFO - no previously-included directories found matching 'build' 07:13:03 INFO - no previously-included directories found matching 'dist' 07:13:03 INFO - no previously-included directories found matching '.git*' 07:13:03 INFO - Installing collected packages: jsonschema, functools32 07:13:03 INFO - Running setup.py install for functools32 07:13:03 INFO - warning: no files found matching '*.txt' 07:13:03 INFO - no previously-included directories found matching 'build' 07:13:03 INFO - no previously-included directories found matching 'dist' 07:13:03 INFO - no previously-included directories found matching '.git*' 07:13:03 INFO - Successfully installed jsonschema functools32 07:13:03 INFO - Cleaning up... 07:13:03 INFO - Return code: 0 07:13:03 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 07:13:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:13:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:13:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:13:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:13:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:03 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 0x10b0d3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0e3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf73478170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af72e00>, '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 0x10ad9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af6db20>, '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.B71nVAXiZk/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.cWjYDGpnDD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 07:13:03 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 07:13:03 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 07:13:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.B71nVAXiZk/Render', 07:13:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:13:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:13:03 INFO - 'HOME': '/Users/cltbld', 07:13:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:13:03 INFO - 'LOGNAME': 'cltbld', 07:13:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:13:03 INFO - 'MOZ_NO_REMOTE': '1', 07:13:03 INFO - 'NO_EM_RESTART': '1', 07:13:03 INFO - 'PAGER': '/bin/cat', 07:13:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:13:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:13:03 INFO - 'PWD': '/builds/slave/test', 07:13:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:13:03 INFO - 'SHELL': '/bin/bash', 07:13:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cWjYDGpnDD/Listeners', 07:13:03 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 07:13:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:13:03 INFO - 'USER': 'cltbld', 07:13:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:13:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:13:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:13:03 INFO - 'XPC_FLAGS': '0x0', 07:13:03 INFO - 'XPC_SERVICE_NAME': '0', 07:13:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:13:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:13:03 INFO - Downloading/unpacking flake8 07:13:03 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 07:13:03 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 07:13:03 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 07:13:03 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 07:13:03 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 07:13:03 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 07:13:06 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 07:13:06 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 07:13:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:13:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:13:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:13:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:13:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:13:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:13:06 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 07:13:06 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 07:13:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:13:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:13:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:13:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:13:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:13:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:13:06 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 07:13:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:13:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:13:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:13:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:13:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:13:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:13:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 07:13:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:13:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:13:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:13:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:13:07 INFO - no previously-included directories found matching 'docs/_build' 07:13:07 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 07:13:07 INFO - Running setup.py install for pep8 07:13:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:13:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:13:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:13:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:13:07 INFO - no previously-included directories found matching 'docs/_build' 07:13:07 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 07:13:07 INFO - Successfully installed flake8 mccabe pyflakes pep8 07:13:07 INFO - Cleaning up... 07:13:07 INFO - Return code: 0 07:13:07 INFO - Running post-action listener: _resource_record_post_action 07:13:07 INFO - Running post-action listener: _start_resource_monitoring 07:13:07 INFO - Starting resource monitoring. 07:13:07 INFO - ##### 07:13:07 INFO - ##### Finished create-virtualenv step (success) 07:13:07 INFO - ##### 07:13:07 INFO - ##### 07:13:07 INFO - ##### Running install step. 07:13:07 INFO - ##### 07:13:07 INFO - Running pre-action listener: _resource_record_pre_action 07:13:07 INFO - Running main action method: install 07:13:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:13:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:13:08 INFO - Reading from file tmpfile_stdout 07:13:08 INFO - Detecting whether we're running mozinstall >=1.0... 07:13:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:13:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:13:08 INFO - Reading from file tmpfile_stdout 07:13:08 INFO - Output received: 07:13:08 INFO - Usage: mozinstall [options] installer 07:13:08 INFO - Options: 07:13:08 INFO - -h, --help show this help message and exit 07:13:08 INFO - -d DEST, --destination=DEST 07:13:08 INFO - Directory to install application into. [default: 07:13:08 INFO - "/builds/slave/test"] 07:13:08 INFO - --app=APP Application being installed. [default: firefox] 07:13:08 INFO - mkdir: /builds/slave/test/build/application 07:13:08 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'] 07:13:08 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 07:13:42 INFO - Reading from file tmpfile_stdout 07:13:42 INFO - Output received: 07:13:42 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:13:42 INFO - Running post-action listener: _resource_record_post_action 07:13:42 INFO - ##### 07:13:42 INFO - ##### Finished install step (success) 07:13:42 INFO - ##### 07:13:42 INFO - ##### 07:13:42 INFO - ##### Running run-tests step. 07:13:42 INFO - ##### 07:13:42 INFO - Running pre-action listener: _resource_record_pre_action 07:13:42 INFO - Running main action method: run_tests 07:13:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:13:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:13:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:13:42 INFO - 2016-05-06 07:13:42.077 screenresolution[1635:8012] starting screenresolution argv=screenresolution get 07:13:42 INFO - 2016-05-06 07:13:42.079 screenresolution[1635:8012] Display 0: 1600x1200x32@60 07:13:42 INFO - 2016-05-06 07:13:42.086 screenresolution[1636:8014] starting screenresolution argv=screenresolution list 07:13:42 INFO - Available Modes on Display 0 07:13:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:13:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:13:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:13:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:13:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:13:42 INFO - Intel Iris: 07:13:42 INFO - Chipset Model: Intel Iris 07:13:42 INFO - Type: GPU 07:13:42 INFO - Bus: Built-In 07:13:42 INFO - VRAM (Dynamic, Max): 1536 MB 07:13:42 INFO - Vendor: Intel (0x8086) 07:13:42 INFO - Device ID: 0x0a2e 07:13:42 INFO - Revision ID: 0x0009 07:13:42 INFO - Displays: 07:13:42 INFO - Display: 07:13:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:13:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:13:42 INFO - Display Serial Number: 1600x1200 60 07:13:42 INFO - Main Display: Yes 07:13:42 INFO - Mirror: Off 07:13:42 INFO - Online: Yes 07:13:42 INFO - Rotation: Supported 07:13:42 INFO - Return code: 0 07:13:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:13:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:13:42 INFO - Python 2.7.3 07:13:42 INFO - Return code: 0 07:13:42 INFO - grabbing minidump binary from tooltool 07:13:42 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')]} 07:13:42 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 0x10af72e00>, '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 0x10ad9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10af6db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:13:42 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 07:13:42 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 07:13:42 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:13:42 INFO - Return code: 0 07:13:42 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:13:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:13:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:13:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:13:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:13:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 07:13:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 07:13:43 INFO - Return code: 0 07:13:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fYTw48n9Rsu4LSGDbhEtbg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0093', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 07:13:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fYTw48n9Rsu4LSGDbhEtbg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0093 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 07:13:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.B71nVAXiZk/Render', 07:13:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:13:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:13:43 INFO - 'HOME': '/Users/cltbld', 07:13:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:13:43 INFO - 'LOGNAME': 'cltbld', 07:13:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:13:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:13:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:13:43 INFO - 'MOZ_NO_REMOTE': '1', 07:13:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:13:43 INFO - 'NO_EM_RESTART': '1', 07:13:43 INFO - 'PAGER': '/bin/cat', 07:13:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:13:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:13:43 INFO - 'PWD': '/builds/slave/test', 07:13:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:13:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:13:43 INFO - 'SHELL': '/bin/bash', 07:13:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cWjYDGpnDD/Listeners', 07:13:43 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 07:13:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:13:43 INFO - 'USER': 'cltbld', 07:13:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:13:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:13:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:13:43 INFO - 'XPC_FLAGS': '0x0', 07:13:43 INFO - 'XPC_SERVICE_NAME': '0', 07:13:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:13:43 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fYTw48n9Rsu4LSGDbhEtbg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0093', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 07:13:43 INFO - mozversion application_buildid: 20160506053348 07:13:43 INFO - mozversion application_changeset: 9d202f109312b37f42224c55724ea845a4b86c23 07:13:43 INFO - mozversion application_display_name: Nightly 07:13:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:13:43 INFO - mozversion application_name: Firefox 07:13:43 INFO - mozversion application_remotingname: firefox 07:13:43 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 07:13:43 INFO - mozversion application_vendor: Mozilla 07:13:43 INFO - mozversion application_version: 49.0a1 07:13:43 INFO - mozversion platform_buildid: 20160506053348 07:13:43 INFO - mozversion platform_changeset: 9d202f109312b37f42224c55724ea845a4b86c23 07:13:43 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 07:13:43 INFO - mozversion platform_version: 49.0a1 07:13:43 INFO - using testdate: 1462544023 07:13:43 INFO - actual date: 1462544023 07:13:43 INFO - starting webserver on 'localhost:49274' 07:13:43 INFO - SUITE-START | Running 2 tests 07:13:43 INFO - TEST-START | dromaeo_css 07:13:43 INFO - operating with platform_type : mac_ 07:13:43 INFO - Initialising browser for dromaeo_css test... 07:13:43 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/tmpslAmoY/profile http://localhost:49274/getInfo.html) 07:13:46 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 07:13:46 INFO - PROCESS | 1654 | colorDepth:24 07:13:46 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 07:13:46 INFO - PROCESS | 1654 | __metrics 07:13:46 INFO - PROCESS | 1654 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:13:46 ERROR - PROCESS | 1654 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:13:47 INFO - TEST-INFO | 1654: exit 0 07:13:47 INFO - Browser initialized. 07:13:47 INFO - Running cycle 1/1 for dromaeo_css test... 07:13:47 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/tmpslAmoY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:13:59 INFO - PROCESS | 1657 | RSS: Main: 239214592 07:13:59 INFO - PROCESS | 1657 | 07:14:50 INFO - PROCESS | 1657 | 1462544090686 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] 07:17:01 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49274/tests/dromaeo/cssquery-ext.html) 07:17:02 INFO - PROCESS | 1657 | RSS: Main: 379691008 07:17:02 INFO - PROCESS | 1657 | 07:20:06 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-ext.html (next: http://localhost:49274/tests/dromaeo/cssquery-jquery.html) 07:20:06 INFO - PROCESS | 1657 | RSS: Main: 370651136 07:20:06 INFO - PROCESS | 1657 | 07:23:08 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49274/tests/dromaeo/cssquery-mootools.html) 07:23:09 INFO - PROCESS | 1657 | RSS: Main: 371200000 07:23:09 INFO - PROCESS | 1657 | 07:26:12 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49274/tests/dromaeo/cssquery-prototype.html) 07:26:12 INFO - PROCESS | 1657 | RSS: Main: 371695616 07:26:12 INFO - PROCESS | 1657 | 07:29:15 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49274/tests/dromaeo/cssquery-yui.html) 07:29:16 INFO - PROCESS | 1657 | RSS: Main: 341196800 07:29:16 INFO - PROCESS | 1657 | 07:32:19 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-yui.html (next: http://localhost:49274/tests/dromaeo/cssquery-dojo.html) 07:32:19 INFO - PROCESS | 1657 | RSS: Main: 397967360 07:32:19 INFO - PROCESS | 1657 | 07:32:19 INFO - PROCESS | 1657 | __start_tp_report 07:32:19 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 07:32:19 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 07:32:19 INFO - PROCESS | 1657 | |i|pagename|runs| 07:32:19 INFO - PROCESS | 1657 | |0;dojo.html;3409.403354412977;3438.0545350028724;3449.672281133292;3471.0107619328496;3509.5086687863686;5588.6646801191955;5659.575531835108;5660.745266463009;5701.857453563658;5724.771009159639;5687.544996400288;5751.309842818868;5751.482366587006;5754.251947246862;5764.365919748835;5126.846194614158;5156.53591176794;5168.870778230536;5176.327077479922;5233.136532472137;5233.084210263208;5245.606579506533;5246.291750613656;5248.950209958005;5249.842504724846;5159.74201289934;5162.277281180638;5177.611679124062;5188.948110518884;5219.739013049351;5231.607629427789;5266.94661067788;5274.129768588178;5283.920741188886;5323.893522129536;5077.949220507785;5080.695158290514;5089.949100508985;5183.559397451221;5194.844154675366;5552.555795536347;5575.135853942646;5634.943650563483;5636.182753500761;5642.351129620105;6278.4663303619245;6285.371462853724;6287.088372186007;6293.527985401068;6296.244450665949;5242.7378631068295;6128.754849806002;6222.968885155546;6316.494680425555;6342.270638876496;5847.766089356463;5857.531397488191;5890.4698577128265;5893.116032595145;5924.14099955504;6274.717637706314;6284.371562843679;6287.842803929847;6291.1192433059405;6293.181886354744;5867.765289388462;5944.881102377928;6102.206713127353;6121.422488707842;6203.844903877439;6316.05259211122;6321.209848768904;6332.461740751995;6339.873202535923;6370.904436433457;6209.348018457997;6224.968875155595;6253.499720022387;6256.7810126645345;6269.623822570569;6093.847653808691;6106.369660900722;6151;6195.349488303754;6286.277078135981;4850.708957462493;4854.101991131582;4881.267809828483;4897.657163998557;4905.975470122627;4166.354215096604;4434.357018232338;4442.73343599385;4460.50934397216;4478.529754375743;5728.942710572925;5775.220345253337;5783.971080144573;5784.045632470596;5809.535237181015;6139.8601398601395;6187.628742275388;6192.81421557354;6338.968305158444;6341.429271365507;5671.6597004179885;5684;5688;5692.345380281238;5703.914441283384;5336.519713225828;5345.732713364317;5352.330958630171;5355.3305836770405;5357.089294819812;6196.690165491707;6227.875442491125;6269.341719119518;6321.146645202839;6321.273053598803;6334.081558174131;6342.080398342122;6359.618422894641;6374.808755737336;6379.617222966637;6217.347178546188;6327.209098862642;6334.841628959313;6335.588186767845;6350.587211831216;6104.853288833144;6164.291106522807;6175.660338681416;6243.43809057187;6245;6294.087357333253;6299.149614802126;6312.6528040956355;6321.1782468278825;6321.462675672436;5879.559033072451;5918.9704051481185;5925.644461332334;6119.296280927751;6144.41628045335;5942.168096466412;6034.939650603438;6060.78787242462;6096.695165241809;6114.755409783559;5933.614315069507;5941;5944.286685597756;6001.939980600138;6007.849803754941;5942.821715348547;6113.341591916454;6116.14702659631;6119.600865257057;6120.969395152996;5849.327327357409;5850.23946886902;5930.199423077829;5950.375210602824;5967.37342579023 07:32:19 INFO - PROCESS | 1657 | |1;ext.html;512.1472747874789;524.845170674651;531.1050879268433;556.0269528325431;582.3361368040435;30134.849325753366;31709.048728538153;41916.58083419165;43590.56409435905;43614;44263.89340266495;44558.33162502557;44606.553934460644;44645.55354446454;44654.553454465444;9546.570404331787;9750.512474376254;9758.121769040785;9782.804343913118;9786.804263914717;8592.05487396386;8813.38306318558;11609.535618575246;11848.881511184863;11851.288922664624;11648.767024659503;11736.706582335424;11742.589009384674;11744.236624619414;11750.41247937604;8609.139086091403;11454.828177577343;11668.941655291754;11745.941270293637;11751.588694395741;11309;11571.884281157165;11578;11583;11583.652490425216;32504.512432313535;40279.99300017494;40332.19335613301;40415.595844041476;40473.39289910654;2721.9591706124424;3191.707358519803;3193.7285330746913;3266.3957167923772;3425.3491836551225;28624.570631440543;28781.424371512454;28975.275618109506;29413;29708.55437168444;2557.475717477931;2686.207568767218;2703.5674292113363;2712.403271280335;2721.7958653101095;472675;475316.62341688416;475597;475876;476921;65434;65892;66016.6699166501;66120.33879661238;66425.66787166033;36679.81660091683;37420.25159496795;37457.87626371213;38667;68706;17033.318667253418;17125.22936467862;17255.309787608607;17310.567235819206;17318.567035824206;11601.651950441501;11842.171048026728;12866.871331286755;12985.610431687064;13094.738105237842;16699.41552045697;19675.311364102476;19902.104405301507;20261.696074558895;20263.594728105356;8170.834728796874;8294.875576866352;8310.916890831015;8332.583370831555;8365.749027529184;848.4273115646913;972.863799068131;1008.4554340655964;1017.4607458047099;1108.9279196852178;29676.406471870443;29676.851615742216;29853.8632510085;29951.850240749092;30050.398992020037;963.6964356227766;1114.6433141394675;1123.741539445932;1145.4902568357002;1167.2762887010108;14376.640583985274;15000.924995375171;15019.324130414157;15080.321385537678;15480.922595387176;664481;665719;665953;668554;669555;60383;61058.084128738104;61135.08297375543;61264.69367653133;61467;1333.1601091312411;1339.7789364754706;1437.2023526942646;1470.4485817818318;1512.6520900193236;30121.09636710902;30177.370213571798;30390.088297351114;30699;30866.382672346877;50343.74828125836;50830.74584627053;50864;50884.72788180325;51056.72358191075;479832;498252;498923;499179;499983;23512.177073802333;24054.278371648878;24069.638955415332;24778.752212477644;26573.06994255192;36219.818900905324;39451;42838.35742463928;48114.27828582644;49694.25458618196;37179.62820371761;37234.25531489409;37821.62178378181;38792.80603596964;38862;36721.08197295089;37138.07154821151;37142.25715485729;37962.24075518419;38098;36430.63569364379;36481.270374591826;36779.63220367762;37130.44304335383;37579.436308454846;31374.372512549166;33479.83260083684;33494.49758253579;33542.664573353955;33555.49666755049;22286.66570001419;23907.40231494157;24314.392140196636;24937.00251989973;25007.249782506555 07:32:19 INFO - PROCESS | 1657 | |2;jquery.html;355.1901664205611;392.16077593948955;394.27059939112644;398.0148395656232;417.5281931417498;13598.932005339984;20086.296979605715;20991.740495570237;21319.93400329985;21338.786612133874;20000.49998750032;20572;20863.37409877706;21025.36923892282;21046.57906841866;5683.602147849642;5968.343482216953;5971.522278217754;5982.491488223484;5986.551008674345;5550.056490396622;5776.075827867542;5884.941150588504;5887.617304875191;5894.233749612545;5830.300363956331;5836.358000619928;5848.736806843681;5852.561057920673;5853.648781073128;5620.662760234379;5835;5852.3562408135285;5971;5976.880462390772;5752.971235143839;5763.682997435139;5765.711714414305;5767.423257674241;5767.942320576783;19596.53026023054;19622.70565941512;24980.750192498024;25062.37344066395;25114.748852511424;2165.512759629071;2178.18318130701;2221.944451388712;2240.820734341265;2329.3827135809024;9730.562124704406;10101.595936162617;12184.756304873941;13421.396037178252;13655.317234138352;1645.0869767279155;1654.5698118489158;1658.519029481449;1692.1623796220827;1699.5666105143232;114267;114695.27957080651;123618;124928;125211.37394313062;9523.000084990976;11666.300021998537;11710.063194944383;11753.823692644617;11941.880581194251;13181.868181318065;13752.587422377144;13870.514531991332;14887.255637218095;14938.626534336729;8517.105703901125;8620.267277281502;8695.869561956575;8808.51553164582;8822.77943051416;8299.253067223857;8406.915930840736;8568.52873091986;8573.056963733985;8774.60514276859;3988.4017397390044;4362.127574485052;4503.414556107685;4528.682992190515;4579.381783459257;1901.034386357287;1944.513871532117;1952.6094781043848;1953.7264782930401;1962.5486138188012;890.2343984173606;933.883264591926;1001.0840081325506;1019.1235537437798;1022.6216299969059;16209.594760130854;18967.051647417575;20132.401471570385;20398.28605998783;20684.379468615967;848.7353842774222;902.3503077784117;918.811643613071;1004;1054.435876805905;10486.26596138263;10647.574097036188;10658.89341106579;10690.679279621623;10695;594046;599903;605918;606484;613635;130967.34516327357;138701.3064934669;138990;142706;143303;1025.646152077532;1032.4373216596823;1039.2309690828731;1051.726551096705;1059.645018918671;17224.913875430542;17548.561285967953;17965.371212007518;17977.370792022215;17978.460646180636;47127.76436117797;50521.74739126281;50738;51022;52027.79722027767;59319.703401482715;71137;73691.26308737058;74650.8802367954;76401.23598763942;8180.877286840821;8276.213759692819;8299.377546684143;8419;8499.830003399773;9032.593533290887;11680.240784348991;12425.813612795635;12852.036097292554;12952.546660866825;10396.58413663445;10545.52545135486;10962.287451315638;11075.446227688744;11126.276792008493;10703.250772446165;10870.20641594128;10892.455377230948;11230.55077796879;11263.380514071805;11278.887211127783;11291.491882865126;11604.825927611262;11673;11716.297022178696;8140.75577732669;8155.306798921921;8208.712695055645;8414.495130292202;8608.956955215184;10080.193584513368;10676.199285053497;10783.137349011904;11137.220394572301;11492.31046137201 07:32:19 INFO - PROCESS | 1657 | |3;mootools.html;1408.401429392508;1580.1862041048862;1629.66591848671;1849.6578133045386;1987.1653905359738;7830.608469576519;8604.182602652754;8856.291496680265;8862.77843053924;8977.50623715697;8574.742757717262;8813.867791983126;8826.823463530725;8830.60262288197;8830.823383532326;2165.501934555053;2360.480694247263;2467.8519288842635;2469.753024697534;2505.649209110717;2065.9586808263825;2512.2337687005456;2513.7863281621085;2514.5222407742563;2520.1683444463283;2023.6357455657976;2443.6701045358827;2477.4054226985486;2478.429961108946;2479;2131.9893400532974;2359.174288998837;2413.7706917842775;2441.231012231159;2446.0460420436043;2457.8156638252026;2468.4569394733307;2491.750824917512;2496.3509487533374;2499.512595043967;8652.134786521361;8715.956420217923;8717.084706105894;8729.301655867515;8742.30061595071;5362.222477740745;5539.723013849291;5546.140348245988;5550.111982082888;5563.054280772279;3481.5299934509026;3554.928901421983;3562.69717074049;3591.2099338145567;3593.3711600469815;1041.8697662792151;1051.4742628685658;1055.757175849559;1058.5342449322275;1061.3897009219652;11496.482658280398;11788.292702437795;11799.056075513938;11803.527858885634;11865.584704535386;4918.975405123023;4933.333999909966;5040.092783299041;5041.395032596039;5051.292819005343;10164.390136591828;10184.949075254724;10191.439470829027;10200.0819926207;10222.6422075227;5529.889402212014;5553.111502159635;5558.138488534284;5564.1931919871395;5568.914061757919;4405.537418571068;4589.173948689268;4860.1737704590305;4985.501449855058;4988.6009119270375;4056.064826785465;4077.164181342877;4156.501219853637;4175.331946888483;4185.91628167441;1311.9360455912602;1328.592161106758;1339.591424615482;1342.3825040481292;1357.151780137414;821.5235163605095;900.9144131307515;941.5292353823088;953.1278879825021;986.663239379524;5887.35239123696;5949.315828679671;6042.788502402394;6052.636841789418;6139.84650383744;372.6050386590223;456.58450809762957;469.0946473306483;481.60989598425385;482.75620811490677;3339.248669049493;4010.1177740896956;4041.9666408837656;4051.736637118578;4077.938830917539;10186.592536298613;10571.894281057244;10598.20513461478;10757.78484430307;10799.514021869034;10572.259941803999;10574.206934479791;10669.09312708413;10693.304935179189;10694.518746656418;8006.879896801553;8024.839503209903;8141.755747327589;8218.260356567818;8220.753377398687;5842.152887831156;5842.561807864342;5895.793647222326;5910.852228694145;5945.851353716192;9831.950840245752;9874;9982.650607228712;9984.251181161588;9992.150667193078;9474.85787713171;9859.211263099078;10071.345362551703;10080.69757907264;10113.59545618167;5950.583459157818;6062;6873.621950792754;7087.964560177373;7605.543667379975;8251.216134467217;8393.874091888749;8440.577971101544;8455.02767181782;8460.957695211484;6135.631862088289;7438.5536867788105;7456;7542.962285188539;7589.354904833151;7299.963500182465;7346.008288881147;7378.409727221917;7417;7418.629068546659;6470.417662410406;6589.143411356493;7034.437245020488;7041.084658994503;7054.188768291712;4857.684250523705;4929.112759703145;4989.201727723546;5008.2738002988635;5151.716655583829;3979.960200398075;4762.404699412574;4940.135476291563;5115.079285728604;5242.659227150223 07:32:19 INFO - PROCESS | 1657 | |4;prototype.html;301.20481927710847;306.3428944913161;319.32144193588624;320.21866645385256;321.0448914479424;4733.8106475741015;5131.820386286481;5180.073258608888;5215.739213039347;5221.634485586011;5122.6424997375725;5134.229865520174;5135.897282054357;5149.330587023682;5163.483651634835;3297.934041319172;3501.509788629594;3848.672208088219;3979.6617287530594;3998.8800335989968;3343.6823501767294;3858.2862170498406;3937.6652984496354;3979.5622481527007;3986.5415477220054;3752.624737526253;3795.9620403795884;3911.5892831252736;3949.526056873165;4060.533038700543;3035.620547431571;3825.0437390652337;3906.3163946309564;3915.8629447969183;3952.6837852971694;2815.619891314667;3008.638963324393;3463.7748546344405;3478.165240342325;3485.459753738149;4141.399497072937;4787.210110331791;4963.255511673242;4996.650234483587;5008.198688209905;3809.771413715186;4338.58783415575;4484.775761211959;4497.707649002804;4514.683972121953;4563.338315944169;4963.468630998181;5406;5424.83725488236;5426.2674538937135;3811.447340135665;3819.8854034379015;3829.770213787154;3843.8462461501786;3850.2085987659;4422.336649502568;5235.790568377261;5478.369987451454;5628.465295796893;5631.915521267184;3761.7178711596193;3786.2738828043766;3898.2008688219416;3900.7464514806447;3916.5104361954754;3096.8451577421038;3451.8274086295582;3798.7530810497783;3935.9016024599614;3979.4428779970826;3800.0879788850475;4150.439690641785;4340.826366945287;4559.042601053749;4572;5146.665466744649;5272.681829542615;5382.604740890344;5428.45715428454;5436.320459942507;1117.6032508459543;1176.3823992404057;1206.7646808872187;1267.9809802852965;1271.319843883518;799.4683535448861;812.695239285268;818.0633175014694;860.4278155026838;898.7213963671282;4129.669626429878;4334.934975975363;4366.8908277292685;4671.32265821454;4773.021530586221;4668.366071874884;4805.783739731721;5090.414602320707;5279.4130875871315;5321.536577441235;4024.778637174925;4270.615644592001;4635.814567417266;4717.226219024767;4791.292919500471;2271.0802125139344;2381.8332716709665;2459.311392810016;2721.7550420461857;2856.8000239983003;4753.667243292936;4814.037192561502;5195.635985626531;5243.003829272426;5528.668279903139;4629.398178236875;5238.869028274247;5256.553192978563;5419.214213938957;5454.4545545445135;4546.863594092117;4796.616270698336;4958.983408401341;5045.2432135180015;5062.696238225718;4573.611243044378;5161.703042146209;5221.556167725786;5230;5282.339707536558;4077.8368865245056;5098.881342389073;5217.40869034948;5250.718301061836;5305.442928492453;4898.853034408973;5597.468240517144;5777.39337369587;5829.446202610745;5830.708464576669;3758.6053464385845;3813.8474461021246;3821.2166505865675;3821.662418153624;3825.1775868188474;4937.160682684024;4969.254611808257;5442.965836491054;5612.8877422450505;5626.465485778845;4727.3381726559;5112.897742045213;5243.895122097613;5409.269748584048;5444.918326225031;4763.642726795435;5010.649254552146;5462.754176062166;5464.91802622953;5494.066008778437;5033.521815427529;5323.387810401853;5332.33345831771;5332.946670533245;5334.453008627454;5230.764615592231;5392.737393876805;5421.620486565903;5428.83713488596;5456.825528470802;4508;4546.636269098531;5178.145605974972;5334.653247538898;5351.491608297206 07:32:19 INFO - PROCESS | 1657 | |5;yui.html;647.1683886206224;675.5273503761799;696.5750891955909;721.2210812322692;747.6897087708597;14416.49542266021;15143.62140946477;15173;15201.239938003111;15224.162671053102;13752.587422377344;13802.654933626663;13823.79264311036;13853.584392468218;13869.445222191125;722.902408174895;747.0437839565365;899.7525680437866;910.4218821048632;929.1219797291562;922.7324076017981;927.1377618814499;928.9767755806091;928.995355023224;929.7768535551487;964.4599024546277;985.5417230987589;986.1026465916018;987.5901500877163;987.9654212102578;758.4084414156966;862.2972277593741;975.4244995452717;977.3207620703614;979.0894468144622;727.5452841973766;727.6725473536877;728.6502478810148;728.7485817393044;728.9125304963438;14235.07472014326;14272.785908211385;14293.642658933506;14299.57101286963;14300.642483937985;10105.747356316077;11922.880771192351;12074.758504829942;12181.903628673374;12265.448054837554;10440.164786817113;10580.523876425503;10949.397783121925;11002.559897604166;11245.212835101545;3901.2977664020464;3982.76103433792;4099.9180016399805;4130.896727581804;4213.47331583552;75145.24854751438;76559.6172019142;76563;77431;77451.22548774496;9242.399244049115;9249.630014799333;9345.112214339626;9533.332666713264;9687.128158465772;19571.92354420492;20390.898045509974;20442.38672839817;20825.166993320097;20893.791062089487;7575.810604734926;7619.390448764086;7629.237076292437;7698.076230852222;8003.5197888126895;4082.408050832613;4088.366303223005;4121.237571049365;4146.357314616239;4452.998075433002;3012.367402845427;3410.863565457354;3535.381308271042;3623.691986181204;3633.909152271214;665.9234188068338;666.5234357434458;669.4309836638915;678.1624693503519;679.6024325769381;552.9142982837667;562.4207066721284;576.4608495317513;584.0800738836265;584.3834754334218;7138.643067846587;7613.314801667766;7811.843763124706;7987;8118.918810811934;568.8094488346368;577.8324285957109;597.8027251007157;610.578700696518;612.6201754911983;3734.533183352081;3871.8838434847;3885.630865067814;3930.76415415076;3950.4469374287623;77942;80065.59967200126;81380.18619813844;81407.18592813997;81665.59167204126;56306.87386252252;77962.6101869487;79138.41723165504;79504.60247698725;81596.18403816005;59860.10209846856;60274.794504108795;60664.39335606588;60909.781804363665;61117.08324375138;7524.962375188089;7635.618219089134;7664.961675191588;7719.498232614862;7854.957755897392;44057.55942440534;44446;44760.552394475635;45490.862728432054;45542.77228613836;56723.71638141783;57012;60254.49363765941;62883;63052.36947630465;9917.900820991697;10287.639932602324;10335.896641033494;10355;10540.367577945348;11100;11101.944490277498;11136.38749868765;11384.43077846121;11603.12976526747;8478.533680647624;9634;10972.615958441416;11184.937430944048;11555.017823485097;11371.090312774793;11503.068250473321;11586;11739.471723772622;12110.576129835414;11205;11278.718032049264;11339.433028348714;11422.82865757031;11506.76986460283;9369.484678342757;9393.906060939576;9476;9549.52252387364;9552.856707149538;9499.097585729345;9527.857082143635;9608.711738647851;9682.951585242028;9701.87164443743 07:32:19 INFO - PROCESS | 1657 | __end_tp_report 07:32:19 INFO - PROCESS | 1657 | __start_cc_report 07:32:19 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,1392 07:32:19 INFO - PROCESS | 1657 | __end_cc_report 07:32:19 INFO - PROCESS | 1657 | __startTimestamp1462545139672__endTimestamp 07:32:19 INFO - PROCESS | 1657 | 07:32:19 INFO - PROCESS | 1657 | ------- Summary: start ------- 07:32:19 INFO - PROCESS | 1657 | Number of tests: 6 07:32:19 INFO - PROCESS | 1657 | 07:32:19 INFO - PROCESS | 1657 | [#0] dojo.html Cycles:165 Average:5743.71 Median:5930.20 stddev:639.08 (10.8%) stddev-sans-first:614.25 07:32:19 INFO - PROCESS | 1657 | Values: 3409.4 3438.1 3449.7 3471.0 3509.5 5588.7 5659.6 5660.7 5701.9 5724.8 5687.5 5751.3 5751.5 5754.3 5764.4 5126.8 5156.5 5168.9 5176.3 5233.1 5233.1 5245.6 5246.3 5249.0 5249.8 5159.7 5162.3 5177.6 5188.9 5219.7 5231.6 5266.9 5274.1 5283.9 5323.9 5077.9 5080.7 5089.9 5183.6 5194.8 5552.6 5575.1 5634.9 5636.2 5642.4 6278.5 6285.4 6287.1 6293.5 6296.2 5242.7 6128.8 6223.0 6316.5 6342.3 5847.8 5857.5 5890.5 5893.1 5924.1 6274.7 6284.4 6287.8 6291.1 6293.2 5867.8 5944.9 6102.2 6121.4 6203.8 6316.1 6321.2 6332.5 6339.9 6370.9 6209.3 6225.0 6253.5 6256.8 6269.6 6093.8 6106.4 6151.0 6195.3 6286.3 4850.7 4854.1 4881.3 4897.7 4906.0 4166.4 4434.4 4442.7 4460.5 4478.5 5728.9 5775.2 5784.0 5784.0 5809.5 6139.9 6187.6 6192.8 6339.0 6341.4 5671.7 5684.0 5688.0 5692.3 5703.9 5336.5 5345.7 5352.3 5355.3 5357.1 6196.7 6227.9 6269.3 6321.1 6321.3 6334.1 6342.1 6359.6 6374.8 6379.6 6217.3 6327.2 6334.8 6335.6 6350.6 6104.9 6164.3 6175.7 6243.4 6245.0 6294.1 6299.1 6312.7 6321.2 6321.5 5879.6 5919.0 5925.6 6119.3 6144.4 5942.2 6034.9 6060.8 6096.7 6114.8 5933.6 5941.0 5944.3 6001.9 6007.8 5942.8 6113.3 6116.1 6119.6 6121.0 5849.3 5850.2 5930.2 5950.4 5967.4 07:32:19 INFO - PROCESS | 1657 | 07:32:19 INFO - PROCESS | 1657 | [#1] ext.html Cycles:180 Average:67889.38 Median:18490.42 stddev:147654.25 (798.5%) stddev-sans-first:147981.80 07:32:19 INFO - PROCESS | 1657 | Values: 512.1 524.8 531.1 556.0 582.3 30134.8 31709.0 41916.6 43590.6 43614.0 44263.9 44558.3 44606.6 44645.6 44654.6 9546.6 9750.5 9758.1 9782.8 9786.8 8592.1 8813.4 11609.5 11848.9 11851.3 11648.8 11736.7 11742.6 11744.2 11750.4 8609.1 11454.8 11668.9 11745.9 11751.6 11309.0 11571.9 11578.0 11583.0 11583.7 32504.5 40280.0 40332.2 40415.6 40473.4 2722.0 3191.7 3193.7 3266.4 3425.3 28624.6 28781.4 28975.3 29413.0 29708.6 2557.5 2686.2 2703.6 2712.4 2721.8 472675.0 475316.6 475597.0 475876.0 476921.0 65434.0 65892.0 66016.7 66120.3 66425.7 36679.8 37420.3 37457.9 38667.0 68706.0 17033.3 17125.2 17255.3 17310.6 17318.6 11601.7 11842.2 12866.9 12985.6 13094.7 16699.4 19675.3 19902.1 20261.7 20263.6 8170.8 8294.9 8310.9 8332.6 8365.7 848.4 972.9 1008.5 1017.5 1108.9 29676.4 29676.9 29853.9 29951.9 30050.4 963.7 1114.6 1123.7 1145.5 1167.3 14376.6 15000.9 15019.3 15080.3 15480.9 664481.0 665719.0 665953.0 668554.0 669555.0 60383.0 61058.1 61135.1 61264.7 61467.0 1333.2 1339.8 1437.2 1470.4 1512.7 30121.1 30177.4 30390.1 30699.0 30866.4 50343.7 50830.7 50864.0 50884.7 51056.7 479832.0 498252.0 498923.0 499179.0 499983.0 23512.2 24054.3 24069.6 24778.8 26573.1 36219.8 39451.0 42838.4 48114.3 49694.3 37179.6 37234.3 37821.6 38792.8 38862.0 36721.1 37138.1 37142.3 37962.2 38098.0 36430.6 36481.3 36779.6 37130.4 37579.4 31374.4 33479.8 33494.5 33542.7 33555.5 22286.7 23907.4 24314.4 24937.0 25007.2 07:32:19 INFO - PROCESS | 1657 | 07:32:19 INFO - PROCESS | 1657 | [#2] jquery.html Cycles:180 Average:35148.07 Median:21035.97 stddev:101058.12 (480.4%) stddev-sans-first:101307.85 07:32:19 INFO - PROCESS | 1657 | Values: 355.2 392.2 394.3 398.0 417.5 13598.9 20086.3 20991.7 21319.9 21338.8 20000.5 20572.0 20863.4 21025.4 21046.6 5683.6 5968.3 5971.5 5982.5 5986.6 5550.1 5776.1 5884.9 5887.6 5894.2 5830.3 5836.4 5848.7 5852.6 5853.6 5620.7 5835.0 5852.4 5971.0 5976.9 5753.0 5763.7 5765.7 5767.4 5767.9 19596.5 19622.7 24980.8 25062.4 25114.7 2165.5 2178.2 2221.9 2240.8 2329.4 9730.6 10101.6 12184.8 13421.4 13655.3 1645.1 1654.6 1658.5 1692.2 1699.6 114267.0 114695.3 123618.0 124928.0 125211.4 9523.0 11666.3 11710.1 11753.8 11941.9 13181.9 13752.6 13870.5 14887.3 14938.6 8517.1 8620.3 8695.9 8808.5 8822.8 8299.3 8406.9 8568.5 8573.1 8774.6 3988.4 4362.1 4503.4 4528.7 4579.4 1901.0 1944.5 1952.6 1953.7 1962.5 890.2 933.9 1001.1 1019.1 1022.6 16209.6 18967.1 20132.4 20398.3 20684.4 848.7 902.4 918.8 1004.0 1054.4 10486.3 10647.6 10658.9 10690.7 10695.0 594046.0 599903.0 605918.0 606484.0 613635.0 130967.3 138701.3 138990.0 142706.0 143303.0 1025.6 1032.4 1039.2 1051.7 1059.6 17224.9 17548.6 17965.4 17977.4 17978.5 47127.8 50521.7 50738.0 51022.0 52027.8 59319.7 71137.0 73691.3 74650.9 76401.2 8180.9 8276.2 8299.4 8419.0 8499.8 9032.6 11680.2 12425.8 12852.0 12952.5 10396.6 10545.5 10962.3 11075.4 11126.3 10703.3 10870.2 10892.5 11230.6 11263.4 11278.9 11291.5 11604.8 11673.0 11716.3 8140.8 8155.3 8208.7 8414.5 8609.0 10080.2 10676.2 10783.1 11137.2 11492.3 07:32:19 INFO - PROCESS | 1657 | 07:32:19 INFO - PROCESS | 1657 | [#3] mootools.html Cycles:180 Average:5706.30 Median:4931.22 stddev:3190.87 (64.7%) stddev-sans-first:3183.47 07:32:19 INFO - PROCESS | 1657 | Values: 1408.4 1580.2 1629.7 1849.7 1987.2 7830.6 8604.2 8856.3 8862.8 8977.5 8574.7 8813.9 8826.8 8830.6 8830.8 2165.5 2360.5 2467.9 2469.8 2505.6 2066.0 2512.2 2513.8 2514.5 2520.2 2023.6 2443.7 2477.4 2478.4 2479.0 2132.0 2359.2 2413.8 2441.2 2446.0 2457.8 2468.5 2491.8 2496.4 2499.5 8652.1 8716.0 8717.1 8729.3 8742.3 5362.2 5539.7 5546.1 5550.1 5563.1 3481.5 3554.9 3562.7 3591.2 3593.4 1041.9 1051.5 1055.8 1058.5 1061.4 11496.5 11788.3 11799.1 11803.5 11865.6 4919.0 4933.3 5040.1 5041.4 5051.3 10164.4 10184.9 10191.4 10200.1 10222.6 5529.9 5553.1 5558.1 5564.2 5568.9 4405.5 4589.2 4860.2 4985.5 4988.6 4056.1 4077.2 4156.5 4175.3 4185.9 1311.9 1328.6 1339.6 1342.4 1357.2 821.5 900.9 941.5 953.1 986.7 5887.4 5949.3 6042.8 6052.6 6139.8 372.6 456.6 469.1 481.6 482.8 3339.2 4010.1 4042.0 4051.7 4077.9 10186.6 10571.9 10598.2 10757.8 10799.5 10572.3 10574.2 10669.1 10693.3 10694.5 8006.9 8024.8 8141.8 8218.3 8220.8 5842.2 5842.6 5895.8 5910.9 5945.9 9832.0 9874.0 9982.7 9984.3 9992.2 9474.9 9859.2 10071.3 10080.7 10113.6 5950.6 6062.0 6873.6 7088.0 7605.5 8251.2 8393.9 8440.6 8455.0 8461.0 6135.6 7438.6 7456.0 7543.0 7589.4 7300.0 7346.0 7378.4 7417.0 7418.6 6470.4 6589.1 7034.4 7041.1 7054.2 4857.7 4929.1 4989.2 5008.3 5151.7 3980.0 4762.4 4940.1 5115.1 5242.7 07:32:19 INFO - PROCESS | 1657 | 07:32:19 INFO - PROCESS | 1657 | [#4] prototype.html Cycles:180 Average:4280.05 Median:4758.65 stddev:1304.68 (27.4%) stddev-sans-first:1273.70 07:32:19 INFO - PROCESS | 1657 | Values: 301.2 306.3 319.3 320.2 321.0 4733.8 5131.8 5180.1 5215.7 5221.6 5122.6 5134.2 5135.9 5149.3 5163.5 3297.9 3501.5 3848.7 3979.7 3998.9 3343.7 3858.3 3937.7 3979.6 3986.5 3752.6 3796.0 3911.6 3949.5 4060.5 3035.6 3825.0 3906.3 3915.9 3952.7 2815.6 3008.6 3463.8 3478.2 3485.5 4141.4 4787.2 4963.3 4996.7 5008.2 3809.8 4338.6 4484.8 4497.7 4514.7 4563.3 4963.5 5406.0 5424.8 5426.3 3811.4 3819.9 3829.8 3843.8 3850.2 4422.3 5235.8 5478.4 5628.5 5631.9 3761.7 3786.3 3898.2 3900.7 3916.5 3096.8 3451.8 3798.8 3935.9 3979.4 3800.1 4150.4 4340.8 4559.0 4572.0 5146.7 5272.7 5382.6 5428.5 5436.3 1117.6 1176.4 1206.8 1268.0 1271.3 799.5 812.7 818.1 860.4 898.7 4129.7 4334.9 4366.9 4671.3 4773.0 4668.4 4805.8 5090.4 5279.4 5321.5 4024.8 4270.6 4635.8 4717.2 4791.3 2271.1 2381.8 2459.3 2721.8 2856.8 4753.7 4814.0 5195.6 5243.0 5528.7 4629.4 5238.9 5256.6 5419.2 5454.5 4546.9 4796.6 4959.0 5045.2 5062.7 4573.6 5161.7 5221.6 5230.0 5282.3 4077.8 5098.9 5217.4 5250.7 5305.4 4898.9 5597.5 5777.4 5829.4 5830.7 3758.6 3813.8 3821.2 3821.7 3825.2 4937.2 4969.3 5443.0 5612.9 5626.5 4727.3 5112.9 5243.9 5409.3 5444.9 4763.6 5010.6 5462.8 5464.9 5494.1 5033.5 5323.4 5332.3 5332.9 5334.5 5230.8 5392.7 5421.6 5428.8 5456.8 4508.0 4546.6 5178.1 5334.7 5351.5 07:32:19 INFO - PROCESS | 1657 | 07:32:19 INFO - PROCESS | 1657 | [#5] yui.html Cycles:180 Average:17015.71 Median:60469.59 stddev:23237.29 (38.4%) stddev-sans-first:23269.98 07:32:19 INFO - PROCESS | 1657 | Values: 647.2 675.5 696.6 721.2 747.7 14416.5 15143.6 15173.0 15201.2 15224.2 13752.6 13802.7 13823.8 13853.6 13869.4 722.9 747.0 899.8 910.4 929.1 922.7 927.1 929.0 929.0 929.8 964.5 985.5 986.1 987.6 988.0 758.4 862.3 975.4 977.3 979.1 727.5 727.7 728.7 728.7 728.9 14235.1 14272.8 14293.6 14299.6 14300.6 10105.7 11922.9 12074.8 12181.9 12265.4 10440.2 10580.5 10949.4 11002.6 11245.2 3901.3 3982.8 4099.9 4130.9 4213.5 75145.2 76559.6 76563.0 77431.0 77451.2 9242.4 9249.6 9345.1 9533.3 9687.1 19571.9 20390.9 20442.4 20825.2 20893.8 7575.8 7619.4 7629.2 7698.1 8003.5 4082.4 4088.4 4121.2 4146.4 4453.0 3012.4 3410.9 3535.4 3623.7 3633.9 665.9 666.5 669.4 678.2 679.6 552.9 562.4 576.5 584.1 584.4 7138.6 7613.3 7811.8 7987.0 8118.9 568.8 577.8 597.8 610.6 612.6 3734.5 3871.9 3885.6 3930.8 3950.4 77942.0 80065.6 81380.2 81407.2 81665.6 56306.9 77962.6 79138.4 79504.6 81596.2 59860.1 60274.8 60664.4 60909.8 61117.1 7525.0 7635.6 7665.0 7719.5 7855.0 44057.6 44446.0 44760.6 45490.9 45542.8 56723.7 57012.0 60254.5 62883.0 63052.4 9917.9 10287.6 10335.9 10355.0 10540.4 11100.0 11101.9 11136.4 11384.4 11603.1 8478.5 9634.0 10972.6 11184.9 11555.0 11371.1 11503.1 11586.0 11739.5 12110.6 11205.0 11278.7 11339.4 11422.8 11506.8 9369.5 9393.9 9476.0 9549.5 9552.9 9499.1 9527.9 9608.7 9683.0 9701.9 07:32:19 INFO - PROCESS | 1657 | -------- Summary: end -------- 07:32:19 INFO - PROCESS | 1657 | 07:32:20 INFO - TEST-INFO | 1657: exit 0 07:32:20 INFO - TEST-OK | dromaeo_css | took 1116464ms 07:32:20 INFO - TEST-START | kraken 07:32:20 INFO - operating with platform_type : mac_ 07:32:20 INFO - Initialising browser for kraken test... 07:32:20 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/tmpEAUi85/profile http://localhost:49274/getInfo.html) 07:32:22 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 07:32:22 INFO - PROCESS | 1667 | colorDepth:24 07:32:22 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 07:32:22 INFO - PROCESS | 1667 | __metrics 07:32:22 INFO - PROCESS | 1667 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:32:22 ERROR - PROCESS | 1667 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:32:23 INFO - TEST-INFO | 1667: exit 0 07:32:23 INFO - Browser initialized. 07:32:23 INFO - Running cycle 1/1 for kraken test... 07:32:23 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/tmpEAUi85/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:32:35 INFO - PROCESS | 1669 | RSS: Main: 239190016 07:32:35 INFO - PROCESS | 1669 | 07:32:37 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection) 07:32:38 INFO - PROCESS | 1669 | RSS: Main: 286466048 07:32:38 INFO - PROCESS | 1669 | 07:32:41 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-dft) 07:32:41 INFO - PROCESS | 1669 | RSS: Main: 295346176 07:32:41 INFO - PROCESS | 1669 | 07:32:45 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-fft) 07:32:46 INFO - PROCESS | 1669 | RSS: Main: 381116416 07:32:46 INFO - PROCESS | 1669 | 07:32:48 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator) 07:32:48 INFO - PROCESS | 1669 | RSS: Main: 283992064 07:32:48 INFO - PROCESS | 1669 | 07:32:50 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:32:51 INFO - PROCESS | 1669 | RSS: Main: 288030720 07:32:51 INFO - PROCESS | 1669 | 07:32:54 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom) 07:32:54 INFO - PROCESS | 1669 | RSS: Main: 326021120 07:32:54 INFO - PROCESS | 1669 | 07:32:57 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate) 07:32:58 INFO - PROCESS | 1669 | RSS: Main: 328347648 07:32:58 INFO - PROCESS | 1669 | 07:33:01 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial) 07:33:01 INFO - PROCESS | 1669 | RSS: Main: 325533696 07:33:01 INFO - PROCESS | 1669 | 07:33:03 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:33:04 INFO - PROCESS | 1669 | RSS: Main: 312934400 07:33:04 INFO - PROCESS | 1669 | 07:33:05 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:33:06 INFO - PROCESS | 1669 | RSS: Main: 319590400 07:33:06 INFO - PROCESS | 1669 | 07:33:08 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:33:09 INFO - PROCESS | 1669 | RSS: Main: 321904640 07:33:09 INFO - PROCESS | 1669 | 07:33:12 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:33:12 INFO - PROCESS | 1669 | RSS: Main: 326230016 07:33:12 INFO - PROCESS | 1669 | 07:33:15 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:33:16 INFO - PROCESS | 1669 | RSS: Main: 323932160 07:33:16 INFO - PROCESS | 1669 | 07:33:18 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49274/tests/kraken/driver.html?testName=ai-astar) 07:33:18 INFO - PROCESS | 1669 | RSS: Main: 370241536 07:33:18 INFO - PROCESS | 1669 | 07:33:18 INFO - PROCESS | 1669 | __start_tp_report 07:33:18 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 07:33:18 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 07:33:18 INFO - PROCESS | 1669 | |i|pagename|runs| 07:33:18 INFO - PROCESS | 1669 | |0;ai-astar;111;106;112;112;112;115;111;112;111;112 07:33:18 INFO - PROCESS | 1669 | |1;audio-beat-detection;151;117;158;189;142;141;135;118;167;117 07:33:18 INFO - PROCESS | 1669 | |2;audio-dft;185;187;187;188;185;186;185;181;189;181 07:33:18 INFO - PROCESS | 1669 | |3;audio-fft;77;74;48;103;72;85;80;81;127;53 07:33:18 INFO - PROCESS | 1669 | |4;audio-oscillator;91;99;92;73;80;78;101;80;81;101 07:33:18 INFO - PROCESS | 1669 | |5;imaging-gaussian-blur;89;87;90;88;90;87;90;88;89;87 07:33:18 INFO - PROCESS | 1669 | |6;imaging-darkroom;127;95;96;95;95;95;95;94;94;95 07:33:18 INFO - PROCESS | 1669 | |7;imaging-desaturate;86;87;85;88;84;86;84;86;83;85 07:33:18 INFO - PROCESS | 1669 | |8;json-parse-financial;47;54;54;52;58;57;57;76;60;57 07:33:18 INFO - PROCESS | 1669 | |9;json-stringify-tinderbox;64;64;76;81;72;70;66;67;68;67 07:33:18 INFO - PROCESS | 1669 | |10;stanford-crypto-aes;86;86;84;83;84;83;85;86;84;83 07:33:18 INFO - PROCESS | 1669 | |11;stanford-crypto-ccm;142;152;134;143;127;142;152;147;184;160 07:33:18 INFO - PROCESS | 1669 | |12;stanford-crypto-pbkdf2;176;168;169;167;168;168;167;168;167;167 07:33:18 INFO - PROCESS | 1669 | |13;stanford-crypto-sha256-iterative;66;67;66;68;67;66;65;66;65;66 07:33:18 INFO - PROCESS | 1669 | __end_tp_report 07:33:18 INFO - PROCESS | 1669 | __start_cc_report 07:33:18 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,1997 07:33:18 INFO - PROCESS | 1669 | __end_cc_report 07:33:18 INFO - PROCESS | 1669 | __startTimestamp1462545198372__endTimestamp 07:33:18 INFO - PROCESS | 1669 | 07:33:18 INFO - PROCESS | 1669 | ------- Summary: start ------- 07:33:18 INFO - PROCESS | 1669 | Number of tests: 14 07:33:18 INFO - PROCESS | 1669 | 07:33:18 INFO - PROCESS | 1669 | [#0] ai-astar Cycles:10 Average:111.40 Median:112.00 stddev:2.22 (2.0%) stddev-sans-first:2.35 07:33:18 INFO - PROCESS | 1669 | Values: 111.0 106.0 112.0 112.0 112.0 115.0 111.0 112.0 111.0 112.0 07:33:18 INFO - PROCESS | 1669 | 07:33:18 INFO - PROCESS | 1669 | [#1] audio-beat-detection Cycles:10 Average:143.50 Median:146.50 stddev:23.63 (16.1%) stddev-sans-first:24.90 07:33:18 INFO - PROCESS | 1669 | Values: 151.0 117.0 158.0 189.0 142.0 141.0 135.0 118.0 167.0 117.0 07:33:18 INFO - PROCESS | 1669 | 07:33:18 INFO - PROCESS | 1669 | [#2] audio-dft Cycles:10 Average:185.40 Median:186.50 stddev:2.67 (1.4%) stddev-sans-first:2.83 07:33:18 INFO - PROCESS | 1669 | Values: 185.0 187.0 187.0 188.0 185.0 186.0 185.0 181.0 189.0 181.0 07:33:18 INFO - PROCESS | 1669 | 07:33:18 INFO - PROCESS | 1669 | [#3] audio-fft Cycles:10 Average:80.00 Median:75.50 stddev:22.67 (30.0%) stddev-sans-first:24.02 07:33:18 INFO - PROCESS | 1669 | Values: 77.0 74.0 48.0 103.0 72.0 85.0 80.0 81.0 127.0 53.0 07:33:18 INFO - PROCESS | 1669 | 07:33:18 INFO - PROCESS | 1669 | [#4] audio-oscillator Cycles:10 Average:87.60 Median:80.50 stddev:10.46 (13.0%) stddev-sans-first:11.02 07:33:18 INFO - PROCESS | 1669 | Values: 91.0 99.0 92.0 73.0 80.0 78.0 101.0 80.0 81.0 101.0 07:33:18 INFO - PROCESS | 1669 | 07:33:18 INFO - PROCESS | 1669 | [#5] imaging-gaussian-blur Cycles:10 Average:88.50 Median:89.00 stddev:1.27 (1.4%) stddev-sans-first:1.33 07:33:18 INFO - PROCESS | 1669 | Values: 89.0 87.0 90.0 88.0 90.0 87.0 90.0 88.0 89.0 87.0 07:33:18 INFO - PROCESS | 1669 | 07:33:18 INFO - PROCESS | 1669 | [#6] imaging-darkroom Cycles:10 Average:98.10 Median:95.00 stddev:10.17 (10.7%) stddev-sans-first:0.60 07:33:18 INFO - PROCESS | 1669 | Values: 127.0 95.0 96.0 95.0 95.0 95.0 95.0 94.0 94.0 95.0 07:33:18 INFO - PROCESS | 1669 | 07:33:18 INFO - PROCESS | 1669 | [#7] imaging-desaturate Cycles:10 Average:85.40 Median:86.00 stddev:1.51 (1.8%) stddev-sans-first:1.58 07:33:18 INFO - PROCESS | 1669 | Values: 86.0 87.0 85.0 88.0 84.0 86.0 84.0 86.0 83.0 85.0 07:33:18 INFO - PROCESS | 1669 | 07:33:18 INFO - PROCESS | 1669 | [#8] json-parse-financial Cycles:10 Average:57.20 Median:57.00 stddev:7.55 (13.3%) stddev-sans-first:7.05 07:33:18 INFO - PROCESS | 1669 | Values: 47.0 54.0 54.0 52.0 58.0 57.0 57.0 76.0 60.0 57.0 07:33:18 INFO - PROCESS | 1669 | 07:33:18 INFO - PROCESS | 1669 | [#9] json-stringify-tinderbox Cycles:10 Average:69.50 Median:69.00 stddev:5.46 (7.9%) stddev-sans-first:5.42 07:33:18 INFO - PROCESS | 1669 | Values: 64.0 64.0 76.0 81.0 72.0 70.0 66.0 67.0 68.0 67.0 07:33:18 INFO - PROCESS | 1669 | 07:33:18 INFO - PROCESS | 1669 | [#10] stanford-crypto-aes Cycles:10 Average:84.40 Median:84.50 stddev:1.26 (1.5%) stddev-sans-first:1.20 07:33:18 INFO - PROCESS | 1669 | Values: 86.0 86.0 84.0 83.0 84.0 83.0 85.0 86.0 84.0 83.0 07:33:18 INFO - PROCESS | 1669 | 07:33:18 INFO - PROCESS | 1669 | [#11] stanford-crypto-ccm Cycles:10 Average:148.30 Median:149.50 stddev:15.66 (10.5%) stddev-sans-first:16.44 07:33:18 INFO - PROCESS | 1669 | Values: 142.0 152.0 134.0 143.0 127.0 142.0 152.0 147.0 184.0 160.0 07:33:18 INFO - PROCESS | 1669 | 07:33:18 INFO - PROCESS | 1669 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.50 Median:168.00 stddev:2.72 (1.6%) stddev-sans-first:0.71 07:33:18 INFO - PROCESS | 1669 | Values: 176.0 168.0 169.0 167.0 168.0 168.0 167.0 168.0 167.0 167.0 07:33:18 INFO - PROCESS | 1669 | 07:33:18 INFO - PROCESS | 1669 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:66.00 stddev:0.92 (1.4%) stddev-sans-first:0.97 07:33:18 INFO - PROCESS | 1669 | Values: 66.0 67.0 66.0 68.0 67.0 66.0 65.0 66.0 65.0 66.0 07:33:18 INFO - PROCESS | 1669 | -------- Summary: end -------- 07:33:18 INFO - PROCESS | 1669 | 07:33:19 INFO - TEST-INFO | 1669: exit 0 07:33:19 INFO - TEST-OK | kraken | took 58932ms 07:33:19 INFO - SUITE-END | took 1175s 07:33:19 INFO - Completed test suite (00:19:35) 07:33:19 INFO - javascript benchmark 07:33:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3409.403354412977, 3438.0545350028724, 3449.672281133292, 3471.0107619328496, 3509.5086687863686, 5588.6646801191955, 5659.575531835108, 5660.745266463009, 5701.857453563658, 5724.771009159639, 5687.544996400288, 5751.309842818868, 5751.482366587006, 5754.251947246862, 5764.365919748835, 5126.846194614158, 5156.53591176794, 5168.870778230536, 5176.327077479922, 5233.136532472137, 5233.084210263208, 5245.606579506533, 5246.291750613656, 5248.950209958005, 5249.842504724846, 5159.74201289934, 5162.277281180638, 5177.611679124062, 5188.948110518884, 5219.739013049351, 5231.607629427789, 5266.94661067788, 5274.129768588178, 5283.920741188886, 5323.893522129536, 5077.949220507785, 5080.695158290514, 5089.949100508985, 5183.559397451221, 5194.844154675366, 5552.555795536347, 5575.135853942646, 5634.943650563483, 5636.182753500761, 5642.351129620105, 6278.4663303619245, 6285.371462853724, 6287.088372186007, 6293.527985401068, 6296.244450665949, 5242.7378631068295, 6128.754849806002, 6222.968885155546, 6316.494680425555, 6342.270638876496, 5847.766089356463, 5857.531397488191, 5890.4698577128265, 5893.116032595145, 5924.14099955504, 6274.717637706314, 6284.371562843679, 6287.842803929847, 6291.1192433059405, 6293.181886354744, 5867.765289388462, 5944.881102377928, 6102.206713127353, 6121.422488707842, 6203.844903877439, 6316.05259211122, 6321.209848768904, 6332.461740751995, 6339.873202535923, 6370.904436433457, 6209.348018457997, 6224.968875155595, 6253.499720022387, 6256.7810126645345, 6269.623822570569, 6093.847653808691, 6106.369660900722, 6151.0, 6195.349488303754, 6286.277078135981, 4850.708957462493, 4854.101991131582, 4881.267809828483, 4897.657163998557, 4905.975470122627, 4166.354215096604, 4434.357018232338, 4442.73343599385, 4460.50934397216, 4478.529754375743, 5728.942710572925, 5775.220345253337, 5783.971080144573, 5784.045632470596, 5809.535237181015, 6139.8601398601395, 6187.628742275388, 6192.81421557354, 6338.968305158444, 6341.429271365507, 5671.6597004179885, 5684.0, 5688.0, 5692.345380281238, 5703.914441283384, 5336.519713225828, 5345.732713364317, 5352.330958630171, 5355.3305836770405, 5357.089294819812, 6196.690165491707, 6227.875442491125, 6269.341719119518, 6321.146645202839, 6321.273053598803, 6334.081558174131, 6342.080398342122, 6359.618422894641, 6374.808755737336, 6379.617222966637, 6217.347178546188, 6327.209098862642, 6334.841628959313, 6335.588186767845, 6350.587211831216, 6104.853288833144, 6164.291106522807, 6175.660338681416, 6243.43809057187, 6245.0, 6294.087357333253, 6299.149614802126, 6312.6528040956355, 6321.1782468278825, 6321.462675672436, 5879.559033072451, 5918.9704051481185, 5925.644461332334, 6119.296280927751, 6144.41628045335, 5942.168096466412, 6034.939650603438, 6060.78787242462, 6096.695165241809, 6114.755409783559, 5933.614315069507, 5941.0, 5944.286685597756, 6001.939980600138, 6007.849803754941, 5942.821715348547, 6113.341591916454, 6116.14702659631, 6119.600865257057, 6120.969395152996, 5849.327327357409, 5850.23946886902, 5930.199423077829, 5950.375210602824, 5967.37342579023], "value": 5703.00739833106, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [512.1472747874789, 524.845170674651, 531.1050879268433, 556.0269528325431, 582.3361368040435, 30134.849325753366, 31709.048728538153, 41916.58083419165, 43590.56409435905, 43614.0, 44263.89340266495, 44558.33162502557, 44606.553934460644, 44645.55354446454, 44654.553454465444, 9546.570404331787, 9750.512474376254, 9758.121769040785, 9782.804343913118, 9786.804263914717, 8592.05487396386, 8813.38306318558, 11609.535618575246, 11848.881511184863, 11851.288922664624, 11648.767024659503, 11736.706582335424, 11742.589009384674, 11744.236624619414, 11750.41247937604, 8609.139086091403, 11454.828177577343, 11668.941655291754, 11745.941270293637, 11751.588694395741, 11309.0, 11571.884281157165, 11578.0, 11583.0, 11583.652490425216, 32504.512432313535, 40279.99300017494, 40332.19335613301, 40415.595844041476, 40473.39289910654, 2721.9591706124424, 3191.707358519803, 3193.7285330746913, 3266.3957167923772, 3425.3491836551225, 28624.570631440543, 28781.424371512454, 28975.275618109506, 29413.0, 29708.55437168444, 2557.475717477931, 2686.207568767218, 2703.5674292113363, 2712.403271280335, 2721.7958653101095, 472675.0, 475316.62341688416, 475597.0, 475876.0, 476921.0, 65434.0, 65892.0, 66016.6699166501, 66120.33879661238, 66425.66787166033, 36679.81660091683, 37420.25159496795, 37457.87626371213, 38667.0, 68706.0, 17033.318667253418, 17125.22936467862, 17255.309787608607, 17310.567235819206, 17318.567035824206, 11601.651950441501, 11842.171048026728, 12866.871331286755, 12985.610431687064, 13094.738105237842, 16699.41552045697, 19675.311364102476, 19902.104405301507, 20261.696074558895, 20263.594728105356, 8170.834728796874, 8294.875576866352, 8310.916890831015, 8332.583370831555, 8365.749027529184, 848.4273115646913, 972.863799068131, 1008.4554340655964, 1017.4607458047099, 1108.9279196852178, 29676.406471870443, 29676.851615742216, 29853.8632510085, 29951.850240749092, 30050.398992020037, 963.6964356227766, 1114.6433141394675, 1123.741539445932, 1145.4902568357002, 1167.2762887010108, 14376.640583985274, 15000.924995375171, 15019.324130414157, 15080.321385537678, 15480.922595387176, 664481.0, 665719.0, 665953.0, 668554.0, 669555.0, 60383.0, 61058.084128738104, 61135.08297375543, 61264.69367653133, 61467.0, 1333.1601091312411, 1339.7789364754706, 1437.2023526942646, 1470.4485817818318, 1512.6520900193236, 30121.09636710902, 30177.370213571798, 30390.088297351114, 30699.0, 30866.382672346877, 50343.74828125836, 50830.74584627053, 50864.0, 50884.72788180325, 51056.72358191075, 479832.0, 498252.0, 498923.0, 499179.0, 499983.0, 23512.177073802333, 24054.278371648878, 24069.638955415332, 24778.752212477644, 26573.06994255192, 36219.818900905324, 39451.0, 42838.35742463928, 48114.27828582644, 49694.25458618196, 37179.62820371761, 37234.25531489409, 37821.62178378181, 38792.80603596964, 38862.0, 36721.08197295089, 37138.07154821151, 37142.25715485729, 37962.24075518419, 38098.0, 36430.63569364379, 36481.270374591826, 36779.63220367762, 37130.44304335383, 37579.436308454846, 31374.372512549166, 33479.83260083684, 33494.49758253579, 33542.664573353955, 33555.49666755049, 22286.66570001419, 23907.40231494157, 24314.392140196636, 24937.00251989973, 25007.249782506555], "value": 19939.24518596107, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.1901664205611, 392.16077593948955, 394.27059939112644, 398.0148395656232, 417.5281931417498, 13598.932005339984, 20086.296979605715, 20991.740495570237, 21319.93400329985, 21338.786612133874, 20000.49998750032, 20572.0, 20863.37409877706, 21025.36923892282, 21046.57906841866, 5683.602147849642, 5968.343482216953, 5971.522278217754, 5982.491488223484, 5986.551008674345, 5550.056490396622, 5776.075827867542, 5884.941150588504, 5887.617304875191, 5894.233749612545, 5830.300363956331, 5836.358000619928, 5848.736806843681, 5852.561057920673, 5853.648781073128, 5620.662760234379, 5835.0, 5852.3562408135285, 5971.0, 5976.880462390772, 5752.971235143839, 5763.682997435139, 5765.711714414305, 5767.423257674241, 5767.942320576783, 19596.53026023054, 19622.70565941512, 24980.750192498024, 25062.37344066395, 25114.748852511424, 2165.512759629071, 2178.18318130701, 2221.944451388712, 2240.820734341265, 2329.3827135809024, 9730.562124704406, 10101.595936162617, 12184.756304873941, 13421.396037178252, 13655.317234138352, 1645.0869767279155, 1654.5698118489158, 1658.519029481449, 1692.1623796220827, 1699.5666105143232, 114267.0, 114695.27957080651, 123618.0, 124928.0, 125211.37394313062, 9523.000084990976, 11666.300021998537, 11710.063194944383, 11753.823692644617, 11941.880581194251, 13181.868181318065, 13752.587422377144, 13870.514531991332, 14887.255637218095, 14938.626534336729, 8517.105703901125, 8620.267277281502, 8695.869561956575, 8808.51553164582, 8822.77943051416, 8299.253067223857, 8406.915930840736, 8568.52873091986, 8573.056963733985, 8774.60514276859, 3988.4017397390044, 4362.127574485052, 4503.414556107685, 4528.682992190515, 4579.381783459257, 1901.034386357287, 1944.513871532117, 1952.6094781043848, 1953.7264782930401, 1962.5486138188012, 890.2343984173606, 933.883264591926, 1001.0840081325506, 1019.1235537437798, 1022.6216299969059, 16209.594760130854, 18967.051647417575, 20132.401471570385, 20398.28605998783, 20684.379468615967, 848.7353842774222, 902.3503077784117, 918.811643613071, 1004.0, 1054.435876805905, 10486.26596138263, 10647.574097036188, 10658.89341106579, 10690.679279621623, 10695.0, 594046.0, 599903.0, 605918.0, 606484.0, 613635.0, 130967.34516327357, 138701.3064934669, 138990.0, 142706.0, 143303.0, 1025.646152077532, 1032.4373216596823, 1039.2309690828731, 1051.726551096705, 1059.645018918671, 17224.913875430542, 17548.561285967953, 17965.371212007518, 17977.370792022215, 17978.460646180636, 47127.76436117797, 50521.74739126281, 50738.0, 51022.0, 52027.79722027767, 59319.703401482715, 71137.0, 73691.26308737058, 74650.8802367954, 76401.23598763942, 8180.877286840821, 8276.213759692819, 8299.377546684143, 8419.0, 8499.830003399773, 9032.593533290887, 11680.240784348991, 12425.813612795635, 12852.036097292554, 12952.546660866825, 10396.58413663445, 10545.52545135486, 10962.287451315638, 11075.446227688744, 11126.276792008493, 10703.250772446165, 10870.20641594128, 10892.455377230948, 11230.55077796879, 11263.380514071805, 11278.887211127783, 11291.491882865126, 11604.825927611262, 11673.0, 11716.297022178696, 8140.75577732669, 8155.306798921921, 8208.712695055645, 8414.495130292202, 8608.956955215184, 10080.193584513368, 10676.199285053497, 10783.137349011904, 11137.220394572301, 11492.31046137201], "value": 9514.293996683464, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1408.401429392508, 1580.1862041048862, 1629.66591848671, 1849.6578133045386, 1987.1653905359738, 7830.608469576519, 8604.182602652754, 8856.291496680265, 8862.77843053924, 8977.50623715697, 8574.742757717262, 8813.867791983126, 8826.823463530725, 8830.60262288197, 8830.823383532326, 2165.501934555053, 2360.480694247263, 2467.8519288842635, 2469.753024697534, 2505.649209110717, 2065.9586808263825, 2512.2337687005456, 2513.7863281621085, 2514.5222407742563, 2520.1683444463283, 2023.6357455657976, 2443.6701045358827, 2477.4054226985486, 2478.429961108946, 2479.0, 2131.9893400532974, 2359.174288998837, 2413.7706917842775, 2441.231012231159, 2446.0460420436043, 2457.8156638252026, 2468.4569394733307, 2491.750824917512, 2496.3509487533374, 2499.512595043967, 8652.134786521361, 8715.956420217923, 8717.084706105894, 8729.301655867515, 8742.30061595071, 5362.222477740745, 5539.723013849291, 5546.140348245988, 5550.111982082888, 5563.054280772279, 3481.5299934509026, 3554.928901421983, 3562.69717074049, 3591.2099338145567, 3593.3711600469815, 1041.8697662792151, 1051.4742628685658, 1055.757175849559, 1058.5342449322275, 1061.3897009219652, 11496.482658280398, 11788.292702437795, 11799.056075513938, 11803.527858885634, 11865.584704535386, 4918.975405123023, 4933.333999909966, 5040.092783299041, 5041.395032596039, 5051.292819005343, 10164.390136591828, 10184.949075254724, 10191.439470829027, 10200.0819926207, 10222.6422075227, 5529.889402212014, 5553.111502159635, 5558.138488534284, 5564.1931919871395, 5568.914061757919, 4405.537418571068, 4589.173948689268, 4860.1737704590305, 4985.501449855058, 4988.6009119270375, 4056.064826785465, 4077.164181342877, 4156.501219853637, 4175.331946888483, 4185.91628167441, 1311.9360455912602, 1328.592161106758, 1339.591424615482, 1342.3825040481292, 1357.151780137414, 821.5235163605095, 900.9144131307515, 941.5292353823088, 953.1278879825021, 986.663239379524, 5887.35239123696, 5949.315828679671, 6042.788502402394, 6052.636841789418, 6139.84650383744, 372.6050386590223, 456.58450809762957, 469.0946473306483, 481.60989598425385, 482.75620811490677, 3339.248669049493, 4010.1177740896956, 4041.9666408837656, 4051.736637118578, 4077.938830917539, 10186.592536298613, 10571.894281057244, 10598.20513461478, 10757.78484430307, 10799.514021869034, 10572.259941803999, 10574.206934479791, 10669.09312708413, 10693.304935179189, 10694.518746656418, 8006.879896801553, 8024.839503209903, 8141.755747327589, 8218.260356567818, 8220.753377398687, 5842.152887831156, 5842.561807864342, 5895.793647222326, 5910.852228694145, 5945.851353716192, 9831.950840245752, 9874.0, 9982.650607228712, 9984.251181161588, 9992.150667193078, 9474.85787713171, 9859.211263099078, 10071.345362551703, 10080.69757907264, 10113.59545618167, 5950.583459157818, 6062.0, 6873.621950792754, 7087.964560177373, 7605.543667379975, 8251.216134467217, 8393.874091888749, 8440.577971101544, 8455.02767181782, 8460.957695211484, 6135.631862088289, 7438.5536867788105, 7456.0, 7542.962285188539, 7589.354904833151, 7299.963500182465, 7346.008288881147, 7378.409727221917, 7417.0, 7418.629068546659, 6470.417662410406, 6589.143411356493, 7034.437245020488, 7041.084658994503, 7054.188768291712, 4857.684250523705, 4929.112759703145, 4989.201727723546, 5008.2738002988635, 5151.716655583829, 3979.960200398075, 4762.404699412574, 4940.135476291563, 5115.079285728604, 5242.659227150223], "value": 4530.745252276717, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.20481927710847, 306.3428944913161, 319.32144193588624, 320.21866645385256, 321.0448914479424, 4733.8106475741015, 5131.820386286481, 5180.073258608888, 5215.739213039347, 5221.634485586011, 5122.6424997375725, 5134.229865520174, 5135.897282054357, 5149.330587023682, 5163.483651634835, 3297.934041319172, 3501.509788629594, 3848.672208088219, 3979.6617287530594, 3998.8800335989968, 3343.6823501767294, 3858.2862170498406, 3937.6652984496354, 3979.5622481527007, 3986.5415477220054, 3752.624737526253, 3795.9620403795884, 3911.5892831252736, 3949.526056873165, 4060.533038700543, 3035.620547431571, 3825.0437390652337, 3906.3163946309564, 3915.8629447969183, 3952.6837852971694, 2815.619891314667, 3008.638963324393, 3463.7748546344405, 3478.165240342325, 3485.459753738149, 4141.399497072937, 4787.210110331791, 4963.255511673242, 4996.650234483587, 5008.198688209905, 3809.771413715186, 4338.58783415575, 4484.775761211959, 4497.707649002804, 4514.683972121953, 4563.338315944169, 4963.468630998181, 5406.0, 5424.83725488236, 5426.2674538937135, 3811.447340135665, 3819.8854034379015, 3829.770213787154, 3843.8462461501786, 3850.2085987659, 4422.336649502568, 5235.790568377261, 5478.369987451454, 5628.465295796893, 5631.915521267184, 3761.7178711596193, 3786.2738828043766, 3898.2008688219416, 3900.7464514806447, 3916.5104361954754, 3096.8451577421038, 3451.8274086295582, 3798.7530810497783, 3935.9016024599614, 3979.4428779970826, 3800.0879788850475, 4150.439690641785, 4340.826366945287, 4559.042601053749, 4572.0, 5146.665466744649, 5272.681829542615, 5382.604740890344, 5428.45715428454, 5436.320459942507, 1117.6032508459543, 1176.3823992404057, 1206.7646808872187, 1267.9809802852965, 1271.319843883518, 799.4683535448861, 812.695239285268, 818.0633175014694, 860.4278155026838, 898.7213963671282, 4129.669626429878, 4334.934975975363, 4366.8908277292685, 4671.32265821454, 4773.021530586221, 4668.366071874884, 4805.783739731721, 5090.414602320707, 5279.4130875871315, 5321.536577441235, 4024.778637174925, 4270.615644592001, 4635.814567417266, 4717.226219024767, 4791.292919500471, 2271.0802125139344, 2381.8332716709665, 2459.311392810016, 2721.7550420461857, 2856.8000239983003, 4753.667243292936, 4814.037192561502, 5195.635985626531, 5243.003829272426, 5528.668279903139, 4629.398178236875, 5238.869028274247, 5256.553192978563, 5419.214213938957, 5454.4545545445135, 4546.863594092117, 4796.616270698336, 4958.983408401341, 5045.2432135180015, 5062.696238225718, 4573.611243044378, 5161.703042146209, 5221.556167725786, 5230.0, 5282.339707536558, 4077.8368865245056, 5098.881342389073, 5217.40869034948, 5250.718301061836, 5305.442928492453, 4898.853034408973, 5597.468240517144, 5777.39337369587, 5829.446202610745, 5830.708464576669, 3758.6053464385845, 3813.8474461021246, 3821.2166505865675, 3821.662418153624, 3825.1775868188474, 4937.160682684024, 4969.254611808257, 5442.965836491054, 5612.8877422450505, 5626.465485778845, 4727.3381726559, 5112.897742045213, 5243.895122097613, 5409.269748584048, 5444.918326225031, 4763.642726795435, 5010.649254552146, 5462.754176062166, 5464.91802622953, 5494.066008778437, 5033.521815427529, 5323.387810401853, 5332.33345831771, 5332.946670533245, 5334.453008627454, 5230.764615592231, 5392.737393876805, 5421.620486565903, 5428.83713488596, 5456.825528470802, 4508.0, 4546.636269098531, 5178.145605974972, 5334.653247538898, 5351.491608297206], "value": 3869.0014485958145, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [647.1683886206224, 675.5273503761799, 696.5750891955909, 721.2210812322692, 747.6897087708597, 14416.49542266021, 15143.62140946477, 15173.0, 15201.239938003111, 15224.162671053102, 13752.587422377344, 13802.654933626663, 13823.79264311036, 13853.584392468218, 13869.445222191125, 722.902408174895, 747.0437839565365, 899.7525680437866, 910.4218821048632, 929.1219797291562, 922.7324076017981, 927.1377618814499, 928.9767755806091, 928.995355023224, 929.7768535551487, 964.4599024546277, 985.5417230987589, 986.1026465916018, 987.5901500877163, 987.9654212102578, 758.4084414156966, 862.2972277593741, 975.4244995452717, 977.3207620703614, 979.0894468144622, 727.5452841973766, 727.6725473536877, 728.6502478810148, 728.7485817393044, 728.9125304963438, 14235.07472014326, 14272.785908211385, 14293.642658933506, 14299.57101286963, 14300.642483937985, 10105.747356316077, 11922.880771192351, 12074.758504829942, 12181.903628673374, 12265.448054837554, 10440.164786817113, 10580.523876425503, 10949.397783121925, 11002.559897604166, 11245.212835101545, 3901.2977664020464, 3982.76103433792, 4099.9180016399805, 4130.896727581804, 4213.47331583552, 75145.24854751438, 76559.6172019142, 76563.0, 77431.0, 77451.22548774496, 9242.399244049115, 9249.630014799333, 9345.112214339626, 9533.332666713264, 9687.128158465772, 19571.92354420492, 20390.898045509974, 20442.38672839817, 20825.166993320097, 20893.791062089487, 7575.810604734926, 7619.390448764086, 7629.237076292437, 7698.076230852222, 8003.5197888126895, 4082.408050832613, 4088.366303223005, 4121.237571049365, 4146.357314616239, 4452.998075433002, 3012.367402845427, 3410.863565457354, 3535.381308271042, 3623.691986181204, 3633.909152271214, 665.9234188068338, 666.5234357434458, 669.4309836638915, 678.1624693503519, 679.6024325769381, 552.9142982837667, 562.4207066721284, 576.4608495317513, 584.0800738836265, 584.3834754334218, 7138.643067846587, 7613.314801667766, 7811.843763124706, 7987.0, 8118.918810811934, 568.8094488346368, 577.8324285957109, 597.8027251007157, 610.578700696518, 612.6201754911983, 3734.533183352081, 3871.8838434847, 3885.630865067814, 3930.76415415076, 3950.4469374287623, 77942.0, 80065.59967200126, 81380.18619813844, 81407.18592813997, 81665.59167204126, 56306.87386252252, 77962.6101869487, 79138.41723165504, 79504.60247698725, 81596.18403816005, 59860.10209846856, 60274.794504108795, 60664.39335606588, 60909.781804363665, 61117.08324375138, 7524.962375188089, 7635.618219089134, 7664.961675191588, 7719.498232614862, 7854.957755897392, 44057.55942440534, 44446.0, 44760.552394475635, 45490.862728432054, 45542.77228613836, 56723.71638141783, 57012.0, 60254.49363765941, 62883.0, 63052.36947630465, 9917.900820991697, 10287.639932602324, 10335.896641033494, 10355.0, 10540.367577945348, 11100.0, 11101.944490277498, 11136.38749868765, 11384.43077846121, 11603.12976526747, 8478.533680647624, 9634.0, 10972.615958441416, 11184.937430944048, 11555.017823485097, 11371.090312774793, 11503.068250473321, 11586.0, 11739.471723772622, 12110.576129835414, 11205.0, 11278.718032049264, 11339.433028348714, 11422.82865757031, 11506.76986460283, 9369.484678342757, 9393.906060939576, 9476.0, 9549.52252387364, 9552.856707149538, 9499.097585729345, 9527.857082143635, 9608.711738647851, 9682.951585242028, 9701.87164443743], "value": 6737.070866843255, "unit": "score"}], "name": "dromaeo_css", "value": 7097.079011155708, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 106.0, 112.0, 112.0, 112.0, 115.0, 111.0, 112.0, 111.0, 112.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 117.0, 158.0, 189.0, 142.0, 141.0, 135.0, 118.0, 167.0, 117.0], "value": 143.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 187.0, 187.0, 188.0, 185.0, 186.0, 185.0, 181.0, 189.0, 181.0], "value": 185.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 74.0, 48.0, 103.0, 72.0, 85.0, 80.0, 81.0, 127.0, 53.0], "value": 80.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 99.0, 92.0, 73.0, 80.0, 78.0, 101.0, 80.0, 81.0, 101.0], "value": 87.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 90.0, 88.0, 90.0, 87.0, 90.0, 88.0, 89.0, 87.0], "value": 88.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 94.0, 94.0, 95.0], "value": 98.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 85.0, 88.0, 84.0, 86.0, 84.0, 86.0, 83.0, 85.0], "value": 85.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [47.0, 54.0, 54.0, 52.0, 58.0, 57.0, 57.0, 76.0, 60.0, 57.0], "value": 57.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 76.0, 81.0, 72.0, 70.0, 66.0, 67.0, 68.0, 67.0], "value": 69.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 84.0, 83.0, 84.0, 83.0, 85.0, 86.0, 84.0, 83.0], "value": 84.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 152.0, 134.0, 143.0, 127.0, 142.0, 152.0, 147.0, 184.0, 160.0], "value": 148.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 168.0, 169.0, 167.0, 168.0, 168.0, 167.0, 168.0, 167.0, 167.0], "value": 168.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 68.0, 67.0, 66.0, 65.0, 66.0, 65.0, 66.0], "value": 66.2, "unit": "score"}], "name": "kraken", "value": 1474.0, "alertThreshold": 2.0}]} 07:33:19 INFO - Return code: 0 07:33:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 07:33:19 ERROR - # TBPL SUCCESS # 07:33:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:33:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:33:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:33:19 INFO - 2016-05-06 07:33:19.698 screenresolution[1673:10158] starting screenresolution argv=screenresolution get 07:33:19 INFO - 2016-05-06 07:33:19.701 screenresolution[1673:10158] Display 0: 1600x1200x32@60 07:33:19 INFO - 2016-05-06 07:33:19.707 screenresolution[1674:10161] starting screenresolution argv=screenresolution list 07:33:19 INFO - Available Modes on Display 0 07:33:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:33:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:33:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:33:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:33:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:33:19 INFO - Intel Iris: 07:33:19 INFO - Chipset Model: Intel Iris 07:33:19 INFO - Type: GPU 07:33:19 INFO - Bus: Built-In 07:33:19 INFO - VRAM (Dynamic, Max): 1536 MB 07:33:19 INFO - Vendor: Intel (0x8086) 07:33:19 INFO - Device ID: 0x0a2e 07:33:19 INFO - Revision ID: 0x0009 07:33:19 INFO - Displays: 07:33:19 INFO - Display: 07:33:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:33:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:33:19 INFO - Display Serial Number: 1600x1200 60 07:33:19 INFO - Main Display: Yes 07:33:19 INFO - Mirror: Off 07:33:19 INFO - Online: Yes 07:33:19 INFO - Rotation: Supported 07:33:19 INFO - Return code: 0 07:33:19 INFO - Running post-action listener: _resource_record_post_action 07:33:19 INFO - ##### 07:33:19 INFO - ##### Finished run-tests step (success) 07:33:19 INFO - ##### 07:33:19 INFO - Running post-run listener: _resource_record_post_run 07:33:20 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 37938688; Write bytes: 698405376; Read time: 394; Write time: 2316 07:33:20 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 394766848; Write bytes: 386897920; Read time: 29760; Write time: 761 07:33:21 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 36156928; Write bytes: 298322432; Read time: 375; Write time: 1518 07:33:21 INFO - Running post-run listener: _upload_blobber_files 07:33:21 INFO - Blob upload gear active. 07:33:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:33:21 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 07:33:21 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'] 07:33:21 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 07:33:21 INFO - (blobuploader) - INFO - Open directory for files ... 07:33:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:33:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:33:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:33:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:33:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:33:22 INFO - (blobuploader) - INFO - Done attempting. 07:33:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:33:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:33:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:33:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:33:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:33:23 INFO - (blobuploader) - INFO - Done attempting. 07:33:23 INFO - (blobuploader) - INFO - Iteration through files over. 07:33:23 INFO - Return code: 0 07:33:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:33:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:33:23 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/45427b175980757eb6e90398337736870589740635d9492e9a0f3f46fc6cb08ef5680aa5fee888c85e962815b11431fde6d09028ff717320da36de9c9476bd82", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9a1f70f92868809f9ec4ea417684cb42e0f58340aa8b2ca9021cf36bc56dca7d0c685b609b359da71eef8df424ee4c60db6d3eade207312456230e35a40eb1e4"} 07:33:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:33:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:33:23 INFO - Contents: 07:33:23 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/45427b175980757eb6e90398337736870589740635d9492e9a0f3f46fc6cb08ef5680aa5fee888c85e962815b11431fde6d09028ff717320da36de9c9476bd82", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9a1f70f92868809f9ec4ea417684cb42e0f58340aa8b2ca9021cf36bc56dca7d0c685b609b359da71eef8df424ee4c60db6d3eade207312456230e35a40eb1e4"} 07:33:23 INFO - Running post-run listener: copy_logs_to_upload_dir 07:33:23 INFO - Copying logs to upload dir... 07:33:23 INFO - mkdir: /builds/slave/test/build/upload/logs 07:33:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.626677 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-05-06 07:33:23.845134) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 07:33:23.848610) ========= 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.B71nVAXiZk/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.cWjYDGpnDD/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/45427b175980757eb6e90398337736870589740635d9492e9a0f3f46fc6cb08ef5680aa5fee888c85e962815b11431fde6d09028ff717320da36de9c9476bd82", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9a1f70f92868809f9ec4ea417684cb42e0f58340aa8b2ca9021cf36bc56dca7d0c685b609b359da71eef8df424ee4c60db6d3eade207312456230e35a40eb1e4"} 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.011409 build_url: 'https://queue.taskcluster.net/v1/task/fYTw48n9Rsu4LSGDbhEtbg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/45427b175980757eb6e90398337736870589740635d9492e9a0f3f46fc6cb08ef5680aa5fee888c85e962815b11431fde6d09028ff717320da36de9c9476bd82", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9a1f70f92868809f9ec4ea417684cb42e0f58340aa8b2ca9021cf36bc56dca7d0c685b609b359da71eef8df424ee4c60db6d3eade207312456230e35a40eb1e4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 07:33:23.876399) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 07:33:23.876697) ========= 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.B71nVAXiZk/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.cWjYDGpnDD/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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.004937 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 07:33:23.926147) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 07:33:23.926465) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 07:33:23.926764) ========= ========= Total master_lag: 0.17 =========