builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0074 starttime: 1461797671.14 results: success (0) buildid: 20160427140549 builduid: 54b1087a9eb24010a2586a5135fe9260 revision: cf603475c4e7ccb7f2c713114eeb41b9ffd17509 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-27 15:54:31.144059) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-27 15:54:31.144851) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-27 15:54:31.145355) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-27 15:54:31.166880) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 15:54:31.167554) ========= 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.TRR32I4UOz/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.hxVWL0vbGy/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-27 15:54:31-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 6.48M=0.002s 2016-04-27 15:54:31 (6.48 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.160428 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 15:54:31.345982) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 15:54:31.346361) ========= 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.TRR32I4UOz/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.hxVWL0vbGy/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.021946 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 15:54:31.395341) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-27 15:54:31.395699) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev cf603475c4e7ccb7f2c713114eeb41b9ffd17509 --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 cf603475c4e7ccb7f2c713114eeb41b9ffd17509 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TRR32I4UOz/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.hxVWL0vbGy/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-27 15:54:31,465 truncating revision to first 12 chars 2016-04-27 15:54:31,465 Setting DEBUG logging. 2016-04-27 15:54:31,465 attempt 1/10 2016-04-27 15:54:31,465 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/cf603475c4e7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-27 15:54:32,548 unpacking tar archive at: fx-team-cf603475c4e7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.330774 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-27 15:54:32.743756) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-27 15:54:32.744089) ========= script_repo_revision: cf603475c4e7ccb7f2c713114eeb41b9ffd17509 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-27 15:54:32.744544) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-27 15:54:32.744817) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-27 15:54:32.758457) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2016-04-27 15:54:32.758784) ========= /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.TRR32I4UOz/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.hxVWL0vbGy/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 15:54:32 INFO - MultiFileLogger online at 20160427 15:54:32 in /builds/slave/test 15:54:32 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 15:54:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:54:32 INFO - {'append_to_log': False, 15:54:32 INFO - 'base_work_dir': '/builds/slave/test', 15:54:32 INFO - 'blob_upload_branch': 'Fx-Team', 15:54:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:54:32 INFO - 'branch': 'Fx-Team', 15:54:32 INFO - 'buildbot_json_path': 'buildprops.json', 15:54:32 INFO - 'config_files': ('talos/mac_config.py',), 15:54:32 INFO - 'default_actions': ('clobber', 15:54:32 INFO - 'read-buildbot-config', 15:54:32 INFO - 'download-and-extract', 15:54:32 INFO - 'populate-webroot', 15:54:32 INFO - 'create-virtualenv', 15:54:32 INFO - 'install', 15:54:32 INFO - 'run-tests'), 15:54:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:54:32 INFO - 'download_minidump_stackwalk': True, 15:54:32 INFO - 'download_symbols': 'ondemand', 15:54:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:54:32 INFO - 'tooltool.py': '/tools/tooltool.py', 15:54:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:54:32 INFO - '/tools/misc-python/virtualenv.py')}, 15:54:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:54:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:54:32 INFO - 'installer_path': 'installer.exe', 15:54:32 INFO - 'log_level': 'info', 15:54:32 INFO - 'log_name': 'talos', 15:54:32 INFO - 'log_to_console': True, 15:54:32 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:54:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:54:32 INFO - 'opt_config_files': (), 15:54:32 INFO - 'pip_index': False, 15:54:32 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:54:32 INFO - 'cmd': ('bash', 15:54:32 INFO - '-c', 15:54:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:54:32 INFO - 'enabled': True, 15:54:32 INFO - 'halt_on_failure': False, 15:54:32 INFO - 'name': 'check_screen_resolution'},), 15:54:32 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:54:32 INFO - 'cmd': ('bash', 15:54:32 INFO - '-c', 15:54:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:54:32 INFO - 'enabled': True, 15:54:32 INFO - 'halt_on_failure': False, 15:54:32 INFO - 'name': 'check_screen_resolution'},), 15:54:32 INFO - 'run_cmd_checks_enabled': True, 15:54:32 INFO - 'sps_profile': False, 15:54:32 INFO - 'sps_profile_interval': 0, 15:54:32 INFO - 'suite': 'dromaeojs', 15:54:32 INFO - 'system_bits': '32', 15:54:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:54:32 INFO - 'title': 't-yosemite-r7-0074', 15:54:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:54:32 INFO - 'use_talos_json': True, 15:54:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:54:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:54:32 INFO - 'work_dir': 'build'} 15:54:32 INFO - ##### 15:54:32 INFO - ##### Running clobber step. 15:54:32 INFO - ##### 15:54:32 INFO - Running pre-action listener: _resource_record_pre_action 15:54:32 INFO - Running main action method: clobber 15:54:32 INFO - rmtree: /builds/slave/test/build 15:54:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:54:33 INFO - Running post-action listener: _resource_record_post_action 15:54:33 INFO - ##### 15:54:33 INFO - ##### Running read-buildbot-config step. 15:54:33 INFO - ##### 15:54:33 INFO - Running pre-action listener: _resource_record_pre_action 15:54:33 INFO - Running main action method: read_buildbot_config 15:54:33 INFO - Using buildbot properties: 15:54:33 INFO - { 15:54:33 INFO - "project": "", 15:54:33 INFO - "product": "firefox", 15:54:33 INFO - "script_repo_revision": "production", 15:54:33 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 15:54:33 INFO - "repository": "", 15:54:33 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 15:54:33 INFO - "buildid": "20160427140549", 15:54:33 INFO - "pgo_build": "False", 15:54:33 INFO - "basedir": "/builds/slave/test", 15:54:33 INFO - "buildnumber": 733, 15:54:33 INFO - "slavename": "t-yosemite-r7-0074", 15:54:33 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 15:54:33 INFO - "platform": "yosemite_r7", 15:54:33 INFO - "scheduler": "tests-fx-team-macosx64-talos", 15:54:33 INFO - "branch": "fx-team", 15:54:33 INFO - "repo_path": "integration/fx-team", 15:54:33 INFO - "revision": "cf603475c4e7ccb7f2c713114eeb41b9ffd17509", 15:54:33 INFO - "stage_platform": "macosx64", 15:54:33 INFO - "builduid": "54b1087a9eb24010a2586a5135fe9260", 15:54:33 INFO - "slavebuilddir": "test" 15:54:33 INFO - } 15:54:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/adh_Mmw3S96bjzi3z257Jg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 15:54:33 INFO - Running post-action listener: _resource_record_post_action 15:54:33 INFO - ##### 15:54:33 INFO - ##### Running download-and-extract step. 15:54:33 INFO - ##### 15:54:33 INFO - Running pre-action listener: _resource_record_pre_action 15:54:33 INFO - Running main action method: download_and_extract 15:54:33 INFO - mkdir: /builds/slave/test/build/tests 15:54:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:54:33 INFO - https://queue.taskcluster.net/v1/task/adh_Mmw3S96bjzi3z257Jg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 15:54:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/adh_Mmw3S96bjzi3z257Jg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 15:54:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/adh_Mmw3S96bjzi3z257Jg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 15:54:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/adh_Mmw3S96bjzi3z257Jg/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 15:54:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/adh_Mmw3S96bjzi3z257Jg/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 15:54:35 INFO - Downloaded 1199 bytes. 15:54:35 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 15:54:35 INFO - Using the following test package requirements: 15:54:35 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 15:54:35 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:54:35 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 15:54:35 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:54:35 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 15:54:35 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:54:35 INFO - u'jsshell-mac.zip'], 15:54:35 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:54:35 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 15:54:35 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 15:54:35 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:54:35 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 15:54:35 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:54:35 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 15:54:35 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:54:35 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 15:54:35 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:54:35 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 15:54:35 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:54:35 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')]} 15:54:35 INFO - https://queue.taskcluster.net/v1/task/adh_Mmw3S96bjzi3z257Jg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:54:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/adh_Mmw3S96bjzi3z257Jg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:54:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/adh_Mmw3S96bjzi3z257Jg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:54:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/adh_Mmw3S96bjzi3z257Jg/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 15:54:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/adh_Mmw3S96bjzi3z257Jg/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 15:54:36 INFO - Downloaded 18117731 bytes. 15:54:36 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'] 15:54:36 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 15:54:39 INFO - Return code: 0 15:54:39 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 15:54:39 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')]} 15:54:39 INFO - https://queue.taskcluster.net/v1/task/adh_Mmw3S96bjzi3z257Jg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:54:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/adh_Mmw3S96bjzi3z257Jg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:54:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/adh_Mmw3S96bjzi3z257Jg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:54:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/adh_Mmw3S96bjzi3z257Jg/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 15:54:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/adh_Mmw3S96bjzi3z257Jg/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 15:54:44 INFO - Downloaded 18117731 bytes. 15:54:44 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'] 15:54:44 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 15:54:47 INFO - Return code: 0 15:54:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:54:47 INFO - https://queue.taskcluster.net/v1/task/adh_Mmw3S96bjzi3z257Jg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 15:54:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/adh_Mmw3S96bjzi3z257Jg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 15:54:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/adh_Mmw3S96bjzi3z257Jg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 15:54:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/adh_Mmw3S96bjzi3z257Jg/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 15:54:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/adh_Mmw3S96bjzi3z257Jg/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 15:54:54 INFO - Downloaded 10840145 bytes. 15:54:54 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'] 15:54:54 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 15:54:54 INFO - Return code: 0 15:54: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')]} 15:54:54 INFO - https://queue.taskcluster.net/v1/task/adh_Mmw3S96bjzi3z257Jg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 15:54:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/adh_Mmw3S96bjzi3z257Jg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:54:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/adh_Mmw3S96bjzi3z257Jg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:54:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/adh_Mmw3S96bjzi3z257Jg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 15:54:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/adh_Mmw3S96bjzi3z257Jg/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 15:55:05 INFO - Downloaded 110192117 bytes. 15:55:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/adh_Mmw3S96bjzi3z257Jg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:55:05 INFO - mkdir: /builds/slave/test/properties 15:55:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:55:05 INFO - Writing to file /builds/slave/test/properties/build_url 15:55:05 INFO - Contents: 15:55:05 INFO - build_url:https://queue.taskcluster.net/v1/task/adh_Mmw3S96bjzi3z257Jg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:55:07 INFO - Running post-action listener: _resource_record_post_action 15:55:07 INFO - Running post-action listener: set_extra_try_arguments 15:55:07 INFO - ##### 15:55:07 INFO - ##### Running populate-webroot step. 15:55:07 INFO - ##### 15:55:07 INFO - Running pre-action listener: _resource_record_pre_action 15:55:07 INFO - Running main action method: populate_webroot 15:55:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:55:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:55:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:55:07 INFO - '--tppagecycles', 15:55:07 INFO - '7'], 15:55:07 INFO - 'tests': ['tsvgm']}}, 15:55:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:55:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:55:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:55:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:55:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:55:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:55:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:55:07 INFO - 'talos_options': ['--disable-e10s'], 15:55:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:55:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:55:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:55:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:55:07 INFO - 'talos_options': ['--disable-e10s'], 15:55:07 INFO - 'tests': ['damp', 'tps']}, 15:55:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:55:07 INFO - 'tests': ['damp', 'tps']}, 15:55:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:55:07 INFO - 'tests': ['dromaeo_dom']}, 15:55:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:55:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:55:07 INFO - 'tests': ['a11yr', 15:55:07 INFO - 'ts_paint', 15:55:07 INFO - 'tpaint', 15:55:07 INFO - 'sessionrestore', 15:55:07 INFO - 'sessionrestore_no_auto_restore', 15:55:07 INFO - 'tabpaint']}, 15:55:07 INFO - 'other-e10s': {'tests': ['a11yr', 15:55:07 INFO - 'ts_paint', 15:55:07 INFO - 'tpaint', 15:55:07 INFO - 'sessionrestore', 15:55:07 INFO - 'sessionrestore_no_auto_restore', 15:55:07 INFO - 'tabpaint']}, 15:55:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:55:07 INFO - 'ts_paint', 15:55:07 INFO - 'tpaint', 15:55:07 INFO - 'sessionrestore', 15:55:07 INFO - 'sessionrestore_no_auto_restore', 15:55:07 INFO - 'tabpaint']}, 15:55:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:55:07 INFO - 'ts_paint', 15:55:07 INFO - 'tpaint', 15:55:07 INFO - 'sessionrestore', 15:55:07 INFO - 'sessionrestore_no_auto_restore', 15:55:07 INFO - 'tabpaint']}, 15:55:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:55:07 INFO - 'tests': ['a11yr', 15:55:07 INFO - 'ts_paint', 15:55:07 INFO - 'tpaint', 15:55:07 INFO - 'sessionrestore', 15:55:07 INFO - 'sessionrestore_no_auto_restore', 15:55:07 INFO - 'tabpaint']}, 15:55:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:55:07 INFO - 'tests': ['a11yr', 15:55:07 INFO - 'ts_paint', 15:55:07 INFO - 'tpaint', 15:55:07 INFO - 'sessionrestore', 15:55:07 INFO - 'sessionrestore_no_auto_restore', 15:55:07 INFO - 'tabpaint']}, 15:55:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:55:07 INFO - 'tests': ['tsvgx', 15:55:07 INFO - 'tsvgr_opacity', 15:55:07 INFO - 'tart', 15:55:07 INFO - 'tscrollx', 15:55:07 INFO - 'cart']}, 15:55:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:55:07 INFO - 'tsvgr_opacity', 15:55:07 INFO - 'tart', 15:55:07 INFO - 'tscrollx', 15:55:07 INFO - 'cart']}, 15:55:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:55:07 INFO - 'talos_options': ['--disable-e10s'], 15:55:07 INFO - 'tests': ['tp5o']}, 15:55:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:55:07 INFO - 'tests': ['tp5o']}, 15:55:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:55:07 INFO - 'talos_options': ['--disable-e10s', 15:55:07 INFO - '--xperf_path', 15:55:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:55:07 INFO - 'tests': ['tp5n']}, 15:55:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:55:07 INFO - 'talos_options': ['--xperf_path', 15:55:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:55:07 INFO - 'tests': ['tp5n']}}, 15:55:07 INFO - 'talos.zip': {'path': '', 15:55:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:55:07 INFO - Running post-action listener: _resource_record_post_action 15:55:07 INFO - ##### 15:55:07 INFO - ##### Running create-virtualenv step. 15:55:07 INFO - ##### 15:55:07 INFO - Running pre-action listener: _resource_record_pre_action 15:55:07 INFO - Running main action method: create_virtualenv 15:55:07 INFO - Creating virtualenv /builds/slave/test/build/venv 15:55:07 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 15:55:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:55:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:55:07 INFO - Using real prefix '/tools/python27' 15:55:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:55:08 INFO - Installing distribute.............................................................................................................................................................................................done. 15:55:11 INFO - Installing pip.................done. 15:55:11 INFO - Return code: 0 15:55:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:55:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:55:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:55:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:11 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 0x1025c5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025d5420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faba2ca7970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102464e00>, '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 0x10228ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10245fb20>, '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.TRR32I4UOz/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.hxVWL0vbGy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:55:11 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 15:55:11 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 15:55:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TRR32I4UOz/Render', 15:55:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:55:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:55:11 INFO - 'HOME': '/Users/cltbld', 15:55:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:55:11 INFO - 'LOGNAME': 'cltbld', 15:55:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:11 INFO - 'MOZ_NO_REMOTE': '1', 15:55:11 INFO - 'NO_EM_RESTART': '1', 15:55:11 INFO - 'PAGER': '/bin/cat', 15:55:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:55:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:11 INFO - 'PWD': '/builds/slave/test', 15:55:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:55:11 INFO - 'SHELL': '/bin/bash', 15:55:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hxVWL0vbGy/Listeners', 15:55:11 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 15:55:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:55:11 INFO - 'USER': 'cltbld', 15:55:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:55:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:55:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:11 INFO - 'XPC_FLAGS': '0x0', 15:55:11 INFO - 'XPC_SERVICE_NAME': '0', 15:55:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:55:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:11 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 15:55:11 INFO - Cleaning up... 15:55:11 INFO - Return code: 0 15:55:11 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 15:55:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:55:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:55:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:11 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 0x1025c5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025d5420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faba2ca7970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102464e00>, '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 0x10228ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10245fb20>, '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.TRR32I4UOz/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.hxVWL0vbGy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:55:11 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 15:55:11 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 15:55:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TRR32I4UOz/Render', 15:55:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:55:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:55:11 INFO - 'HOME': '/Users/cltbld', 15:55:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:55:11 INFO - 'LOGNAME': 'cltbld', 15:55:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:11 INFO - 'MOZ_NO_REMOTE': '1', 15:55:11 INFO - 'NO_EM_RESTART': '1', 15:55:11 INFO - 'PAGER': '/bin/cat', 15:55:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:55:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:11 INFO - 'PWD': '/builds/slave/test', 15:55:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:55:11 INFO - 'SHELL': '/bin/bash', 15:55:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hxVWL0vbGy/Listeners', 15:55:11 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 15:55:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:55:11 INFO - 'USER': 'cltbld', 15:55:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:55:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:55:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:11 INFO - 'XPC_FLAGS': '0x0', 15:55:11 INFO - 'XPC_SERVICE_NAME': '0', 15:55:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:55:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:11 INFO - Downloading/unpacking psutil>=0.7.1 15:55:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:55:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:55:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:55:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:55:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:55:14 INFO - Installing collected packages: psutil 15:55:14 INFO - Running setup.py install for psutil 15:55:14 INFO - building 'psutil._psutil_osx' extension 15:55:14 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 15:55:15 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 15:55:15 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 15:55:15 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 15:55:15 INFO - building 'psutil._psutil_posix' extension 15:55:15 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 15:55:15 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:55:15 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:55:15 INFO - ^ 15:55:15 INFO - 1 warning generated. 15:55:15 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 15:55:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:55:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:55:15 INFO - Successfully installed psutil 15:55:15 INFO - Cleaning up... 15:55:15 INFO - Return code: 0 15:55:15 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 15:55: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')]} 15:55:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55: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')]} 15:55:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:15 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 0x1025c5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025d5420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faba2ca7970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102464e00>, '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 0x10228ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10245fb20>, '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.TRR32I4UOz/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.hxVWL0vbGy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:55:15 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 15:55:15 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 15:55:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TRR32I4UOz/Render', 15:55:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:55:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:55:15 INFO - 'HOME': '/Users/cltbld', 15:55:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:55:15 INFO - 'LOGNAME': 'cltbld', 15:55:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:15 INFO - 'MOZ_NO_REMOTE': '1', 15:55:15 INFO - 'NO_EM_RESTART': '1', 15:55:15 INFO - 'PAGER': '/bin/cat', 15:55:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:55:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:15 INFO - 'PWD': '/builds/slave/test', 15:55:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:55:15 INFO - 'SHELL': '/bin/bash', 15:55:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hxVWL0vbGy/Listeners', 15:55:15 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 15:55:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:55:15 INFO - 'USER': 'cltbld', 15:55:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:55:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:55:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:15 INFO - 'XPC_FLAGS': '0x0', 15:55:15 INFO - 'XPC_SERVICE_NAME': '0', 15:55:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:55:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:15 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 15:55:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:55:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:55:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:18 INFO - Downloading mozsystemmonitor-0.0.tar.gz 15:55:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:55:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 15:55:18 INFO - Installing collected packages: mozsystemmonitor 15:55:18 INFO - Running setup.py install for mozsystemmonitor 15:55:18 INFO - Successfully installed mozsystemmonitor 15:55:18 INFO - Cleaning up... 15:55:18 INFO - Return code: 0 15:55:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:55:18 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')]} 15:55:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:18 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')]} 15:55:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:18 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 0x1025c5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025d5420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faba2ca7970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102464e00>, '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 0x10228ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10245fb20>, '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.TRR32I4UOz/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.hxVWL0vbGy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:55:18 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 15:55:18 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 15:55:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TRR32I4UOz/Render', 15:55:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:55:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:55:18 INFO - 'HOME': '/Users/cltbld', 15:55:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:55:18 INFO - 'LOGNAME': 'cltbld', 15:55:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:18 INFO - 'MOZ_NO_REMOTE': '1', 15:55:18 INFO - 'NO_EM_RESTART': '1', 15:55:18 INFO - 'PAGER': '/bin/cat', 15:55:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:55:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:18 INFO - 'PWD': '/builds/slave/test', 15:55:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:55:18 INFO - 'SHELL': '/bin/bash', 15:55:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hxVWL0vbGy/Listeners', 15:55:18 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 15:55:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:55:18 INFO - 'USER': 'cltbld', 15:55:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:55:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:55:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:18 INFO - 'XPC_FLAGS': '0x0', 15:55:18 INFO - 'XPC_SERVICE_NAME': '0', 15:55:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:55:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:18 INFO - Downloading/unpacking blobuploader==1.2.4 15:55:18 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 15:55:18 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 15:55:18 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 15:55:18 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 15:55:18 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 15:55:18 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 15:55:21 INFO - Downloading blobuploader-1.2.4.tar.gz 15:55:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:55:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:55:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:55:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:55:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:55:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:55:22 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 15:55:22 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 15:55:22 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 15:55:22 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 15:55:22 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 15:55:22 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 15:55:22 INFO - Downloading docopt-0.6.1.tar.gz 15:55:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:55:22 INFO - Installing collected packages: blobuploader, requests, docopt 15:55:22 INFO - Running setup.py install for blobuploader 15:55:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:55:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:55:22 INFO - Running setup.py install for requests 15:55:23 INFO - Running setup.py install for docopt 15:55:23 INFO - Successfully installed blobuploader requests docopt 15:55:23 INFO - Cleaning up... 15:55:23 INFO - Return code: 0 15:55:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:55:23 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')]} 15:55:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:23 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')]} 15:55:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:23 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 0x1025c5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025d5420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faba2ca7970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102464e00>, '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 0x10228ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10245fb20>, '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.TRR32I4UOz/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.hxVWL0vbGy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:55:23 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 15:55:23 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 15:55:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TRR32I4UOz/Render', 15:55:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:55:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:55:23 INFO - 'HOME': '/Users/cltbld', 15:55:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:55:23 INFO - 'LOGNAME': 'cltbld', 15:55:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:23 INFO - 'MOZ_NO_REMOTE': '1', 15:55:23 INFO - 'NO_EM_RESTART': '1', 15:55:23 INFO - 'PAGER': '/bin/cat', 15:55:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:55:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:23 INFO - 'PWD': '/builds/slave/test', 15:55:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:55:23 INFO - 'SHELL': '/bin/bash', 15:55:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hxVWL0vbGy/Listeners', 15:55:23 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 15:55:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:55:23 INFO - 'USER': 'cltbld', 15:55:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:55:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:55:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:23 INFO - 'XPC_FLAGS': '0x0', 15:55:23 INFO - 'XPC_SERVICE_NAME': '0', 15:55:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:55:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:55:23 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-806azz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:55:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:55:23 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-OVB5gx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:55:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:55:23 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-4cQT0k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:55:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:55:23 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-5MLSyk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:55:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:55:24 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-dCpaH1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:55:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:55:24 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-fQiqVs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:55:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:55:24 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-aoinc0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:55:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:55:24 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-o2oXne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:55:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:55:24 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-8BGXRS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:55:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:55:24 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-O51fHY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:55:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:55:24 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-eavSRJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:55:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:55:24 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-VbrSy_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:55:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:55:24 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-AyKcyA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:55:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:55:25 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-ncZMUm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:55:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:55:25 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-Ekyn1H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:55:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:55:25 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-1uLDDL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:55:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:55:25 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-_6D2Cu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:55:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:55:25 INFO - Running setup.py install for manifestparser 15:55:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:55:25 INFO - Running setup.py install for mozcrash 15:55:25 INFO - Running setup.py install for mozdebug 15:55:25 INFO - Running setup.py install for mozdevice 15:55:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:55:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:55:26 INFO - Running setup.py install for mozfile 15:55:26 INFO - Running setup.py install for mozhttpd 15:55:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:55:26 INFO - Running setup.py install for mozinfo 15:55:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:55:26 INFO - Running setup.py install for mozInstall 15:55:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:55:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:55:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:55:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:55:26 INFO - Running setup.py install for mozleak 15:55:26 INFO - Running setup.py install for mozlog 15:55:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:55:26 INFO - Running setup.py install for moznetwork 15:55:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:55:27 INFO - Running setup.py install for mozprocess 15:55:27 INFO - Running setup.py install for mozprofile 15:55:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:55:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:55:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:55:27 INFO - Running setup.py install for mozrunner 15:55:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:55:27 INFO - Running setup.py install for mozscreenshot 15:55:27 INFO - Running setup.py install for moztest 15:55:27 INFO - Running setup.py install for mozversion 15:55:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:55:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:55:27 INFO - Cleaning up... 15:55:28 INFO - Return code: 0 15:55:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:55: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')]} 15:55:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55: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')]} 15:55:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:28 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 0x1025c5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025d5420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faba2ca7970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102464e00>, '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 0x10228ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10245fb20>, '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.TRR32I4UOz/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.hxVWL0vbGy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:55:28 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 15:55:28 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 15:55:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TRR32I4UOz/Render', 15:55:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:55:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:55:28 INFO - 'HOME': '/Users/cltbld', 15:55:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:55:28 INFO - 'LOGNAME': 'cltbld', 15:55:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:28 INFO - 'MOZ_NO_REMOTE': '1', 15:55:28 INFO - 'NO_EM_RESTART': '1', 15:55:28 INFO - 'PAGER': '/bin/cat', 15:55:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:55:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:28 INFO - 'PWD': '/builds/slave/test', 15:55:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:55:28 INFO - 'SHELL': '/bin/bash', 15:55:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hxVWL0vbGy/Listeners', 15:55:28 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 15:55:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:55:28 INFO - 'USER': 'cltbld', 15:55:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:55:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:55:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:28 INFO - 'XPC_FLAGS': '0x0', 15:55:28 INFO - 'XPC_SERVICE_NAME': '0', 15:55:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:55:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:55:28 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-hmd791-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:55:28 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)) 15:55:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:55:28 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-6UJfnS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:55:28 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)) 15:55:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:55:28 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-YZx9el-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:55:28 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)) 15:55:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:55:28 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-zA4Ve0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:55:28 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)) 15:55:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:55:28 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-lswc0W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:55:28 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)) 15:55:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:55:28 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-n7TPk_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:55:28 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)) 15:55:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:55:28 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-RJPCPp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:55:29 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)) 15:55:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:55:29 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-lWzPeg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:55:29 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)) 15:55:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:55:29 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-EeevJf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:55:29 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)) 15:55:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:55:29 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-AIWlrI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:55:29 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)) 15:55:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:55:29 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-e9DrCt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:55:29 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)) 15:55:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:55:29 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-LW0mVF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:55:29 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)) 15:55:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:55:29 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-U5S3XZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:55:29 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)) 15:55:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:55:29 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-Yv01U2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:55:29 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)) 15:55:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:55:29 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-rHmimH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:55:29 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)) 15:55:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:55:29 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-bJZ0sI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:55:30 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)) 15:55:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:55:30 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-iRxzNV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:55:30 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)) 15:55:30 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)) 15:55:30 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)) 15:55:30 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)) 15:55:30 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)) 15:55:30 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)) 15:55:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:55:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:55:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:55:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:32 INFO - Downloading blessings-1.6.tar.gz 15:55:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:55:33 INFO - Installing collected packages: blessings 15:55:33 INFO - Running setup.py install for blessings 15:55:33 INFO - Successfully installed blessings 15:55:33 INFO - Cleaning up... 15:55:33 INFO - Return code: 0 15:55:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:55:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:55:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:55:33 INFO - Reading from file tmpfile_stdout 15:55:33 INFO - Current package versions: 15:55:33 INFO - blessings == 1.6 15:55:33 INFO - blobuploader == 1.2.4 15:55:33 INFO - docopt == 0.6.1 15:55:33 INFO - manifestparser == 1.1 15:55:33 INFO - mozInstall == 1.12 15:55:33 INFO - mozcrash == 0.17 15:55:33 INFO - mozdebug == 0.1 15:55:33 INFO - mozdevice == 0.48 15:55:33 INFO - mozfile == 1.2 15:55:33 INFO - mozhttpd == 0.7 15:55:33 INFO - mozinfo == 0.9 15:55:33 INFO - mozleak == 0.1 15:55:33 INFO - mozlog == 3.1 15:55:33 INFO - moznetwork == 0.27 15:55:33 INFO - mozprocess == 0.22 15:55:33 INFO - mozprofile == 0.28 15:55:33 INFO - mozrunner == 6.11 15:55:33 INFO - mozscreenshot == 0.1 15:55:33 INFO - mozsystemmonitor == 0.0 15:55:33 INFO - moztest == 0.7 15:55:33 INFO - mozversion == 1.4 15:55:33 INFO - psutil == 3.1.1 15:55:33 INFO - requests == 1.2.3 15:55:33 INFO - wsgiref == 0.1.2 15:55:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:55:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:55:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:55:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:33 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 0x1025c5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025d5420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faba2ca7970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102464e00>, '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 0x10228ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10245fb20>, '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.TRR32I4UOz/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.hxVWL0vbGy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:55:33 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 15:55:33 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 15:55:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TRR32I4UOz/Render', 15:55:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:55:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:55:33 INFO - 'HOME': '/Users/cltbld', 15:55:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:55:33 INFO - 'LOGNAME': 'cltbld', 15:55:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:33 INFO - 'MOZ_NO_REMOTE': '1', 15:55:33 INFO - 'NO_EM_RESTART': '1', 15:55:33 INFO - 'PAGER': '/bin/cat', 15:55:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:55:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:33 INFO - 'PWD': '/builds/slave/test', 15:55:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:55:33 INFO - 'SHELL': '/bin/bash', 15:55:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hxVWL0vbGy/Listeners', 15:55:33 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 15:55:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:55:33 INFO - 'USER': 'cltbld', 15:55:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:55:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:55:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:33 INFO - 'XPC_FLAGS': '0x0', 15:55:33 INFO - 'XPC_SERVICE_NAME': '0', 15:55:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:55:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:34 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)) 15:55:34 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)) 15:55:34 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)) 15:55:34 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)) 15:55:34 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)) 15:55:34 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)) 15:55:34 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)) 15:55:34 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)) 15:55:34 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)) 15:55:34 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)) 15:55:34 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)) 15:55:34 INFO - Cleaning up... 15:55:34 INFO - Return code: 0 15:55:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:55:34 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')]} 15:55:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:34 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')]} 15:55:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55:34 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 0x1025c5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025d5420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faba2ca7970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102464e00>, '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 0x10228ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10245fb20>, '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.TRR32I4UOz/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.hxVWL0vbGy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:55:34 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 15:55:34 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 15:55:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TRR32I4UOz/Render', 15:55:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:55:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:55:34 INFO - 'HOME': '/Users/cltbld', 15:55:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:55:34 INFO - 'LOGNAME': 'cltbld', 15:55:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:34 INFO - 'MOZ_NO_REMOTE': '1', 15:55:34 INFO - 'NO_EM_RESTART': '1', 15:55:34 INFO - 'PAGER': '/bin/cat', 15:55:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:55:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:34 INFO - 'PWD': '/builds/slave/test', 15:55:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:55:34 INFO - 'SHELL': '/bin/bash', 15:55:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hxVWL0vbGy/Listeners', 15:55:34 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 15:55:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:55:34 INFO - 'USER': 'cltbld', 15:55:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:55:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:55:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:34 INFO - 'XPC_FLAGS': '0x0', 15:55:34 INFO - 'XPC_SERVICE_NAME': '0', 15:55:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:55:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:34 INFO - Downloading/unpacking jsonschema 15:55:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:55:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:55:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:55:37 INFO - Downloading/unpacking functools32 (from jsonschema) 15:55:37 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 15:55:37 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 15:55:37 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 15:55:37 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 15:55:37 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 15:55:37 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 15:55:37 INFO - Downloading functools32-3.2.3-2.tar.gz 15:55:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:55:37 INFO - warning: no files found matching '*.txt' 15:55:37 INFO - no previously-included directories found matching 'build' 15:55:37 INFO - no previously-included directories found matching 'dist' 15:55:37 INFO - no previously-included directories found matching '.git*' 15:55:37 INFO - Installing collected packages: jsonschema, functools32 15:55:37 INFO - Running setup.py install for functools32 15:55:37 INFO - warning: no files found matching '*.txt' 15:55:37 INFO - no previously-included directories found matching 'build' 15:55:37 INFO - no previously-included directories found matching 'dist' 15:55:37 INFO - no previously-included directories found matching '.git*' 15:55:37 INFO - Successfully installed jsonschema functools32 15:55:37 INFO - Cleaning up... 15:55:37 INFO - Return code: 0 15:55:37 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:55: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')]} 15:55:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55: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')]} 15:55:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:55: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1025c5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025d5420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faba2ca7970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102464e00>, '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 0x10228ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10245fb20>, '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.TRR32I4UOz/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.hxVWL0vbGy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:55: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', 'flake8'] in /builds/slave/test/build 15:55: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 flake8 15:55:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TRR32I4UOz/Render', 15:55:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:55:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:55:37 INFO - 'HOME': '/Users/cltbld', 15:55:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:55:37 INFO - 'LOGNAME': 'cltbld', 15:55:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:37 INFO - 'MOZ_NO_REMOTE': '1', 15:55:37 INFO - 'NO_EM_RESTART': '1', 15:55:37 INFO - 'PAGER': '/bin/cat', 15:55:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:55:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:37 INFO - 'PWD': '/builds/slave/test', 15:55:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:55:37 INFO - 'SHELL': '/bin/bash', 15:55:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hxVWL0vbGy/Listeners', 15:55:37 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 15:55:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:55:37 INFO - 'USER': 'cltbld', 15:55:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:55:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:55:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:37 INFO - 'XPC_FLAGS': '0x0', 15:55:37 INFO - 'XPC_SERVICE_NAME': '0', 15:55:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:55:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:38 INFO - Downloading/unpacking flake8 15:55: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 15:55: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 15:55: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 15:55: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 15:55: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 15:55: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 15:55:40 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:55:40 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:55:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:55:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:55:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:41 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:55:41 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:55:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:55:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:55:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:41 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:55:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:55:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:55:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:55:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:55:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:55:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:55:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:55:41 INFO - no previously-included directories found matching 'docs/_build' 15:55:41 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:55:42 INFO - Running setup.py install for pep8 15:55:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:55:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:55:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:55:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:55:42 INFO - no previously-included directories found matching 'docs/_build' 15:55:42 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:55:42 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:55:42 INFO - Cleaning up... 15:55:42 INFO - Return code: 0 15:55:42 INFO - Running post-action listener: _resource_record_post_action 15:55:42 INFO - Running post-action listener: _start_resource_monitoring 15:55:42 INFO - Starting resource monitoring. 15:55:42 INFO - ##### 15:55:42 INFO - ##### Running install step. 15:55:42 INFO - ##### 15:55:42 INFO - Running pre-action listener: _resource_record_pre_action 15:55:42 INFO - Running main action method: install 15:55:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:55:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:55:42 INFO - Reading from file tmpfile_stdout 15:55:42 INFO - Detecting whether we're running mozinstall >=1.0... 15:55:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:55:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:55:42 INFO - Reading from file tmpfile_stdout 15:55:42 INFO - Output received: 15:55:42 INFO - Usage: mozinstall [options] installer 15:55:42 INFO - Options: 15:55:42 INFO - -h, --help show this help message and exit 15:55:42 INFO - -d DEST, --destination=DEST 15:55:42 INFO - Directory to install application into. [default: 15:55:42 INFO - "/builds/slave/test"] 15:55:42 INFO - --app=APP Application being installed. [default: firefox] 15:55:42 INFO - mkdir: /builds/slave/test/build/application 15:55:42 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'] 15:55:42 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 15:56:16 INFO - Reading from file tmpfile_stdout 15:56:16 INFO - Output received: 15:56:16 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:56:16 INFO - Running post-action listener: _resource_record_post_action 15:56:16 INFO - ##### 15:56:16 INFO - ##### Running run-tests step. 15:56:16 INFO - ##### 15:56:16 INFO - Running pre-action listener: _resource_record_pre_action 15:56:16 INFO - Running main action method: run_tests 15:56:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:56:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:56:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:56:16 INFO - 2016-04-27 15:56:16.658 screenresolution[6740:111792] starting screenresolution argv=screenresolution get 15:56:16 INFO - 2016-04-27 15:56:16.661 screenresolution[6740:111792] Display 0: 1600x1200x32@60 15:56:16 INFO - 2016-04-27 15:56:16.667 screenresolution[6741:111794] starting screenresolution argv=screenresolution list 15:56:16 INFO - Available Modes on Display 0 15:56:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:56:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:56:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:56:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:56:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:56:16 INFO - Intel Iris: 15:56:16 INFO - Chipset Model: Intel Iris 15:56:16 INFO - Type: GPU 15:56:16 INFO - Bus: Built-In 15:56:16 INFO - VRAM (Dynamic, Max): 1536 MB 15:56:16 INFO - Vendor: Intel (0x8086) 15:56:16 INFO - Device ID: 0x0a2e 15:56:16 INFO - Revision ID: 0x0009 15:56:16 INFO - Displays: 15:56:16 INFO - Display: 15:56:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:56:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:56:16 INFO - Display Serial Number: 1600x1200 60 15:56:16 INFO - Main Display: Yes 15:56:16 INFO - Mirror: Off 15:56:16 INFO - Online: Yes 15:56:16 INFO - Rotation: Supported 15:56:16 INFO - Return code: 0 15:56:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:56:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:56:16 INFO - Python 2.7.3 15:56:16 INFO - Return code: 0 15:56:16 INFO - grabbing minidump binary from tooltool 15:56: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')]} 15:56:16 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 0x102464e00>, '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 0x10228ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10245fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:56:16 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 15:56:16 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 15:56:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:56:16 INFO - Return code: 0 15:56:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:56:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:56:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:56:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:56:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:56:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:56:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:56:18 INFO - Return code: 0 15:56:18 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/adh_Mmw3S96bjzi3z257Jg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0074', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 15:56:18 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/adh_Mmw3S96bjzi3z257Jg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0074 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 15:56:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TRR32I4UOz/Render', 15:56:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:56:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:56:18 INFO - 'HOME': '/Users/cltbld', 15:56:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:56:18 INFO - 'LOGNAME': 'cltbld', 15:56:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:56:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:56:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:56:18 INFO - 'MOZ_NO_REMOTE': '1', 15:56:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:56:18 INFO - 'NO_EM_RESTART': '1', 15:56:18 INFO - 'PAGER': '/bin/cat', 15:56:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:56:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:56:18 INFO - 'PWD': '/builds/slave/test', 15:56:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:56:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:56:18 INFO - 'SHELL': '/bin/bash', 15:56:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hxVWL0vbGy/Listeners', 15:56:18 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 15:56:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:56:18 INFO - 'USER': 'cltbld', 15:56:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:56:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:56:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:56:18 INFO - 'XPC_FLAGS': '0x0', 15:56:18 INFO - 'XPC_SERVICE_NAME': '0', 15:56:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:56:18 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/adh_Mmw3S96bjzi3z257Jg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0074', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 15:56:18 INFO - mozversion application_buildid: 20160427140549 15:56:18 INFO - mozversion application_changeset: cf603475c4e7ccb7f2c713114eeb41b9ffd17509 15:56:18 INFO - mozversion application_display_name: Nightly 15:56:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:56:18 INFO - mozversion application_name: Firefox 15:56:18 INFO - mozversion application_remotingname: firefox 15:56:18 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 15:56:18 INFO - mozversion application_vendor: Mozilla 15:56:18 INFO - mozversion application_version: 49.0a1 15:56:18 INFO - mozversion platform_buildid: 20160427140549 15:56:18 INFO - mozversion platform_changeset: cf603475c4e7ccb7f2c713114eeb41b9ffd17509 15:56:18 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 15:56:18 INFO - mozversion platform_version: 49.0a1 15:56:18 INFO - using testdate: 1461797778 15:56:18 INFO - actual date: 1461797778 15:56:18 INFO - starting webserver on 'localhost:57827' 15:56:18 INFO - SUITE-START | Running 2 tests 15:56:18 INFO - TEST-START | dromaeo_css 15:56:18 INFO - operating with platform_type : mac_ 15:56:18 INFO - Initialising browser for dromaeo_css test... 15:56:18 INFO - TEST-INFO | started process 6759 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/tmp7snvZA/profile http://localhost:57827/getInfo.html) 15:56:20 INFO - PROCESS | 6759 | __metrics Screen width/height:1600/1200 15:56:20 INFO - PROCESS | 6759 | colorDepth:24 15:56:20 INFO - PROCESS | 6759 | Browser inner width/height: 1024/689 15:56:20 INFO - PROCESS | 6759 | __metrics 15:56:20 INFO - PROCESS | 6759 | 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" 15:56:20 ERROR - PROCESS | 6759 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:56:21 INFO - TEST-INFO | 6759: exit 0 15:56:21 INFO - Browser initialized. 15:56:21 INFO - Running cycle 1/1 for dromaeo_css test... 15:56:21 INFO - TEST-INFO | started process 6761 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/tmp7snvZA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:56:33 INFO - PROCESS | 6761 | RSS: Main: 239501312 15:56:33 INFO - PROCESS | 6761 | 15:57:24 INFO - PROCESS | 6761 | 1461797844209 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] 15:59:34 INFO - PROCESS | 6761 | Cycle 1(1): loaded http://localhost:57827/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57827/tests/dromaeo/cssquery-ext.html) 15:59:36 INFO - PROCESS | 6761 | RSS: Main: 410202112 15:59:36 INFO - PROCESS | 6761 | 16:02:39 INFO - PROCESS | 6761 | Cycle 1(1): loaded http://localhost:57827/tests/dromaeo/cssquery-ext.html (next: http://localhost:57827/tests/dromaeo/cssquery-jquery.html) 16:02:40 INFO - PROCESS | 6761 | RSS: Main: 378134528 16:02:40 INFO - PROCESS | 6761 | 16:05:42 INFO - PROCESS | 6761 | Cycle 1(1): loaded http://localhost:57827/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57827/tests/dromaeo/cssquery-mootools.html) 16:05:42 INFO - PROCESS | 6761 | RSS: Main: 377446400 16:05:42 INFO - PROCESS | 6761 | 16:08:44 INFO - PROCESS | 6761 | Cycle 1(1): loaded http://localhost:57827/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57827/tests/dromaeo/cssquery-prototype.html) 16:08:45 INFO - PROCESS | 6761 | RSS: Main: 375562240 16:08:45 INFO - PROCESS | 6761 | 16:11:48 INFO - PROCESS | 6761 | Cycle 1(1): loaded http://localhost:57827/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57827/tests/dromaeo/cssquery-yui.html) 16:11:49 INFO - PROCESS | 6761 | RSS: Main: 350244864 16:11:49 INFO - PROCESS | 6761 | 16:14:52 INFO - PROCESS | 6761 | Cycle 1(1): loaded http://localhost:57827/tests/dromaeo/cssquery-yui.html (next: http://localhost:57827/tests/dromaeo/cssquery-dojo.html) 16:14:52 INFO - PROCESS | 6761 | RSS: Main: 381677568 16:14:52 INFO - PROCESS | 6761 | 16:14:52 INFO - PROCESS | 6761 | __start_tp_report 16:14:52 INFO - PROCESS | 6761 | _x_x_mozilla_page_load 16:14:52 INFO - PROCESS | 6761 | _x_x_mozilla_page_load_details 16:14:52 INFO - PROCESS | 6761 | |i|pagename|runs| 16:14:52 INFO - PROCESS | 6761 | |0;dojo.html;3095.95356069659;3374.510695949087;3394.4724873806776;3412;3446.6725661062205;5559.388467268597;5678.375378708344;5682.516986056185;5684.630499017561;5685.232493613361;4792.329073929653;5664.178694089365;5668.404817494165;5748.425157484249;5820.417958204177;5104.310918026056;5145.6398052136365;5181.533661970422;5211.895762084756;5273.841784746463;5258.3689957205;5267.315249017623;5271.367435907697;5315.973420132894;5360.785568577252;5081.923771143417;5208.635395522315;5217.426083130853;5223.738813059332;5227.634065615409;5137.51193636604;5192.532672059495;5255.159174532057;5263.605229607757;5283.128283833163;5133.563647090002;5140.177571588565;5159;5174.327337446108;5176.689398636094;5547.195656629807;5552.639078459928;5559.9444005559835;5583.274174357308;5614.831555053355;6094.2077529920825;6143.201383820119;6174.5060395168275;6208.2239720034995;6236.189295391616;6168.352323006109;6225.252969643627;6246.469050130744;6270.996640537538;6272.466840318579;5740.311162660508;5747.942520574825;5782.56630752691;5783.190353350535;5785.987452195891;6148.862460444832;6149.723262453201;6223.190985171898;6262.060690896311;6274.560780745395;6118.265808103056;6128.295246046672;6130.938690613037;6134.601250918676;6144.692765361803;6187.504999600022;6223.066540019034;6255.40573645503;6264.68676566179;6270.153529273581;6062.393760623902;6116.122740413025;6121.540884433685;6185.721642526007;6211.503079753609;6155.199824022938;6166.876662466726;6183.876322473615;6193.969030154821;6218.06728990655;4895.461499235082;4906.9263961040615;4910.508949105061;4917.360743103373;4951.8266860659705;4295.914081718348;4323.805428755713;4437.33439984005;4446.9555304447185;4461.286194208976;5619.634723742944;5640;5644.717764111778;5663.745131469093;5688.488036076773;6204.751809927552;6238.937610623836;6249.437550620466;6269.811905642838;6284.402981716729;5522.779088836482;5596.1605759136455;5625.859353516113;5631.352394474688;5725.427457254241;5378.650387724785;5380.058489764354;5388.515033646912;5396.83809485716;5424.294841670558;6268;6299.433051025429;6333.018382150774;6337.112804207414;6340.4927605792345;6055.9091613626715;6122.398155112157;6148.200733904564;6239.0953311770445;6264.21697287839;6295.496360291258;6301.968490157703;6308.400701933309;6317.989121740499;6335.524835637253;6158;6159.692015399301;6159.876802464015;6164.506839452923;6203.751849925952;5511.559075274049;5977.850553736192;6080.118382834376;6130.908036379368;6200.286966998853;5989.401059893976;6104.67717083347;6148.047052706837;6150.661713605616;6150.876982460236;6120.418560236771;6120.8775824484155;6129.233845769279;6132.5707200495535;6137.723802428812;5806;5865.120231965239;5897.587168898136;6012.579119461596;6023.397660233942;6062.42376981979;6126.540509461719;6126.663033533198;6137.41694539018;6143.6928153593035;5967.7911273106965;5972;5978.282606087298;5979.641221526722;5980.19267398913 16:14:52 INFO - PROCESS | 6761 | |1;ext.html;489.7037292438073;498.713239887065;499.8650364401612;503.70533238055737;574.2133277409949;30370;30981.535276970837;31041.223969400748;37317;38343.61656383435;40124;40810.795946020226;40946.97632559187;40977;41167.38248926269;9260.490673012999;9405.388649737744;9465.574049167804;9472.194863436616;9490.762730931712;8427.157284271585;8624.396292259544;9340.065993400674;11097.33415995039;11369.0904727622;11263.831042534368;11358.545658173663;11372.772544549103;11373.431328433586;11377.658670239865;11182.496787644535;11288.943555282252;11295.604653837076;11296.435178241056;11321.264117832314;8427.115152908918;11029.007389334998;11262.493187806569;11283.887161128365;11312.717182070432;36851.631483685356;37360.43959340612;37500;37502.06244843874;37681.24637507262;3093.690630936911;3422.57217847769;3518.8240587970754;3568.3220188164164;4000.0199951012187;25791;26530.938762449478;27904.860475697693;27935.860320698266;27960.02139925113;2659.6409484719507;2734.4941185880675;2765.3224959884956;2824.5057115004997;2928.1947464447235;292141;349810.2509487436;350215;350824;350956;54879.451205487436;54932.90134197294;55052.449475505535;55128.62178445491;57806;53062.67343316448;55692.443075569536;59921.40078599158;59921.70039149776;59973.700131499936;14336.56990290293;16238;16565.751513727304;16574;16631.501054968372;11660.591879284184;11895.107866910017;11899.88100119005;12382.938085309515;12547.435365408579;15290.617734556503;15514.681252093698;15522.767158492406;15528.844711552965;15543.766843497357;4683.909963676563;6901.551399159039;7110.146782386147;7193.3166349196745;7204.603746793872;901.5987885391069;923.7182659288846;936.6815282803743;995.0994350113159;1141.914356423255;25215.873920630278;27824.443511129663;28297.009604663737;28507;28650.713492864805;939.7885475767897;1048.5596049659162;1067.6743593204146;1084.810158222308;1129.728865072393;10946.616868409568;14750.63123421923;14826.703465930621;15077.47228847007;15318.387264509518;472054.63972679916;476727;478102;482358;483388;40536;56666;56704;56748.432515674314;56873;1331.8601546837635;1365.0376484578348;1480.5410322799967;1485.8737007354496;1494.4844028810044;28167.295817604725;28496.43007139887;28588;28824.85587572049;28860.567091494064;40996.590034099274;47548;47750.283745743145;47911.76044119757;47923;338650;357912;358331;358499;358510;30787.384252315278;35595.288094237454;36350.454743179405;36426.4536031965;36956;43652.781736091114;44020.89947751204;44340;44480.33279500875;44615.55384446114;32094.51858222082;34874;36130.8193459031;36470.4529432064;37248.81375593105;34896.65103348934;35135.47296790601;35570.64429355673;35742.4638630426;36065;35091.82454087713;35320.76377326781;35443.82278088593;35826.492145549564;36230;31073.378532429677;31480;31617.367652647277;31629.36741265208;31687.841560792047;18629.627407451502;23061.88469057711;23265.534689306456;23948.64077038811;24253.878730606233 16:14:52 INFO - PROCESS | 6761 | |2;jquery.html;390.578175570384;396.37175077502155;397.0688734916623;401.21962782388266;412.8947118484786;13452.962878126998;19109.904450477745;19374.418767436964;19788.109535070922;19789.109490072944;15544.06735595865;15749.60625984351;19547.5113122172;19722.647149984503;19854.702179467284;5585.636933599303;5828.679422631753;5880.8235752927485;5900.90833195858;5905.6751878646655;5710.77156913723;5739.770409183628;5775.740091695863;5836.095405212178;5840;5669.716514174295;5779.017567013597;5831.329397119343;5831.766729330821;5835.095560188178;5725.742341594639;5791.102379131242;5791.594588378815;5800.564957628195;5806.258122563257;4915.213565829486;5083.6441449098575;5100.132977393852;5114.744262786883;5117.027764724728;24034.639480407634;25255.36861578457;25575.104871329415;25659.87170064138;25693.743062569323;2130.286354071388;2142.2609199826034;2228.6322756745117;2651.469706058777;2758.2001219646277;12682.492700291978;14141.929290353586;14413.495527656481;14602.853971460256;14614.780778288334;1582.090298078598;1621.26232564183;1633.8039435267724;1636.7053930292543;1721.4921598128583;94698.52650736771;96988;97977;98815.50592247065;104844.51554844531;11119.666410007712;11302.491387887565;11464.369459679798;11557.688462307573;11659.766804664028;14484;14510.637234069232;14548.345324460424;14553.12681239129;14620.268986550629;7554;7910.406719496061;8058.798530036796;8581.356398270154;8758.86861697075;8515;8527;8533.957330213309;8611.61247743853;8680.95659521711;4547.681662283675;4571.862844114682;4575.565321294472;4575.702579332333;4634.976825115853;1901.458084445954;1942.5726340205133;1943.9805601944083;1948.4057362504288;1974.763028436568;874.6413970272158;925.4539821505344;930.6091441594411;980.764616492051;981.2444417798256;18422.171002304927;19369.806301937082;19419;19598;20072.290240817303;918.5958178401482;922.6171138977384;945.9054094590485;1022.9437380943971;1061.0715623829149;10471.214658900613;10637;10664.093552048163;10684.572617095384;10697.786044279072;543492;557450;562553.1872340695;566601;574502;71620.56758864793;104827.95172048334;106304;108342.99884013968;109335;985.1281615769952;995.2286977592279;1024.6977141743203;1024.7847951930178;1025.994870025645;17210.569735756708;17335.913320433316;17409.651806964044;17737;17844.821551784316;42569.361459577514;45022;46984.06031879275;48115.75942120267;48676.26985595148;63495.73008539896;66138;70672;71139.64430178022;71772.28227717656;7622.8094297643;7778.222177782177;8074.111847696866;8198;8478.872816907628;9354.53227338644;10666.573337066431;11690.491174443603;12687.492500299884;12923.870761292266;10616.787664246825;10788.838167427652;11054.502547385226;11088;11118.100487259482;10867.184961127788;10875.456227188452;10990.505427255634;11115.666530004113;11306.830397544209;6006.768612434528;9392.77893873792;10998.615048473264;11456.026237769884;11534.59628912984;6669.4664426844765;7687.5387476751575;8159.755207343789;8337.166283371615;8592.785180370542;9111.908880911105;10993.780124397308;10997.175211859303;11154.386508742096;11208.831867522156 16:14:52 INFO - PROCESS | 6761 | |3;mootools.html;1450.4560789703862;1606.437746788624;1608.5094046315874;1850.750148729921;1954.8240658340737;7865.76402707919;8666.913330866697;8888.422252553579;8902.688405905794;8913.50975696338;8430.957845210765;8730.86903696445;8734.650613975433;8740.475571465717;8745.387822852403;2201.4606421426774;2295.483516208852;2445.645381419693;2446.2049833803994;2447.3759191406257;2413.095089341497;2434.513097380522;2450.5588993981078;2457.8033757299463;2462.926112216627;2391.282615215437;2405.2663937499055;2419.6128619420897;2431.8054555635595;2434.3914021494625;2410.1323523531514;2420.322309753263;2431.4407686232266;2442.5725498037773;2453.374389530676;2389.020501594355;2421.0315873650507;2421.8183636227354;2424.769646883561;2426.4904370082127;8591.312694984386;8676.045634980148;8804;8811.074837142072;8824.426412283181;5225.320708307933;5439.3200849893765;5447.673139611636;5457.208704737791;5465.480779325958;3307.3881331953667;3308.8511017004053;3399.3371292597953;3477.8608855645743;3485.5991560970306;967.2552134856192;973.0804389851522;974.9756256093583;977.359829311802;989.7525618595351;11429.485673144729;11512.654620361403;11660.06719462441;11671.358075305854;11686.707832304175;4523.886902827456;4728.976355118271;4743.478217396084;4755.096531658974;4763.618910487153;9180.632774689071;9238.214751746176;9250.90749092514;9257.444553326823;9264.768380790534;5204.88095059564;5238.816641417532;5321.255024296602;5361.329833770778;5369.275147855068;4399.428074350378;4536.364908912755;4808.687435316693;4932.580730637864;4960.131976904099;3762.473253744478;3776.3013842439236;3850.4609354690365;3864.8840534784003;3873.573906870242;1128.0749785176076;1128.198978725098;1141.6632093532428;1144.2162118948547;1162.3316592959081;654.456800855289;781.1680560203365;834.4742812028504;869.1221865915471;936.5644975086617;5356.651817631841;5575.69333686643;5964.135200395918;5993.921094203085;6000.549958753111;488.3066046214384;504.44259093701663;504.57363527818933;504.86873412912905;647.4464332995316;3735.34631439497;3945.9605403946166;3956.4460975463458;3999.1801680655394;4010.7192496525545;10327.586896524204;10778;10830.187735919837;10938.343699378063;10979;10148.441835698959;10767.515461804238;10773.676789696321;10776.461176941279;10780.51487683056;7985.720499782596;8194.262516373674;8266.297364724065;8319.916800832036;8347.707830226034;5648.85877853057;5865.882682346244;5985.371535988658;6090.786822461193;6094.451499364901;8913.955430223066;8924.018357980747;10193.286469947032;10225.130863876653;10228.335158214692;9204.355695101547;10105.141063009727;10255.025772551595;10314.793704125725;10345.120664743281;6180.289266734203;7551.169371369255;7647.617619118911;7715.691372345043;7744;8287.95856020716;8331.833363332578;8358.414910956175;8381.58092095405;8384.329253659815;6155.446009859134;7454.403647708279;7561;7562.357199638092;7750.534967901944;7209.134903811576;7339.339459448675;7353.889691654736;7471.551706897603;7518.360939320219;6870.587764734132;6889.345512176335;6927.93072069293;6941.791746247621;6949.3745562899585;4647.442306923191;4795.280707893844;4931.654784165074;4980.676256043345;5135.409427915688;4831.879203019953;4886.169351210374;4958.528939750718;5021.949780502295;5169.172932330809 16:14:52 INFO - PROCESS | 6761 | |4;prototype.html;301.2363658126648;313.52031391970286;320.6376794222529;322.31990500044884;322.7917992894583;4176.791160441977;4503.324501324803;4585.014221942281;4718.646101542381;5344.9844529539405;4136.131412403399;4520.68355215135;4856.271559266103;5046.470120637336;5276.261323414725;4049.5545489996075;4102.671786257092;4102.671786257107;4102.815373308208;4132.2768515509815;3758.304713627974;3875.642011760908;3964.8612298569556;4029.811205694313;4077.103037331782;3838.923221535568;4064.0738668159024;4073.368627862671;4114.6296833285;4137.2552940470705;3568.3220188164164;3873.5280593374478;4064.1871625674985;4133.4626498555;4155.085881106152;3166.889158879428;3538.7620327400396;3551.5738111426535;3557.3774589446743;3589.881969637926;3744;5090.10923088458;5141.460146684582;5148.433672296044;5160.864609785841;3753;4356.259435895905;4573.062522182944;4581.496035436099;4595.448546174447;5574.442555744434;5581.720913954286;5587.804426845041;5601.523870471015;5619.7471113799975;3627.256412435471;3674.3386190485703;3828.4831547741255;3830.9808450957844;3846.9993405144032;4929.137400954819;5261.631685781997;5759.366469688331;5768.74040668171;5866.384029676865;3156.2898347871546;3854.9036274093373;3858.614138586175;3870.3613903705573;3877.1664092219744;3401.268727223659;3830.367989281737;3885.873096801952;3903.1803321302846;3953.5453422856162;3843.9039024024623;4512.34570987205;4561.521040290788;4563.224251877189;4570.405847239837;5608.990381731328;5616.887662246814;5653.886922261532;5655.208270842086;5688.345840228427;1243.5274595653775;1282.852471965736;1370.3627813066773;1392.9930350348184;1418.0711633879826;841.1966571923921;937.5921474158763;948.5020364308655;952.3333666433524;952.7570469530225;5019.498050194951;5614.747336369873;5704.657720536781;5726.541876649858;5753.626014309057;5500.339959204921;5645.604807663424;5813.924423981577;6179.969100154471;6270.3416141305415;4515.887102822456;4828.975855120772;5335.279737235423;5692.658440493584;5701;3177.4916013437737;3298.191942973987;3305.058058453329;3306.2560923791953;3316.005198440458;6338.714757835908;6441.549091563639;6524;6543.901841472381;6586.7035983380865;6319.526035547352;6356.872862542723;6410.8076757697345;6413.358664133642;6428.003659432796;5477.972610136924;6202.875942481125;6541.672916354163;6562.5406221564035;6567.605943643397;5459.426760190282;5727.828165155052;6202.193714817226;6215.533834962486;6217;5168.870778230575;5606;6120.918887578245;6123.4488895999575;6150.138980542638;5431.5926305526455;6196.481861943853;6439.420452159328;6506.186726659168;6517.608943463408;3526.4131140986146;4116.744392960176;4119.835206591702;4133.793310334411;4134.813933373066;4987.526185012418;5086.491350864884;5658.688772117408;5666.405027472221;5674.092145256739;5560.110382338807;5595.692236927008;5622.32532096151;5638.85902852432;5674.205611214516;5276.39321478035;5561.610687252015;5599.80400685974;5612.747426365905;5644.8024319148635;5509.955465021114;5510.669359838423;5542.919130769461;5554.361248456479;5568.638038527482;5182.870428239174;5305.867353316198;5594.356648985418;5630.943690563042;5637.379888212211;4899.387576552931;5314.548263397654;5503.724813759216;5511.5039646432015;5521.536792750005 16:14:52 INFO - PROCESS | 6761 | |5;yui.html;656.1764984943894;688.7245101959215;738.5531753289257;755.5806527377307;785.2187073861509;14706.852931470683;15206.543803685907;15212.54362369128;15215.011024283414;15232.466863659774;13990;14039.719205615882;14079.5072172474;14416.135031898093;14503.854961450383;750.8423231121471;916.6104405627615;931.3061768982084;932.4778124250441;935.4293880732781;737.3879679865698;758.9886151707728;882.5410786391072;932.4451951089092;934.8784657994452;982.7444864335253;986.0336869867534;987.20036770216;987.7234374375187;992.1715367668006;773.2769860180749;934.3879758758025;988.9703308900708;993.0466751918167;997.670768646345;692.1590267824618;709.5458906299972;710.605613884294;735.2427000189813;735.915369732477;15300.923495382563;15361.308741106677;15384.769228461582;15386.99984501004;15402.460913868073;11466.48400821965;11486.195966282363;11492.195546311761;11738.295702257892;11926.224795388272;9865.950670246675;10098.394096354243;10146.594136234542;10174.898251017468;10385.2211084169;3448.4654878493875;3516.7186625069935;3518.894433167009;3607.8556857725657;3613.2050948791225;78863;79766.80349794758;80887.99999999942;81171;82836.58581707113;9656.130948214695;9708;9889.554970026365;10005.949970250102;10134;22629.66055509136;22669.97985090642;22680.546389072126;22704.205352812904;22712.65931011036;7497.900587835415;7559.697612095564;7564.886526702104;7590.696372145052;7706.036745406825;3850.8652197172964;3912.921741565153;3913.8238779255003;3923.1761330120426;4036.6972477064337;3603.5855876574005;3668.229671768905;3677.1910179760944;3682.1531047858607;3686.907827304285;638.4477427025589;644.1722386733026;652.354169372318;654.8768861402717;657.980260592183;496.8698191176121;561.2507302750791;597.1845589408238;601.9879602407927;611.2084850119106;6950.860982780316;7795.415343849234;8036.45940043885;8220.424570280142;8388.79028024292;522.9320188375486;586.5776640818603;590.9320428150731;615.5814046448369;618.3167599802243;3704.685101766326;3710.128119891823;3750.9062273443383;3774.3206222880144;3931.6264954829817;81351.59324203341;83768.16231837604;83950.58024709838;95546;97059;74470.62764686142;74649.25350746531;78154;79025.60487197642;82582;48183.03633927302;61091;63214.73570528564;65243;66870.32824179337;7919.722809701863;8404;8492.74521764348;8497.037419970533;8626.56867156627;43558.78220608877;44138.779306103264;44396.55603443924;45507;46530.836729082046;61779;62182;64687.67656161689;66266.66866665636;66286;10940.890591093987;11028.393438360967;11153.55385784592;11263;11360.091192704398;11895.76208475843;11978.940105299765;12158.270503769802;12167.878321216675;13034.739305214032;7940.960295198487;10657.094146997591;11798.646040618794;11972.64082077539;12020.879791201976;11240;11431.256968297033;11631.6510504685;11760.05919526453;11867.347295898808;11172.608958686407;11229.922464344123;11319.547218111184;11592.362420066976;11635.069194464591;9497.28770342241;9523.28575356837;9644.421334719938;9815.509224538888;9858.457784821903;9414.858777118487;9717.22262219035;9734.172595329477;9783.51082445889;9975.551100200653 16:14:52 INFO - PROCESS | 6761 | __end_tp_report 16:14:52 INFO - PROCESS | 6761 | __start_cc_report 16:14:52 INFO - PROCESS | 6761 | _x_x_mozilla_cycle_collect,1735 16:14:52 INFO - PROCESS | 6761 | __end_cc_report 16:14:52 INFO - PROCESS | 6761 | __startTimestamp1461798892893__endTimestamp 16:14:52 INFO - PROCESS | 6761 | 16:14:52 INFO - PROCESS | 6761 | ------- Summary: start ------- 16:14:52 INFO - PROCESS | 6761 | Number of tests: 6 16:14:52 INFO - PROCESS | 6761 | 16:14:52 INFO - PROCESS | 6761 | [#0] dojo.html Cycles:165 Average:5717.22 Median:5978.28 stddev:639.00 (10.7%) stddev-sans-first:606.98 16:14:52 INFO - PROCESS | 6761 | Values: 3096.0 3374.5 3394.5 3412.0 3446.7 5559.4 5678.4 5682.5 5684.6 5685.2 4792.3 5664.2 5668.4 5748.4 5820.4 5104.3 5145.6 5181.5 5211.9 5273.8 5258.4 5267.3 5271.4 5316.0 5360.8 5081.9 5208.6 5217.4 5223.7 5227.6 5137.5 5192.5 5255.2 5263.6 5283.1 5133.6 5140.2 5159.0 5174.3 5176.7 5547.2 5552.6 5559.9 5583.3 5614.8 6094.2 6143.2 6174.5 6208.2 6236.2 6168.4 6225.3 6246.5 6271.0 6272.5 5740.3 5747.9 5782.6 5783.2 5786.0 6148.9 6149.7 6223.2 6262.1 6274.6 6118.3 6128.3 6130.9 6134.6 6144.7 6187.5 6223.1 6255.4 6264.7 6270.2 6062.4 6116.1 6121.5 6185.7 6211.5 6155.2 6166.9 6183.9 6194.0 6218.1 4895.5 4906.9 4910.5 4917.4 4951.8 4295.9 4323.8 4437.3 4447.0 4461.3 5619.6 5640.0 5644.7 5663.7 5688.5 6204.8 6238.9 6249.4 6269.8 6284.4 5522.8 5596.2 5625.9 5631.4 5725.4 5378.7 5380.1 5388.5 5396.8 5424.3 6268.0 6299.4 6333.0 6337.1 6340.5 6055.9 6122.4 6148.2 6239.1 6264.2 6295.5 6302.0 6308.4 6318.0 6335.5 6158.0 6159.7 6159.9 6164.5 6203.8 5511.6 5977.9 6080.1 6130.9 6200.3 5989.4 6104.7 6148.0 6150.7 6150.9 6120.4 6120.9 6129.2 6132.6 6137.7 5806.0 5865.1 5897.6 6012.6 6023.4 6062.4 6126.5 6126.7 6137.4 6143.7 5967.8 5972.0 5978.3 5979.6 5980.2 16:14:52 INFO - PROCESS | 6761 | 16:14:52 INFO - PROCESS | 6761 | [#1] ext.html Cycles:180 Average:54094.47 Median:193023.91 stddev:104723.75 (54.3%) stddev-sans-first:104940.19 16:14:52 INFO - PROCESS | 6761 | Values: 489.7 498.7 499.9 503.7 574.2 30370.0 30981.5 31041.2 37317.0 38343.6 40124.0 40810.8 40947.0 40977.0 41167.4 9260.5 9405.4 9465.6 9472.2 9490.8 8427.2 8624.4 9340.1 11097.3 11369.1 11263.8 11358.5 11372.8 11373.4 11377.7 11182.5 11288.9 11295.6 11296.4 11321.3 8427.1 11029.0 11262.5 11283.9 11312.7 36851.6 37360.4 37500.0 37502.1 37681.2 3093.7 3422.6 3518.8 3568.3 4000.0 25791.0 26530.9 27904.9 27935.9 27960.0 2659.6 2734.5 2765.3 2824.5 2928.2 292141.0 349810.3 350215.0 350824.0 350956.0 54879.5 54932.9 55052.4 55128.6 57806.0 53062.7 55692.4 59921.4 59921.7 59973.7 14336.6 16238.0 16565.8 16574.0 16631.5 11660.6 11895.1 11899.9 12382.9 12547.4 15290.6 15514.7 15522.8 15528.8 15543.8 4683.9 6901.6 7110.1 7193.3 7204.6 901.6 923.7 936.7 995.1 1141.9 25215.9 27824.4 28297.0 28507.0 28650.7 939.8 1048.6 1067.7 1084.8 1129.7 10946.6 14750.6 14826.7 15077.5 15318.4 472054.6 476727.0 478102.0 482358.0 483388.0 40536.0 56666.0 56704.0 56748.4 56873.0 1331.9 1365.0 1480.5 1485.9 1494.5 28167.3 28496.4 28588.0 28824.9 28860.6 40996.6 47548.0 47750.3 47911.8 47923.0 338650.0 357912.0 358331.0 358499.0 358510.0 30787.4 35595.3 36350.5 36426.5 36956.0 43652.8 44020.9 44340.0 44480.3 44615.6 32094.5 34874.0 36130.8 36470.5 37248.8 34896.7 35135.5 35570.6 35742.5 36065.0 35091.8 35320.8 35443.8 35826.5 36230.0 31073.4 31480.0 31617.4 31629.4 31687.8 18629.6 23061.9 23265.5 23948.6 24253.9 16:14:52 INFO - PROCESS | 6761 | 16:14:52 INFO - PROCESS | 6761 | [#2] jquery.html Cycles:180 Average:32015.60 Median:1574.39 stddev:92810.91 (5895.0%) stddev-sans-first:93040.89 16:14:52 INFO - PROCESS | 6761 | Values: 390.6 396.4 397.1 401.2 412.9 13453.0 19109.9 19374.4 19788.1 19789.1 15544.1 15749.6 19547.5 19722.6 19854.7 5585.6 5828.7 5880.8 5900.9 5905.7 5710.8 5739.8 5775.7 5836.1 5840.0 5669.7 5779.0 5831.3 5831.8 5835.1 5725.7 5791.1 5791.6 5800.6 5806.3 4915.2 5083.6 5100.1 5114.7 5117.0 24034.6 25255.4 25575.1 25659.9 25693.7 2130.3 2142.3 2228.6 2651.5 2758.2 12682.5 14141.9 14413.5 14602.9 14614.8 1582.1 1621.3 1633.8 1636.7 1721.5 94698.5 96988.0 97977.0 98815.5 104844.5 11119.7 11302.5 11464.4 11557.7 11659.8 14484.0 14510.6 14548.3 14553.1 14620.3 7554.0 7910.4 8058.8 8581.4 8758.9 8515.0 8527.0 8534.0 8611.6 8681.0 4547.7 4571.9 4575.6 4575.7 4635.0 1901.5 1942.6 1944.0 1948.4 1974.8 874.6 925.5 930.6 980.8 981.2 18422.2 19369.8 19419.0 19598.0 20072.3 918.6 922.6 945.9 1022.9 1061.1 10471.2 10637.0 10664.1 10684.6 10697.8 543492.0 557450.0 562553.2 566601.0 574502.0 71620.6 104828.0 106304.0 108343.0 109335.0 985.1 995.2 1024.7 1024.8 1026.0 17210.6 17335.9 17409.7 17737.0 17844.8 42569.4 45022.0 46984.1 48115.8 48676.3 63495.7 66138.0 70672.0 71139.6 71772.3 7622.8 7778.2 8074.1 8198.0 8478.9 9354.5 10666.6 11690.5 12687.5 12923.9 10616.8 10788.8 11054.5 11088.0 11118.1 10867.2 10875.5 10990.5 11115.7 11306.8 6006.8 9392.8 10998.6 11456.0 11534.6 6669.5 7687.5 8159.8 8337.2 8592.8 9111.9 10993.8 10997.2 11154.4 11208.8 16:14:52 INFO - PROCESS | 6761 | 16:14:52 INFO - PROCESS | 6761 | [#3] mootools.html Cycles:180 Average:5658.18 Median:2763.20 stddev:3184.34 (115.2%) stddev-sans-first:3177.57 16:14:52 INFO - PROCESS | 6761 | Values: 1450.5 1606.4 1608.5 1850.8 1954.8 7865.8 8666.9 8888.4 8902.7 8913.5 8431.0 8730.9 8734.7 8740.5 8745.4 2201.5 2295.5 2445.6 2446.2 2447.4 2413.1 2434.5 2450.6 2457.8 2462.9 2391.3 2405.3 2419.6 2431.8 2434.4 2410.1 2420.3 2431.4 2442.6 2453.4 2389.0 2421.0 2421.8 2424.8 2426.5 8591.3 8676.0 8804.0 8811.1 8824.4 5225.3 5439.3 5447.7 5457.2 5465.5 3307.4 3308.9 3399.3 3477.9 3485.6 967.3 973.1 975.0 977.4 989.8 11429.5 11512.7 11660.1 11671.4 11686.7 4523.9 4729.0 4743.5 4755.1 4763.6 9180.6 9238.2 9250.9 9257.4 9264.8 5204.9 5238.8 5321.3 5361.3 5369.3 4399.4 4536.4 4808.7 4932.6 4960.1 3762.5 3776.3 3850.5 3864.9 3873.6 1128.1 1128.2 1141.7 1144.2 1162.3 654.5 781.2 834.5 869.1 936.6 5356.7 5575.7 5964.1 5993.9 6000.5 488.3 504.4 504.6 504.9 647.4 3735.3 3946.0 3956.4 3999.2 4010.7 10327.6 10778.0 10830.2 10938.3 10979.0 10148.4 10767.5 10773.7 10776.5 10780.5 7985.7 8194.3 8266.3 8319.9 8347.7 5648.9 5865.9 5985.4 6090.8 6094.5 8914.0 8924.0 10193.3 10225.1 10228.3 9204.4 10105.1 10255.0 10314.8 10345.1 6180.3 7551.2 7647.6 7715.7 7744.0 8288.0 8331.8 8358.4 8381.6 8384.3 6155.4 7454.4 7561.0 7562.4 7750.5 7209.1 7339.3 7353.9 7471.6 7518.4 6870.6 6889.3 6927.9 6941.8 6949.4 4647.4 4795.3 4931.7 4980.7 5135.4 4831.9 4886.2 4958.5 5021.9 5169.2 16:14:52 INFO - PROCESS | 6761 | 16:14:52 INFO - PROCESS | 6761 | [#4] prototype.html Cycles:180 Average:4645.90 Median:5175.87 stddev:1476.70 (28.5%) stddev-sans-first:1444.39 16:14:52 INFO - PROCESS | 6761 | Values: 301.2 313.5 320.6 322.3 322.8 4176.8 4503.3 4585.0 4718.6 5345.0 4136.1 4520.7 4856.3 5046.5 5276.3 4049.6 4102.7 4102.7 4102.8 4132.3 3758.3 3875.6 3964.9 4029.8 4077.1 3838.9 4064.1 4073.4 4114.6 4137.3 3568.3 3873.5 4064.2 4133.5 4155.1 3166.9 3538.8 3551.6 3557.4 3589.9 3744.0 5090.1 5141.5 5148.4 5160.9 3753.0 4356.3 4573.1 4581.5 4595.4 5574.4 5581.7 5587.8 5601.5 5619.7 3627.3 3674.3 3828.5 3831.0 3847.0 4929.1 5261.6 5759.4 5768.7 5866.4 3156.3 3854.9 3858.6 3870.4 3877.2 3401.3 3830.4 3885.9 3903.2 3953.5 3843.9 4512.3 4561.5 4563.2 4570.4 5609.0 5616.9 5653.9 5655.2 5688.3 1243.5 1282.9 1370.4 1393.0 1418.1 841.2 937.6 948.5 952.3 952.8 5019.5 5614.7 5704.7 5726.5 5753.6 5500.3 5645.6 5813.9 6180.0 6270.3 4515.9 4829.0 5335.3 5692.7 5701.0 3177.5 3298.2 3305.1 3306.3 3316.0 6338.7 6441.5 6524.0 6543.9 6586.7 6319.5 6356.9 6410.8 6413.4 6428.0 5478.0 6202.9 6541.7 6562.5 6567.6 5459.4 5727.8 6202.2 6215.5 6217.0 5168.9 5606.0 6120.9 6123.4 6150.1 5431.6 6196.5 6439.4 6506.2 6517.6 3526.4 4116.7 4119.8 4133.8 4134.8 4987.5 5086.5 5658.7 5666.4 5674.1 5560.1 5595.7 5622.3 5638.9 5674.2 5276.4 5561.6 5599.8 5612.7 5644.8 5510.0 5510.7 5542.9 5554.4 5568.6 5182.9 5305.9 5594.4 5630.9 5637.4 4899.4 5314.5 5503.7 5511.5 5521.5 16:14:52 INFO - PROCESS | 6761 | 16:14:52 INFO - PROCESS | 6761 | [#5] yui.html Cycles:180 Average:17725.68 Median:31197.29 stddev:24574.88 (78.8%) stddev-sans-first:24610.40 16:14:52 INFO - PROCESS | 6761 | Values: 656.2 688.7 738.6 755.6 785.2 14706.9 15206.5 15212.5 15215.0 15232.5 13990.0 14039.7 14079.5 14416.1 14503.9 750.8 916.6 931.3 932.5 935.4 737.4 759.0 882.5 932.4 934.9 982.7 986.0 987.2 987.7 992.2 773.3 934.4 989.0 993.0 997.7 692.2 709.5 710.6 735.2 735.9 15300.9 15361.3 15384.8 15387.0 15402.5 11466.5 11486.2 11492.2 11738.3 11926.2 9866.0 10098.4 10146.6 10174.9 10385.2 3448.5 3516.7 3518.9 3607.9 3613.2 78863.0 79766.8 80888.0 81171.0 82836.6 9656.1 9708.0 9889.6 10005.9 10134.0 22629.7 22670.0 22680.5 22704.2 22712.7 7497.9 7559.7 7564.9 7590.7 7706.0 3850.9 3912.9 3913.8 3923.2 4036.7 3603.6 3668.2 3677.2 3682.2 3686.9 638.4 644.2 652.4 654.9 658.0 496.9 561.3 597.2 602.0 611.2 6950.9 7795.4 8036.5 8220.4 8388.8 522.9 586.6 590.9 615.6 618.3 3704.7 3710.1 3750.9 3774.3 3931.6 81351.6 83768.2 83950.6 95546.0 97059.0 74470.6 74649.3 78154.0 79025.6 82582.0 48183.0 61091.0 63214.7 65243.0 66870.3 7919.7 8404.0 8492.7 8497.0 8626.6 43558.8 44138.8 44396.6 45507.0 46530.8 61779.0 62182.0 64687.7 66266.7 66286.0 10940.9 11028.4 11153.6 11263.0 11360.1 11895.8 11978.9 12158.3 12167.9 13034.7 7941.0 10657.1 11798.6 11972.6 12020.9 11240.0 11431.3 11631.7 11760.1 11867.3 11172.6 11229.9 11319.5 11592.4 11635.1 9497.3 9523.3 9644.4 9815.5 9858.5 9414.9 9717.2 9734.2 9783.5 9975.6 16:14:52 INFO - PROCESS | 6761 | -------- Summary: end -------- 16:14:52 INFO - PROCESS | 6761 | 16:14:53 INFO - TEST-INFO | 6761: exit 0 16:14:53 INFO - TEST-OK | dromaeo_css | took 1115203ms 16:14:53 INFO - TEST-START | kraken 16:14:53 INFO - operating with platform_type : mac_ 16:14:53 INFO - Initialising browser for kraken test... 16:14:53 INFO - TEST-INFO | started process 6763 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/tmp2vOpIl/profile http://localhost:57827/getInfo.html) 16:14:55 INFO - PROCESS | 6763 | __metrics Screen width/height:1600/1200 16:14:55 INFO - PROCESS | 6763 | colorDepth:24 16:14:55 INFO - PROCESS | 6763 | Browser inner width/height: 1024/689 16:14:55 INFO - PROCESS | 6763 | __metrics 16:14:55 INFO - PROCESS | 6763 | 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" 16:14:55 ERROR - PROCESS | 6763 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:14:56 INFO - TEST-INFO | 6763: exit 0 16:14:56 INFO - Browser initialized. 16:14:56 INFO - Running cycle 1/1 for kraken test... 16:14:56 INFO - TEST-INFO | started process 6765 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/tmp2vOpIl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:15:08 INFO - PROCESS | 6765 | RSS: Main: 239333376 16:15:08 INFO - PROCESS | 6765 | 16:15:11 INFO - PROCESS | 6765 | Cycle 1(1): loaded http://localhost:57827/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57827/tests/kraken/driver.html?testName=audio-beat-detection) 16:15:12 INFO - PROCESS | 6765 | RSS: Main: 287727616 16:15:12 INFO - PROCESS | 6765 | 16:15:15 INFO - PROCESS | 6765 | Cycle 1(1): loaded http://localhost:57827/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57827/tests/kraken/driver.html?testName=audio-dft) 16:15:15 INFO - PROCESS | 6765 | RSS: Main: 300380160 16:15:15 INFO - PROCESS | 6765 | 16:15:19 INFO - PROCESS | 6765 | Cycle 1(1): loaded http://localhost:57827/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57827/tests/kraken/driver.html?testName=audio-fft) 16:15:19 INFO - PROCESS | 6765 | RSS: Main: 387506176 16:15:19 INFO - PROCESS | 6765 | 16:15:21 INFO - PROCESS | 6765 | Cycle 1(1): loaded http://localhost:57827/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57827/tests/kraken/driver.html?testName=audio-oscillator) 16:15:22 INFO - PROCESS | 6765 | RSS: Main: 288743424 16:15:22 INFO - PROCESS | 6765 | 16:15:24 INFO - PROCESS | 6765 | Cycle 1(1): loaded http://localhost:57827/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57827/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:15:25 INFO - PROCESS | 6765 | RSS: Main: 293068800 16:15:25 INFO - PROCESS | 6765 | 16:15:27 INFO - PROCESS | 6765 | Cycle 1(1): loaded http://localhost:57827/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57827/tests/kraken/driver.html?testName=imaging-darkroom) 16:15:28 INFO - PROCESS | 6765 | RSS: Main: 331177984 16:15:28 INFO - PROCESS | 6765 | 16:15:31 INFO - PROCESS | 6765 | Cycle 1(1): loaded http://localhost:57827/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57827/tests/kraken/driver.html?testName=imaging-desaturate) 16:15:31 INFO - PROCESS | 6765 | RSS: Main: 330207232 16:15:31 INFO - PROCESS | 6765 | 16:15:34 INFO - PROCESS | 6765 | Cycle 1(1): loaded http://localhost:57827/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57827/tests/kraken/driver.html?testName=json-parse-financial) 16:15:35 INFO - PROCESS | 6765 | RSS: Main: 330895360 16:15:35 INFO - PROCESS | 6765 | 16:15:36 INFO - PROCESS | 6765 | Cycle 1(1): loaded http://localhost:57827/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57827/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:15:37 INFO - PROCESS | 6765 | RSS: Main: 321998848 16:15:37 INFO - PROCESS | 6765 | 16:15:39 INFO - PROCESS | 6765 | Cycle 1(1): loaded http://localhost:57827/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57827/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:15:40 INFO - PROCESS | 6765 | RSS: Main: 328892416 16:15:40 INFO - PROCESS | 6765 | 16:15:42 INFO - PROCESS | 6765 | Cycle 1(1): loaded http://localhost:57827/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57827/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:15:43 INFO - PROCESS | 6765 | RSS: Main: 330719232 16:15:43 INFO - PROCESS | 6765 | 16:15:46 INFO - PROCESS | 6765 | Cycle 1(1): loaded http://localhost:57827/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57827/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:15:46 INFO - PROCESS | 6765 | RSS: Main: 327802880 16:15:46 INFO - PROCESS | 6765 | 16:15:49 INFO - PROCESS | 6765 | Cycle 1(1): loaded http://localhost:57827/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57827/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:15:50 INFO - PROCESS | 6765 | RSS: Main: 328683520 16:15:50 INFO - PROCESS | 6765 | 16:15:52 INFO - PROCESS | 6765 | Cycle 1(1): loaded http://localhost:57827/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57827/tests/kraken/driver.html?testName=ai-astar) 16:15:52 INFO - PROCESS | 6765 | RSS: Main: 392945664 16:15:52 INFO - PROCESS | 6765 | 16:15:52 INFO - PROCESS | 6765 | __start_tp_report 16:15:52 INFO - PROCESS | 6765 | _x_x_mozilla_page_load 16:15:52 INFO - PROCESS | 6765 | _x_x_mozilla_page_load_details 16:15:52 INFO - PROCESS | 6765 | |i|pagename|runs| 16:15:52 INFO - PROCESS | 6765 | |0;ai-astar;157;166;85;153;162;165;117;111;105;112 16:15:52 INFO - PROCESS | 6765 | |1;audio-beat-detection;160;135;127;204;147;170;156;119;172;117 16:15:52 INFO - PROCESS | 6765 | |2;audio-dft;185;189;187;184;188;186;184;186;183;184 16:15:52 INFO - PROCESS | 6765 | |3;audio-fft;78;73;91;94;56;56;58;82;87;55 16:15:52 INFO - PROCESS | 6765 | |4;audio-oscillator;94;99;135;78;87;84;121;78;76;131 16:15:52 INFO - PROCESS | 6765 | |5;imaging-gaussian-blur;90;87;90;88;90;86;90;88;90;85 16:15:52 INFO - PROCESS | 6765 | |6;imaging-darkroom;95;94;95;101;88;93;94;95;94;96 16:15:52 INFO - PROCESS | 6765 | |7;imaging-desaturate;84;84;84;87;85;85;87;84;84;98 16:15:52 INFO - PROCESS | 6765 | |8;json-parse-financial;61;62;61;60;58;59;60;79;61;58 16:15:52 INFO - PROCESS | 6765 | |9;json-stringify-tinderbox;64;62;77;75;72;67;68;69;68;69 16:15:52 INFO - PROCESS | 6765 | |10;stanford-crypto-aes;84;80;83;82;98;103;103;102;82;81 16:15:52 INFO - PROCESS | 6765 | |11;stanford-crypto-ccm;140;147;158;139;138;150;130;157;191;139 16:15:52 INFO - PROCESS | 6765 | |12;stanford-crypto-pbkdf2;209;196;195;198;197;197;198;199;267;209 16:15:52 INFO - PROCESS | 6765 | |13;stanford-crypto-sha256-iterative;69;70;71;70;71;72;72;72;68;69 16:15:52 INFO - PROCESS | 6765 | __end_tp_report 16:15:52 INFO - PROCESS | 6765 | __start_cc_report 16:15:52 INFO - PROCESS | 6765 | _x_x_mozilla_cycle_collect,1998 16:15:52 INFO - PROCESS | 6765 | __end_cc_report 16:15:52 INFO - PROCESS | 6765 | __startTimestamp1461798952541__endTimestamp 16:15:52 INFO - PROCESS | 6765 | 16:15:52 INFO - PROCESS | 6765 | ------- Summary: start ------- 16:15:52 INFO - PROCESS | 6765 | Number of tests: 14 16:15:52 INFO - PROCESS | 6765 | 16:15:52 INFO - PROCESS | 6765 | [#0] ai-astar Cycles:10 Average:133.30 Median:159.50 stddev:30.18 (18.9%) stddev-sans-first:30.77 16:15:52 INFO - PROCESS | 6765 | Values: 157.0 166.0 85.0 153.0 162.0 165.0 117.0 111.0 105.0 112.0 16:15:52 INFO - PROCESS | 6765 | 16:15:52 INFO - PROCESS | 6765 | [#1] audio-beat-detection Cycles:10 Average:150.70 Median:158.00 stddev:27.37 (17.3%) stddev-sans-first:28.83 16:15:52 INFO - PROCESS | 6765 | Values: 160.0 135.0 127.0 204.0 147.0 170.0 156.0 119.0 172.0 117.0 16:15:52 INFO - PROCESS | 6765 | 16:15:52 INFO - PROCESS | 6765 | [#2] audio-dft Cycles:10 Average:185.60 Median:186.00 stddev:1.96 (1.1%) stddev-sans-first:2.06 16:15:52 INFO - PROCESS | 6765 | Values: 185.0 189.0 187.0 184.0 188.0 186.0 184.0 186.0 183.0 184.0 16:15:52 INFO - PROCESS | 6765 | 16:15:52 INFO - PROCESS | 6765 | [#3] audio-fft Cycles:10 Average:73.00 Median:80.00 stddev:15.61 (19.5%) stddev-sans-first:16.46 16:15:52 INFO - PROCESS | 6765 | Values: 78.0 73.0 91.0 94.0 56.0 56.0 58.0 82.0 87.0 55.0 16:15:52 INFO - PROCESS | 6765 | 16:15:52 INFO - PROCESS | 6765 | [#4] audio-oscillator Cycles:10 Average:98.30 Median:81.00 stddev:22.62 (27.9%) stddev-sans-first:23.94 16:15:52 INFO - PROCESS | 6765 | Values: 94.0 99.0 135.0 78.0 87.0 84.0 121.0 78.0 76.0 131.0 16:15:52 INFO - PROCESS | 6765 | 16:15:52 INFO - PROCESS | 6765 | [#5] imaging-gaussian-blur Cycles:10 Average:88.40 Median:90.00 stddev:1.90 (2.1%) stddev-sans-first:1.92 16:15:52 INFO - PROCESS | 6765 | Values: 90.0 87.0 90.0 88.0 90.0 86.0 90.0 88.0 90.0 85.0 16:15:52 INFO - PROCESS | 6765 | 16:15:52 INFO - PROCESS | 6765 | [#6] imaging-darkroom Cycles:10 Average:94.50 Median:94.50 stddev:3.17 (3.4%) stddev-sans-first:3.36 16:15:52 INFO - PROCESS | 6765 | Values: 95.0 94.0 95.0 101.0 88.0 93.0 94.0 95.0 94.0 96.0 16:15:52 INFO - PROCESS | 6765 | 16:15:52 INFO - PROCESS | 6765 | [#7] imaging-desaturate Cycles:10 Average:86.20 Median:85.00 stddev:4.32 (5.1%) stddev-sans-first:4.50 16:15:52 INFO - PROCESS | 6765 | Values: 84.0 84.0 84.0 87.0 85.0 85.0 87.0 84.0 84.0 98.0 16:15:52 INFO - PROCESS | 6765 | 16:15:52 INFO - PROCESS | 6765 | [#8] json-parse-financial Cycles:10 Average:61.90 Median:61.00 stddev:6.15 (10.1%) stddev-sans-first:6.52 16:15:52 INFO - PROCESS | 6765 | Values: 61.0 62.0 61.0 60.0 58.0 59.0 60.0 79.0 61.0 58.0 16:15:52 INFO - PROCESS | 6765 | 16:15:52 INFO - PROCESS | 6765 | [#9] json-stringify-tinderbox Cycles:10 Average:69.10 Median:69.00 stddev:4.58 (6.6%) stddev-sans-first:4.47 16:15:52 INFO - PROCESS | 6765 | Values: 64.0 62.0 77.0 75.0 72.0 67.0 68.0 69.0 68.0 69.0 16:15:52 INFO - PROCESS | 6765 | 16:15:52 INFO - PROCESS | 6765 | [#10] stanford-crypto-aes Cycles:10 Average:89.80 Median:82.00 stddev:10.22 (12.5%) stddev-sans-first:10.62 16:15:52 INFO - PROCESS | 6765 | Values: 84.0 80.0 83.0 82.0 98.0 103.0 103.0 102.0 82.0 81.0 16:15:52 INFO - PROCESS | 6765 | 16:15:52 INFO - PROCESS | 6765 | [#11] stanford-crypto-ccm Cycles:10 Average:148.90 Median:148.50 stddev:17.25 (11.6%) stddev-sans-first:17.99 16:15:52 INFO - PROCESS | 6765 | Values: 140.0 147.0 158.0 139.0 138.0 150.0 130.0 157.0 191.0 139.0 16:15:52 INFO - PROCESS | 6765 | 16:15:52 INFO - PROCESS | 6765 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:206.50 Median:198.50 stddev:21.85 (11.0%) stddev-sans-first:23.16 16:15:52 INFO - PROCESS | 6765 | Values: 209.0 196.0 195.0 198.0 197.0 197.0 198.0 199.0 267.0 209.0 16:15:52 INFO - PROCESS | 6765 | 16:15:52 INFO - PROCESS | 6765 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.40 Median:71.00 stddev:1.43 (2.0%) stddev-sans-first:1.42 16:15:52 INFO - PROCESS | 6765 | Values: 69.0 70.0 71.0 70.0 71.0 72.0 72.0 72.0 68.0 69.0 16:15:52 INFO - PROCESS | 6765 | -------- Summary: end -------- 16:15:52 INFO - PROCESS | 6765 | 16:15:53 INFO - TEST-INFO | 6765: exit 0 16:15:53 INFO - TEST-OK | kraken | took 59812ms 16:15:53 INFO - SUITE-END | took 1175s 16:15:53 INFO - Completed test suite (00:19:35) 16:15:53 INFO - javascript benchmark 16:15:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3095.95356069659, 3374.510695949087, 3394.4724873806776, 3412.0, 3446.6725661062205, 5559.388467268597, 5678.375378708344, 5682.516986056185, 5684.630499017561, 5685.232493613361, 4792.329073929653, 5664.178694089365, 5668.404817494165, 5748.425157484249, 5820.417958204177, 5104.310918026056, 5145.6398052136365, 5181.533661970422, 5211.895762084756, 5273.841784746463, 5258.3689957205, 5267.315249017623, 5271.367435907697, 5315.973420132894, 5360.785568577252, 5081.923771143417, 5208.635395522315, 5217.426083130853, 5223.738813059332, 5227.634065615409, 5137.51193636604, 5192.532672059495, 5255.159174532057, 5263.605229607757, 5283.128283833163, 5133.563647090002, 5140.177571588565, 5159.0, 5174.327337446108, 5176.689398636094, 5547.195656629807, 5552.639078459928, 5559.9444005559835, 5583.274174357308, 5614.831555053355, 6094.2077529920825, 6143.201383820119, 6174.5060395168275, 6208.2239720034995, 6236.189295391616, 6168.352323006109, 6225.252969643627, 6246.469050130744, 6270.996640537538, 6272.466840318579, 5740.311162660508, 5747.942520574825, 5782.56630752691, 5783.190353350535, 5785.987452195891, 6148.862460444832, 6149.723262453201, 6223.190985171898, 6262.060690896311, 6274.560780745395, 6118.265808103056, 6128.295246046672, 6130.938690613037, 6134.601250918676, 6144.692765361803, 6187.504999600022, 6223.066540019034, 6255.40573645503, 6264.68676566179, 6270.153529273581, 6062.393760623902, 6116.122740413025, 6121.540884433685, 6185.721642526007, 6211.503079753609, 6155.199824022938, 6166.876662466726, 6183.876322473615, 6193.969030154821, 6218.06728990655, 4895.461499235082, 4906.9263961040615, 4910.508949105061, 4917.360743103373, 4951.8266860659705, 4295.914081718348, 4323.805428755713, 4437.33439984005, 4446.9555304447185, 4461.286194208976, 5619.634723742944, 5640.0, 5644.717764111778, 5663.745131469093, 5688.488036076773, 6204.751809927552, 6238.937610623836, 6249.437550620466, 6269.811905642838, 6284.402981716729, 5522.779088836482, 5596.1605759136455, 5625.859353516113, 5631.352394474688, 5725.427457254241, 5378.650387724785, 5380.058489764354, 5388.515033646912, 5396.83809485716, 5424.294841670558, 6268.0, 6299.433051025429, 6333.018382150774, 6337.112804207414, 6340.4927605792345, 6055.9091613626715, 6122.398155112157, 6148.200733904564, 6239.0953311770445, 6264.21697287839, 6295.496360291258, 6301.968490157703, 6308.400701933309, 6317.989121740499, 6335.524835637253, 6158.0, 6159.692015399301, 6159.876802464015, 6164.506839452923, 6203.751849925952, 5511.559075274049, 5977.850553736192, 6080.118382834376, 6130.908036379368, 6200.286966998853, 5989.401059893976, 6104.67717083347, 6148.047052706837, 6150.661713605616, 6150.876982460236, 6120.418560236771, 6120.8775824484155, 6129.233845769279, 6132.5707200495535, 6137.723802428812, 5806.0, 5865.120231965239, 5897.587168898136, 6012.579119461596, 6023.397660233942, 6062.42376981979, 6126.540509461719, 6126.663033533198, 6137.41694539018, 6143.6928153593035, 5967.7911273106965, 5972.0, 5978.282606087298, 5979.641221526722, 5980.19267398913], "value": 5675.769232406435, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [489.7037292438073, 498.713239887065, 499.8650364401612, 503.70533238055737, 574.2133277409949, 30370.0, 30981.535276970837, 31041.223969400748, 37317.0, 38343.61656383435, 40124.0, 40810.795946020226, 40946.97632559187, 40977.0, 41167.38248926269, 9260.490673012999, 9405.388649737744, 9465.574049167804, 9472.194863436616, 9490.762730931712, 8427.157284271585, 8624.396292259544, 9340.065993400674, 11097.33415995039, 11369.0904727622, 11263.831042534368, 11358.545658173663, 11372.772544549103, 11373.431328433586, 11377.658670239865, 11182.496787644535, 11288.943555282252, 11295.604653837076, 11296.435178241056, 11321.264117832314, 8427.115152908918, 11029.007389334998, 11262.493187806569, 11283.887161128365, 11312.717182070432, 36851.631483685356, 37360.43959340612, 37500.0, 37502.06244843874, 37681.24637507262, 3093.690630936911, 3422.57217847769, 3518.8240587970754, 3568.3220188164164, 4000.0199951012187, 25791.0, 26530.938762449478, 27904.860475697693, 27935.860320698266, 27960.02139925113, 2659.6409484719507, 2734.4941185880675, 2765.3224959884956, 2824.5057115004997, 2928.1947464447235, 292141.0, 349810.2509487436, 350215.0, 350824.0, 350956.0, 54879.451205487436, 54932.90134197294, 55052.449475505535, 55128.62178445491, 57806.0, 53062.67343316448, 55692.443075569536, 59921.40078599158, 59921.70039149776, 59973.700131499936, 14336.56990290293, 16238.0, 16565.751513727304, 16574.0, 16631.501054968372, 11660.591879284184, 11895.107866910017, 11899.88100119005, 12382.938085309515, 12547.435365408579, 15290.617734556503, 15514.681252093698, 15522.767158492406, 15528.844711552965, 15543.766843497357, 4683.909963676563, 6901.551399159039, 7110.146782386147, 7193.3166349196745, 7204.603746793872, 901.5987885391069, 923.7182659288846, 936.6815282803743, 995.0994350113159, 1141.914356423255, 25215.873920630278, 27824.443511129663, 28297.009604663737, 28507.0, 28650.713492864805, 939.7885475767897, 1048.5596049659162, 1067.6743593204146, 1084.810158222308, 1129.728865072393, 10946.616868409568, 14750.63123421923, 14826.703465930621, 15077.47228847007, 15318.387264509518, 472054.63972679916, 476727.0, 478102.0, 482358.0, 483388.0, 40536.0, 56666.0, 56704.0, 56748.432515674314, 56873.0, 1331.8601546837635, 1365.0376484578348, 1480.5410322799967, 1485.8737007354496, 1494.4844028810044, 28167.295817604725, 28496.43007139887, 28588.0, 28824.85587572049, 28860.567091494064, 40996.590034099274, 47548.0, 47750.283745743145, 47911.76044119757, 47923.0, 338650.0, 357912.0, 358331.0, 358499.0, 358510.0, 30787.384252315278, 35595.288094237454, 36350.454743179405, 36426.4536031965, 36956.0, 43652.781736091114, 44020.89947751204, 44340.0, 44480.33279500875, 44615.55384446114, 32094.51858222082, 34874.0, 36130.8193459031, 36470.4529432064, 37248.81375593105, 34896.65103348934, 35135.47296790601, 35570.64429355673, 35742.4638630426, 36065.0, 35091.82454087713, 35320.76377326781, 35443.82278088593, 35826.492145549564, 36230.0, 31073.378532429677, 31480.0, 31617.367652647277, 31629.36741265208, 31687.841560792047, 18629.627407451502, 23061.88469057711, 23265.534689306456, 23948.64077038811, 24253.878730606233], "value": 18742.474841371597, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.578175570384, 396.37175077502155, 397.0688734916623, 401.21962782388266, 412.8947118484786, 13452.962878126998, 19109.904450477745, 19374.418767436964, 19788.109535070922, 19789.109490072944, 15544.06735595865, 15749.60625984351, 19547.5113122172, 19722.647149984503, 19854.702179467284, 5585.636933599303, 5828.679422631753, 5880.8235752927485, 5900.90833195858, 5905.6751878646655, 5710.77156913723, 5739.770409183628, 5775.740091695863, 5836.095405212178, 5840.0, 5669.716514174295, 5779.017567013597, 5831.329397119343, 5831.766729330821, 5835.095560188178, 5725.742341594639, 5791.102379131242, 5791.594588378815, 5800.564957628195, 5806.258122563257, 4915.213565829486, 5083.6441449098575, 5100.132977393852, 5114.744262786883, 5117.027764724728, 24034.639480407634, 25255.36861578457, 25575.104871329415, 25659.87170064138, 25693.743062569323, 2130.286354071388, 2142.2609199826034, 2228.6322756745117, 2651.469706058777, 2758.2001219646277, 12682.492700291978, 14141.929290353586, 14413.495527656481, 14602.853971460256, 14614.780778288334, 1582.090298078598, 1621.26232564183, 1633.8039435267724, 1636.7053930292543, 1721.4921598128583, 94698.52650736771, 96988.0, 97977.0, 98815.50592247065, 104844.51554844531, 11119.666410007712, 11302.491387887565, 11464.369459679798, 11557.688462307573, 11659.766804664028, 14484.0, 14510.637234069232, 14548.345324460424, 14553.12681239129, 14620.268986550629, 7554.0, 7910.406719496061, 8058.798530036796, 8581.356398270154, 8758.86861697075, 8515.0, 8527.0, 8533.957330213309, 8611.61247743853, 8680.95659521711, 4547.681662283675, 4571.862844114682, 4575.565321294472, 4575.702579332333, 4634.976825115853, 1901.458084445954, 1942.5726340205133, 1943.9805601944083, 1948.4057362504288, 1974.763028436568, 874.6413970272158, 925.4539821505344, 930.6091441594411, 980.764616492051, 981.2444417798256, 18422.171002304927, 19369.806301937082, 19419.0, 19598.0, 20072.290240817303, 918.5958178401482, 922.6171138977384, 945.9054094590485, 1022.9437380943971, 1061.0715623829149, 10471.214658900613, 10637.0, 10664.093552048163, 10684.572617095384, 10697.786044279072, 543492.0, 557450.0, 562553.1872340695, 566601.0, 574502.0, 71620.56758864793, 104827.95172048334, 106304.0, 108342.99884013968, 109335.0, 985.1281615769952, 995.2286977592279, 1024.6977141743203, 1024.7847951930178, 1025.994870025645, 17210.569735756708, 17335.913320433316, 17409.651806964044, 17737.0, 17844.821551784316, 42569.361459577514, 45022.0, 46984.06031879275, 48115.75942120267, 48676.26985595148, 63495.73008539896, 66138.0, 70672.0, 71139.64430178022, 71772.28227717656, 7622.8094297643, 7778.222177782177, 8074.111847696866, 8198.0, 8478.872816907628, 9354.53227338644, 10666.573337066431, 11690.491174443603, 12687.492500299884, 12923.870761292266, 10616.787664246825, 10788.838167427652, 11054.502547385226, 11088.0, 11118.100487259482, 10867.184961127788, 10875.456227188452, 10990.505427255634, 11115.666530004113, 11306.830397544209, 6006.768612434528, 9392.77893873792, 10998.615048473264, 11456.026237769884, 11534.59628912984, 6669.4664426844765, 7687.5387476751575, 8159.755207343789, 8337.166283371615, 8592.785180370542, 9111.908880911105, 10993.780124397308, 10997.175211859303, 11154.386508742096, 11208.831867522156], "value": 9245.148420630489, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1450.4560789703862, 1606.437746788624, 1608.5094046315874, 1850.750148729921, 1954.8240658340737, 7865.76402707919, 8666.913330866697, 8888.422252553579, 8902.688405905794, 8913.50975696338, 8430.957845210765, 8730.86903696445, 8734.650613975433, 8740.475571465717, 8745.387822852403, 2201.4606421426774, 2295.483516208852, 2445.645381419693, 2446.2049833803994, 2447.3759191406257, 2413.095089341497, 2434.513097380522, 2450.5588993981078, 2457.8033757299463, 2462.926112216627, 2391.282615215437, 2405.2663937499055, 2419.6128619420897, 2431.8054555635595, 2434.3914021494625, 2410.1323523531514, 2420.322309753263, 2431.4407686232266, 2442.5725498037773, 2453.374389530676, 2389.020501594355, 2421.0315873650507, 2421.8183636227354, 2424.769646883561, 2426.4904370082127, 8591.312694984386, 8676.045634980148, 8804.0, 8811.074837142072, 8824.426412283181, 5225.320708307933, 5439.3200849893765, 5447.673139611636, 5457.208704737791, 5465.480779325958, 3307.3881331953667, 3308.8511017004053, 3399.3371292597953, 3477.8608855645743, 3485.5991560970306, 967.2552134856192, 973.0804389851522, 974.9756256093583, 977.359829311802, 989.7525618595351, 11429.485673144729, 11512.654620361403, 11660.06719462441, 11671.358075305854, 11686.707832304175, 4523.886902827456, 4728.976355118271, 4743.478217396084, 4755.096531658974, 4763.618910487153, 9180.632774689071, 9238.214751746176, 9250.90749092514, 9257.444553326823, 9264.768380790534, 5204.88095059564, 5238.816641417532, 5321.255024296602, 5361.329833770778, 5369.275147855068, 4399.428074350378, 4536.364908912755, 4808.687435316693, 4932.580730637864, 4960.131976904099, 3762.473253744478, 3776.3013842439236, 3850.4609354690365, 3864.8840534784003, 3873.573906870242, 1128.0749785176076, 1128.198978725098, 1141.6632093532428, 1144.2162118948547, 1162.3316592959081, 654.456800855289, 781.1680560203365, 834.4742812028504, 869.1221865915471, 936.5644975086617, 5356.651817631841, 5575.69333686643, 5964.135200395918, 5993.921094203085, 6000.549958753111, 488.3066046214384, 504.44259093701663, 504.57363527818933, 504.86873412912905, 647.4464332995316, 3735.34631439497, 3945.9605403946166, 3956.4460975463458, 3999.1801680655394, 4010.7192496525545, 10327.586896524204, 10778.0, 10830.187735919837, 10938.343699378063, 10979.0, 10148.441835698959, 10767.515461804238, 10773.676789696321, 10776.461176941279, 10780.51487683056, 7985.720499782596, 8194.262516373674, 8266.297364724065, 8319.916800832036, 8347.707830226034, 5648.85877853057, 5865.882682346244, 5985.371535988658, 6090.786822461193, 6094.451499364901, 8913.955430223066, 8924.018357980747, 10193.286469947032, 10225.130863876653, 10228.335158214692, 9204.355695101547, 10105.141063009727, 10255.025772551595, 10314.793704125725, 10345.120664743281, 6180.289266734203, 7551.169371369255, 7647.617619118911, 7715.691372345043, 7744.0, 8287.95856020716, 8331.833363332578, 8358.414910956175, 8381.58092095405, 8384.329253659815, 6155.446009859134, 7454.403647708279, 7561.0, 7562.357199638092, 7750.534967901944, 7209.134903811576, 7339.339459448675, 7353.889691654736, 7471.551706897603, 7518.360939320219, 6870.587764734132, 6889.345512176335, 6927.93072069293, 6941.791746247621, 6949.3745562899585, 4647.442306923191, 4795.280707893844, 4931.654784165074, 4980.676256043345, 5135.409427915688, 4831.879203019953, 4886.169351210374, 4958.528939750718, 5021.949780502295, 5169.172932330809], "value": 4475.800501551866, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.2363658126648, 313.52031391970286, 320.6376794222529, 322.31990500044884, 322.7917992894583, 4176.791160441977, 4503.324501324803, 4585.014221942281, 4718.646101542381, 5344.9844529539405, 4136.131412403399, 4520.68355215135, 4856.271559266103, 5046.470120637336, 5276.261323414725, 4049.5545489996075, 4102.671786257092, 4102.671786257107, 4102.815373308208, 4132.2768515509815, 3758.304713627974, 3875.642011760908, 3964.8612298569556, 4029.811205694313, 4077.103037331782, 3838.923221535568, 4064.0738668159024, 4073.368627862671, 4114.6296833285, 4137.2552940470705, 3568.3220188164164, 3873.5280593374478, 4064.1871625674985, 4133.4626498555, 4155.085881106152, 3166.889158879428, 3538.7620327400396, 3551.5738111426535, 3557.3774589446743, 3589.881969637926, 3744.0, 5090.10923088458, 5141.460146684582, 5148.433672296044, 5160.864609785841, 3753.0, 4356.259435895905, 4573.062522182944, 4581.496035436099, 4595.448546174447, 5574.442555744434, 5581.720913954286, 5587.804426845041, 5601.523870471015, 5619.7471113799975, 3627.256412435471, 3674.3386190485703, 3828.4831547741255, 3830.9808450957844, 3846.9993405144032, 4929.137400954819, 5261.631685781997, 5759.366469688331, 5768.74040668171, 5866.384029676865, 3156.2898347871546, 3854.9036274093373, 3858.614138586175, 3870.3613903705573, 3877.1664092219744, 3401.268727223659, 3830.367989281737, 3885.873096801952, 3903.1803321302846, 3953.5453422856162, 3843.9039024024623, 4512.34570987205, 4561.521040290788, 4563.224251877189, 4570.405847239837, 5608.990381731328, 5616.887662246814, 5653.886922261532, 5655.208270842086, 5688.345840228427, 1243.5274595653775, 1282.852471965736, 1370.3627813066773, 1392.9930350348184, 1418.0711633879826, 841.1966571923921, 937.5921474158763, 948.5020364308655, 952.3333666433524, 952.7570469530225, 5019.498050194951, 5614.747336369873, 5704.657720536781, 5726.541876649858, 5753.626014309057, 5500.339959204921, 5645.604807663424, 5813.924423981577, 6179.969100154471, 6270.3416141305415, 4515.887102822456, 4828.975855120772, 5335.279737235423, 5692.658440493584, 5701.0, 3177.4916013437737, 3298.191942973987, 3305.058058453329, 3306.2560923791953, 3316.005198440458, 6338.714757835908, 6441.549091563639, 6524.0, 6543.901841472381, 6586.7035983380865, 6319.526035547352, 6356.872862542723, 6410.8076757697345, 6413.358664133642, 6428.003659432796, 5477.972610136924, 6202.875942481125, 6541.672916354163, 6562.5406221564035, 6567.605943643397, 5459.426760190282, 5727.828165155052, 6202.193714817226, 6215.533834962486, 6217.0, 5168.870778230575, 5606.0, 6120.918887578245, 6123.4488895999575, 6150.138980542638, 5431.5926305526455, 6196.481861943853, 6439.420452159328, 6506.186726659168, 6517.608943463408, 3526.4131140986146, 4116.744392960176, 4119.835206591702, 4133.793310334411, 4134.813933373066, 4987.526185012418, 5086.491350864884, 5658.688772117408, 5666.405027472221, 5674.092145256739, 5560.110382338807, 5595.692236927008, 5622.32532096151, 5638.85902852432, 5674.205611214516, 5276.39321478035, 5561.610687252015, 5599.80400685974, 5612.747426365905, 5644.8024319148635, 5509.955465021114, 5510.669359838423, 5542.919130769461, 5554.361248456479, 5568.638038527482, 5182.870428239174, 5305.867353316198, 5594.356648985418, 5630.943690563042, 5637.379888212211, 4899.387576552931, 5314.548263397654, 5503.724813759216, 5511.5039646432015, 5521.536792750005], "value": 4182.62989614713, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [656.1764984943894, 688.7245101959215, 738.5531753289257, 755.5806527377307, 785.2187073861509, 14706.852931470683, 15206.543803685907, 15212.54362369128, 15215.011024283414, 15232.466863659774, 13990.0, 14039.719205615882, 14079.5072172474, 14416.135031898093, 14503.854961450383, 750.8423231121471, 916.6104405627615, 931.3061768982084, 932.4778124250441, 935.4293880732781, 737.3879679865698, 758.9886151707728, 882.5410786391072, 932.4451951089092, 934.8784657994452, 982.7444864335253, 986.0336869867534, 987.20036770216, 987.7234374375187, 992.1715367668006, 773.2769860180749, 934.3879758758025, 988.9703308900708, 993.0466751918167, 997.670768646345, 692.1590267824618, 709.5458906299972, 710.605613884294, 735.2427000189813, 735.915369732477, 15300.923495382563, 15361.308741106677, 15384.769228461582, 15386.99984501004, 15402.460913868073, 11466.48400821965, 11486.195966282363, 11492.195546311761, 11738.295702257892, 11926.224795388272, 9865.950670246675, 10098.394096354243, 10146.594136234542, 10174.898251017468, 10385.2211084169, 3448.4654878493875, 3516.7186625069935, 3518.894433167009, 3607.8556857725657, 3613.2050948791225, 78863.0, 79766.80349794758, 80887.99999999942, 81171.0, 82836.58581707113, 9656.130948214695, 9708.0, 9889.554970026365, 10005.949970250102, 10134.0, 22629.66055509136, 22669.97985090642, 22680.546389072126, 22704.205352812904, 22712.65931011036, 7497.900587835415, 7559.697612095564, 7564.886526702104, 7590.696372145052, 7706.036745406825, 3850.8652197172964, 3912.921741565153, 3913.8238779255003, 3923.1761330120426, 4036.6972477064337, 3603.5855876574005, 3668.229671768905, 3677.1910179760944, 3682.1531047858607, 3686.907827304285, 638.4477427025589, 644.1722386733026, 652.354169372318, 654.8768861402717, 657.980260592183, 496.8698191176121, 561.2507302750791, 597.1845589408238, 601.9879602407927, 611.2084850119106, 6950.860982780316, 7795.415343849234, 8036.45940043885, 8220.424570280142, 8388.79028024292, 522.9320188375486, 586.5776640818603, 590.9320428150731, 615.5814046448369, 618.3167599802243, 3704.685101766326, 3710.128119891823, 3750.9062273443383, 3774.3206222880144, 3931.6264954829817, 81351.59324203341, 83768.16231837604, 83950.58024709838, 95546.0, 97059.0, 74470.62764686142, 74649.25350746531, 78154.0, 79025.60487197642, 82582.0, 48183.03633927302, 61091.0, 63214.73570528564, 65243.0, 66870.32824179337, 7919.722809701863, 8404.0, 8492.74521764348, 8497.037419970533, 8626.56867156627, 43558.78220608877, 44138.779306103264, 44396.55603443924, 45507.0, 46530.836729082046, 61779.0, 62182.0, 64687.67656161689, 66266.66866665636, 66286.0, 10940.890591093987, 11028.393438360967, 11153.55385784592, 11263.0, 11360.091192704398, 11895.76208475843, 11978.940105299765, 12158.270503769802, 12167.878321216675, 13034.739305214032, 7940.960295198487, 10657.094146997591, 11798.646040618794, 11972.64082077539, 12020.879791201976, 11240.0, 11431.256968297033, 11631.6510504685, 11760.05919526453, 11867.347295898808, 11172.608958686407, 11229.922464344123, 11319.547218111184, 11592.362420066976, 11635.069194464591, 9497.28770342241, 9523.28575356837, 9644.421334719938, 9815.509224538888, 9858.457784821903, 9414.858777118487, 9717.22262219035, 9734.172595329477, 9783.51082445889, 9975.551100200653], "value": 6849.32920016357, "unit": "score"}], "name": "dromaeo_css", "value": 7081.56836022532, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 166.0, 85.0, 153.0, 162.0, 165.0, 117.0, 111.0, 105.0, 112.0], "value": 133.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 135.0, 127.0, 204.0, 147.0, 170.0, 156.0, 119.0, 172.0, 117.0], "value": 150.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 189.0, 187.0, 184.0, 188.0, 186.0, 184.0, 186.0, 183.0, 184.0], "value": 185.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 73.0, 91.0, 94.0, 56.0, 56.0, 58.0, 82.0, 87.0, 55.0], "value": 73.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 99.0, 135.0, 78.0, 87.0, 84.0, 121.0, 78.0, 76.0, 131.0], "value": 98.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 90.0, 88.0, 90.0, 86.0, 90.0, 88.0, 90.0, 85.0], "value": 88.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 101.0, 88.0, 93.0, 94.0, 95.0, 94.0, 96.0], "value": 94.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 84.0, 87.0, 85.0, 85.0, 87.0, 84.0, 84.0, 98.0], "value": 86.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 61.0, 60.0, 58.0, 59.0, 60.0, 79.0, 61.0, 58.0], "value": 61.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 77.0, 75.0, 72.0, 67.0, 68.0, 69.0, 68.0, 69.0], "value": 69.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 80.0, 83.0, 82.0, 98.0, 103.0, 103.0, 102.0, 82.0, 81.0], "value": 89.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 147.0, 158.0, 139.0, 138.0, 150.0, 130.0, 157.0, 191.0, 139.0], "value": 148.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [209.0, 196.0, 195.0, 198.0, 197.0, 197.0, 198.0, 199.0, 267.0, 209.0], "value": 206.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 71.0, 70.0, 71.0, 72.0, 72.0, 72.0, 68.0, 69.0], "value": 70.4, "unit": "score"}], "name": "kraken", "value": 1556.6000000000001, "alertThreshold": 2.0}]} 16:15:53 INFO - Return code: 0 16:15:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:15:53 ERROR - # TBPL SUCCESS # 16:15:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:15:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:15:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:15:53 INFO - 2016-04-27 16:15:53.851 screenresolution[6770:113876] starting screenresolution argv=screenresolution get 16:15:53 INFO - 2016-04-27 16:15:53.853 screenresolution[6770:113876] Display 0: 1600x1200x32@60 16:15:53 INFO - 2016-04-27 16:15:53.859 screenresolution[6771:113878] starting screenresolution argv=screenresolution list 16:15:53 INFO - Available Modes on Display 0 16:15:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:15:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:15:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:15:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:15:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:15:54 INFO - Intel Iris: 16:15:54 INFO - Chipset Model: Intel Iris 16:15:54 INFO - Type: GPU 16:15:54 INFO - Bus: Built-In 16:15:54 INFO - VRAM (Dynamic, Max): 1536 MB 16:15:54 INFO - Vendor: Intel (0x8086) 16:15:54 INFO - Device ID: 0x0a2e 16:15:54 INFO - Revision ID: 0x0009 16:15:54 INFO - Displays: 16:15:54 INFO - Display: 16:15:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:15:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:15:54 INFO - Display Serial Number: 1600x1200 60 16:15:54 INFO - Main Display: Yes 16:15:54 INFO - Mirror: Off 16:15:54 INFO - Online: Yes 16:15:54 INFO - Rotation: Supported 16:15:54 INFO - Return code: 0 16:15:54 INFO - Running post-action listener: _resource_record_post_action 16:15:54 INFO - Running post-run listener: _resource_record_post_run 16:15:55 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 614400; Write bytes: 695339520; Read time: 5; Write time: 2060 16:15:55 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 391047680; Write bytes: 386061824; Read time: 29538; Write time: 728 16:15:55 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 614400; Write bytes: 295736320; Read time: 5; Write time: 1295 16:15:55 INFO - Running post-run listener: _upload_blobber_files 16:15:55 INFO - Blob upload gear active. 16:15:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:15:55 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 16:15:55 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'] 16:15:55 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 16:15:55 INFO - (blobuploader) - INFO - Open directory for files ... 16:15:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:15:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:15:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:15:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:15:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:15:57 INFO - (blobuploader) - INFO - Done attempting. 16:15:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:15:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:15:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:15:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:15:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:15:58 INFO - (blobuploader) - INFO - Done attempting. 16:15:58 INFO - (blobuploader) - INFO - Iteration through files over. 16:15:58 INFO - Return code: 0 16:15:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:15:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:15:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bfd3f119b6865fca84af36203ede954277d4e3a9b18b639a2e7679902b7469ab33a665da862d93343c95d4e64ca0dda39697092af68a3a24ffcde04323ff021a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0786033b44e5ed3b4146114aa8cde09e66458a3283d25d4a1ff2cdd46a52fb4889fefd0cd60f8d18ea4526a9c9f93989ec89954d3aa4afb51b516695887625fa"} 16:15:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:15:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:15:58 INFO - Contents: 16:15:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bfd3f119b6865fca84af36203ede954277d4e3a9b18b639a2e7679902b7469ab33a665da862d93343c95d4e64ca0dda39697092af68a3a24ffcde04323ff021a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0786033b44e5ed3b4146114aa8cde09e66458a3283d25d4a1ff2cdd46a52fb4889fefd0cd60f8d18ea4526a9c9f93989ec89954d3aa4afb51b516695887625fa"} 16:15:58 INFO - Running post-run listener: copy_logs_to_upload_dir 16:15:58 INFO - Copying logs to upload dir... 16:15:58 INFO - mkdir: /builds/slave/test/build/upload/logs 16:15:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1286.197670 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2016-04-27 16:15:58.991624) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-27 16:15:58.995985) ========= 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.TRR32I4UOz/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.hxVWL0vbGy/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bfd3f119b6865fca84af36203ede954277d4e3a9b18b639a2e7679902b7469ab33a665da862d93343c95d4e64ca0dda39697092af68a3a24ffcde04323ff021a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0786033b44e5ed3b4146114aa8cde09e66458a3283d25d4a1ff2cdd46a52fb4889fefd0cd60f8d18ea4526a9c9f93989ec89954d3aa4afb51b516695887625fa"} build_url:https://queue.taskcluster.net/v1/task/adh_Mmw3S96bjzi3z257Jg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011180 build_url: 'https://queue.taskcluster.net/v1/task/adh_Mmw3S96bjzi3z257Jg/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/bfd3f119b6865fca84af36203ede954277d4e3a9b18b639a2e7679902b7469ab33a665da862d93343c95d4e64ca0dda39697092af68a3a24ffcde04323ff021a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0786033b44e5ed3b4146114aa8cde09e66458a3283d25d4a1ff2cdd46a52fb4889fefd0cd60f8d18ea4526a9c9f93989ec89954d3aa4afb51b516695887625fa"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-27 16:15:59.031764) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 16:15:59.032186) ========= 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.TRR32I4UOz/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.hxVWL0vbGy/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005383 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 16:15:59.081289) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-27 16:15:59.081610) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-27 16:15:59.082037) ========= ========= Total master_lag: 0.17 =========