builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0066 starttime: 1467675063.56 results: success (0) buildid: 20160704150823 builduid: da2965c87fca4c1cb34c6577566940e7 revision: 162b6fca7d868e8165d0f6ef80e02b5dd73161cb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-04 16:31:03.564809) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-04 16:31:03.565246) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-04 16:31:03.565521) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-04 16:31:03.615555) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 16:31:03.615851) ========= 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.LkDfcOQWAm/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.Ac9E8tuFKc/Listeners TMPDIR=/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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-07-04 16:31:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 12.4M=0.001s 2016-07-04 16:31:03 (12.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.098137 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 16:31:03.736870) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 16:31:03.737204) ========= 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.LkDfcOQWAm/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.Ac9E8tuFKc/Listeners TMPDIR=/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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.028461 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 16:31:03.787443) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-04 16:31:03.787843) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 162b6fca7d868e8165d0f6ef80e02b5dd73161cb --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 162b6fca7d868e8165d0f6ef80e02b5dd73161cb --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LkDfcOQWAm/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.Ac9E8tuFKc/Listeners TMPDIR=/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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-07-04 16:31:03,859 truncating revision to first 12 chars 2016-07-04 16:31:03,859 Setting DEBUG logging. 2016-07-04 16:31:03,859 attempt 1/10 2016-07-04 16:31:03,859 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/162b6fca7d86?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-04 16:31:04,708 unpacking tar archive at: fx-team-162b6fca7d86/testing/mozharness/ program finished with exit code 0 elapsedTime=1.098497 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-04 16:31:04.908215) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-04 16:31:04.908540) ========= script_repo_revision: 162b6fca7d868e8165d0f6ef80e02b5dd73161cb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-04 16:31:04.908922) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-04 16:31:04.909202) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-04 16:31:04.927652) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-07-04 16:31:04.928111) ========= /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.LkDfcOQWAm/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.Ac9E8tuFKc/Listeners TMPDIR=/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 16:31:05 INFO - MultiFileLogger online at 20160704 16:31:05 in /builds/slave/test 16:31:05 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 16:31:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:31:05 INFO - {'append_to_log': False, 16:31:05 INFO - 'base_work_dir': '/builds/slave/test', 16:31:05 INFO - 'blob_upload_branch': 'Fx-Team', 16:31:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:31:05 INFO - 'branch': 'Fx-Team', 16:31:05 INFO - 'buildbot_json_path': 'buildprops.json', 16:31:05 INFO - 'config_files': ('talos/mac_config.py',), 16:31:05 INFO - 'default_actions': ('clobber', 16:31:05 INFO - 'read-buildbot-config', 16:31:05 INFO - 'download-and-extract', 16:31:05 INFO - 'populate-webroot', 16:31:05 INFO - 'create-virtualenv', 16:31:05 INFO - 'install', 16:31:05 INFO - 'run-tests'), 16:31:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:31:05 INFO - 'download_minidump_stackwalk': True, 16:31:05 INFO - 'download_symbols': 'ondemand', 16:31:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:31:05 INFO - 'tooltool.py': '/tools/tooltool.py', 16:31:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:31:05 INFO - '/tools/misc-python/virtualenv.py')}, 16:31:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:31:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:31:05 INFO - 'installer_path': 'installer.exe', 16:31:05 INFO - 'log_level': 'info', 16:31:05 INFO - 'log_name': 'talos', 16:31:05 INFO - 'log_to_console': True, 16:31:05 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:31:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:31:05 INFO - 'opt_config_files': (), 16:31:05 INFO - 'pip_index': False, 16:31:05 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:31:05 INFO - 'cmd': ('bash', 16:31:05 INFO - '-c', 16:31:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:31:05 INFO - 'enabled': True, 16:31:05 INFO - 'halt_on_failure': False, 16:31:05 INFO - 'name': 'check_screen_resolution'},), 16:31:05 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:31:05 INFO - 'cmd': ('bash', 16:31:05 INFO - '-c', 16:31:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:31:05 INFO - 'enabled': True, 16:31:05 INFO - 'halt_on_failure': False, 16:31:05 INFO - 'name': 'check_screen_resolution'},), 16:31:05 INFO - 'run_cmd_checks_enabled': True, 16:31:05 INFO - 'sps_profile': False, 16:31:05 INFO - 'sps_profile_interval': 0, 16:31:05 INFO - 'suite': 'dromaeojs', 16:31:05 INFO - 'system_bits': '32', 16:31:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:31:05 INFO - 'title': 't-yosemite-r7-0066', 16:31:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:31:05 INFO - 'use_talos_json': True, 16:31:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:31:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:31:05 INFO - 'work_dir': 'build'} 16:31:05 INFO - ##### 16:31:05 INFO - ##### Running clobber step. 16:31:05 INFO - ##### 16:31:05 INFO - Running pre-action listener: _resource_record_pre_action 16:31:05 INFO - Running main action method: clobber 16:31:05 INFO - rmtree: /builds/slave/test/build 16:31:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:31:07 INFO - Running post-action listener: _resource_record_post_action 16:31:07 INFO - ##### 16:31:07 INFO - ##### Finished clobber step (success) 16:31:07 INFO - ##### 16:31:07 INFO - ##### 16:31:07 INFO - ##### Running read-buildbot-config step. 16:31:07 INFO - ##### 16:31:07 INFO - Running pre-action listener: _resource_record_pre_action 16:31:07 INFO - Running main action method: read_buildbot_config 16:31:07 INFO - Using buildbot properties: 16:31:07 INFO - { 16:31:07 INFO - "project": "", 16:31:07 INFO - "product": "firefox", 16:31:07 INFO - "script_repo_revision": "production", 16:31:07 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 16:31:07 INFO - "repository": "", 16:31:07 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 16:31:07 INFO - "buildid": "20160704150823", 16:31:07 INFO - "pgo_build": "False", 16:31:07 INFO - "basedir": "/builds/slave/test", 16:31:07 INFO - "buildnumber": 869, 16:31:07 INFO - "slavename": "t-yosemite-r7-0066", 16:31:07 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 16:31:07 INFO - "platform": "yosemite_r7", 16:31:07 INFO - "scheduler": "tests-fx-team-macosx64-talos", 16:31:07 INFO - "branch": "fx-team", 16:31:07 INFO - "repo_path": "integration/fx-team", 16:31:07 INFO - "revision": "162b6fca7d868e8165d0f6ef80e02b5dd73161cb", 16:31:07 INFO - "stage_platform": "macosx64", 16:31:07 INFO - "builduid": "da2965c87fca4c1cb34c6577566940e7", 16:31:07 INFO - "slavebuilddir": "test" 16:31:07 INFO - } 16:31:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/dep_SHRERLeo_YIjuMtGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 16:31:07 INFO - Running post-action listener: _resource_record_post_action 16:31:07 INFO - ##### 16:31:07 INFO - ##### Finished read-buildbot-config step (success) 16:31:07 INFO - ##### 16:31:07 INFO - ##### 16:31:07 INFO - ##### Running download-and-extract step. 16:31:07 INFO - ##### 16:31:07 INFO - Running pre-action listener: _resource_record_pre_action 16:31:07 INFO - Running main action method: download_and_extract 16:31:07 INFO - mkdir: /builds/slave/test/build/tests 16:31:07 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')]} 16:31:07 INFO - https://queue.taskcluster.net/v1/task/dep_SHRERLeo_YIjuMtGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 16:31:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dep_SHRERLeo_YIjuMtGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 16:31:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dep_SHRERLeo_YIjuMtGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 16:31:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dep_SHRERLeo_YIjuMtGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 16:31:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dep_SHRERLeo_YIjuMtGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 16:31:11 INFO - Downloaded 1199 bytes. 16:31:11 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 16:31:11 INFO - Using the following test package requirements: 16:31:11 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 16:31:11 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:31:11 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 16:31:11 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:31:11 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 16:31:11 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:31:11 INFO - u'jsshell-mac.zip'], 16:31:11 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:31:11 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 16:31:11 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 16:31:11 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:31:11 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 16:31:11 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:31:11 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 16:31:11 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:31:11 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 16:31:11 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:31:11 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 16:31:11 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:31: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')]} 16:31:11 INFO - https://queue.taskcluster.net/v1/task/dep_SHRERLeo_YIjuMtGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:31:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dep_SHRERLeo_YIjuMtGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:31:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dep_SHRERLeo_YIjuMtGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:31:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dep_SHRERLeo_YIjuMtGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:31:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dep_SHRERLeo_YIjuMtGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:31:12 INFO - Downloaded 18800208 bytes. 16:31:12 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:31:12 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:31:15 INFO - Return code: 0 16:31:15 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 16:31: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')]} 16:31:15 INFO - https://queue.taskcluster.net/v1/task/dep_SHRERLeo_YIjuMtGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:31:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dep_SHRERLeo_YIjuMtGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:31:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dep_SHRERLeo_YIjuMtGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:31:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dep_SHRERLeo_YIjuMtGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:31:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dep_SHRERLeo_YIjuMtGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:31:24 INFO - Downloaded 18800208 bytes. 16:31:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:31:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:31:28 INFO - Return code: 0 16:31: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')]} 16:31:28 INFO - https://queue.taskcluster.net/v1/task/dep_SHRERLeo_YIjuMtGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 16:31:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dep_SHRERLeo_YIjuMtGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 16:31:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dep_SHRERLeo_YIjuMtGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 16:31:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dep_SHRERLeo_YIjuMtGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 16:31:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dep_SHRERLeo_YIjuMtGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:31:29 INFO - Downloaded 14403333 bytes. 16:31:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:31:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 16:31:29 INFO - Return code: 0 16:31:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:31:29 INFO - https://queue.taskcluster.net/v1/task/dep_SHRERLeo_YIjuMtGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 16:31:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dep_SHRERLeo_YIjuMtGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 16:31:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dep_SHRERLeo_YIjuMtGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 16:31:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dep_SHRERLeo_YIjuMtGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 16:31:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/dep_SHRERLeo_YIjuMtGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 16:31:36 INFO - Downloaded 112420805 bytes. 16:31:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dep_SHRERLeo_YIjuMtGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 16:31:36 INFO - mkdir: /builds/slave/test/properties 16:31:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:31:36 INFO - Writing to file /builds/slave/test/properties/build_url 16:31:36 INFO - Contents: 16:31:36 INFO - build_url:https://queue.taskcluster.net/v1/task/dep_SHRERLeo_YIjuMtGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 16:31:37 INFO - Running post-action listener: _resource_record_post_action 16:31:37 INFO - Running post-action listener: set_extra_try_arguments 16:31:37 INFO - ##### 16:31:37 INFO - ##### Finished download-and-extract step (success) 16:31:37 INFO - ##### 16:31:37 INFO - ##### 16:31:37 INFO - ##### Running populate-webroot step. 16:31:37 INFO - ##### 16:31:37 INFO - Running pre-action listener: _resource_record_pre_action 16:31:37 INFO - Running main action method: populate_webroot 16:31:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:31:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:31:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:31:37 INFO - '--tppagecycles', 16:31:37 INFO - '7'], 16:31:37 INFO - 'tests': ['tsvgm']}}, 16:31:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:31:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:31:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:31:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:31:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:31:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:31:37 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:31:37 INFO - 'talos_options': ['--disable-e10s'], 16:31:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:31:37 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:31:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:31:37 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:31:37 INFO - 'talos_options': ['--disable-e10s'], 16:31:37 INFO - 'tests': ['damp', 'tps']}, 16:31:37 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:31:37 INFO - 'tests': ['damp', 'tps']}, 16:31:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:31:37 INFO - 'tests': ['dromaeo_dom']}, 16:31:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:31:37 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:31:37 INFO - 'tests': ['basic_compositor_video']}, 16:31:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:31:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:31:37 INFO - 'tests': ['a11yr', 16:31:37 INFO - 'ts_paint', 16:31:37 INFO - 'tpaint', 16:31:37 INFO - 'sessionrestore', 16:31:37 INFO - 'sessionrestore_no_auto_restore', 16:31:37 INFO - 'tabpaint']}, 16:31:37 INFO - 'other-e10s': {'tests': ['a11yr', 16:31:37 INFO - 'ts_paint', 16:31:37 INFO - 'tpaint', 16:31:37 INFO - 'sessionrestore', 16:31:37 INFO - 'sessionrestore_no_auto_restore', 16:31:37 INFO - 'tabpaint']}, 16:31:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:31:37 INFO - 'ts_paint', 16:31:37 INFO - 'tpaint', 16:31:37 INFO - 'sessionrestore', 16:31:37 INFO - 'sessionrestore_no_auto_restore', 16:31:37 INFO - 'tabpaint']}, 16:31:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:31:37 INFO - 'ts_paint', 16:31:37 INFO - 'tpaint', 16:31:37 INFO - 'sessionrestore', 16:31:37 INFO - 'sessionrestore_no_auto_restore', 16:31:37 INFO - 'tabpaint']}, 16:31:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:31:37 INFO - 'tests': ['a11yr', 16:31:37 INFO - 'ts_paint', 16:31:37 INFO - 'tpaint', 16:31:37 INFO - 'sessionrestore', 16:31:37 INFO - 'sessionrestore_no_auto_restore', 16:31:37 INFO - 'tabpaint']}, 16:31:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:31:37 INFO - 'tests': ['a11yr', 16:31:37 INFO - 'ts_paint', 16:31:37 INFO - 'tpaint', 16:31:37 INFO - 'sessionrestore', 16:31:37 INFO - 'sessionrestore_no_auto_restore', 16:31:37 INFO - 'tabpaint']}, 16:31:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:31:37 INFO - 'tests': ['tsvgx', 16:31:37 INFO - 'tsvgr_opacity', 16:31:37 INFO - 'tart', 16:31:37 INFO - 'tscrollx', 16:31:37 INFO - 'cart']}, 16:31:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:31:37 INFO - 'tsvgr_opacity', 16:31:37 INFO - 'tart', 16:31:37 INFO - 'tscrollx', 16:31:37 INFO - 'cart']}, 16:31:37 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:31:37 INFO - 'talos_options': ['--disable-e10s'], 16:31:37 INFO - 'tests': ['tp5o']}, 16:31:37 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:31:37 INFO - 'tests': ['tp5o']}, 16:31:37 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:31:37 INFO - 'talos_options': ['--disable-e10s', 16:31:37 INFO - '--xperf_path', 16:31:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:31:37 INFO - 'tests': ['tp5n']}, 16:31:37 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:31:37 INFO - 'talos_options': ['--xperf_path', 16:31:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:31:37 INFO - 'tests': ['tp5n']}}, 16:31:37 INFO - 'talos.zip': {'path': '', 16:31:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:31:37 INFO - Running post-action listener: _resource_record_post_action 16:31:37 INFO - ##### 16:31:37 INFO - ##### Finished populate-webroot step (success) 16:31:37 INFO - ##### 16:31:37 INFO - ##### 16:31:37 INFO - ##### Running create-virtualenv step. 16:31:37 INFO - ##### 16:31:37 INFO - Running pre-action listener: _resource_record_pre_action 16:31:37 INFO - Running main action method: create_virtualenv 16:31:37 INFO - Creating virtualenv /builds/slave/test/build/venv 16:31:37 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:31:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:31:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:31:37 INFO - Using real prefix '/tools/python27' 16:31:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:31:38 INFO - Installing distribute.............................................................................................................................................................................................done. 16:31:41 INFO - Installing pip.................done. 16:31:41 INFO - Return code: 0 16:31:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:31:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:31:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:31:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102552a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025e7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa26169e640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024b6d30>, '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 0x10221bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024aeb20>, '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.LkDfcOQWAm/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.Ac9E8tuFKc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 16:31:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:31:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:31:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LkDfcOQWAm/Render', 16:31:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:31:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:31:41 INFO - 'HOME': '/Users/cltbld', 16:31:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:31:41 INFO - 'LOGNAME': 'cltbld', 16:31:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:41 INFO - 'MOZ_NO_REMOTE': '1', 16:31:41 INFO - 'NO_EM_RESTART': '1', 16:31:41 INFO - 'PAGER': '/bin/cat', 16:31:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:31:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:41 INFO - 'PWD': '/builds/slave/test', 16:31:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:31:41 INFO - 'SHELL': '/bin/bash', 16:31:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ac9E8tuFKc/Listeners', 16:31:41 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 16:31:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:31:41 INFO - 'USER': 'cltbld', 16:31:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:31:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:31:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:41 INFO - 'XPC_FLAGS': '0x0', 16:31:41 INFO - 'XPC_SERVICE_NAME': '0', 16:31:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:31:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:41 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 16:31:41 INFO - Cleaning up... 16:31:41 INFO - Return code: 0 16:31:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:31:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:31:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:31:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102552a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025e7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa26169e640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024b6d30>, '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 0x10221bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024aeb20>, '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.LkDfcOQWAm/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.Ac9E8tuFKc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 16:31:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:31:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:31:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LkDfcOQWAm/Render', 16:31:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:31:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:31:41 INFO - 'HOME': '/Users/cltbld', 16:31:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:31:41 INFO - 'LOGNAME': 'cltbld', 16:31:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:41 INFO - 'MOZ_NO_REMOTE': '1', 16:31:41 INFO - 'NO_EM_RESTART': '1', 16:31:41 INFO - 'PAGER': '/bin/cat', 16:31:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:31:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:41 INFO - 'PWD': '/builds/slave/test', 16:31:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:31:41 INFO - 'SHELL': '/bin/bash', 16:31:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ac9E8tuFKc/Listeners', 16:31:41 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 16:31:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:31:41 INFO - 'USER': 'cltbld', 16:31:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:31:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:31:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:41 INFO - 'XPC_FLAGS': '0x0', 16:31:41 INFO - 'XPC_SERVICE_NAME': '0', 16:31:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:31:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:41 INFO - Downloading/unpacking psutil>=3.1.1 16:31: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 16:31: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 16:31: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 16:31: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 16:31: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 16:31: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 16:31:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:31:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:31:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:31:44 INFO - Installing collected packages: psutil 16:31:44 INFO - Running setup.py install for psutil 16:31:44 INFO - building 'psutil._psutil_osx' extension 16:31:44 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 16:31:45 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 16:31:45 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 16:31:45 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 16:31:45 INFO - building 'psutil._psutil_posix' extension 16:31:45 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 16:31:45 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:31:45 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:31:45 INFO - ^ 16:31:45 INFO - 1 warning generated. 16:31:45 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 16:31:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:31:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:31:46 INFO - Successfully installed psutil 16:31:46 INFO - Cleaning up... 16:31:46 INFO - Return code: 0 16:31:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:31:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:31:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:31:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102552a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025e7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa26169e640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024b6d30>, '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 0x10221bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024aeb20>, '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.LkDfcOQWAm/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.Ac9E8tuFKc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 16:31:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:31:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:31:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LkDfcOQWAm/Render', 16:31:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:31:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:31:46 INFO - 'HOME': '/Users/cltbld', 16:31:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:31:46 INFO - 'LOGNAME': 'cltbld', 16:31:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:46 INFO - 'MOZ_NO_REMOTE': '1', 16:31:46 INFO - 'NO_EM_RESTART': '1', 16:31:46 INFO - 'PAGER': '/bin/cat', 16:31:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:31:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:46 INFO - 'PWD': '/builds/slave/test', 16:31:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:31:46 INFO - 'SHELL': '/bin/bash', 16:31:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ac9E8tuFKc/Listeners', 16:31:46 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 16:31:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:31:46 INFO - 'USER': 'cltbld', 16:31:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:31:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:31:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:46 INFO - 'XPC_FLAGS': '0x0', 16:31:46 INFO - 'XPC_SERVICE_NAME': '0', 16:31:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:31:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:31:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:31:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:31:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:31:49 INFO - Installing collected packages: mozsystemmonitor 16:31:49 INFO - Running setup.py install for mozsystemmonitor 16:31:49 INFO - Successfully installed mozsystemmonitor 16:31:49 INFO - Cleaning up... 16:31:49 INFO - Return code: 0 16:31:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:31:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:31:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:31:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102552a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025e7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa26169e640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024b6d30>, '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 0x10221bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024aeb20>, '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.LkDfcOQWAm/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.Ac9E8tuFKc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 16:31:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:31:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:31:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LkDfcOQWAm/Render', 16:31:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:31:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:31:49 INFO - 'HOME': '/Users/cltbld', 16:31:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:31:49 INFO - 'LOGNAME': 'cltbld', 16:31:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:49 INFO - 'MOZ_NO_REMOTE': '1', 16:31:49 INFO - 'NO_EM_RESTART': '1', 16:31:49 INFO - 'PAGER': '/bin/cat', 16:31:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:31:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:49 INFO - 'PWD': '/builds/slave/test', 16:31:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:31:49 INFO - 'SHELL': '/bin/bash', 16:31:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ac9E8tuFKc/Listeners', 16:31:49 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 16:31:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:31:49 INFO - 'USER': 'cltbld', 16:31:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:31:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:31:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:49 INFO - 'XPC_FLAGS': '0x0', 16:31:49 INFO - 'XPC_SERVICE_NAME': '0', 16:31:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:31:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:50 INFO - Downloading/unpacking jsonschema==2.5.1 16:31:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:31:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:31:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:52 INFO - Downloading functools32-3.2.3-2.tar.gz 16:31:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:31:52 INFO - warning: no files found matching '*.txt' 16:31:52 INFO - no previously-included directories found matching 'build' 16:31:52 INFO - no previously-included directories found matching 'dist' 16:31:52 INFO - no previously-included directories found matching '.git*' 16:31:52 INFO - Installing collected packages: jsonschema, functools32 16:31:53 INFO - Running setup.py install for functools32 16:31:53 INFO - warning: no files found matching '*.txt' 16:31:53 INFO - no previously-included directories found matching 'build' 16:31:53 INFO - no previously-included directories found matching 'dist' 16:31:53 INFO - no previously-included directories found matching '.git*' 16:31:53 INFO - Successfully installed jsonschema functools32 16:31:53 INFO - Cleaning up... 16:31:53 INFO - Return code: 0 16:31:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:31:53 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')]} 16:31:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:53 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')]} 16:31:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:53 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 0x102552a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025e7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa26169e640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024b6d30>, '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 0x10221bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024aeb20>, '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.LkDfcOQWAm/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.Ac9E8tuFKc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 16:31:53 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 16:31:53 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 16:31:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LkDfcOQWAm/Render', 16:31:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:31:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:31:53 INFO - 'HOME': '/Users/cltbld', 16:31:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:31:53 INFO - 'LOGNAME': 'cltbld', 16:31:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:53 INFO - 'MOZ_NO_REMOTE': '1', 16:31:53 INFO - 'NO_EM_RESTART': '1', 16:31:53 INFO - 'PAGER': '/bin/cat', 16:31:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:31:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:53 INFO - 'PWD': '/builds/slave/test', 16:31:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:31:53 INFO - 'SHELL': '/bin/bash', 16:31:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ac9E8tuFKc/Listeners', 16:31:53 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 16:31:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:31:53 INFO - 'USER': 'cltbld', 16:31:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:31:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:31:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:53 INFO - 'XPC_FLAGS': '0x0', 16:31:53 INFO - 'XPC_SERVICE_NAME': '0', 16:31:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:31:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:53 INFO - Downloading/unpacking blobuploader==1.2.4 16:31:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:55 INFO - Downloading blobuploader-1.2.4.tar.gz 16:31:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:31:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:31:55 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 16:31:55 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 16:31:55 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 16:31:55 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 16:31:55 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 16:31:55 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 16:31:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:31:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:31:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:31:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:31:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:31:57 INFO - Downloading docopt-0.6.1.tar.gz 16:31:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:31:57 INFO - Installing collected packages: blobuploader, requests, docopt 16:31:57 INFO - Running setup.py install for blobuploader 16:31:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:31:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:31:57 INFO - Running setup.py install for requests 16:31:57 INFO - Running setup.py install for docopt 16:31:57 INFO - Successfully installed blobuploader requests docopt 16:31:57 INFO - Cleaning up... 16:31:58 INFO - Return code: 0 16:31:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:31:58 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')]} 16:31:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:58 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')]} 16:31:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:58 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 0x102552a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025e7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa26169e640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024b6d30>, '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 0x10221bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024aeb20>, '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.LkDfcOQWAm/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.Ac9E8tuFKc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 16:31:58 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 16:31:58 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 16:31:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LkDfcOQWAm/Render', 16:31:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:31:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:31:58 INFO - 'HOME': '/Users/cltbld', 16:31:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:31:58 INFO - 'LOGNAME': 'cltbld', 16:31:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:58 INFO - 'MOZ_NO_REMOTE': '1', 16:31:58 INFO - 'NO_EM_RESTART': '1', 16:31:58 INFO - 'PAGER': '/bin/cat', 16:31:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:31:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:58 INFO - 'PWD': '/builds/slave/test', 16:31:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:31:58 INFO - 'SHELL': '/bin/bash', 16:31:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ac9E8tuFKc/Listeners', 16:31:58 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 16:31:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:31:58 INFO - 'USER': 'cltbld', 16:31:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:31:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:31:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:58 INFO - 'XPC_FLAGS': '0x0', 16:31:58 INFO - 'XPC_SERVICE_NAME': '0', 16:31:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:31:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:31:58 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-o2924L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:31:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:31:58 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-PGrxsx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:31:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:31:58 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-wfmhmK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:31:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:31:58 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-FF1rzf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:31:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:31:58 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-Kn2uUi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:31:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:31:58 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-Wz9Tsq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:31:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:31:58 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-9NaFh_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:31:59 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-ZfxIn6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:31:59 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-rSl3ux-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:31:59 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-vpZ_kn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:31:59 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-ahTOCV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:31:59 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-awR1_o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:31:59 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-0jTTmd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:31:59 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-qLaNdK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:31:59 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-MI4fZy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:31:59 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-U6CKPT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:32:00 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-eKX8RQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:32:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:32:00 INFO - Running setup.py install for manifestparser 16:32:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:32:00 INFO - Running setup.py install for mozcrash 16:32:00 INFO - Running setup.py install for mozdebug 16:32:00 INFO - Running setup.py install for mozdevice 16:32:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:32:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:32:00 INFO - Running setup.py install for mozfile 16:32:00 INFO - Running setup.py install for mozhttpd 16:32:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:32:01 INFO - Running setup.py install for mozinfo 16:32:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:32:01 INFO - Running setup.py install for mozInstall 16:32:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:32:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:32:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:32:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:32:01 INFO - Running setup.py install for mozleak 16:32:01 INFO - Running setup.py install for mozlog 16:32:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:32:01 INFO - Running setup.py install for moznetwork 16:32:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:32:01 INFO - Running setup.py install for mozprocess 16:32:01 INFO - Running setup.py install for mozprofile 16:32:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:32:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:32:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:32:02 INFO - Running setup.py install for mozrunner 16:32:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:32:02 INFO - Running setup.py install for mozscreenshot 16:32:02 INFO - Running setup.py install for moztest 16:32:02 INFO - Running setup.py install for mozversion 16:32:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:32:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:32:02 INFO - Cleaning up... 16:32:02 INFO - Return code: 0 16:32:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:32:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:32:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:32:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:02 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 0x102552a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025e7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa26169e640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024b6d30>, '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 0x10221bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024aeb20>, '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.LkDfcOQWAm/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.Ac9E8tuFKc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 16:32:02 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 16:32:02 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 16:32:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LkDfcOQWAm/Render', 16:32:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:32:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:32:02 INFO - 'HOME': '/Users/cltbld', 16:32:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:32:02 INFO - 'LOGNAME': 'cltbld', 16:32:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:02 INFO - 'MOZ_NO_REMOTE': '1', 16:32:02 INFO - 'NO_EM_RESTART': '1', 16:32:02 INFO - 'PAGER': '/bin/cat', 16:32:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:32:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:02 INFO - 'PWD': '/builds/slave/test', 16:32:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:32:02 INFO - 'SHELL': '/bin/bash', 16:32:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ac9E8tuFKc/Listeners', 16:32:02 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 16:32:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:32:02 INFO - 'USER': 'cltbld', 16:32:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:32:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:32:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:02 INFO - 'XPC_FLAGS': '0x0', 16:32:02 INFO - 'XPC_SERVICE_NAME': '0', 16:32:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:32:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:32:02 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-d47n9m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:32:03 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)) 16:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:32:03 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-XdImxp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:32:03 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)) 16:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:32:03 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-8YoGyA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:32:03 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)) 16:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:32:03 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-E00ffJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:32:03 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)) 16:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:32:03 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-mKTMtv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:32:03 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)) 16:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:32:03 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-XpSmpz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:32:03 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)) 16:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:32:03 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-XBM5pH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:32:03 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)) 16:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:32:03 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-hUtZ7E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:32:03 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)) 16:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:32:03 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-gb98D0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:32:03 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)) 16:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:32:03 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-PSBTAQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:32:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 16:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:32:04 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-arTs_K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:32:04 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)) 16:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:32:04 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-zESD0D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:32:04 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)) 16:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:32:04 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-3PCJwz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:32:04 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)) 16:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:32:04 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-XSxT3H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:32:04 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)) 16:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:32:04 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-GZpdOt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:32:04 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)) 16:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:32:04 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-zFWNfx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:32:04 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)) 16:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:32:04 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-kOEliq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:32:04 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)) 16:32:04 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)) 16:32:04 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)) 16:32:04 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)) 16:32:04 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)) 16:32:04 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)) 16:32:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:32:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:07 INFO - Downloading blessings-1.6.tar.gz 16:32:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:32:07 INFO - Installing collected packages: blessings 16:32:07 INFO - Running setup.py install for blessings 16:32:07 INFO - Successfully installed blessings 16:32:07 INFO - Cleaning up... 16:32:07 INFO - Return code: 0 16:32:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:32:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:32:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:32:07 INFO - Reading from file tmpfile_stdout 16:32:07 INFO - Current package versions: 16:32:07 INFO - blessings == 1.6 16:32:07 INFO - blobuploader == 1.2.4 16:32:07 INFO - docopt == 0.6.1 16:32:07 INFO - functools32 == 3.2.3-2 16:32:07 INFO - jsonschema == 2.5.1 16:32:07 INFO - manifestparser == 1.1 16:32:07 INFO - mozInstall == 1.12 16:32:07 INFO - mozcrash == 0.17 16:32:07 INFO - mozdebug == 0.1 16:32:07 INFO - mozdevice == 0.48 16:32:07 INFO - mozfile == 1.2 16:32:07 INFO - mozhttpd == 0.7 16:32:07 INFO - mozinfo == 0.9 16:32:07 INFO - mozleak == 0.1 16:32:07 INFO - mozlog == 3.2 16:32:07 INFO - moznetwork == 0.27 16:32:07 INFO - mozprocess == 0.22 16:32:07 INFO - mozprofile == 0.28 16:32:07 INFO - mozrunner == 6.11 16:32:07 INFO - mozscreenshot == 0.1 16:32:07 INFO - mozsystemmonitor == 0.3 16:32:07 INFO - moztest == 0.7 16:32:07 INFO - mozversion == 1.4 16:32:07 INFO - psutil == 3.1.1 16:32:07 INFO - requests == 1.2.3 16:32:07 INFO - wsgiref == 0.1.2 16:32:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:32:07 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')]} 16:32:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:07 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')]} 16:32:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:07 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 0x102552a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025e7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa26169e640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024b6d30>, '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 0x10221bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024aeb20>, '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.LkDfcOQWAm/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.Ac9E8tuFKc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 16:32:07 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 16:32:07 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 16:32:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LkDfcOQWAm/Render', 16:32:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:32:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:32:07 INFO - 'HOME': '/Users/cltbld', 16:32:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:32:07 INFO - 'LOGNAME': 'cltbld', 16:32:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:07 INFO - 'MOZ_NO_REMOTE': '1', 16:32:07 INFO - 'NO_EM_RESTART': '1', 16:32:07 INFO - 'PAGER': '/bin/cat', 16:32:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:32:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:07 INFO - 'PWD': '/builds/slave/test', 16:32:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:32:07 INFO - 'SHELL': '/bin/bash', 16:32:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ac9E8tuFKc/Listeners', 16:32:07 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 16:32:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:32:07 INFO - 'USER': 'cltbld', 16:32:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:32:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:32:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:07 INFO - 'XPC_FLAGS': '0x0', 16:32:07 INFO - 'XPC_SERVICE_NAME': '0', 16:32:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:32:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:08 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)) 16:32:08 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)) 16:32:08 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)) 16:32:08 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)) 16:32:08 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)) 16:32:08 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)) 16:32:08 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)) 16:32:08 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)) 16:32:08 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)) 16:32:08 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)) 16:32:08 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)) 16:32:08 INFO - Cleaning up... 16:32:08 INFO - Return code: 0 16:32:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:32:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:32:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:32:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102552a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025e7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa26169e640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024b6d30>, '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 0x10221bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024aeb20>, '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.LkDfcOQWAm/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.Ac9E8tuFKc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 16:32:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:32:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:32:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LkDfcOQWAm/Render', 16:32:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:32:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:32:08 INFO - 'HOME': '/Users/cltbld', 16:32:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:32:08 INFO - 'LOGNAME': 'cltbld', 16:32:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:08 INFO - 'MOZ_NO_REMOTE': '1', 16:32:08 INFO - 'NO_EM_RESTART': '1', 16:32:08 INFO - 'PAGER': '/bin/cat', 16:32:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:32:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:08 INFO - 'PWD': '/builds/slave/test', 16:32:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:32:08 INFO - 'SHELL': '/bin/bash', 16:32:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ac9E8tuFKc/Listeners', 16:32:08 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 16:32:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:32:08 INFO - 'USER': 'cltbld', 16:32:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:32:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:32:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:08 INFO - 'XPC_FLAGS': '0x0', 16:32:08 INFO - 'XPC_SERVICE_NAME': '0', 16:32:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:32:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:32:08 INFO - Cleaning up... 16:32:08 INFO - Return code: 0 16:32:08 INFO - Running post-action listener: _resource_record_post_action 16:32:08 INFO - Running post-action listener: _start_resource_monitoring 16:32:08 INFO - Starting resource monitoring. 16:32:08 INFO - ##### 16:32:08 INFO - ##### Finished create-virtualenv step (success) 16:32:08 INFO - ##### 16:32:08 INFO - ##### 16:32:08 INFO - ##### Running install step. 16:32:08 INFO - ##### 16:32:08 INFO - Running pre-action listener: _resource_record_pre_action 16:32:08 INFO - Running main action method: install 16:32:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:32:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:32:08 INFO - Reading from file tmpfile_stdout 16:32:08 INFO - Detecting whether we're running mozinstall >=1.0... 16:32:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:32:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:32:08 INFO - Reading from file tmpfile_stdout 16:32:08 INFO - Output received: 16:32:08 INFO - Usage: mozinstall [options] installer 16:32:08 INFO - Options: 16:32:08 INFO - -h, --help show this help message and exit 16:32:08 INFO - -d DEST, --destination=DEST 16:32:08 INFO - Directory to install application into. [default: 16:32:08 INFO - "/builds/slave/test"] 16:32:08 INFO - --app=APP Application being installed. [default: firefox] 16:32:08 INFO - mkdir: /builds/slave/test/build/application 16:32:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:32:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 16:32:44 INFO - Reading from file tmpfile_stdout 16:32:44 INFO - Output received: 16:32:44 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:32:44 INFO - Running post-action listener: _resource_record_post_action 16:32:44 INFO - ##### 16:32:44 INFO - ##### Finished install step (success) 16:32:44 INFO - ##### 16:32:44 INFO - ##### 16:32:44 INFO - ##### Running run-tests step. 16:32:44 INFO - ##### 16:32:44 INFO - Running pre-action listener: _resource_record_pre_action 16:32:44 INFO - Running main action method: run_tests 16:32:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:32:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:32:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:32:44 INFO - 2016-07-04 16:32:44.239 screenresolution[1647:7513] starting screenresolution argv=screenresolution get 16:32:44 INFO - 2016-07-04 16:32:44.242 screenresolution[1647:7513] Display 0: 1600x1200x32@60 16:32:44 INFO - 2016-07-04 16:32:44.247 screenresolution[1648:7515] starting screenresolution argv=screenresolution list 16:32:44 INFO - Available Modes on Display 0 16:32:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:32:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:32:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:32:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:32:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:32:44 INFO - Intel Iris: 16:32:44 INFO - Chipset Model: Intel Iris 16:32:44 INFO - Type: GPU 16:32:44 INFO - Bus: Built-In 16:32:44 INFO - VRAM (Dynamic, Max): 1536 MB 16:32:44 INFO - Vendor: Intel (0x8086) 16:32:44 INFO - Device ID: 0x0a2e 16:32:44 INFO - Revision ID: 0x0009 16:32:44 INFO - Displays: 16:32:44 INFO - Display: 16:32:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:32:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:32:44 INFO - Display Serial Number: 1600x1200 60 16:32:44 INFO - Main Display: Yes 16:32:44 INFO - Mirror: Off 16:32:44 INFO - Online: Yes 16:32:44 INFO - Rotation: Supported 16:32:44 INFO - Return code: 0 16:32:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:32:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:32:44 INFO - Python 2.7.3 16:32:44 INFO - Return code: 0 16:32:44 INFO - grabbing minidump binary from tooltool 16:32:44 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')]} 16:32:44 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024b6d30>, '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 0x10221bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024aeb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:32:44 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 16:32:44 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 16:32:44 INFO - Calling ['/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'] with output_timeout 600 16:32:44 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:32:44 INFO - Return code: 0 16:32:44 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:32:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:32:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:32:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:32:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:32:44 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/dep_SHRERLeo_YIjuMtGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0066', '--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 16:32:44 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/dep_SHRERLeo_YIjuMtGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0066 --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 16:32:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LkDfcOQWAm/Render', 16:32:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:32:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:32:44 INFO - 'HOME': '/Users/cltbld', 16:32:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:32:44 INFO - 'LOGNAME': 'cltbld', 16:32:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:32:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:32:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:44 INFO - 'MOZ_NO_REMOTE': '1', 16:32:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:32:44 INFO - 'NO_EM_RESTART': '1', 16:32:44 INFO - 'PAGER': '/bin/cat', 16:32:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:32:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:44 INFO - 'PWD': '/builds/slave/test', 16:32:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:32:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:32:44 INFO - 'SHELL': '/bin/bash', 16:32:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ac9E8tuFKc/Listeners', 16:32:44 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 16:32:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:32:44 INFO - 'USER': 'cltbld', 16:32:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:32:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:32:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:44 INFO - 'XPC_FLAGS': '0x0', 16:32:44 INFO - 'XPC_SERVICE_NAME': '0', 16:32:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:32:44 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/dep_SHRERLeo_YIjuMtGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0066', '--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 16:32:44 INFO - mozversion application_buildid: 20160704150823 16:32:44 INFO - mozversion application_changeset: 162b6fca7d868e8165d0f6ef80e02b5dd73161cb 16:32:44 INFO - mozversion application_display_name: Nightly 16:32:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:32:44 INFO - mozversion application_name: Firefox 16:32:44 INFO - mozversion application_remotingname: firefox 16:32:44 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 16:32:44 INFO - mozversion application_vendor: Mozilla 16:32:44 INFO - mozversion application_version: 50.0a1 16:32:44 INFO - mozversion platform_buildid: 20160704150823 16:32:44 INFO - mozversion platform_changeset: 162b6fca7d868e8165d0f6ef80e02b5dd73161cb 16:32:44 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 16:32:44 INFO - mozversion platform_version: 50.0a1 16:32:44 INFO - using testdate: 1467675164 16:32:44 INFO - actual date: 1467675164 16:32:44 INFO - starting webserver on 'localhost:49277' 16:32:44 INFO - SUITE-START | Running 2 tests 16:32:44 INFO - TEST-START | dromaeo_css 16:32:44 INFO - operating with platform_type : mac_ 16:32:44 INFO - Initialising browser for dromaeo_css test... 16:32:44 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/tmp0JVYUn/profile http://localhost:49277/getInfo.html) 16:32:47 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 16:32:47 INFO - PROCESS | 1658 | colorDepth:24 16:32:47 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 16:32:47 INFO - PROCESS | 1658 | __metrics 16:32:47 INFO - PROCESS | 1658 | 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:32:47 INFO - PROCESS | 1658 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:32:47 INFO - TEST-INFO | 1658: exit 0 16:32:47 INFO - Browser initialized. 16:32:47 INFO - Running cycle 1/1 for dromaeo_css test... 16:32:47 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/tmp0JVYUn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:32:59 INFO - PROCESS | 1660 | RSS: Main: 243630080 16:32:59 INFO - PROCESS | 1660 | 16:33:51 INFO - PROCESS | 1660 | 1467675231408 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] 16:36:01 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 16:36:03 INFO - PROCESS | 1660 | RSS: Main: 357474304 16:36:03 INFO - PROCESS | 1660 | 16:39:06 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 16:39:07 INFO - PROCESS | 1660 | RSS: Main: 375771136 16:39:07 INFO - PROCESS | 1660 | 16:42:09 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 16:42:09 INFO - PROCESS | 1660 | RSS: Main: 370040832 16:42:09 INFO - PROCESS | 1660 | 16:45:12 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 16:45:12 INFO - PROCESS | 1660 | RSS: Main: 370606080 16:45:12 INFO - PROCESS | 1660 | 16:48:16 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 16:48:16 INFO - PROCESS | 1660 | RSS: Main: 338067456 16:48:16 INFO - PROCESS | 1660 | 16:51:19 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 16:51:20 INFO - PROCESS | 1660 | RSS: Main: 409600000 16:51:20 INFO - PROCESS | 1660 | 16:51:20 INFO - PROCESS | 1660 | __start_tp_report 16:51:20 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 16:51:20 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 16:51:20 INFO - PROCESS | 1660 | |i|pagename|runs| 16:51:20 INFO - PROCESS | 1660 | |0;dojo.html;2867.5555288930213;3382.2220889195482;3387.2209391839874;3410.164509695125;3437.5359326490934;5689.687067211312;5693.231413759141;5758.798442054518;5761.596688231825;5785.595008349423;5744.281964754406;5755.769769209227;5797.1594118852745;5840.941590584103;5896.882062358761;4449.998750281193;5070.695758254498;5143.53708166265;5147.562457191143;5307.283516725251;5271.551918086967;5313.814016509423;5331.760070796823;5347.0909945309395;5368.651037682557;5130.486951304877;5192.454792246797;5205.505476979681;5235.2670626112185;5252.36971563411;5140.640155189139;5198.896022079575;5201.713905735183;5202.505761952609;5241.318628578299;5172.27588137661;5183.196604526304;5187.766550505236;5205.27126202332;5275.604329675251;5819.592628516006;5833.06670932653;5884.352721200665;5886.6173698709445;5889.381614930457;5061.5950723942;6109.920916985209;6172.105044768484;6245.375462453764;6264.185655864754;6203.751849925998;6280.528960327993;6312.463440607554;6315.715792789336;6324.114623952651;5947.375525569796;5974.193483879664;5981.790637327673;5982.820515384458;5992.041273396236;6148.907766383508;6187.195664563578;6200.720967556471;6240.812775616739;6259.967105427644;6226.501879849601;6245.656488893063;6251.4061164189325;6251.749930002749;6255.968720156461;6288.622682639057;6295.874082518416;6300.621962682254;6300.653464059521;6310.4636105930595;6092.296407170863;6173.660448675366;6229.626222426669;6249.531285153632;6252.093446450239;6286.402791734778;6293.905591416133;6296.653684047421;6299.874002519924;6329.208848893888;4945.480724523945;4989.12690279207;5024.974875125601;5032.7735251913755;5040.722760248222;4474.507804141542;4481.843135490308;4483.036147228361;4514.32285157229;4541.772911354419;5770.740316685759;5784.566157538202;5791.391903850119;5807.854803629859;5854.414558544197;5209.973950130225;6165;6188.907166392508;6309.968450157719;6334.3032266450655;5712.514436272881;5762.855928601818;5777.277840269966;5790.565707571865;5821.18503409523;5452.6183167177915;5462.754176062087;5463;5477.20580515823;5485.890282194414;5466.1527463243265;6175.536834737502;6279.560430769802;6299.338569450233;6304.338044505261;5709.857253568694;6223.96888015557;6306.9684651576445;6309.432151106423;6326.43062124411;5069.949300506948;6154.476869465966;6287.528435367274;6320.1151838743535;6335.524835637253;6122;6183.752649893954;6237.407446292595;6260.655663938527;6271.027990661455;6242.219722534684;6291.937080629135;6307.180066591313;6307.621542707452;6331.0819931110655;4921.26181072842;5940.79207227745;6139.969300153471;6154.353792851686;6158.261008678808;5101.464346243591;5995.790147344822;6086.087086936995;6175.3515880831865;6180.505559555135;5938.495227905503;6041.426064523863;6061.748482082497;6083.117947897442;6101.389861013863;5122.359705036871;6137.570370074052;6190.566660333733;6219.968900155471;6222.81331560054;5805.071188609802;5989.460948514655;6068.757249709962;6086.421789929949;6099.085137229451 16:51:20 INFO - PROCESS | 1660 | |1;ext.html;470.9105269998702;483.37885816725515;518.5747686896743;535.9758810853514;558.8267637032519;29412.41175176498;34222.14444638882;41019.79490102549;42140.367894481606;42224.78887605558;43836.561634383725;44021.55978440215;44100.33849492252;44163.11673766523;44243.77878110613;8902.910970890305;9934.254930880159;10094.041066098745;10104.44425556594;10121.291509594332;8859.20267175954;10190;12129.150959432844;12335.889769920705;12557.309347985856;12278.631641050782;12314.076444266671;12335;12337.876621233807;12362;8843.159899809507;12176.452059657247;12210.450529726184;12239.75520489594;12335.25988440692;11902.166848320621;12360.567380141742;12366.319852408114;12366.44351004198;12384.504619815196;42250;42663;42667;42670;42672.57327426748;3541.964580354215;3555.6444355564495;3584.946225806615;3689.24370504046;4067.593240675909;32580.837095814608;32863.84976525834;33042.17394565131;33183.668163318296;33225.00324990254;3212.16483714236;3227.757918156124;3253.4794432890862;3260.233845046412;3373.207296285371;540860;541410;541736;544060;544129.2793536047;62033.68983155055;62593;62628.61599687978;62653;62714.37285627086;35600.821995889855;35916.82041589828;35941.64058359435;37135.25729485395;37211.4418283726;16356.345746170258;16468.752968705478;16619.3352265908;16677.916610417113;16816.411425600047;11783.88216117845;11995.64013079609;12001.1599188056;12796.232226066466;12948.805767913489;14757.852421475647;16891.81757276979;18307.502477144855;20168.991550422714;20237.898810505852;7920.900961245275;7929.762107136795;7945.404094692921;7949.284564389232;7973.481723687942;864.9351298652626;958.2334132693817;1071.844582535528;1118.703543560957;1156.4217891054473;25920.61119083215;31362.372752544823;31428.214294642818;31525.52711709326;32210.516842247383;1045.3309881675734;1059.989400105989;1078.5470102556944;1144.0733006264952;1154.7632735289415;16515.256813443422;16608.335666573203;16645.83354166467;16708.832911670972;17014.234359453854;714787;1039284;1050214;1053854;1054168;62960;63711.68144159342;63933.36066639367;64058;64058.6797066021;1261.5647601577625;1308.4700696218047;1409.6264489910182;1436.174199835078;1449.355037008521;30912.227194320323;31631.52552711757;32428.513572295964;32724.672753272163;32784.50823237701;58276.08619569089;58423;59250.703746482715;59252;59293.40706592879;657852;662986;664814;664840;665748;23539.17612883541;24708.876455617607;27204;27528.862355688092;27609.309767255178;48179;48251.75874120607;48855;49254.26118608152;49438;39355.80322098371;40263.79868100641;40646.983825404604;41180.38229426501;41212;37897.81051094727;39633.60366396299;39864.202715944935;40114;40935.1812963745;38628.6137138625;39160.80419597884;39342;39368.80315598404;39471.81584552468;28579;33467.497987530696;34399.48400774041;35317.29365412729;35522.82238588791;25408.618870716586;25690.743092568835;25771.098011569506;25789.613155803054;26340.604890926268 16:51:20 INFO - PROCESS | 1660 | |2;jquery.html;354.3444627439236;377.5450582048631;382.60782697734817;386.08690447092624;404.10087555189705;15637.687246255067;16361.509154725363;20510.89744551279;21364.038618262177;21407.464813379655;19966.800331996677;20809.479763005933;21138.788612113873;21160;21196.89401552994;5580.637258578179;5674.716264186794;5881.67650779207;5933.080372542263;5937.7624895004365;5721.456461636138;5728.77084916605;5788.47903688668;5796.884062318751;5803.767849286045;5509.228707980866;5750.424957504258;5761.625494342875;5781;5787;5724.34170070441;5752.395998420168;5782.913256301159;5790.623609465392;5793.478586927196;4837.0809546186465;5068.138416469209;5071;5072.112380333427;5078.289039534468;22214.88925553732;22217.22239721602;24168.274951751475;24923.626145607832;24929.376765580822;1825.9360922367653;2265.864048157099;2289.564982653307;2309.2841219222096;2928.740641524144;10123.34198277117;10566.366018038942;10589.364638121737;12417.565385211565;15244.618884527865;1703.2080082761574;1794.856411487091;1861.8138186181409;1908;1929.8938558379284;122778;125506;129568;135151;135644.32177839146;11504.252223605607;11542.210957301631;11553.826692599618;11579.710507237387;11730.237534560227;14511.927440362731;14567.490137845125;14588.635284117981;14595;14625.78061329081;8619.482831030158;8631.482111073356;8712.085231050649;8731.476111433334;8744.256738177326;7387.928750331172;8329.583520823935;8668.393212475066;8671;8786.209241168326;4556.066006468666;4564.680472366902;4576.199165146086;4585.449746030497;4608.239640459354;1876.2870109358357;1919.7696276446916;1923.422973108062;1923.9326623568388;1924.7209154672494;851.2381418630415;913.2785099771239;940.9670661526814;957.0572985609169;1023.6059117239917;18639.440816775517;21323.57352852934;21371.67942480864;21378.251761188283;21393.358199254046;918.9586468608928;964.2189826240767;964.6864768950119;986.3687240166299;1056.9524371403306;9721.854172187423;10118.443485608363;11057.778844423066;11174.776504470028;11282.548698051985;613676;620893;627474;632295;636309;90061;102888.88444462288;137594.93607595895;142527.28736356253;150743;976.72651657536;985.6353149334792;1010.7624708193715;1018.674024312213;1020.5152552537634;18635.90682046581;19031.143598537823;19032.714509282094;19250.71123933114;19315.90342048337;44528.32452592561;54791.178132328845;58263.4173658258;58283;59389;74254;77497.83753243594;85391.57304213439;86002.27995440182;86727;7736.7292144774665;7923.326517246099;8083.636236369495;8121.79695507617;8231.218034286732;12287.815682764587;12882.162659427107;13278.601641950758;13429.395677194745;13461.528846490326;11168.106551476025;11323.377214253294;11352.659420217407;11372.545098196311;11586.652400428;10473.266871318934;10976.780464390828;11099.223054386115;11184;11425.2002359834;11282.210245282751;11361.375124367909;11443.198976071595;11540.711482213012;11626.476808543466;6764.187363595056;8130.552819594979;8345.706415505607;8356.456830306011;8496.150384961455;8269.83460330778;10253;11140.665780026613;11287.266327688674;11327.773444531229 16:51:20 INFO - PROCESS | 1660 | |3;mootools.html;1581.533447632948;1594.1311984968193;1676.1702957036268;1933.9226430942763;1956.9804301956985;8528.104549022357;8878.15657512537;9029.232515236205;9032.954835225832;9049.230815380692;7820.491668041574;8044.798880028002;8543.444676096049;8763.474191548512;8829.381943263974;2002.2391491233311;2206.8013878750908;2305.939267936751;2384.7853693167613;2384.8688322142275;2354.1407386303963;2369.862466016313;2394.904203831845;2395.64065390192;2401.411654144737;2088.1542975095113;2158.676198570219;2306.169778879602;2368;2370.9644355334685;2333.381653861728;2338.368640467073;2364.2198074635417;2370.656254843055;2375.655529948165;2329.23135365329;2344.2498400511845;2349.447879748258;2361.8346715729904;2373.0389192377115;8747.08155643661;8836.646534138627;8837.823243535158;8839;8843.20411162992;5199.272101905736;5365.973170134163;5367.570594352482;5368.865778355534;5374.865628359284;3559.555055618048;3570.9107272318142;3629.5825980012373;3646.908827279313;3647.1793846384617;1055.6252530351692;1059.8834128245887;1067.7010437077631;1078.6170909327154;1078.95684172633;11462.254953428;11473.196876218668;11478.368689722061;11494.31034137946;11518.136139789549;4513.75174365413;4580.450345958507;4750.809967601257;4802.807887684454;4806.182948898626;9762.804743905224;9777.120059194707;9785.804283914282;9789.167920726675;9796.9020309796;5330.987112448623;5331.093714068617;5335.119705248668;5337.839864804062;5338.3060202173465;4721.5278472153195;4868.14807408702;4882.194437917704;4885.145099607555;4915.3855768029;3790.279846829149;3811.561670407883;3843.5964223756664;3867.361885288952;3901.51231096113;1096.736783172033;1118.8042092633757;1168.1122347016328;1168.2639936839737;1177.3701069927547;731.3344856180956;855.6705668317618;895.7357579514058;916.4730280088976;948.3503799897093;5668.433156684298;5725.736903236744;5834.533237341003;5896.469317761336;6210.658413787285;850.5492089192614;857.3355649371662;928.8095940332215;965.9517024148764;998.9350692204983;4002.9799851000557;4152.1072969311745;4197.202531519001;4214.325707886724;4261.850835220753;10311.1235544978;10645.680629581126;10721.678349649523;10729.03438690506;10730.892691073146;8387.664493420194;10323.74190645225;10558.894411055944;10743.731406714895;10787.514561844735;7555.16893141754;7809.789482630202;7914.445988780845;8294.294984926228;8310.418270721113;5252.632315737862;5932.762689492372;6206.751729930752;6238.6880655967925;6289.371062893674;9589.808203836024;10023.997600240209;10041.698749037541;10046;10061.094501494608;9827;10146.98530147009;10156.644517441853;10186.74533136648;10197.13324367437;6113.449789518964;7023.332783385775;7070.858582828418;7166.605836679033;7167.139943206848;8150.551719655476;8246.587670616565;8251.463654862415;8259.463134896212;8277.04813946404;6747.122874026345;7334.523255988343;7521.5111017783665;7643.770686879402;7666.080070391366;7264.636768161465;7430.925690743024;7485.5508669480005;7558.281963213486;7603.88594171099;6825.726970921108;7070.646467676493;7075.221725610281;7173.856522869618;7186.281371862772;5060.114479966065;5081.669691470191;5126.4617215191865;5132.743362831919;5236.528712415901;5127.948720512747;5165.845024649267;5171.81898633546;5219.869503262297;5350.652207606493 16:51:20 INFO - PROCESS | 1660 | |4;prototype.html;301.4633951566213;305.28715449425596;314.214463840399;318.13784643615827;319.40112289457267;4494.730316181031;5341.305630268064;5412.675239485634;5447;5513.814529876081;4272.679549033819;5050.318207042057;5220.451852555484;5292.253556326413;5392.245085688007;3347.564816573842;3653.6346365363515;3942.5860284670125;3984.322665146932;3999.8000099994956;3616.3129005489004;3678.4850120983083;4052.2705912935658;4092.5088989321325;4115.567865374138;3742.981285093571;3937.8818635440985;4040.6363427291685;4042.9797851010703;4102.630763231308;3432.1419645088727;3820.9110403535137;4078.755274683499;4091.7749523776033;4111.8560850370095;3081;3406.880759173417;3495.5281037059926;3509.7540373167412;3520.3487354839435;5235.869103272411;5243.292155558989;5251.872991438474;5268.288781014552;5302.125149350352;3886.980565097185;4681.765911704435;4704.0356726870905;4704.3299628631685;4719.433667959833;5475.260839786618;5577.330720313588;5713.371529131792;5769.365369809318;5776;3941.2708648900043;3947.1908258806875;3957.544882338539;3964.1278918637854;3974.5230572331507;4661.97204216167;6021.578489505737;6031.035034394476;6073.392660733935;6084.726187321581;3866.845326186977;4035.3745169498775;4035.535913369941;4049.331860243086;4070.715049946475;3394.609619893744;4126.979365103215;4131.607497287753;4148.771817550003;4151.190517849051;4749.9362910922955;4750.501197374295;4771.665983381199;4780.163471392492;4786.305985632064;5556.611037227355;5757;5759.337676167211;5809;5809.82570522885;1213.8604060532975;1303.5242136620066;1368.50733735857;1392.3525560614362;1398.3148257353823;813.3086876155221;895.3866601378077;920.3603495570514;920.3847248079577;922.727795300388;4645.581897629162;4693.6949098308505;5174.379074511083;5339.626226164209;5412.377576578744;5346.91979620306;5769.8557536061935;5836.88326233473;5845.7369418376275;5960.135780311831;4063.5733248008532;4832.734199618984;5407.83776486706;5423.403425623178;5459.884928879601;2654.0976068136924;2770.252031951402;2866.4553734790325;3077.8768849246226;3089.8609562569736;4781.880452988703;5684.54523638108;5943;6058.485378655336;6074.9392506075255;4928.926066109011;5024.371953505812;5627.020087222755;5945.851353716105;6001.699915004232;5569.303837020372;5630.873825234888;6033.909491357633;6089.751600921801;6111.463742546794;5151.819686311111;5243.820140468364;5287.206918962186;5410.350757908918;5501.587380946365;5770.6249093808765;5805.593608447537;5822;5833.358330583717;5845.883082338244;5176.50823171798;5860.794872179454;6130.295016073209;6134.509239260938;6207.720652570555;3580.818329951162;3736.9065773355883;3908.726389152732;4016.6184212499766;4041.8181181847476;4893.559579637849;4944.75276236194;5302;5957.0044035248675;5981.6840295135;5493.23094766725;5557.499825015768;5697.116946873241;5727.255456790591;5770.422957704196;4759.452662943806;5100.209467532539;5623.803166889139;5626.2967129108865;5651.350094739158;5279.762410691451;5455.1544510600925;5491.533219676213;5538.978272930432;5659.292588426447;4967.7516124192925;5676.233708449472;5680.886382272249;5696.914546281892;5713.971430142823;4007.6192761687594;4978.054169707744;5207.557357624644;5341.866453338697;5546.362168350692 16:51:20 INFO - PROCESS | 1660 | |5;yui.html;722.429280868114;731.104397113536;737.513241260768;772.1043589436255;783.627776806017;14376.065555738884;14775.556733298004;14810.481633142843;14811.185384803832;14874.405023799036;15540;16247.943883647555;16256.674866502663;16259.756103658454;16335;707.1090426063172;868.3791089371099;886.3441053620306;893.1693525021727;895.7447127568644;722.3859719238658;860.2129051917487;884.6107712606432;885.0883589902411;890.995545022274;933.355984370782;938.2353381993687;939.4551160326994;939.5067589515525;942.2603256443693;737.4432303610739;757.9242075792432;931.7344556801347;932.8787257656529;935.4948327902924;564.0580231014216;564.5991346144242;683.9145106861642;701.515953991744;702.420503084957;15258.313375898111;15307.617309567238;15312.310946007456;15326.770098448533;15414.22928853564;13843.653908652264;14030.859691403057;14052.578422647337;14088;14194.21931793751;13061.412236449383;13086;13270.336483175803;13398.397072131778;13494.797578036338;4650.697704649187;4663.370444989917;4710.034442939189;4855.732934688591;4893.070316639827;116078.83921160764;117965;119565.40217298857;119980.40009799982;120098.19852702216;10989.340639561651;11101.611443599435;11181.049610783175;11207.439628018567;11223.494942727597;21825.890870545863;21931.01310441034;21936.561268774534;22017.559648806935;22157.66763498549;7601.011868457065;7730.574818385043;7779.416543759241;7827.334676552443;8064.354851611857;4304.677149213759;4340.891477713019;4342.978285108554;4349.499807522176;4523.344115103291;2855.528837741791;3580.1765593913547;3581.7492775505984;3587.4260118380935;3588.138846676831;659.528437167418;674.20780582815;680.9080774095532;683.2620769568854;695.4644923409048;537.4330081763777;556.3185098254687;557.3005877623638;582.4845012164266;634.1502386801708;7842.882356764653;8751.343649226334;9011.324150688724;9082;9222.26248206919;605.8455093951103;617.6572002538634;618.2303032724287;628.8742251549617;641.7978336823885;4387.407699960529;4422.778861056934;4431.82272709088;4483.409388199342;4487.932681009787;99346;127886;133829;152866.7069993951;156316.43683563246;69889.3011069893;109501;114079;131156.3442182783;132804;104628.95371046344;110070;110904.4454777721;116632.41683791357;116679.8332016669;9722.173615242784;10004.549795259376;10023.348482348916;10050.547725352231;10070.44612546317;41812.96775547405;55148.448515515935;55912;56510.717446412506;56752;87252;87304.69042964233;89607.55196224237;89897.55051224964;90253.54873225592;13095.738085238052;13644.386002630104;13873;14161.433542658591;14398.496052638107;14669.92665036668;15361.462348818184;15467.690646187239;15511.844881551491;15764.527064188092;14204.289785510477;14302.785458217926;14341;14654.340554674853;15103.169325686753;11634.301941883514;14417.207053612152;14686.825053995868;14885.106893586419;14975.850241497446;13364.398602063126;13628.65928351799;13692.589222323346;14206.039040869693;14312.276948607143;11420.820414977594;11832.408379580815;11857.110716696456;11863.228890122115;11927.10546708983;10808.621698240524;11184.440777960906;11360.886391135982;11649.767004660029;11801.232919860182 16:51:20 INFO - PROCESS | 1660 | __end_tp_report 16:51:20 INFO - PROCESS | 1660 | __start_cc_report 16:51:20 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,1155 16:51:20 INFO - PROCESS | 1660 | __end_cc_report 16:51:20 INFO - PROCESS | 1660 | __startTimestamp1467676280169__endTimestamp 16:51:20 INFO - PROCESS | 1660 | 16:51:20 INFO - PROCESS | 1660 | ------- Summary: start ------- 16:51:20 INFO - PROCESS | 1660 | Number of tests: 6 16:51:20 INFO - PROCESS | 1660 | 16:51:20 INFO - PROCESS | 1660 | [#0] dojo.html Cycles:165 Average:5738.38 Median:5974.19 stddev:659.35 (11.0%) stddev-sans-first:621.72 16:51:20 INFO - PROCESS | 1660 | Values: 2867.6 3382.2 3387.2 3410.2 3437.5 5689.7 5693.2 5758.8 5761.6 5785.6 5744.3 5755.8 5797.2 5840.9 5896.9 4450.0 5070.7 5143.5 5147.6 5307.3 5271.6 5313.8 5331.8 5347.1 5368.7 5130.5 5192.5 5205.5 5235.3 5252.4 5140.6 5198.9 5201.7 5202.5 5241.3 5172.3 5183.2 5187.8 5205.3 5275.6 5819.6 5833.1 5884.4 5886.6 5889.4 5061.6 6109.9 6172.1 6245.4 6264.2 6203.8 6280.5 6312.5 6315.7 6324.1 5947.4 5974.2 5981.8 5982.8 5992.0 6148.9 6187.2 6200.7 6240.8 6260.0 6226.5 6245.7 6251.4 6251.7 6256.0 6288.6 6295.9 6300.6 6300.7 6310.5 6092.3 6173.7 6229.6 6249.5 6252.1 6286.4 6293.9 6296.7 6299.9 6329.2 4945.5 4989.1 5025.0 5032.8 5040.7 4474.5 4481.8 4483.0 4514.3 4541.8 5770.7 5784.6 5791.4 5807.9 5854.4 5210.0 6165.0 6188.9 6310.0 6334.3 5712.5 5762.9 5777.3 5790.6 5821.2 5452.6 5462.8 5463.0 5477.2 5485.9 5466.2 6175.5 6279.6 6299.3 6304.3 5709.9 6224.0 6307.0 6309.4 6326.4 5069.9 6154.5 6287.5 6320.1 6335.5 6122.0 6183.8 6237.4 6260.7 6271.0 6242.2 6291.9 6307.2 6307.6 6331.1 4921.3 5940.8 6140.0 6154.4 6158.3 5101.5 5995.8 6086.1 6175.4 6180.5 5938.5 6041.4 6061.7 6083.1 6101.4 5122.4 6137.6 6190.6 6220.0 6222.8 5805.1 5989.5 6068.8 6086.4 6099.1 16:51:20 INFO - PROCESS | 1660 | 16:51:20 INFO - PROCESS | 1660 | [#1] ext.html Cycles:180 Average:83974.35 Median:18797.68 stddev:204413.15 (1087.4%) stddev-sans-first:204890.43 16:51:20 INFO - PROCESS | 1660 | Values: 470.9 483.4 518.6 536.0 558.8 29412.4 34222.1 41019.8 42140.4 42224.8 43836.6 44021.6 44100.3 44163.1 44243.8 8902.9 9934.3 10094.0 10104.4 10121.3 8859.2 10190.0 12129.2 12335.9 12557.3 12278.6 12314.1 12335.0 12337.9 12362.0 8843.2 12176.5 12210.5 12239.8 12335.3 11902.2 12360.6 12366.3 12366.4 12384.5 42250.0 42663.0 42667.0 42670.0 42672.6 3542.0 3555.6 3584.9 3689.2 4067.6 32580.8 32863.8 33042.2 33183.7 33225.0 3212.2 3227.8 3253.5 3260.2 3373.2 540860.0 541410.0 541736.0 544060.0 544129.3 62033.7 62593.0 62628.6 62653.0 62714.4 35600.8 35916.8 35941.6 37135.3 37211.4 16356.3 16468.8 16619.3 16677.9 16816.4 11783.9 11995.6 12001.2 12796.2 12948.8 14757.9 16891.8 18307.5 20169.0 20237.9 7920.9 7929.8 7945.4 7949.3 7973.5 864.9 958.2 1071.8 1118.7 1156.4 25920.6 31362.4 31428.2 31525.5 32210.5 1045.3 1060.0 1078.5 1144.1 1154.8 16515.3 16608.3 16645.8 16708.8 17014.2 714787.0 1039284.0 1050214.0 1053854.0 1054168.0 62960.0 63711.7 63933.4 64058.0 64058.7 1261.6 1308.5 1409.6 1436.2 1449.4 30912.2 31631.5 32428.5 32724.7 32784.5 58276.1 58423.0 59250.7 59252.0 59293.4 657852.0 662986.0 664814.0 664840.0 665748.0 23539.2 24708.9 27204.0 27528.9 27609.3 48179.0 48251.8 48855.0 49254.3 49438.0 39355.8 40263.8 40647.0 41180.4 41212.0 37897.8 39633.6 39864.2 40114.0 40935.2 38628.6 39160.8 39342.0 39368.8 39471.8 28579.0 33467.5 34399.5 35317.3 35522.8 25408.6 25690.7 25771.1 25789.6 26340.6 16:51:20 INFO - PROCESS | 1660 | 16:51:20 INFO - PROCESS | 1660 | [#2] jquery.html Cycles:180 Average:36173.31 Median:2277.71 stddev:104600.15 (4592.3%) stddev-sans-first:104859.00 16:51:20 INFO - PROCESS | 1660 | Values: 354.3 377.5 382.6 386.1 404.1 15637.7 16361.5 20510.9 21364.0 21407.5 19966.8 20809.5 21138.8 21160.0 21196.9 5580.6 5674.7 5881.7 5933.1 5937.8 5721.5 5728.8 5788.5 5796.9 5803.8 5509.2 5750.4 5761.6 5781.0 5787.0 5724.3 5752.4 5782.9 5790.6 5793.5 4837.1 5068.1 5071.0 5072.1 5078.3 22214.9 22217.2 24168.3 24923.6 24929.4 1825.9 2265.9 2289.6 2309.3 2928.7 10123.3 10566.4 10589.4 12417.6 15244.6 1703.2 1794.9 1861.8 1908.0 1929.9 122778.0 125506.0 129568.0 135151.0 135644.3 11504.3 11542.2 11553.8 11579.7 11730.2 14511.9 14567.5 14588.6 14595.0 14625.8 8619.5 8631.5 8712.1 8731.5 8744.3 7387.9 8329.6 8668.4 8671.0 8786.2 4556.1 4564.7 4576.2 4585.4 4608.2 1876.3 1919.8 1923.4 1923.9 1924.7 851.2 913.3 941.0 957.1 1023.6 18639.4 21323.6 21371.7 21378.3 21393.4 919.0 964.2 964.7 986.4 1057.0 9721.9 10118.4 11057.8 11174.8 11282.5 613676.0 620893.0 627474.0 632295.0 636309.0 90061.0 102888.9 137594.9 142527.3 150743.0 976.7 985.6 1010.8 1018.7 1020.5 18635.9 19031.1 19032.7 19250.7 19315.9 44528.3 54791.2 58263.4 58283.0 59389.0 74254.0 77497.8 85391.6 86002.3 86727.0 7736.7 7923.3 8083.6 8121.8 8231.2 12287.8 12882.2 13278.6 13429.4 13461.5 11168.1 11323.4 11352.7 11372.5 11586.7 10473.3 10976.8 11099.2 11184.0 11425.2 11282.2 11361.4 11443.2 11540.7 11626.5 6764.2 8130.6 8345.7 8356.5 8496.2 8269.8 10253.0 11140.7 11287.3 11327.8 16:51:20 INFO - PROCESS | 1660 | 16:51:20 INFO - PROCESS | 1660 | [#3] mootools.html Cycles:180 Average:5684.86 Median:5130.35 stddev:3152.03 (61.4%) stddev-sans-first:3145.79 16:51:20 INFO - PROCESS | 1660 | Values: 1581.5 1594.1 1676.2 1933.9 1957.0 8528.1 8878.2 9029.2 9033.0 9049.2 7820.5 8044.8 8543.4 8763.5 8829.4 2002.2 2206.8 2305.9 2384.8 2384.9 2354.1 2369.9 2394.9 2395.6 2401.4 2088.2 2158.7 2306.2 2368.0 2371.0 2333.4 2338.4 2364.2 2370.7 2375.7 2329.2 2344.2 2349.4 2361.8 2373.0 8747.1 8836.6 8837.8 8839.0 8843.2 5199.3 5366.0 5367.6 5368.9 5374.9 3559.6 3570.9 3629.6 3646.9 3647.2 1055.6 1059.9 1067.7 1078.6 1079.0 11462.3 11473.2 11478.4 11494.3 11518.1 4513.8 4580.5 4750.8 4802.8 4806.2 9762.8 9777.1 9785.8 9789.2 9796.9 5331.0 5331.1 5335.1 5337.8 5338.3 4721.5 4868.1 4882.2 4885.1 4915.4 3790.3 3811.6 3843.6 3867.4 3901.5 1096.7 1118.8 1168.1 1168.3 1177.4 731.3 855.7 895.7 916.5 948.4 5668.4 5725.7 5834.5 5896.5 6210.7 850.5 857.3 928.8 966.0 998.9 4003.0 4152.1 4197.2 4214.3 4261.9 10311.1 10645.7 10721.7 10729.0 10730.9 8387.7 10323.7 10558.9 10743.7 10787.5 7555.2 7809.8 7914.4 8294.3 8310.4 5252.6 5932.8 6206.8 6238.7 6289.4 9589.8 10024.0 10041.7 10046.0 10061.1 9827.0 10147.0 10156.6 10186.7 10197.1 6113.4 7023.3 7070.9 7166.6 7167.1 8150.6 8246.6 8251.5 8259.5 8277.0 6747.1 7334.5 7521.5 7643.8 7666.1 7264.6 7430.9 7485.6 7558.3 7603.9 6825.7 7070.6 7075.2 7173.9 7186.3 5060.1 5081.7 5126.5 5132.7 5236.5 5127.9 5165.8 5171.8 5219.9 5350.7 16:51:20 INFO - PROCESS | 1660 | 16:51:20 INFO - PROCESS | 1660 | [#4] prototype.html Cycles:180 Average:4575.13 Median:5192.03 stddev:1417.82 (27.3%) stddev-sans-first:1385.03 16:51:20 INFO - PROCESS | 1660 | Values: 301.5 305.3 314.2 318.1 319.4 4494.7 5341.3 5412.7 5447.0 5513.8 4272.7 5050.3 5220.5 5292.3 5392.2 3347.6 3653.6 3942.6 3984.3 3999.8 3616.3 3678.5 4052.3 4092.5 4115.6 3743.0 3937.9 4040.6 4043.0 4102.6 3432.1 3820.9 4078.8 4091.8 4111.9 3081.0 3406.9 3495.5 3509.8 3520.3 5235.9 5243.3 5251.9 5268.3 5302.1 3887.0 4681.8 4704.0 4704.3 4719.4 5475.3 5577.3 5713.4 5769.4 5776.0 3941.3 3947.2 3957.5 3964.1 3974.5 4662.0 6021.6 6031.0 6073.4 6084.7 3866.8 4035.4 4035.5 4049.3 4070.7 3394.6 4127.0 4131.6 4148.8 4151.2 4749.9 4750.5 4771.7 4780.2 4786.3 5556.6 5757.0 5759.3 5809.0 5809.8 1213.9 1303.5 1368.5 1392.4 1398.3 813.3 895.4 920.4 920.4 922.7 4645.6 4693.7 5174.4 5339.6 5412.4 5346.9 5769.9 5836.9 5845.7 5960.1 4063.6 4832.7 5407.8 5423.4 5459.9 2654.1 2770.3 2866.5 3077.9 3089.9 4781.9 5684.5 5943.0 6058.5 6074.9 4928.9 5024.4 5627.0 5945.9 6001.7 5569.3 5630.9 6033.9 6089.8 6111.5 5151.8 5243.8 5287.2 5410.4 5501.6 5770.6 5805.6 5822.0 5833.4 5845.9 5176.5 5860.8 6130.3 6134.5 6207.7 3580.8 3736.9 3908.7 4016.6 4041.8 4893.6 4944.8 5302.0 5957.0 5981.7 5493.2 5557.5 5697.1 5727.3 5770.4 4759.5 5100.2 5623.8 5626.3 5651.4 5279.8 5455.2 5491.5 5539.0 5659.3 4967.8 5676.2 5680.9 5696.9 5714.0 4007.6 4978.1 5207.6 5341.9 5546.4 16:51:20 INFO - PROCESS | 1660 | 16:51:20 INFO - PROCESS | 1660 | [#5] yui.html Cycles:180 Average:24163.52 Median:22087.61 stddev:37744.53 (170.9%) stddev-sans-first:37809.38 16:51:20 INFO - PROCESS | 1660 | Values: 722.4 731.1 737.5 772.1 783.6 14376.1 14775.6 14810.5 14811.2 14874.4 15540.0 16247.9 16256.7 16259.8 16335.0 707.1 868.4 886.3 893.2 895.7 722.4 860.2 884.6 885.1 891.0 933.4 938.2 939.5 939.5 942.3 737.4 757.9 931.7 932.9 935.5 564.1 564.6 683.9 701.5 702.4 15258.3 15307.6 15312.3 15326.8 15414.2 13843.7 14030.9 14052.6 14088.0 14194.2 13061.4 13086.0 13270.3 13398.4 13494.8 4650.7 4663.4 4710.0 4855.7 4893.1 116078.8 117965.0 119565.4 119980.4 120098.2 10989.3 11101.6 11181.0 11207.4 11223.5 21825.9 21931.0 21936.6 22017.6 22157.7 7601.0 7730.6 7779.4 7827.3 8064.4 4304.7 4340.9 4343.0 4349.5 4523.3 2855.5 3580.2 3581.7 3587.4 3588.1 659.5 674.2 680.9 683.3 695.5 537.4 556.3 557.3 582.5 634.2 7842.9 8751.3 9011.3 9082.0 9222.3 605.8 617.7 618.2 628.9 641.8 4387.4 4422.8 4431.8 4483.4 4487.9 99346.0 127886.0 133829.0 152866.7 156316.4 69889.3 109501.0 114079.0 131156.3 132804.0 104629.0 110070.0 110904.4 116632.4 116679.8 9722.2 10004.5 10023.3 10050.5 10070.4 41813.0 55148.4 55912.0 56510.7 56752.0 87252.0 87304.7 89607.6 89897.6 90253.5 13095.7 13644.4 13873.0 14161.4 14398.5 14669.9 15361.5 15467.7 15511.8 15764.5 14204.3 14302.8 14341.0 14654.3 15103.2 11634.3 14417.2 14686.8 14885.1 14975.9 13364.4 13628.7 13692.6 14206.0 14312.3 11420.8 11832.4 11857.1 11863.2 11927.1 10808.6 11184.4 11360.9 11649.8 11801.2 16:51:20 INFO - PROCESS | 1660 | -------- Summary: end -------- 16:51:20 INFO - PROCESS | 1660 | 16:51:20 INFO - TEST-INFO | 1660: exit 0 16:51:20 INFO - TEST-OK | dromaeo_css | took 1115956ms 16:51:20 INFO - TEST-START | kraken 16:51:20 INFO - operating with platform_type : mac_ 16:51:20 INFO - Initialising browser for kraken test... 16:51:20 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/tmphhRHU9/profile http://localhost:49277/getInfo.html) 16:51:22 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 16:51:22 INFO - PROCESS | 1667 | colorDepth:24 16:51:22 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 16:51:22 INFO - PROCESS | 1667 | __metrics 16:51:23 INFO - PROCESS | 1667 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:51:23 INFO - PROCESS | 1667 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:51:23 INFO - TEST-INFO | 1667: exit 0 16:51:23 INFO - Browser initialized. 16:51:23 INFO - Running cycle 1/1 for kraken test... 16:51:23 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/tmphhRHU9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:51:35 INFO - PROCESS | 1668 | RSS: Main: 244879360 16:51:35 INFO - PROCESS | 1668 | 16:51:38 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 16:51:39 INFO - PROCESS | 1668 | RSS: Main: 294133760 16:51:39 INFO - PROCESS | 1668 | 16:51:41 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 16:51:42 INFO - PROCESS | 1668 | RSS: Main: 304631808 16:51:42 INFO - PROCESS | 1668 | 16:51:45 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 16:51:46 INFO - PROCESS | 1668 | RSS: Main: 388571136 16:51:46 INFO - PROCESS | 1668 | 16:51:48 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 16:51:49 INFO - PROCESS | 1668 | RSS: Main: 293052416 16:51:49 INFO - PROCESS | 1668 | 16:51:51 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:51:51 INFO - PROCESS | 1668 | RSS: Main: 296378368 16:51:51 INFO - PROCESS | 1668 | 16:51:54 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 16:51:55 INFO - PROCESS | 1668 | RSS: Main: 332443648 16:51:55 INFO - PROCESS | 1668 | 16:51:58 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 16:51:58 INFO - PROCESS | 1668 | RSS: Main: 336416768 16:51:58 INFO - PROCESS | 1668 | 16:52:01 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 16:52:02 INFO - PROCESS | 1668 | RSS: Main: 334725120 16:52:02 INFO - PROCESS | 1668 | 16:52:03 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:52:04 INFO - PROCESS | 1668 | RSS: Main: 311676928 16:52:04 INFO - PROCESS | 1668 | 16:52:06 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:52:07 INFO - PROCESS | 1668 | RSS: Main: 318390272 16:52:07 INFO - PROCESS | 1668 | 16:52:09 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:52:09 INFO - PROCESS | 1668 | RSS: Main: 316461056 16:52:09 INFO - PROCESS | 1668 | 16:52:12 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:52:13 INFO - PROCESS | 1668 | RSS: Main: 325120000 16:52:13 INFO - PROCESS | 1668 | 16:52:16 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:52:16 INFO - PROCESS | 1668 | RSS: Main: 326422528 16:52:16 INFO - PROCESS | 1668 | 16:52:18 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 16:52:18 INFO - PROCESS | 1668 | RSS: Main: 370106368 16:52:18 INFO - PROCESS | 1668 | 16:52:18 INFO - PROCESS | 1668 | __start_tp_report 16:52:18 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 16:52:18 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 16:52:18 INFO - PROCESS | 1668 | |i|pagename|runs| 16:52:18 INFO - PROCESS | 1668 | |0;ai-astar;102;112;114;112;114;113;113;113;113;114 16:52:18 INFO - PROCESS | 1668 | |1;audio-beat-detection;144;109;121;195;137;114;128;105;140;105 16:52:18 INFO - PROCESS | 1668 | |2;audio-dft;185;184;186;183;183;183;181;181;183;184 16:52:18 INFO - PROCESS | 1668 | |3;audio-fft;78;73;58;106;60;57;62;60;126;58 16:52:18 INFO - PROCESS | 1668 | |4;audio-oscillator;82;85;93;70;68;67;95;70;68;100 16:52:18 INFO - PROCESS | 1668 | |5;imaging-gaussian-blur;91;88;91;88;91;88;92;89;92;87 16:52:18 INFO - PROCESS | 1668 | |6;imaging-darkroom;96;117;122;96;96;95;96;120;149;153 16:52:18 INFO - PROCESS | 1668 | |7;imaging-desaturate;85;84;84;86;84;85;85;83;87;83 16:52:18 INFO - PROCESS | 1668 | |8;json-parse-financial;56;55;56;57;55;54;55;55;56;55 16:52:18 INFO - PROCESS | 1668 | |9;json-stringify-tinderbox;66;66;79;78;75;72;71;73;71;70 16:52:18 INFO - PROCESS | 1668 | |10;stanford-crypto-aes;82;83;80;79;101;82;82;78;83;86 16:52:18 INFO - PROCESS | 1668 | |11;stanford-crypto-ccm;155;150;156;135;139;143;153;135;173;133 16:52:18 INFO - PROCESS | 1668 | |12;stanford-crypto-pbkdf2;162;164;160;161;161;161;164;159;162;162 16:52:18 INFO - PROCESS | 1668 | |13;stanford-crypto-sha256-iterative;66;67;66;65;66;65;66;67;64;65 16:52:18 INFO - PROCESS | 1668 | __end_tp_report 16:52:18 INFO - PROCESS | 1668 | __start_cc_report 16:52:18 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,2013 16:52:18 INFO - PROCESS | 1668 | __end_cc_report 16:52:18 INFO - PROCESS | 1668 | __startTimestamp1467676338776__endTimestamp 16:52:18 INFO - PROCESS | 1668 | 16:52:18 INFO - PROCESS | 1668 | ------- Summary: start ------- 16:52:18 INFO - PROCESS | 1668 | Number of tests: 14 16:52:18 INFO - PROCESS | 1668 | 16:52:18 INFO - PROCESS | 1668 | [#0] ai-astar Cycles:10 Average:112.00 Median:113.00 stddev:3.59 (3.2%) stddev-sans-first:0.78 16:52:18 INFO - PROCESS | 1668 | Values: 102.0 112.0 114.0 112.0 114.0 113.0 113.0 113.0 113.0 114.0 16:52:18 INFO - PROCESS | 1668 | 16:52:18 INFO - PROCESS | 1668 | [#1] audio-beat-detection Cycles:10 Average:129.80 Median:132.50 stddev:27.08 (20.4%) stddev-sans-first:28.23 16:52:18 INFO - PROCESS | 1668 | Values: 144.0 109.0 121.0 195.0 137.0 114.0 128.0 105.0 140.0 105.0 16:52:18 INFO - PROCESS | 1668 | 16:52:18 INFO - PROCESS | 1668 | [#2] audio-dft Cycles:10 Average:183.30 Median:183.50 stddev:1.57 (0.9%) stddev-sans-first:1.54 16:52:18 INFO - PROCESS | 1668 | Values: 185.0 184.0 186.0 183.0 183.0 183.0 181.0 181.0 183.0 184.0 16:52:18 INFO - PROCESS | 1668 | 16:52:18 INFO - PROCESS | 1668 | [#3] audio-fft Cycles:10 Average:73.80 Median:60.00 stddev:23.76 (39.6%) stddev-sans-first:25.15 16:52:18 INFO - PROCESS | 1668 | Values: 78.0 73.0 58.0 106.0 60.0 57.0 62.0 60.0 126.0 58.0 16:52:18 INFO - PROCESS | 1668 | 16:52:18 INFO - PROCESS | 1668 | [#4] audio-oscillator Cycles:10 Average:79.80 Median:76.00 stddev:12.82 (16.9%) stddev-sans-first:13.57 16:52:18 INFO - PROCESS | 1668 | Values: 82.0 85.0 93.0 70.0 68.0 67.0 95.0 70.0 68.0 100.0 16:52:18 INFO - PROCESS | 1668 | 16:52:18 INFO - PROCESS | 1668 | [#5] imaging-gaussian-blur Cycles:10 Average:89.70 Median:91.00 stddev:1.89 (2.1%) stddev-sans-first:1.94 16:52:18 INFO - PROCESS | 1668 | Values: 91.0 88.0 91.0 88.0 91.0 88.0 92.0 89.0 92.0 87.0 16:52:18 INFO - PROCESS | 1668 | 16:52:18 INFO - PROCESS | 1668 | [#6] imaging-darkroom Cycles:10 Average:114.00 Median:95.50 stddev:22.39 (23.4%) stddev-sans-first:22.78 16:52:18 INFO - PROCESS | 1668 | Values: 96.0 117.0 122.0 96.0 96.0 95.0 96.0 120.0 149.0 153.0 16:52:18 INFO - PROCESS | 1668 | 16:52:18 INFO - PROCESS | 1668 | [#7] imaging-desaturate Cycles:10 Average:84.60 Median:85.00 stddev:1.26 (1.5%) stddev-sans-first:1.33 16:52:18 INFO - PROCESS | 1668 | Values: 85.0 84.0 84.0 86.0 84.0 85.0 85.0 83.0 87.0 83.0 16:52:18 INFO - PROCESS | 1668 | 16:52:18 INFO - PROCESS | 1668 | [#8] json-parse-financial Cycles:10 Average:55.40 Median:55.50 stddev:0.84 (1.5%) stddev-sans-first:0.87 16:52:18 INFO - PROCESS | 1668 | Values: 56.0 55.0 56.0 57.0 55.0 54.0 55.0 55.0 56.0 55.0 16:52:18 INFO - PROCESS | 1668 | 16:52:18 INFO - PROCESS | 1668 | [#9] json-stringify-tinderbox Cycles:10 Average:72.10 Median:72.50 stddev:4.38 (6.0%) stddev-sans-first:4.06 16:52:18 INFO - PROCESS | 1668 | Values: 66.0 66.0 79.0 78.0 75.0 72.0 71.0 73.0 71.0 70.0 16:52:18 INFO - PROCESS | 1668 | 16:52:18 INFO - PROCESS | 1668 | [#10] stanford-crypto-aes Cycles:10 Average:83.60 Median:82.00 stddev:6.52 (7.9%) stddev-sans-first:6.89 16:52:18 INFO - PROCESS | 1668 | Values: 82.0 83.0 80.0 79.0 101.0 82.0 82.0 78.0 83.0 86.0 16:52:18 INFO - PROCESS | 1668 | 16:52:18 INFO - PROCESS | 1668 | [#11] stanford-crypto-ccm Cycles:10 Average:147.20 Median:151.50 stddev:12.60 (8.3%) stddev-sans-first:13.05 16:52:18 INFO - PROCESS | 1668 | Values: 155.0 150.0 156.0 135.0 139.0 143.0 153.0 135.0 173.0 133.0 16:52:18 INFO - PROCESS | 1668 | 16:52:18 INFO - PROCESS | 1668 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.60 Median:162.00 stddev:1.58 (1.0%) stddev-sans-first:1.67 16:52:18 INFO - PROCESS | 1668 | Values: 162.0 164.0 160.0 161.0 161.0 161.0 164.0 159.0 162.0 162.0 16:52:18 INFO - PROCESS | 1668 | 16:52:18 INFO - PROCESS | 1668 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.70 Median:66.00 stddev:0.95 (1.4%) stddev-sans-first:1.00 16:52:18 INFO - PROCESS | 1668 | Values: 66.0 67.0 66.0 65.0 66.0 65.0 66.0 67.0 64.0 65.0 16:52:18 INFO - PROCESS | 1668 | -------- Summary: end -------- 16:52:18 INFO - PROCESS | 1668 | 16:52:19 INFO - TEST-INFO | 1668: exit 0 16:52:19 INFO - TEST-OK | kraken | took 58799ms 16:52:19 INFO - SUITE-END | took 1174s 16:52:19 INFO - Completed test suite (00:19:35) 16:52:19 INFO - javascript benchmark 16:52:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2867.5555288930213, 3382.2220889195482, 3387.2209391839874, 3410.164509695125, 3437.5359326490934, 5689.687067211312, 5693.231413759141, 5758.798442054518, 5761.596688231825, 5785.595008349423, 5744.281964754406, 5755.769769209227, 5797.1594118852745, 5840.941590584103, 5896.882062358761, 4449.998750281193, 5070.695758254498, 5143.53708166265, 5147.562457191143, 5307.283516725251, 5271.551918086967, 5313.814016509423, 5331.760070796823, 5347.0909945309395, 5368.651037682557, 5130.486951304877, 5192.454792246797, 5205.505476979681, 5235.2670626112185, 5252.36971563411, 5140.640155189139, 5198.896022079575, 5201.713905735183, 5202.505761952609, 5241.318628578299, 5172.27588137661, 5183.196604526304, 5187.766550505236, 5205.27126202332, 5275.604329675251, 5819.592628516006, 5833.06670932653, 5884.352721200665, 5886.6173698709445, 5889.381614930457, 5061.5950723942, 6109.920916985209, 6172.105044768484, 6245.375462453764, 6264.185655864754, 6203.751849925998, 6280.528960327993, 6312.463440607554, 6315.715792789336, 6324.114623952651, 5947.375525569796, 5974.193483879664, 5981.790637327673, 5982.820515384458, 5992.041273396236, 6148.907766383508, 6187.195664563578, 6200.720967556471, 6240.812775616739, 6259.967105427644, 6226.501879849601, 6245.656488893063, 6251.4061164189325, 6251.749930002749, 6255.968720156461, 6288.622682639057, 6295.874082518416, 6300.621962682254, 6300.653464059521, 6310.4636105930595, 6092.296407170863, 6173.660448675366, 6229.626222426669, 6249.531285153632, 6252.093446450239, 6286.402791734778, 6293.905591416133, 6296.653684047421, 6299.874002519924, 6329.208848893888, 4945.480724523945, 4989.12690279207, 5024.974875125601, 5032.7735251913755, 5040.722760248222, 4474.507804141542, 4481.843135490308, 4483.036147228361, 4514.32285157229, 4541.772911354419, 5770.740316685759, 5784.566157538202, 5791.391903850119, 5807.854803629859, 5854.414558544197, 5209.973950130225, 6165.0, 6188.907166392508, 6309.968450157719, 6334.3032266450655, 5712.514436272881, 5762.855928601818, 5777.277840269966, 5790.565707571865, 5821.18503409523, 5452.6183167177915, 5462.754176062087, 5463.0, 5477.20580515823, 5485.890282194414, 5466.1527463243265, 6175.536834737502, 6279.560430769802, 6299.338569450233, 6304.338044505261, 5709.857253568694, 6223.96888015557, 6306.9684651576445, 6309.432151106423, 6326.43062124411, 5069.949300506948, 6154.476869465966, 6287.528435367274, 6320.1151838743535, 6335.524835637253, 6122.0, 6183.752649893954, 6237.407446292595, 6260.655663938527, 6271.027990661455, 6242.219722534684, 6291.937080629135, 6307.180066591313, 6307.621542707452, 6331.0819931110655, 4921.26181072842, 5940.79207227745, 6139.969300153471, 6154.353792851686, 6158.261008678808, 5101.464346243591, 5995.790147344822, 6086.087086936995, 6175.3515880831865, 6180.505559555135, 5938.495227905503, 6041.426064523863, 6061.748482082497, 6083.117947897442, 6101.389861013863, 5122.359705036871, 6137.570370074052, 6190.566660333733, 6219.968900155471, 6222.81331560054, 5805.071188609802, 5989.460948514655, 6068.757249709962, 6086.421789929949, 6099.085137229451], "value": 5697.576219034696, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [470.9105269998702, 483.37885816725515, 518.5747686896743, 535.9758810853514, 558.8267637032519, 29412.41175176498, 34222.14444638882, 41019.79490102549, 42140.367894481606, 42224.78887605558, 43836.561634383725, 44021.55978440215, 44100.33849492252, 44163.11673766523, 44243.77878110613, 8902.910970890305, 9934.254930880159, 10094.041066098745, 10104.44425556594, 10121.291509594332, 8859.20267175954, 10190.0, 12129.150959432844, 12335.889769920705, 12557.309347985856, 12278.631641050782, 12314.076444266671, 12335.0, 12337.876621233807, 12362.0, 8843.159899809507, 12176.452059657247, 12210.450529726184, 12239.75520489594, 12335.25988440692, 11902.166848320621, 12360.567380141742, 12366.319852408114, 12366.44351004198, 12384.504619815196, 42250.0, 42663.0, 42667.0, 42670.0, 42672.57327426748, 3541.964580354215, 3555.6444355564495, 3584.946225806615, 3689.24370504046, 4067.593240675909, 32580.837095814608, 32863.84976525834, 33042.17394565131, 33183.668163318296, 33225.00324990254, 3212.16483714236, 3227.757918156124, 3253.4794432890862, 3260.233845046412, 3373.207296285371, 540860.0, 541410.0, 541736.0, 544060.0, 544129.2793536047, 62033.68983155055, 62593.0, 62628.61599687978, 62653.0, 62714.37285627086, 35600.821995889855, 35916.82041589828, 35941.64058359435, 37135.25729485395, 37211.4418283726, 16356.345746170258, 16468.752968705478, 16619.3352265908, 16677.916610417113, 16816.411425600047, 11783.88216117845, 11995.64013079609, 12001.1599188056, 12796.232226066466, 12948.805767913489, 14757.852421475647, 16891.81757276979, 18307.502477144855, 20168.991550422714, 20237.898810505852, 7920.900961245275, 7929.762107136795, 7945.404094692921, 7949.284564389232, 7973.481723687942, 864.9351298652626, 958.2334132693817, 1071.844582535528, 1118.703543560957, 1156.4217891054473, 25920.61119083215, 31362.372752544823, 31428.214294642818, 31525.52711709326, 32210.516842247383, 1045.3309881675734, 1059.989400105989, 1078.5470102556944, 1144.0733006264952, 1154.7632735289415, 16515.256813443422, 16608.335666573203, 16645.83354166467, 16708.832911670972, 17014.234359453854, 714787.0, 1039284.0, 1050214.0, 1053854.0, 1054168.0, 62960.0, 63711.68144159342, 63933.36066639367, 64058.0, 64058.6797066021, 1261.5647601577625, 1308.4700696218047, 1409.6264489910182, 1436.174199835078, 1449.355037008521, 30912.227194320323, 31631.52552711757, 32428.513572295964, 32724.672753272163, 32784.50823237701, 58276.08619569089, 58423.0, 59250.703746482715, 59252.0, 59293.40706592879, 657852.0, 662986.0, 664814.0, 664840.0, 665748.0, 23539.17612883541, 24708.876455617607, 27204.0, 27528.862355688092, 27609.309767255178, 48179.0, 48251.75874120607, 48855.0, 49254.26118608152, 49438.0, 39355.80322098371, 40263.79868100641, 40646.983825404604, 41180.38229426501, 41212.0, 37897.81051094727, 39633.60366396299, 39864.202715944935, 40114.0, 40935.1812963745, 38628.6137138625, 39160.80419597884, 39342.0, 39368.80315598404, 39471.81584552468, 28579.0, 33467.497987530696, 34399.48400774041, 35317.29365412729, 35522.82238588791, 25408.618870716586, 25690.743092568835, 25771.098011569506, 25789.613155803054, 26340.604890926268], "value": 21055.16688732408, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.3444627439236, 377.5450582048631, 382.60782697734817, 386.08690447092624, 404.10087555189705, 15637.687246255067, 16361.509154725363, 20510.89744551279, 21364.038618262177, 21407.464813379655, 19966.800331996677, 20809.479763005933, 21138.788612113873, 21160.0, 21196.89401552994, 5580.637258578179, 5674.716264186794, 5881.67650779207, 5933.080372542263, 5937.7624895004365, 5721.456461636138, 5728.77084916605, 5788.47903688668, 5796.884062318751, 5803.767849286045, 5509.228707980866, 5750.424957504258, 5761.625494342875, 5781.0, 5787.0, 5724.34170070441, 5752.395998420168, 5782.913256301159, 5790.623609465392, 5793.478586927196, 4837.0809546186465, 5068.138416469209, 5071.0, 5072.112380333427, 5078.289039534468, 22214.88925553732, 22217.22239721602, 24168.274951751475, 24923.626145607832, 24929.376765580822, 1825.9360922367653, 2265.864048157099, 2289.564982653307, 2309.2841219222096, 2928.740641524144, 10123.34198277117, 10566.366018038942, 10589.364638121737, 12417.565385211565, 15244.618884527865, 1703.2080082761574, 1794.856411487091, 1861.8138186181409, 1908.0, 1929.8938558379284, 122778.0, 125506.0, 129568.0, 135151.0, 135644.32177839146, 11504.252223605607, 11542.210957301631, 11553.826692599618, 11579.710507237387, 11730.237534560227, 14511.927440362731, 14567.490137845125, 14588.635284117981, 14595.0, 14625.78061329081, 8619.482831030158, 8631.482111073356, 8712.085231050649, 8731.476111433334, 8744.256738177326, 7387.928750331172, 8329.583520823935, 8668.393212475066, 8671.0, 8786.209241168326, 4556.066006468666, 4564.680472366902, 4576.199165146086, 4585.449746030497, 4608.239640459354, 1876.2870109358357, 1919.7696276446916, 1923.422973108062, 1923.9326623568388, 1924.7209154672494, 851.2381418630415, 913.2785099771239, 940.9670661526814, 957.0572985609169, 1023.6059117239917, 18639.440816775517, 21323.57352852934, 21371.67942480864, 21378.251761188283, 21393.358199254046, 918.9586468608928, 964.2189826240767, 964.6864768950119, 986.3687240166299, 1056.9524371403306, 9721.854172187423, 10118.443485608363, 11057.778844423066, 11174.776504470028, 11282.548698051985, 613676.0, 620893.0, 627474.0, 632295.0, 636309.0, 90061.0, 102888.88444462288, 137594.93607595895, 142527.28736356253, 150743.0, 976.72651657536, 985.6353149334792, 1010.7624708193715, 1018.674024312213, 1020.5152552537634, 18635.90682046581, 19031.143598537823, 19032.714509282094, 19250.71123933114, 19315.90342048337, 44528.32452592561, 54791.178132328845, 58263.4173658258, 58283.0, 59389.0, 74254.0, 77497.83753243594, 85391.57304213439, 86002.27995440182, 86727.0, 7736.7292144774665, 7923.326517246099, 8083.636236369495, 8121.79695507617, 8231.218034286732, 12287.815682764587, 12882.162659427107, 13278.601641950758, 13429.395677194745, 13461.528846490326, 11168.106551476025, 11323.377214253294, 11352.659420217407, 11372.545098196311, 11586.652400428, 10473.266871318934, 10976.780464390828, 11099.223054386115, 11184.0, 11425.2002359834, 11282.210245282751, 11361.375124367909, 11443.198976071595, 11540.711482213012, 11626.476808543466, 6764.187363595056, 8130.552819594979, 8345.706415505607, 8356.456830306011, 8496.150384961455, 8269.83460330778, 10253.0, 11140.665780026613, 11287.266327688674, 11327.773444531229], "value": 9615.063352113593, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1581.533447632948, 1594.1311984968193, 1676.1702957036268, 1933.9226430942763, 1956.9804301956985, 8528.104549022357, 8878.15657512537, 9029.232515236205, 9032.954835225832, 9049.230815380692, 7820.491668041574, 8044.798880028002, 8543.444676096049, 8763.474191548512, 8829.381943263974, 2002.2391491233311, 2206.8013878750908, 2305.939267936751, 2384.7853693167613, 2384.8688322142275, 2354.1407386303963, 2369.862466016313, 2394.904203831845, 2395.64065390192, 2401.411654144737, 2088.1542975095113, 2158.676198570219, 2306.169778879602, 2368.0, 2370.9644355334685, 2333.381653861728, 2338.368640467073, 2364.2198074635417, 2370.656254843055, 2375.655529948165, 2329.23135365329, 2344.2498400511845, 2349.447879748258, 2361.8346715729904, 2373.0389192377115, 8747.08155643661, 8836.646534138627, 8837.823243535158, 8839.0, 8843.20411162992, 5199.272101905736, 5365.973170134163, 5367.570594352482, 5368.865778355534, 5374.865628359284, 3559.555055618048, 3570.9107272318142, 3629.5825980012373, 3646.908827279313, 3647.1793846384617, 1055.6252530351692, 1059.8834128245887, 1067.7010437077631, 1078.6170909327154, 1078.95684172633, 11462.254953428, 11473.196876218668, 11478.368689722061, 11494.31034137946, 11518.136139789549, 4513.75174365413, 4580.450345958507, 4750.809967601257, 4802.807887684454, 4806.182948898626, 9762.804743905224, 9777.120059194707, 9785.804283914282, 9789.167920726675, 9796.9020309796, 5330.987112448623, 5331.093714068617, 5335.119705248668, 5337.839864804062, 5338.3060202173465, 4721.5278472153195, 4868.14807408702, 4882.194437917704, 4885.145099607555, 4915.3855768029, 3790.279846829149, 3811.561670407883, 3843.5964223756664, 3867.361885288952, 3901.51231096113, 1096.736783172033, 1118.8042092633757, 1168.1122347016328, 1168.2639936839737, 1177.3701069927547, 731.3344856180956, 855.6705668317618, 895.7357579514058, 916.4730280088976, 948.3503799897093, 5668.433156684298, 5725.736903236744, 5834.533237341003, 5896.469317761336, 6210.658413787285, 850.5492089192614, 857.3355649371662, 928.8095940332215, 965.9517024148764, 998.9350692204983, 4002.9799851000557, 4152.1072969311745, 4197.202531519001, 4214.325707886724, 4261.850835220753, 10311.1235544978, 10645.680629581126, 10721.678349649523, 10729.03438690506, 10730.892691073146, 8387.664493420194, 10323.74190645225, 10558.894411055944, 10743.731406714895, 10787.514561844735, 7555.16893141754, 7809.789482630202, 7914.445988780845, 8294.294984926228, 8310.418270721113, 5252.632315737862, 5932.762689492372, 6206.751729930752, 6238.6880655967925, 6289.371062893674, 9589.808203836024, 10023.997600240209, 10041.698749037541, 10046.0, 10061.094501494608, 9827.0, 10146.98530147009, 10156.644517441853, 10186.74533136648, 10197.13324367437, 6113.449789518964, 7023.332783385775, 7070.858582828418, 7166.605836679033, 7167.139943206848, 8150.551719655476, 8246.587670616565, 8251.463654862415, 8259.463134896212, 8277.04813946404, 6747.122874026345, 7334.523255988343, 7521.5111017783665, 7643.770686879402, 7666.080070391366, 7264.636768161465, 7430.925690743024, 7485.5508669480005, 7558.281963213486, 7603.88594171099, 6825.726970921108, 7070.646467676493, 7075.221725610281, 7173.856522869618, 7186.281371862772, 5060.114479966065, 5081.669691470191, 5126.4617215191865, 5132.743362831919, 5236.528712415901, 5127.948720512747, 5165.845024649267, 5171.81898633546, 5219.869503262297, 5350.652207606493], "value": 4571.421715486023, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.4633951566213, 305.28715449425596, 314.214463840399, 318.13784643615827, 319.40112289457267, 4494.730316181031, 5341.305630268064, 5412.675239485634, 5447.0, 5513.814529876081, 4272.679549033819, 5050.318207042057, 5220.451852555484, 5292.253556326413, 5392.245085688007, 3347.564816573842, 3653.6346365363515, 3942.5860284670125, 3984.322665146932, 3999.8000099994956, 3616.3129005489004, 3678.4850120983083, 4052.2705912935658, 4092.5088989321325, 4115.567865374138, 3742.981285093571, 3937.8818635440985, 4040.6363427291685, 4042.9797851010703, 4102.630763231308, 3432.1419645088727, 3820.9110403535137, 4078.755274683499, 4091.7749523776033, 4111.8560850370095, 3081.0, 3406.880759173417, 3495.5281037059926, 3509.7540373167412, 3520.3487354839435, 5235.869103272411, 5243.292155558989, 5251.872991438474, 5268.288781014552, 5302.125149350352, 3886.980565097185, 4681.765911704435, 4704.0356726870905, 4704.3299628631685, 4719.433667959833, 5475.260839786618, 5577.330720313588, 5713.371529131792, 5769.365369809318, 5776.0, 3941.2708648900043, 3947.1908258806875, 3957.544882338539, 3964.1278918637854, 3974.5230572331507, 4661.97204216167, 6021.578489505737, 6031.035034394476, 6073.392660733935, 6084.726187321581, 3866.845326186977, 4035.3745169498775, 4035.535913369941, 4049.331860243086, 4070.715049946475, 3394.609619893744, 4126.979365103215, 4131.607497287753, 4148.771817550003, 4151.190517849051, 4749.9362910922955, 4750.501197374295, 4771.665983381199, 4780.163471392492, 4786.305985632064, 5556.611037227355, 5757.0, 5759.337676167211, 5809.0, 5809.82570522885, 1213.8604060532975, 1303.5242136620066, 1368.50733735857, 1392.3525560614362, 1398.3148257353823, 813.3086876155221, 895.3866601378077, 920.3603495570514, 920.3847248079577, 922.727795300388, 4645.581897629162, 4693.6949098308505, 5174.379074511083, 5339.626226164209, 5412.377576578744, 5346.91979620306, 5769.8557536061935, 5836.88326233473, 5845.7369418376275, 5960.135780311831, 4063.5733248008532, 4832.734199618984, 5407.83776486706, 5423.403425623178, 5459.884928879601, 2654.0976068136924, 2770.252031951402, 2866.4553734790325, 3077.8768849246226, 3089.8609562569736, 4781.880452988703, 5684.54523638108, 5943.0, 6058.485378655336, 6074.9392506075255, 4928.926066109011, 5024.371953505812, 5627.020087222755, 5945.851353716105, 6001.699915004232, 5569.303837020372, 5630.873825234888, 6033.909491357633, 6089.751600921801, 6111.463742546794, 5151.819686311111, 5243.820140468364, 5287.206918962186, 5410.350757908918, 5501.587380946365, 5770.6249093808765, 5805.593608447537, 5822.0, 5833.358330583717, 5845.883082338244, 5176.50823171798, 5860.794872179454, 6130.295016073209, 6134.509239260938, 6207.720652570555, 3580.818329951162, 3736.9065773355883, 3908.726389152732, 4016.6184212499766, 4041.8181181847476, 4893.559579637849, 4944.75276236194, 5302.0, 5957.0044035248675, 5981.6840295135, 5493.23094766725, 5557.499825015768, 5697.116946873241, 5727.255456790591, 5770.422957704196, 4759.452662943806, 5100.209467532539, 5623.803166889139, 5626.2967129108865, 5651.350094739158, 5279.762410691451, 5455.1544510600925, 5491.533219676213, 5538.978272930432, 5659.292588426447, 4967.7516124192925, 5676.233708449472, 5680.886382272249, 5696.914546281892, 5713.971430142823, 4007.6192761687594, 4978.054169707744, 5207.557357624644, 5341.866453338697, 5546.362168350692], "value": 4125.910071033499, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [722.429280868114, 731.104397113536, 737.513241260768, 772.1043589436255, 783.627776806017, 14376.065555738884, 14775.556733298004, 14810.481633142843, 14811.185384803832, 14874.405023799036, 15540.0, 16247.943883647555, 16256.674866502663, 16259.756103658454, 16335.0, 707.1090426063172, 868.3791089371099, 886.3441053620306, 893.1693525021727, 895.7447127568644, 722.3859719238658, 860.2129051917487, 884.6107712606432, 885.0883589902411, 890.995545022274, 933.355984370782, 938.2353381993687, 939.4551160326994, 939.5067589515525, 942.2603256443693, 737.4432303610739, 757.9242075792432, 931.7344556801347, 932.8787257656529, 935.4948327902924, 564.0580231014216, 564.5991346144242, 683.9145106861642, 701.515953991744, 702.420503084957, 15258.313375898111, 15307.617309567238, 15312.310946007456, 15326.770098448533, 15414.22928853564, 13843.653908652264, 14030.859691403057, 14052.578422647337, 14088.0, 14194.21931793751, 13061.412236449383, 13086.0, 13270.336483175803, 13398.397072131778, 13494.797578036338, 4650.697704649187, 4663.370444989917, 4710.034442939189, 4855.732934688591, 4893.070316639827, 116078.83921160764, 117965.0, 119565.40217298857, 119980.40009799982, 120098.19852702216, 10989.340639561651, 11101.611443599435, 11181.049610783175, 11207.439628018567, 11223.494942727597, 21825.890870545863, 21931.01310441034, 21936.561268774534, 22017.559648806935, 22157.66763498549, 7601.011868457065, 7730.574818385043, 7779.416543759241, 7827.334676552443, 8064.354851611857, 4304.677149213759, 4340.891477713019, 4342.978285108554, 4349.499807522176, 4523.344115103291, 2855.528837741791, 3580.1765593913547, 3581.7492775505984, 3587.4260118380935, 3588.138846676831, 659.528437167418, 674.20780582815, 680.9080774095532, 683.2620769568854, 695.4644923409048, 537.4330081763777, 556.3185098254687, 557.3005877623638, 582.4845012164266, 634.1502386801708, 7842.882356764653, 8751.343649226334, 9011.324150688724, 9082.0, 9222.26248206919, 605.8455093951103, 617.6572002538634, 618.2303032724287, 628.8742251549617, 641.7978336823885, 4387.407699960529, 4422.778861056934, 4431.82272709088, 4483.409388199342, 4487.932681009787, 99346.0, 127886.0, 133829.0, 152866.7069993951, 156316.43683563246, 69889.3011069893, 109501.0, 114079.0, 131156.3442182783, 132804.0, 104628.95371046344, 110070.0, 110904.4454777721, 116632.41683791357, 116679.8332016669, 9722.173615242784, 10004.549795259376, 10023.348482348916, 10050.547725352231, 10070.44612546317, 41812.96775547405, 55148.448515515935, 55912.0, 56510.717446412506, 56752.0, 87252.0, 87304.69042964233, 89607.55196224237, 89897.55051224964, 90253.54873225592, 13095.738085238052, 13644.386002630104, 13873.0, 14161.433542658591, 14398.496052638107, 14669.92665036668, 15361.462348818184, 15467.690646187239, 15511.844881551491, 15764.527064188092, 14204.289785510477, 14302.785458217926, 14341.0, 14654.340554674853, 15103.169325686753, 11634.301941883514, 14417.207053612152, 14686.825053995868, 14885.106893586419, 14975.850241497446, 13364.398602063126, 13628.65928351799, 13692.589222323346, 14206.039040869693, 14312.276948607143, 11420.820414977594, 11832.408379580815, 11857.110716696456, 11863.228890122115, 11927.10546708983, 10808.621698240524, 11184.440777960906, 11360.886391135982, 11649.767004660029, 11801.232919860182], "value": 7878.551111274034, "unit": "score"}], "name": "dromaeo_css", "value": 7453.22719559748, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 112.0, 114.0, 112.0, 114.0, 113.0, 113.0, 113.0, 113.0, 114.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 109.0, 121.0, 195.0, 137.0, 114.0, 128.0, 105.0, 140.0, 105.0], "value": 129.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 186.0, 183.0, 183.0, 183.0, 181.0, 181.0, 183.0, 184.0], "value": 183.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 73.0, 58.0, 106.0, 60.0, 57.0, 62.0, 60.0, 126.0, 58.0], "value": 73.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 85.0, 93.0, 70.0, 68.0, 67.0, 95.0, 70.0, 68.0, 100.0], "value": 79.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 88.0, 91.0, 88.0, 91.0, 88.0, 92.0, 89.0, 92.0, 87.0], "value": 89.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 117.0, 122.0, 96.0, 96.0, 95.0, 96.0, 120.0, 149.0, 153.0], "value": 114.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 84.0, 86.0, 84.0, 85.0, 85.0, 83.0, 87.0, 83.0], "value": 84.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 56.0, 57.0, 55.0, 54.0, 55.0, 55.0, 56.0, 55.0], "value": 55.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 79.0, 78.0, 75.0, 72.0, 71.0, 73.0, 71.0, 70.0], "value": 72.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 80.0, 79.0, 101.0, 82.0, 82.0, 78.0, 83.0, 86.0], "value": 83.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 150.0, 156.0, 135.0, 139.0, 143.0, 153.0, 135.0, 173.0, 133.0], "value": 147.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 164.0, 160.0, 161.0, 161.0, 161.0, 164.0, 159.0, 162.0, 162.0], "value": 161.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 65.0, 66.0, 65.0, 66.0, 67.0, 64.0, 65.0], "value": 65.7, "unit": "score"}], "name": "kraken", "value": 1452.6000000000001, "alertThreshold": 2.0}]} 16:52:19 INFO - Return code: 0 16:52:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:52:20 INFO - # TBPL SUCCESS # 16:52:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:52:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:52:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:52:20 INFO - 2016-07-04 16:52:20.071 screenresolution[1672:9777] starting screenresolution argv=screenresolution get 16:52:20 INFO - 2016-07-04 16:52:20.073 screenresolution[1672:9777] Display 0: 1600x1200x32@60 16:52:20 INFO - 2016-07-04 16:52:20.079 screenresolution[1673:9780] starting screenresolution argv=screenresolution list 16:52:20 INFO - Available Modes on Display 0 16:52:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:52:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:52:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:52:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:52:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:52:20 INFO - Intel Iris: 16:52:20 INFO - Chipset Model: Intel Iris 16:52:20 INFO - Type: GPU 16:52:20 INFO - Bus: Built-In 16:52:20 INFO - VRAM (Dynamic, Max): 1536 MB 16:52:20 INFO - Vendor: Intel (0x8086) 16:52:20 INFO - Device ID: 0x0a2e 16:52:20 INFO - Revision ID: 0x0009 16:52:20 INFO - Displays: 16:52:20 INFO - Display: 16:52:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:52:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:52:20 INFO - Display Serial Number: 1600x1200 60 16:52:20 INFO - Main Display: Yes 16:52:20 INFO - Mirror: Off 16:52:20 INFO - Online: Yes 16:52:20 INFO - Rotation: Supported 16:52:20 INFO - Return code: 0 16:52:20 INFO - Running post-action listener: _resource_record_post_action 16:52:20 INFO - ##### 16:52:20 INFO - ##### Finished run-tests step (success) 16:52:20 INFO - ##### 16:52:20 INFO - Running post-run listener: _resource_record_post_run 16:52:20 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 49355264; Write bytes: 714702848; Read time: 569; Write time: 2353 16:52:20 INFO - TinderboxPrint: CPU usage
53.3% 16:52:20 INFO - TinderboxPrint: I/O read bytes / time
49,355,264 / 569 16:52:20 INFO - TinderboxPrint: I/O write bytes / time
714,702,848 / 2,353 16:52:20 INFO - TinderboxPrint: CPU idle
2,265.2 (46.7%) 16:52:20 INFO - TinderboxPrint: CPU system
964.5 (19.9%) 16:52:20 INFO - TinderboxPrint: CPU user
1,616.0 (33.3%) 16:52:20 INFO - TinderboxPrint: Swap in / out
448,495,616 / 0 16:52:20 INFO - install - Wall time: 36s; CPU: 54.0%; Read bytes: 397558272; Write bytes: 387082240; Read time: 30051; Write time: 732 16:52:20 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 43155968; Write bytes: 304396288; Read time: 481; Write time: 1574 16:52:20 INFO - Running post-run listener: _upload_blobber_files 16:52:20 INFO - Blob upload gear active. 16:52:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:52:20 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:52:20 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:52:20 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:52:21 INFO - (blobuploader) - INFO - Open directory for files ... 16:52:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:52:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:52:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:52:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:52:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:52:21 INFO - (blobuploader) - INFO - Done attempting. 16:52:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:52:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:52:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:52:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:52:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:52:22 INFO - (blobuploader) - INFO - Done attempting. 16:52:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:52:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:52:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:52:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:52:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:52:25 INFO - (blobuploader) - INFO - Done attempting. 16:52:25 INFO - (blobuploader) - INFO - Iteration through files over. 16:52:25 INFO - Return code: 0 16:52:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:52:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:52:25 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/95a8055ba37209688b9ceae1b9270b51c51dc9a9818f60a54021234d6bb0f34a3c8aff147a65f4945345c3922369bc55d1b456d227412ec74bef4402b4e13392", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2ce8d9ab8f908ef8929e42c93ce455ffa71b374c986234ca7621f1d6e494ff3f3e9587ee9d3efeed7c59534d306263fb4bd1aa9e383cae7732e8113081cbd2de", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f6920104fb5c9508e6d866f1b4b1db5ca615e4e02a4f472ad2ca0086184ee2f2a8c594aa39c1c4f5137d369e1ffcf86aced7553b1bc087fa9a619d72e36426bd"} 16:52:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:52:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:52:25 INFO - Contents: 16:52:25 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/95a8055ba37209688b9ceae1b9270b51c51dc9a9818f60a54021234d6bb0f34a3c8aff147a65f4945345c3922369bc55d1b456d227412ec74bef4402b4e13392", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2ce8d9ab8f908ef8929e42c93ce455ffa71b374c986234ca7621f1d6e494ff3f3e9587ee9d3efeed7c59534d306263fb4bd1aa9e383cae7732e8113081cbd2de", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f6920104fb5c9508e6d866f1b4b1db5ca615e4e02a4f472ad2ca0086184ee2f2a8c594aa39c1c4f5137d369e1ffcf86aced7553b1bc087fa9a619d72e36426bd"} 16:52:25 INFO - Running post-run listener: copy_logs_to_upload_dir 16:52:25 INFO - Copying logs to upload dir... 16:52:25 INFO - mkdir: /builds/slave/test/build/upload/logs 16:52:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1280.366288 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-07-04 16:52:25.331726) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-04 16:52:25.335489) ========= 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.LkDfcOQWAm/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.Ac9E8tuFKc/Listeners TMPDIR=/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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/95a8055ba37209688b9ceae1b9270b51c51dc9a9818f60a54021234d6bb0f34a3c8aff147a65f4945345c3922369bc55d1b456d227412ec74bef4402b4e13392", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2ce8d9ab8f908ef8929e42c93ce455ffa71b374c986234ca7621f1d6e494ff3f3e9587ee9d3efeed7c59534d306263fb4bd1aa9e383cae7732e8113081cbd2de", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f6920104fb5c9508e6d866f1b4b1db5ca615e4e02a4f472ad2ca0086184ee2f2a8c594aa39c1c4f5137d369e1ffcf86aced7553b1bc087fa9a619d72e36426bd"} build_url:https://queue.taskcluster.net/v1/task/dep_SHRERLeo_YIjuMtGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011105 build_url: 'https://queue.taskcluster.net/v1/task/dep_SHRERLeo_YIjuMtGkg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/95a8055ba37209688b9ceae1b9270b51c51dc9a9818f60a54021234d6bb0f34a3c8aff147a65f4945345c3922369bc55d1b456d227412ec74bef4402b4e13392", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2ce8d9ab8f908ef8929e42c93ce455ffa71b374c986234ca7621f1d6e494ff3f3e9587ee9d3efeed7c59534d306263fb4bd1aa9e383cae7732e8113081cbd2de", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f6920104fb5c9508e6d866f1b4b1db5ca615e4e02a4f472ad2ca0086184ee2f2a8c594aa39c1c4f5137d369e1ffcf86aced7553b1bc087fa9a619d72e36426bd"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-04 16:52:25.371666) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 16:52:25.372027) ========= 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.LkDfcOQWAm/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.Ac9E8tuFKc/Listeners TMPDIR=/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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.005140 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-04 16:52:25.421026) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-04 16:52:25.421363) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-04 16:52:25.421708) ========= ========= Total master_lag: 0.17 =========