builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0279 starttime: 1472512325.67 results: success (0) buildid: 20160829140030 builduid: c2386aa6b1c245dc9a6da4141a55f29d revision: f33f496ab60c433b68d0b9f8dc2aeb765e92d093 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-29 16:12:05.674467) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-29 16:12:05.674876) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-29 16:12:05.675156) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-29 16:12:05.699845) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 16:12:05.700157) ========= 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.m8opt3Knae/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.p1Rxy3dBrm/Listeners TMPDIR=/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/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-08-29 16:12:05-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.1M=0.001s 2016-08-29 16:12:06 (18.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.391212 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 16:12:06.114281) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 16:12:06.114597) ========= 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.m8opt3Knae/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.p1Rxy3dBrm/Listeners TMPDIR=/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/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.025938 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 16:12:06.165294) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 16:12:06.165633) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev f33f496ab60c433b68d0b9f8dc2aeb765e92d093 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev f33f496ab60c433b68d0b9f8dc2aeb765e92d093 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.m8opt3Knae/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.p1Rxy3dBrm/Listeners TMPDIR=/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/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-08-29 16:12:06,246 truncating revision to first 12 chars 2016-08-29 16:12:06,246 Setting DEBUG logging. 2016-08-29 16:12:06,246 attempt 1/10 2016-08-29 16:12:06,246 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/f33f496ab60c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-29 16:12:06,907 unpacking tar archive at: autoland-f33f496ab60c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.928455 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 16:12:07.114776) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-29 16:12:07.115102) ========= script_repo_revision: f33f496ab60c433b68d0b9f8dc2aeb765e92d093 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-29 16:12:07.115519) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-29 16:12:07.115809) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-29 16:12:07.134304) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 16 secs) (at 2016-08-29 16:12:07.134718) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.m8opt3Knae/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.p1Rxy3dBrm/Listeners TMPDIR=/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/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:12:07 INFO - MultiFileLogger online at 20160829 16:12:07 in /builds/slave/test 16:12:07 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 16:12:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:12:07 INFO - {'append_to_log': False, 16:12:07 INFO - 'base_work_dir': '/builds/slave/test', 16:12:07 INFO - 'blob_upload_branch': 'Autoland', 16:12:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:12:07 INFO - 'branch': 'Autoland', 16:12:07 INFO - 'buildbot_json_path': 'buildprops.json', 16:12:07 INFO - 'config_files': ('talos/mac_config.py',), 16:12:07 INFO - 'default_actions': ('clobber', 16:12:07 INFO - 'read-buildbot-config', 16:12:07 INFO - 'download-and-extract', 16:12:07 INFO - 'populate-webroot', 16:12:07 INFO - 'create-virtualenv', 16:12:07 INFO - 'install', 16:12:07 INFO - 'run-tests'), 16:12:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:12:07 INFO - 'download_minidump_stackwalk': True, 16:12:07 INFO - 'download_symbols': 'ondemand', 16:12:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:12:07 INFO - 'tooltool.py': '/tools/tooltool.py', 16:12:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:12:07 INFO - '/tools/misc-python/virtualenv.py')}, 16:12:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:12:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:12:07 INFO - 'installer_path': 'installer.exe', 16:12:07 INFO - 'log_level': 'info', 16:12:07 INFO - 'log_name': 'talos', 16:12:07 INFO - 'log_to_console': True, 16:12:07 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:12:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:12:07 INFO - 'opt_config_files': (), 16:12:07 INFO - 'pip_index': False, 16:12:07 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:12:07 INFO - 'cmd': ('bash', 16:12:07 INFO - '-c', 16:12:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:12:07 INFO - 'enabled': True, 16:12:07 INFO - 'halt_on_failure': False, 16:12:07 INFO - 'name': 'check_screen_resolution'},), 16:12:07 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:12:07 INFO - 'cmd': ('bash', 16:12:07 INFO - '-c', 16:12:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:12:07 INFO - 'enabled': True, 16:12:07 INFO - 'halt_on_failure': False, 16:12:07 INFO - 'name': 'check_screen_resolution'},), 16:12:07 INFO - 'run_cmd_checks_enabled': True, 16:12:07 INFO - 'sps_profile': False, 16:12:07 INFO - 'sps_profile_interval': 0, 16:12:07 INFO - 'suite': 'dromaeojs', 16:12:07 INFO - 'system_bits': '32', 16:12:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:12:07 INFO - 'title': 't-yosemite-r7-0279', 16:12:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:12:07 INFO - 'use_talos_json': True, 16:12:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:12:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:12:07 INFO - 'work_dir': 'build'} 16:12:07 INFO - [mozharness: 2016-08-29 23:12:07.325935Z] Running clobber step. 16:12:07 INFO - Running pre-action listener: _resource_record_pre_action 16:12:07 INFO - Running main action method: clobber 16:12:07 INFO - rmtree: /builds/slave/test/build 16:12:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:12:09 INFO - Running post-action listener: _resource_record_post_action 16:12:09 INFO - [mozharness: 2016-08-29 23:12:09.444354Z] Finished clobber step (success) 16:12:09 INFO - [mozharness: 2016-08-29 23:12:09.444476Z] Running read-buildbot-config step. 16:12:09 INFO - Running pre-action listener: _resource_record_pre_action 16:12:09 INFO - Running main action method: read_buildbot_config 16:12:09 INFO - Using buildbot properties: 16:12:09 INFO - { 16:12:09 INFO - "project": "", 16:12:09 INFO - "product": "firefox", 16:12:09 INFO - "script_repo_revision": "production", 16:12:09 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 16:12:09 INFO - "repository": "", 16:12:09 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 16:12:09 INFO - "buildid": "20160829140030", 16:12:09 INFO - "pgo_build": "False", 16:12:09 INFO - "basedir": "/builds/slave/test", 16:12:09 INFO - "buildnumber": 162, 16:12:09 INFO - "slavename": "t-yosemite-r7-0279", 16:12:09 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 16:12:09 INFO - "platform": "yosemite_r7", 16:12:09 INFO - "scheduler": "tests-autoland-macosx64-talos", 16:12:09 INFO - "branch": "autoland", 16:12:09 INFO - "repo_path": "integration/autoland", 16:12:09 INFO - "revision": "f33f496ab60c433b68d0b9f8dc2aeb765e92d093", 16:12:09 INFO - "stage_platform": "macosx64", 16:12:09 INFO - "builduid": "c2386aa6b1c245dc9a6da4141a55f29d", 16:12:09 INFO - "slavebuilddir": "test" 16:12:09 INFO - } 16:12:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/cSlvNJf9R7-SlC7kg8mnTA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 16:12:09 INFO - Running post-action listener: _resource_record_post_action 16:12:09 INFO - [mozharness: 2016-08-29 23:12:09.447765Z] Finished read-buildbot-config step (success) 16:12:09 INFO - [mozharness: 2016-08-29 23:12:09.447888Z] Running download-and-extract step. 16:12:09 INFO - Running pre-action listener: _resource_record_pre_action 16:12:09 INFO - Running main action method: download_and_extract 16:12:09 INFO - mkdir: /builds/slave/test/build/tests 16:12:09 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:12:09 INFO - trying https://queue.taskcluster.net/v1/task/cSlvNJf9R7-SlC7kg8mnTA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 16:12:09 INFO - Downloading https://queue.taskcluster.net/v1/task/cSlvNJf9R7-SlC7kg8mnTA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 16:12:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cSlvNJf9R7-SlC7kg8mnTA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 16:12:10 INFO - Downloaded 1199 bytes. 16:12:10 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 16:12:10 INFO - Using the following test package requirements: 16:12:10 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 16:12:10 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:12:10 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 16:12:10 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:12:10 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 16:12:10 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:12:10 INFO - u'jsshell-mac.zip'], 16:12:10 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:12:10 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 16:12:10 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 16:12:10 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:12:10 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 16:12:10 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:12:10 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 16:12:10 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:12:10 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 16:12:10 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:12:10 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 16:12:10 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:12:10 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:12:10 INFO - trying https://queue.taskcluster.net/v1/task/cSlvNJf9R7-SlC7kg8mnTA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 16:12:10 INFO - Downloading https://queue.taskcluster.net/v1/task/cSlvNJf9R7-SlC7kg8mnTA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 16:12:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cSlvNJf9R7-SlC7kg8mnTA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:12:42 WARNING - Socket error when accessing https://queue.taskcluster.net/v1/task/cSlvNJf9R7-SlC7kg8mnTA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip: The read operation timed out 16:12:42 INFO - retry: attempt #1 caught exception: The read operation timed out 16:12:42 INFO - retry: Failed, sleeping 30 seconds before retrying 16:13:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cSlvNJf9R7-SlC7kg8mnTA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #2 16:13:13 INFO - Downloaded 16372696 bytes. 16:13:13 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 16:13:21 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 16:13:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:21 INFO - trying https://queue.taskcluster.net/v1/task/cSlvNJf9R7-SlC7kg8mnTA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 16:13:21 INFO - Downloading https://queue.taskcluster.net/v1/task/cSlvNJf9R7-SlC7kg8mnTA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 16:13:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cSlvNJf9R7-SlC7kg8mnTA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:13:21 INFO - Downloaded 16372696 bytes. 16:13:21 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 16:13: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:28 INFO - trying https://queue.taskcluster.net/v1/task/cSlvNJf9R7-SlC7kg8mnTA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 16:13:28 INFO - Downloading https://queue.taskcluster.net/v1/task/cSlvNJf9R7-SlC7kg8mnTA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 16:13:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cSlvNJf9R7-SlC7kg8mnTA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:13:29 INFO - Downloaded 14413998 bytes. 16:13:29 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 16:13: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:29 INFO - trying https://queue.taskcluster.net/v1/task/cSlvNJf9R7-SlC7kg8mnTA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 16:13:29 INFO - Downloading https://queue.taskcluster.net/v1/task/cSlvNJf9R7-SlC7kg8mnTA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 16:13:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cSlvNJf9R7-SlC7kg8mnTA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 16:13:32 INFO - Downloaded 111560239 bytes. 16:13:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cSlvNJf9R7-SlC7kg8mnTA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 16:13:32 INFO - mkdir: /builds/slave/test/properties 16:13:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:13:32 INFO - Writing to file /builds/slave/test/properties/build_url 16:13:32 INFO - Contents: 16:13:32 INFO - build_url:https://queue.taskcluster.net/v1/task/cSlvNJf9R7-SlC7kg8mnTA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 16:13:33 INFO - Running post-action listener: _resource_record_post_action 16:13:33 INFO - Running post-action listener: set_extra_try_arguments 16:13:33 INFO - [mozharness: 2016-08-29 23:13:33.247946Z] Finished download-and-extract step (success) 16:13:33 INFO - [mozharness: 2016-08-29 23:13:33.248096Z] Running populate-webroot step. 16:13:33 INFO - Running pre-action listener: _resource_record_pre_action 16:13:33 INFO - Running main action method: populate_webroot 16:13:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:13:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:13:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:13:33 INFO - '--tppagecycles', 16:13:33 INFO - '7'], 16:13:33 INFO - 'tests': ['tsvgm']}}, 16:13:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:13:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:13:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:13:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:13:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:13:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:13:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:13:33 INFO - 'talos_options': ['--disable-e10s'], 16:13:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:13:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:13:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:13:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:13:33 INFO - 'talos_options': ['--disable-e10s'], 16:13:33 INFO - 'tests': ['damp', 'tps']}, 16:13:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:13:33 INFO - 'tests': ['damp', 'tps']}, 16:13:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:13:33 INFO - 'tests': ['dromaeo_dom']}, 16:13:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:13:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:13:33 INFO - 'tests': ['basic_compositor_video']}, 16:13:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:13:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:13:33 INFO - 'tests': ['a11yr', 16:13:33 INFO - 'ts_paint', 16:13:33 INFO - 'tpaint', 16:13:33 INFO - 'sessionrestore', 16:13:33 INFO - 'sessionrestore_no_auto_restore', 16:13:33 INFO - 'tabpaint']}, 16:13:33 INFO - 'other-e10s': {'tests': ['a11yr', 16:13:33 INFO - 'ts_paint', 16:13:33 INFO - 'tpaint', 16:13:33 INFO - 'sessionrestore', 16:13:33 INFO - 'sessionrestore_no_auto_restore', 16:13:33 INFO - 'tabpaint']}, 16:13:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:13:33 INFO - 'ts_paint', 16:13:33 INFO - 'tpaint', 16:13:33 INFO - 'sessionrestore', 16:13:33 INFO - 'sessionrestore_no_auto_restore', 16:13:33 INFO - 'tabpaint']}, 16:13:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:13:33 INFO - 'ts_paint', 16:13:33 INFO - 'tpaint', 16:13:33 INFO - 'sessionrestore', 16:13:33 INFO - 'sessionrestore_no_auto_restore', 16:13:33 INFO - 'tabpaint']}, 16:13:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:13:33 INFO - 'tests': ['a11yr', 16:13:33 INFO - 'ts_paint', 16:13:33 INFO - 'tpaint', 16:13:33 INFO - 'sessionrestore', 16:13:33 INFO - 'sessionrestore_no_auto_restore', 16:13:33 INFO - 'tabpaint']}, 16:13:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:13:33 INFO - 'tests': ['a11yr', 16:13:33 INFO - 'ts_paint', 16:13:33 INFO - 'tpaint', 16:13:33 INFO - 'sessionrestore', 16:13:33 INFO - 'sessionrestore_no_auto_restore', 16:13:33 INFO - 'tabpaint']}, 16:13:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:13:33 INFO - 'tests': ['tsvgx', 16:13:33 INFO - 'tsvgr_opacity', 16:13:33 INFO - 'tart', 16:13:33 INFO - 'tscrollx', 16:13:33 INFO - 'cart']}, 16:13:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:13:33 INFO - 'tsvgr_opacity', 16:13:33 INFO - 'tart', 16:13:33 INFO - 'tscrollx', 16:13:33 INFO - 'cart']}, 16:13:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:13:33 INFO - 'talos_options': ['--disable-e10s'], 16:13:33 INFO - 'tests': ['tp5o']}, 16:13:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:13:33 INFO - 'tests': ['tp5o']}, 16:13:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:13:33 INFO - 'talos_options': ['--disable-e10s', 16:13:33 INFO - '--xperf_path', 16:13:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:13:33 INFO - 'tests': ['tp5n']}, 16:13:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:13:33 INFO - 'talos_options': ['--xperf_path', 16:13:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:13:33 INFO - 'tests': ['tp5n']}}, 16:13:33 INFO - 'talos.zip': {'path': '', 16:13:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:13:33 INFO - Running post-action listener: _resource_record_post_action 16:13:33 INFO - [mozharness: 2016-08-29 23:13:33.266690Z] Finished populate-webroot step (success) 16:13:33 INFO - [mozharness: 2016-08-29 23:13:33.266789Z] Running create-virtualenv step. 16:13:33 INFO - Running pre-action listener: _resource_record_pre_action 16:13:33 INFO - Running main action method: create_virtualenv 16:13:33 INFO - Creating virtualenv /builds/slave/test/build/venv 16:13:33 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:13:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:13:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:13:33 INFO - Using real prefix '/tools/python27' 16:13:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:13:34 INFO - Installing distribute.............................................................................................................................................................................................done. 16:13:37 INFO - Installing pip.................done. 16:13:37 INFO - Return code: 0 16:13:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:13:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:13:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:13:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a40da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4a2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf105b1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a371d30>, '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 0x10a0d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a369b20>, '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.m8opt3Knae/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.p1Rxy3dBrm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/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:13:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:13:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:13:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m8opt3Knae/Render', 16:13:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:13:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:13:37 INFO - 'HOME': '/Users/cltbld', 16:13:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:13:37 INFO - 'LOGNAME': 'cltbld', 16:13:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:37 INFO - 'MOZ_NO_REMOTE': '1', 16:13:37 INFO - 'NO_EM_RESTART': '1', 16:13:37 INFO - 'PAGER': '/bin/cat', 16:13:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:13:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:13:37 INFO - 'PWD': '/builds/slave/test', 16:13:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:13:37 INFO - 'SHELL': '/bin/bash', 16:13:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p1Rxy3dBrm/Listeners', 16:13:37 INFO - 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', 16:13:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:13:37 INFO - 'USER': 'cltbld', 16:13:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:13:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:13:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:37 INFO - 'XPC_FLAGS': '0x0', 16:13:37 INFO - 'XPC_SERVICE_NAME': '0', 16:13:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:13:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:13:37 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:13:37 INFO - Cleaning up... 16:13:37 INFO - Return code: 0 16:13:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:13:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:13:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:13:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a40da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4a2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf105b1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a371d30>, '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 0x10a0d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a369b20>, '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.m8opt3Knae/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.p1Rxy3dBrm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/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:13:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:13:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:13:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m8opt3Knae/Render', 16:13:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:13:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:13:37 INFO - 'HOME': '/Users/cltbld', 16:13:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:13:37 INFO - 'LOGNAME': 'cltbld', 16:13:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:37 INFO - 'MOZ_NO_REMOTE': '1', 16:13:37 INFO - 'NO_EM_RESTART': '1', 16:13:37 INFO - 'PAGER': '/bin/cat', 16:13:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:13:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:13:37 INFO - 'PWD': '/builds/slave/test', 16:13:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:13:37 INFO - 'SHELL': '/bin/bash', 16:13:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p1Rxy3dBrm/Listeners', 16:13:37 INFO - 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', 16:13:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:13:37 INFO - 'USER': 'cltbld', 16:13:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:13:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:13:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:37 INFO - 'XPC_FLAGS': '0x0', 16:13:37 INFO - 'XPC_SERVICE_NAME': '0', 16:13:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:13:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:13:37 INFO - Downloading/unpacking psutil>=3.1.1 16:13:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:13:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:13:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:13:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:13:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:13:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:13:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:13:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:13:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:13:40 INFO - Installing collected packages: psutil 16:13:40 INFO - Running setup.py install for psutil 16:13:41 INFO - building 'psutil._psutil_osx' extension 16:13:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 16:13:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 16:13:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 16:13:41 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:13:41 INFO - building 'psutil._psutil_posix' extension 16:13:41 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:13:41 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:13:41 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:13:41 INFO - ^ 16:13:41 INFO - 1 warning generated. 16:13:41 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:13:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:13:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:13:42 INFO - Successfully installed psutil 16:13:42 INFO - Cleaning up... 16:13:42 INFO - Return code: 0 16:13:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:13:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:13:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:13:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a40da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4a2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf105b1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a371d30>, '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 0x10a0d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a369b20>, '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.m8opt3Knae/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.p1Rxy3dBrm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/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:13:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:13:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:13:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m8opt3Knae/Render', 16:13:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:13:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:13:42 INFO - 'HOME': '/Users/cltbld', 16:13:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:13:42 INFO - 'LOGNAME': 'cltbld', 16:13:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:42 INFO - 'MOZ_NO_REMOTE': '1', 16:13:42 INFO - 'NO_EM_RESTART': '1', 16:13:42 INFO - 'PAGER': '/bin/cat', 16:13:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:13:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:13:42 INFO - 'PWD': '/builds/slave/test', 16:13:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:13:42 INFO - 'SHELL': '/bin/bash', 16:13:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p1Rxy3dBrm/Listeners', 16:13:42 INFO - 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', 16:13:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:13:42 INFO - 'USER': 'cltbld', 16:13:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:13:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:13:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:42 INFO - 'XPC_FLAGS': '0x0', 16:13:42 INFO - 'XPC_SERVICE_NAME': '0', 16:13:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:13:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:13:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:13:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:13:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:13:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:13:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:13:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:13:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:13:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:13:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:13:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:13:45 INFO - Installing collected packages: mozsystemmonitor 16:13:45 INFO - Running setup.py install for mozsystemmonitor 16:13:45 INFO - Successfully installed mozsystemmonitor 16:13:45 INFO - Cleaning up... 16:13:45 INFO - Return code: 0 16:13:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:13:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:13:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:13:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a40da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4a2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf105b1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a371d30>, '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 0x10a0d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a369b20>, '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.m8opt3Knae/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.p1Rxy3dBrm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/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:13:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:13:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:13:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m8opt3Knae/Render', 16:13:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:13:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:13:45 INFO - 'HOME': '/Users/cltbld', 16:13:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:13:45 INFO - 'LOGNAME': 'cltbld', 16:13:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:45 INFO - 'MOZ_NO_REMOTE': '1', 16:13:45 INFO - 'NO_EM_RESTART': '1', 16:13:45 INFO - 'PAGER': '/bin/cat', 16:13:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:13:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:13:45 INFO - 'PWD': '/builds/slave/test', 16:13:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:13:45 INFO - 'SHELL': '/bin/bash', 16:13:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p1Rxy3dBrm/Listeners', 16:13:45 INFO - 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', 16:13:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:13:45 INFO - 'USER': 'cltbld', 16:13:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:13:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:13:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:45 INFO - 'XPC_FLAGS': '0x0', 16:13:45 INFO - 'XPC_SERVICE_NAME': '0', 16:13:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:13:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:13:45 INFO - Downloading/unpacking jsonschema==2.5.1 16:13:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:13:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:13:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:13:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:13:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:13:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:13:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:13:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:13:48 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:13:48 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:13:48 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:13:48 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:13:48 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:13:48 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:13:48 INFO - Downloading functools32-3.2.3-2.tar.gz 16:13:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:13:48 INFO - warning: no files found matching '*.txt' 16:13:48 INFO - no previously-included directories found matching 'build' 16:13:48 INFO - no previously-included directories found matching 'dist' 16:13:48 INFO - no previously-included directories found matching '.git*' 16:13:48 INFO - Installing collected packages: jsonschema, functools32 16:13:48 INFO - Running setup.py install for functools32 16:13:48 INFO - warning: no files found matching '*.txt' 16:13:48 INFO - no previously-included directories found matching 'build' 16:13:48 INFO - no previously-included directories found matching 'dist' 16:13:48 INFO - no previously-included directories found matching '.git*' 16:13:48 INFO - Successfully installed jsonschema functools32 16:13:48 INFO - Cleaning up... 16:13:48 INFO - Return code: 0 16:13:48 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:13:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:13:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:13:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:48 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a40da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4a2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf105b1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a371d30>, '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 0x10a0d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a369b20>, '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.m8opt3Knae/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.p1Rxy3dBrm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/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:13:48 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:13:48 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 functools32==3.2.3-2 16:13:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m8opt3Knae/Render', 16:13:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:13:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:13:48 INFO - 'HOME': '/Users/cltbld', 16:13:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:13:48 INFO - 'LOGNAME': 'cltbld', 16:13:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:48 INFO - 'MOZ_NO_REMOTE': '1', 16:13:48 INFO - 'NO_EM_RESTART': '1', 16:13:48 INFO - 'PAGER': '/bin/cat', 16:13:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:13:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:13:48 INFO - 'PWD': '/builds/slave/test', 16:13:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:13:48 INFO - 'SHELL': '/bin/bash', 16:13:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p1Rxy3dBrm/Listeners', 16:13:48 INFO - 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', 16:13:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:13:48 INFO - 'USER': 'cltbld', 16:13:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:13:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:13:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:48 INFO - 'XPC_FLAGS': '0x0', 16:13:48 INFO - 'XPC_SERVICE_NAME': '0', 16:13:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:13:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:13:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:13:49 INFO - Cleaning up... 16:13:49 INFO - Return code: 0 16:13:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:13: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:13:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:13:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a40da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4a2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf105b1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a371d30>, '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 0x10a0d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a369b20>, '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.m8opt3Knae/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.p1Rxy3dBrm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/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:13:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:13:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:13:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m8opt3Knae/Render', 16:13:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:13:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:13:49 INFO - 'HOME': '/Users/cltbld', 16:13:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:13:49 INFO - 'LOGNAME': 'cltbld', 16:13:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:49 INFO - 'MOZ_NO_REMOTE': '1', 16:13:49 INFO - 'NO_EM_RESTART': '1', 16:13:49 INFO - 'PAGER': '/bin/cat', 16:13:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:13:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:13:49 INFO - 'PWD': '/builds/slave/test', 16:13:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:13:49 INFO - 'SHELL': '/bin/bash', 16:13:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p1Rxy3dBrm/Listeners', 16:13:49 INFO - 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', 16:13:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:13:49 INFO - 'USER': 'cltbld', 16:13:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:13:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:13:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:49 INFO - 'XPC_FLAGS': '0x0', 16:13:49 INFO - 'XPC_SERVICE_NAME': '0', 16:13:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:13:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:13:49 INFO - Downloading/unpacking blobuploader==1.2.4 16:13:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:13:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:13:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:13:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:13:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:13:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:13:51 INFO - Downloading blobuploader-1.2.4.tar.gz 16:13:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:13:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:13:51 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:13:51 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:13:51 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:13:51 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:13:51 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:13:51 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:13:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:13:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:13: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:13: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:13: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:13: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:13: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:13: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:13:52 INFO - Downloading docopt-0.6.1.tar.gz 16:13:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:13:53 INFO - Installing collected packages: blobuploader, requests, docopt 16:13:53 INFO - Running setup.py install for blobuploader 16:13:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:13:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:13:53 INFO - Running setup.py install for requests 16:13:53 INFO - Running setup.py install for docopt 16:13:53 INFO - Successfully installed blobuploader requests docopt 16:13:53 INFO - Cleaning up... 16:13:54 INFO - Return code: 0 16:13:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:13:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:13:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:13:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a40da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4a2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf105b1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a371d30>, '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 0x10a0d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a369b20>, '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.m8opt3Knae/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.p1Rxy3dBrm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/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:13:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:13:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:13:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m8opt3Knae/Render', 16:13:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:13:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:13:54 INFO - 'HOME': '/Users/cltbld', 16:13:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:13:54 INFO - 'LOGNAME': 'cltbld', 16:13:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:54 INFO - 'MOZ_NO_REMOTE': '1', 16:13:54 INFO - 'NO_EM_RESTART': '1', 16:13:54 INFO - 'PAGER': '/bin/cat', 16:13:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:13:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:13:54 INFO - 'PWD': '/builds/slave/test', 16:13:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:13:54 INFO - 'SHELL': '/bin/bash', 16:13:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p1Rxy3dBrm/Listeners', 16:13:54 INFO - 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', 16:13:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:13:54 INFO - 'USER': 'cltbld', 16:13:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:13:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:13:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:54 INFO - 'XPC_FLAGS': '0x0', 16:13:54 INFO - 'XPC_SERVICE_NAME': '0', 16:13:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:13:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:13:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:13:54 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-fsDif0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:13:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:13:54 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-j2uPYy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:13:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:13:54 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-VolOHe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:13:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:13:54 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-98AMzA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:13:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:13:54 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-NuSmBR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:13:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:13:54 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-rZM91m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:13:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:13:54 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-ZO6Oxz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:13:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:13:55 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-4vH67D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:13:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:13:55 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-fgm_4U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:13:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:13:55 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-bWWd5s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:13:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:13:55 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-I8CL1d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:13:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:13:55 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-zEx90O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:13:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:13:55 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-4ttv13-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:13:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:13:55 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-1rnMt_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:13:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:13:55 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-vQzxb8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:13:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:13:55 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-gf3Wdr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:13:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:13:55 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-A8E8bw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:13:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:13:56 INFO - Running setup.py install for manifestparser 16:13:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:13:56 INFO - Running setup.py install for mozcrash 16:13:56 INFO - Running setup.py install for mozdebug 16:13:56 INFO - Running setup.py install for mozdevice 16:13:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:13:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:13:56 INFO - Running setup.py install for mozfile 16:13:56 INFO - Running setup.py install for mozhttpd 16:13:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:13:57 INFO - Running setup.py install for mozinfo 16:13:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:13:57 INFO - Running setup.py install for mozInstall 16:13:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:13:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:13:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:13:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:13:57 INFO - Running setup.py install for mozleak 16:13:57 INFO - Running setup.py install for mozlog 16:13:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:13:57 INFO - Running setup.py install for moznetwork 16:13:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:13:57 INFO - Running setup.py install for mozprocess 16:13:57 INFO - Running setup.py install for mozprofile 16:13:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:13:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:13:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:13:58 INFO - Running setup.py install for mozrunner 16:13:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:13:58 INFO - Running setup.py install for mozscreenshot 16:13:58 INFO - Running setup.py install for moztest 16:13:58 INFO - Running setup.py install for mozversion 16:13:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:13:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:13:58 INFO - Cleaning up... 16:13:58 INFO - Return code: 0 16:13:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:13: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:13:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:13:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:58 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 0x10a40da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4a2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf105b1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a371d30>, '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 0x10a0d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a369b20>, '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.m8opt3Knae/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.p1Rxy3dBrm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/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:13:58 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:13:58 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:13:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m8opt3Knae/Render', 16:13:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:13:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:13:58 INFO - 'HOME': '/Users/cltbld', 16:13:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:13:58 INFO - 'LOGNAME': 'cltbld', 16:13:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:58 INFO - 'MOZ_NO_REMOTE': '1', 16:13:58 INFO - 'NO_EM_RESTART': '1', 16:13:58 INFO - 'PAGER': '/bin/cat', 16:13:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:13:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:13:58 INFO - 'PWD': '/builds/slave/test', 16:13:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:13:58 INFO - 'SHELL': '/bin/bash', 16:13:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p1Rxy3dBrm/Listeners', 16:13:58 INFO - 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', 16:13:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:13:58 INFO - 'USER': 'cltbld', 16:13:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:13:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:13:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:58 INFO - 'XPC_FLAGS': '0x0', 16:13:58 INFO - 'XPC_SERVICE_NAME': '0', 16:13:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:13:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:13:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:13:58 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-8itWDu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:13:59 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 16:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:13:59 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-qPcoAN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:13:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:13:59 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-ye3vQc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:13:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:13:59 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-pnDBSW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:13:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:13:59 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-azfzUY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:13:59 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:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:13:59 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-o9VR0g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:13:59 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:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:13:59 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-EeZjHg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:13:59 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:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:13:59 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-zG9xeg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:13:59 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:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:13:59 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-BKLbQ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:13:59 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:13:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:13:59 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-oRBZwt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:14:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:14:00 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-yZSYJL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:14:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:14:00 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-_Fojhn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:14:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:14:00 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-4gjMxu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:14:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:14:00 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-tjUtag-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:14:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:14:00 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-msgguD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:14:00 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:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:14:00 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-apmmM5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:14:00 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:14:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:14:00 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-7ZFZb3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:14:00 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:14:00 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:14:00 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:14:00 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:14:00 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:14:00 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:14:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:14:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:03 INFO - Downloading blessings-1.6.tar.gz 16:14:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:14:03 INFO - Installing collected packages: blessings 16:14:03 INFO - Running setup.py install for blessings 16:14:03 INFO - Successfully installed blessings 16:14:03 INFO - Cleaning up... 16:14:03 INFO - Return code: 0 16:14:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:14:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:14:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:14:03 INFO - Reading from file tmpfile_stdout 16:14:03 INFO - Current package versions: 16:14:03 INFO - blessings == 1.6 16:14:03 INFO - blobuploader == 1.2.4 16:14:03 INFO - docopt == 0.6.1 16:14:03 INFO - functools32 == 3.2.3-2 16:14:03 INFO - jsonschema == 2.5.1 16:14:03 INFO - manifestparser == 1.1 16:14:03 INFO - mozInstall == 1.12 16:14:03 INFO - mozcrash == 0.17 16:14:03 INFO - mozdebug == 0.1 16:14:03 INFO - mozdevice == 0.48 16:14:03 INFO - mozfile == 1.2 16:14:03 INFO - mozhttpd == 0.7 16:14:03 INFO - mozinfo == 0.9 16:14:03 INFO - mozleak == 0.1 16:14:03 INFO - mozlog == 3.3 16:14:03 INFO - moznetwork == 0.27 16:14:03 INFO - mozprocess == 0.23 16:14:03 INFO - mozprofile == 0.28 16:14:03 INFO - mozrunner == 6.12 16:14:03 INFO - mozscreenshot == 0.1 16:14:03 INFO - mozsystemmonitor == 0.3 16:14:03 INFO - moztest == 0.7 16:14:03 INFO - mozversion == 1.4 16:14:03 INFO - psutil == 3.1.1 16:14:03 INFO - requests == 1.2.3 16:14:03 INFO - wsgiref == 0.1.2 16:14:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:14:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:14:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:14:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:14:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:14:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:03 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 0x10a40da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4a2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf105b1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a371d30>, '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 0x10a0d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a369b20>, '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.m8opt3Knae/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.p1Rxy3dBrm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/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:14:03 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:14:03 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:14:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m8opt3Knae/Render', 16:14:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:14:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:14:03 INFO - 'HOME': '/Users/cltbld', 16:14:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:14:03 INFO - 'LOGNAME': 'cltbld', 16:14:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:03 INFO - 'MOZ_NO_REMOTE': '1', 16:14:03 INFO - 'NO_EM_RESTART': '1', 16:14:03 INFO - 'PAGER': '/bin/cat', 16:14:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:14:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:14:03 INFO - 'PWD': '/builds/slave/test', 16:14:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:14:03 INFO - 'SHELL': '/bin/bash', 16:14:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p1Rxy3dBrm/Listeners', 16:14:03 INFO - 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', 16:14:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:14:03 INFO - 'USER': 'cltbld', 16:14:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:14:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:14:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:03 INFO - 'XPC_FLAGS': '0x0', 16:14:03 INFO - 'XPC_SERVICE_NAME': '0', 16:14:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:14:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:14:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:14:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 16:14:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 16:14:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:14:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 16:14:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 16:14:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 16:14:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 16:14:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 16:14:04 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:14:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:14:04 INFO - Cleaning up... 16:14:04 INFO - Return code: 0 16:14:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:14:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:14:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:14:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:14:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:14:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a40da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4a2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf105b1ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a371d30>, '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 0x10a0d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a369b20>, '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.m8opt3Knae/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.p1Rxy3dBrm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/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:14:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:14:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:14:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m8opt3Knae/Render', 16:14:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:14:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:14:04 INFO - 'HOME': '/Users/cltbld', 16:14:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:14:04 INFO - 'LOGNAME': 'cltbld', 16:14:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:04 INFO - 'MOZ_NO_REMOTE': '1', 16:14:04 INFO - 'NO_EM_RESTART': '1', 16:14:04 INFO - 'PAGER': '/bin/cat', 16:14:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:14:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:14:04 INFO - 'PWD': '/builds/slave/test', 16:14:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:14:04 INFO - 'SHELL': '/bin/bash', 16:14:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p1Rxy3dBrm/Listeners', 16:14:04 INFO - 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', 16:14:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:14:04 INFO - 'USER': 'cltbld', 16:14:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:14:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:14:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:04 INFO - 'XPC_FLAGS': '0x0', 16:14:04 INFO - 'XPC_SERVICE_NAME': '0', 16:14:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:14:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:14:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:14:04 INFO - Cleaning up... 16:14:04 INFO - Return code: 0 16:14:04 INFO - Running post-action listener: _resource_record_post_action 16:14:04 INFO - Running post-action listener: _start_resource_monitoring 16:14:04 INFO - Starting resource monitoring. 16:14:04 INFO - [mozharness: 2016-08-29 23:14:04.511116Z] Finished create-virtualenv step (success) 16:14:04 INFO - [mozharness: 2016-08-29 23:14:04.512140Z] Running install step. 16:14:04 INFO - Running pre-action listener: _resource_record_pre_action 16:14:04 INFO - Running main action method: install 16:14:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:14:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:14:04 INFO - Reading from file tmpfile_stdout 16:14:04 INFO - Detecting whether we're running mozinstall >=1.0... 16:14:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:14:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:14:04 INFO - Reading from file tmpfile_stdout 16:14:04 INFO - Output received: 16:14:04 INFO - Usage: mozinstall [options] installer 16:14:04 INFO - Options: 16:14:04 INFO - -h, --help show this help message and exit 16:14:04 INFO - -d DEST, --destination=DEST 16:14:04 INFO - Directory to install application into. [default: 16:14:04 INFO - "/builds/slave/test"] 16:14:04 INFO - --app=APP Application being installed. [default: firefox] 16:14:04 INFO - mkdir: /builds/slave/test/build/application 16:14:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:14:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 16:14:39 INFO - Reading from file tmpfile_stdout 16:14:39 INFO - Output received: 16:14:39 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:14:39 INFO - Running post-action listener: _resource_record_post_action 16:14:39 INFO - [mozharness: 2016-08-29 23:14:39.980826Z] Finished install step (success) 16:14:39 INFO - [mozharness: 2016-08-29 23:14:39.980964Z] Running run-tests step. 16:14:39 INFO - Running pre-action listener: _resource_record_pre_action 16:14:39 INFO - Running main action method: run_tests 16:14:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:14:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:14:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:14:39 INFO - 2016-08-29 16:14:39.995 screenresolution[1634:7695] starting screenresolution argv=screenresolution get 16:14:39 INFO - 2016-08-29 16:14:39.998 screenresolution[1634:7695] Display 0: 1600x1200x32@60 16:14:40 INFO - 2016-08-29 16:14:40.004 screenresolution[1635:7697] starting screenresolution argv=screenresolution list 16:14:40 INFO - Available Modes on Display 0 16:14:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:14:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:14:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:14:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:14:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:14:40 INFO - Intel Iris: 16:14:40 INFO - Chipset Model: Intel Iris 16:14:40 INFO - Type: GPU 16:14:40 INFO - Bus: Built-In 16:14:40 INFO - VRAM (Dynamic, Max): 1536 MB 16:14:40 INFO - Vendor: Intel (0x8086) 16:14:40 INFO - Device ID: 0x0a2e 16:14:40 INFO - Revision ID: 0x0009 16:14:40 INFO - Displays: 16:14:40 INFO - Display: 16:14:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:14:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:14:40 INFO - Display Serial Number: 1600x1200 60 16:14:40 INFO - Main Display: Yes 16:14:40 INFO - Mirror: Off 16:14:40 INFO - Online: Yes 16:14:40 INFO - Rotation: Supported 16:14:40 INFO - Return code: 0 16:14:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:14:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:14:40 INFO - Python 2.7.3 16:14:40 INFO - Return code: 0 16:14:40 INFO - grabbing minidump binary from tooltool 16:14:40 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:14:40 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 0x10a371d30>, '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 0x10a0d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a369b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:14:40 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:14:40 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:14:40 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:14:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:14:40 INFO - Return code: 0 16:14:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:14:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:14:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:14:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:14:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:14:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cSlvNJf9R7-SlC7kg8mnTA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0279', '--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:14:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cSlvNJf9R7-SlC7kg8mnTA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0279 --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:14:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m8opt3Knae/Render', 16:14:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:14:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:14:40 INFO - 'HOME': '/Users/cltbld', 16:14:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:14:40 INFO - 'LOGNAME': 'cltbld', 16:14:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:14:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:14:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:40 INFO - 'MOZ_NO_REMOTE': '1', 16:14:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:14:40 INFO - 'NO_EM_RESTART': '1', 16:14:40 INFO - 'PAGER': '/bin/cat', 16:14:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:14:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:14:40 INFO - 'PWD': '/builds/slave/test', 16:14:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:14:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:14:40 INFO - 'SHELL': '/bin/bash', 16:14:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p1Rxy3dBrm/Listeners', 16:14:40 INFO - 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', 16:14:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:14:40 INFO - 'USER': 'cltbld', 16:14:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:14:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:14:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:40 INFO - 'XPC_FLAGS': '0x0', 16:14:40 INFO - 'XPC_SERVICE_NAME': '0', 16:14:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:14:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cSlvNJf9R7-SlC7kg8mnTA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0279', '--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:14:40 INFO - mozversion application_buildid: 20160829140030 16:14:40 INFO - mozversion application_changeset: f33f496ab60c433b68d0b9f8dc2aeb765e92d093 16:14:40 INFO - mozversion application_display_name: Nightly 16:14:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:14:40 INFO - mozversion application_name: Firefox 16:14:40 INFO - mozversion application_remotingname: firefox 16:14:40 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 16:14:40 INFO - mozversion application_vendor: Mozilla 16:14:40 INFO - mozversion application_version: 51.0a1 16:14:40 INFO - mozversion platform_buildid: 20160829140030 16:14:40 INFO - mozversion platform_changeset: f33f496ab60c433b68d0b9f8dc2aeb765e92d093 16:14:40 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 16:14:40 INFO - mozversion platform_version: 51.0a1 16:14:40 INFO - using testdate: 1472512480 16:14:40 INFO - actual date: 1472512480 16:14:40 INFO - starting webserver on 'localhost:49265' 16:14:40 INFO - SUITE-START | Running 2 tests 16:14:40 INFO - TEST-START | dromaeo_css 16:14:40 INFO - operating with platform_type : mac_ 16:14:40 INFO - Initialising browser for dromaeo_css test... 16:14:40 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/tmpRGv6uc/profile http://localhost:49265/getInfo.html) 16:14:43 INFO - PROCESS | 1645 | __metrics Screen width/height:1600/1200 16:14:43 INFO - PROCESS | 1645 | colorDepth:24 16:14:43 INFO - PROCESS | 1645 | Browser inner width/height: 1024/689 16:14:43 INFO - PROCESS | 1645 | __metrics 16:14:43 INFO - PROCESS | 1645 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:14:43 INFO - PROCESS | 1645 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:14:44 INFO - TEST-INFO | 1645: exit 0 16:14:44 INFO - Browser initialized. 16:14:44 INFO - Running cycle 1/1 for dromaeo_css test... 16:14:44 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/tmpRGv6uc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:14:56 INFO - PROCESS | 1647 | RSS: Main: 234557440 16:14:56 INFO - PROCESS | 1647 | 16:15:47 INFO - PROCESS | 1647 | 1472512547165 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:17:59 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49265/tests/dromaeo/cssquery-ext.html) 16:18:00 INFO - PROCESS | 1647 | RSS: Main: 373612544 16:18:00 INFO - PROCESS | 1647 | 16:21:03 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-ext.html (next: http://localhost:49265/tests/dromaeo/cssquery-jquery.html) 16:21:04 INFO - PROCESS | 1647 | RSS: Main: 378834944 16:21:04 INFO - PROCESS | 1647 | 16:24:06 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49265/tests/dromaeo/cssquery-mootools.html) 16:24:07 INFO - PROCESS | 1647 | RSS: Main: 337285120 16:24:07 INFO - PROCESS | 1647 | 16:27:09 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49265/tests/dromaeo/cssquery-prototype.html) 16:27:10 INFO - PROCESS | 1647 | RSS: Main: 348155904 16:27:10 INFO - PROCESS | 1647 | 16:30:13 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49265/tests/dromaeo/cssquery-yui.html) 16:30:14 INFO - PROCESS | 1647 | RSS: Main: 326770688 16:30:14 INFO - PROCESS | 1647 | 16:33:17 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-yui.html (next: http://localhost:49265/tests/dromaeo/cssquery-dojo.html) 16:33:17 INFO - PROCESS | 1647 | RSS: Main: 473997312 16:33:17 INFO - PROCESS | 1647 | 16:33:17 INFO - PROCESS | 1647 | __start_tp_report 16:33:17 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 16:33:17 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 16:33:17 INFO - PROCESS | 1647 | |i|pagename|runs| 16:33:17 INFO - PROCESS | 1647 | |0;dojo.html;2762.944741105178;3275.557799697042;3317.1209629448185;3321.966780332196;3386.0349800306917;5421.484958928905;5431.809886653963;5508.173773933912;5533.031719449095;5551.77792888285;4594.196015697256;5502;5528.391876893538;5530.723463826813;5541.501264886159;5065.316182315396;5075.4163271223915;5091.0581542414775;5140.7172605506685;5149.897002059956;5096.79612815487;5157.252198431225;5160.612954028463;5164.561012313958;5170.2503137045105;5068.341115654978;5073.162928116856;5114.258432527281;5114.923276150861;5155.81954631588;5141.511556402154;5172.302513665537;5173;5185.948140518622;5231.555317797992;4968.205087186033;4987.376577927759;5031.572316353115;5099.209622508517;5099.847004589868;5465.617406781527;5539.169124631298;5556.444355564452;5563.165525171216;5573.359063707644;6058.363871793486;6085.421884920926;6085.817425477243;6096.634201947897;6099.268087829444;5994.490468310199;6048.818535443944;6078.635281883057;6154.784582539634;6169.845753856144;5552.861178470571;5610.523105535994;5617.21359009739;5675.801346952837;5677.37548869624;6248.0468653118605;6262.780802671885;6265.467435267962;6295.937040629627;6299.779507717208;5790.073588225948;5887.882242355129;5927.644341339534;5934.3175534814045;5936.643801371845;6087.05650624154;6096.298925623521;6118.010103775246;6145.877082458326;6146.078088286793;5927.911081333784;6005.039193728982;6014.1580178775;6017.458428741346;6071.969640151771;5874.794382196688;5891.646501209942;6074.54440916933;6097.298810636745;6103.450689437972;4774.427068751772;4778.546038126373;4788.281757736298;4789.616830653539;4796.544328288807;4304.225239456865;4334.13317336534;4349.304111342233;4350.260455722535;4357.302831546937;5498.697571633598;5505.394406615269;5525.613207075547;5527.198556209408;5597.412271711493;6188.226471691039;6196.845078873064;6202.131701561785;6212.4408803207925;6213.844653883689;5592.832215033555;5592.972035139798;5594.216809646653;5597.972010140004;5626.352969408488;5228.973855130776;5254.684718916801;5257.76340064698;5299.682019078868;5314.441983591667;6223.688815559204;6255.562110652301;6288.213973253343;6290.685465726604;6291.937080629226;6185.41238582352;6190.350013248544;6194.194754681863;6194.504439644727;6205.472534834409;6309.90535141964;6319.747210111728;6333.20834895638;6334.049892516159;6336.7465301389275;5834.299884013945;5904.675242861684;5952.107183922273;5967.6419414835245;5975;6100.611450805989;6119.112728654409;6127.953177891246;6131.193168498027;6136.519078024374;4957.652964292465;5745.454181852718;5833.562482813891;5857.824265272048;5957.851053723692;5750.6262092963825;5808.099744539603;5857.26784151981;5883.558733094949;5890.263717035371;5734.741936612946;5767.769289228384;5775.104858746901;5813.82558523245;5831.271091113611;5898;5907.556933229939;5919.112133180058;5926.081457374114;5940.821775346746;5626.88746225065;5690.914636280542;5718.085106383125;5728.627639203439;5755.597108202385 16:33:17 INFO - PROCESS | 1647 | |1;ext.html;469.4413647759166;484.176899271239;509.7247486357367;510.85058618109275;533.2294833964921;30098.548521772165;30354.241143971383;39294.42822287105;40176.59823401765;40364.596354036454;41716.79141604288;41839.163216735644;41951.99999999993;42073.73778786634;42223;9557.044295570457;9752.951235243814;9760.316777825556;9764.414135151914;9782.41305521671;8700.825983480327;8923;11655.125865560074;11929.58246461374;11949.58176463824;11845;11901.94049029758;11946.761064778699;11947.283163010205;11952.282863028202;11896.82154767675;11992.520299188021;12001.939990300036;12005.039596832232;12010;11682.474288657031;11765.529378824836;11773.175877688565;11775.764484710344;11789;33085.503717444255;38209;39070.02324941871;39169;39301.803490982646;3335.6497567755277;3387.5257463955068;3410.334984677964;3411.8635254589785;3519.225770330523;30631.540526892113;31518;31632;31732.88934887268;32001.679983200334;3155.368926214743;3224.629167645727;3271.1167984644253;3324.6010478742705;3390.1524618845287;494325;495150;497246;497312;497404;59974.70012649909;60239.698801505714;60276.698616506634;60373;60396.69801650964;62830.68584657047;62836.68581657062;62877;62893;63038;16213.270402831666;16357.918210408872;16363.509094727176;16377.754333685005;16381.344746210256;11469.885301146882;11631.243969142148;12673.493060277486;12695.555655552196;12718;14789.260536973323;14855.554333370017;14864.331105100297;16668.833311666727;17012.57468563271;6330.556861019684;6333.46165575922;6361.586496877688;6423.678816059179;6460.192475940507;851.8892543969245;916.9770755731026;933.9206167475704;1063.3407287481812;1095.6986828622194;29112.272193194916;29603;29674;30191.849040754656;30317;1004.467632154959;1011.145581983223;1049.8530205771199;1128.2215271462665;1131.1403333466624;15175.772363414557;15437.614059648375;15514.9224253878;15890.44383446597;15915.283812228477;624579;627640;629225;631717.6828231751;631786;49643.50356496461;50743.74628126836;57065;58053.12920306199;58072;1316.644505983379;1353.2421843767522;1369.2400717601827;1374.7456720506739;1378.4348417148922;29559.556606650487;30283.69716302897;31342.52986205163;31933.840330798197;31990;53377.73311133419;56513.15230271467;56557.15164272622;56613;56631.43368566426;402930;412098;414622;501752;502120;25965.091221807146;26293;26304.4739105213;26388.340291492867;26457.470850582496;46840.53159468498;47402.288965664855;47940.041199175124;48295.5170448291;49078.5092149074;33063.17342066467;39927.600723992386;40456.59543404528;40503.596406577475;41145.17709645731;38875.80562097171;39041.60958390493;39599;39709.80145099256;40441.98895027648;38152.61847381491;38276.425853612775;39091;39121.217575648894;39151.2169756609;33016.83491582623;34332.14169645779;35009.64990350064;35081.64918350784;35193.648063520064;25192.874035629706;25428.110016149345;25477.872610636827;26312.473750525267;26702.59946100771 16:33:17 INFO - PROCESS | 1647 | |2;jquery.html;344.2237753865034;363.1031352559179;365.46459436924897;378.17369048628746;395.4957429277671;15530.84469155308;16330.346786128555;19866.92727990762;20364.287249946254;20460.795392046093;19817;20447.386578402635;21381.786182138174;21491.67762483564;21689.783102168974;5478.287822583068;5656.717164141797;5661.46216109471;5707.942920570804;6147.60040597362;6064.545159113062;6174.012158054689;6181.258249010125;6185.907211391833;6191.969040154793;3948.545917219528;5877.089051197071;6129.662868542228;6184.5052395808225;6242.875142497147;5707.571932105088;5865.912011319833;5926.614770039977;6127;6209.285932117774;6016.639001659871;6017.338092809788;6018.247719035121;6026.819195424145;6037.064255040432;13504.729905401935;23260.53478930429;25421.364465888317;26099.347516312053;26725.465490690272;2254.5716313900307;2286.931392058241;2407.4703565215623;2459.1761759810483;2501.2871331670385;9569.37799043067;10032.79934401308;10108.545115469822;10446.582136714522;10523.684289471328;1704.9403270885584;1822.2346614421974;1879.4173806120145;1886.6608891412416;1912.2733361322748;100570.49714751453;123419;129330.35334823359;129618;131873.34063329716;11033.062189713964;11054.392008439612;11395.886041139649;11420.657380278604;11618.825717614243;14457.98794084404;14472.782908256384;14487.637809054859;14505.492307769178;14508;8164.75505734817;8213.137620549876;8483.915160848312;8501.429479034303;8654.35092368075;8495.235428811437;8506.532140732319;8510.063892971768;8684.913150868537;8717.389782715276;3793.5447746270625;4397.978010109929;4507.864764057083;4565.634749220055;4613.907721845544;1820.4902627264387;1877.6995680691298;1877.7183422486462;1878.1454438230571;1888.4712280561464;804.3324041045918;866.9826603467895;867.5185272173878;894.0657013420861;989.0949780950493;20587.79412205859;21389;21459.248926287506;21508.89245553762;21545.49181557276;894.4275663575187;911.1389736698756;929.7303781903307;931.9394239374419;978.8384916488842;7840.490368126168;11079.889201107886;11185.217034807649;11302.604408845651;11314.717132071764;543586;549463;556737;559560;561019;89094;129531;129646;131441;131509.34245328713;910.4764760262743;937.1612406895927;940.5438362394233;941.0636416765362;949.2927768812141;16575.91712041432;19025;19071.33250336287;19433.611327773648;19546;38474.61525384822;47430;50181.74909125431;53501.732491337294;54771.45228547663;69734;76692;77222.84165737631;77777;78693.2130678686;7028.262032486516;7241.384482318851;7253.56478611285;7440.218777028334;7888.408369372435;11457.885421145682;12713;12898.742025159632;13179.209247445184;13208.735825283633;5426.755795989111;5505.834824955257;11069.446527673423;11108.333499990027;11437.313761174357;10144.087032167141;11022.228444008842;11384.544618215179;11414.82877756851;11420.371879546705;11115.333080015225;11135.83296250573;11307.830382544433;11624.745071380026;11700.47347869365;7024.859502810017;7109.82225444368;7233.4574906883245;7234.131904171322;7359.521631093961;10385.480725963822;11073.446327683423;11385.430728463378;11431.771364572829;11566.826497602375 16:33:17 INFO - PROCESS | 1647 | |3;mootools.html;1346.2259200959452;1400.6133927411863;1532.8467153284669;1727.2745446912304;1945.0566475259504;7854;8665.350098742589;8929.732108036753;8931.972823125334;9052.90947090529;8793.912060879406;8883.55582220888;8883.911160888405;8893.91106088939;8910.376273660846;2366.4438856868624;2390.5457962986975;2390.8565486070893;2391.892364843586;2396.7842894139526;2331.650252462136;2375.0262392419113;2377.405648587853;2378.120095564635;2382.17814853876;2337.590921588715;2356.15178535727;2363.669086327907;2379.809615230786;2380.8095352371856;2303.6544518322385;2326.441654003044;2342.2856028911174;2343.2267351773876;2345.5308938212256;2004.959900801976;2328.382978510696;2332.1137967572386;2335.649652552114;2341.6604592334015;8644.783880402978;8840.8673869892;8871.112888711143;8935.419197752126;8935.463872167626;4496.348029535699;5435.537979271767;5437.619366644337;5439.755211015514;5442.673439593637;3502.317048175607;3503.509508668763;3569.4824250483534;3574.392353299945;3586.372384832644;1070.7216123807789;1093.2675107677876;1102.9834552481718;1105.6682995101519;1119.283658458587;11899.524019039229;11935.403229838474;11981.28112313264;11998;12017.399130043463;4399.5820397062225;4486.371907932892;4646.395968524138;4666.0901124280435;4674.883127921829;9281.95359023214;9282.443053416682;9284.62861485533;9292.39599426035;9292.442453452815;5304.575633949275;5304.761285742151;5309.150535914312;5309.522143007071;5310.522053015247;4337.349397590387;4447.3773671685985;4604.217283061887;4934.18585933317;4972.328735620645;3703.388940824788;3703.4629978652943;3792.50697409335;3794.1652836376506;3801.9619803801606;954.7994921066654;1131.9490622921987;1140.2645293785463;1142.782871254459;1148.1675785055934;850.1753299299669;865.9350548708873;873.7859224489913;920.2836709536666;958.7459323279337;5644.5484361251;5721.1990321354215;5953.821385358447;6088.878222435526;6148.631082135086;368.40134780980907;432.57607544606464;441.58270434439146;450.776865451597;450.88051666308456;3637.6907962823457;3913.412988051815;3933.9968308081257;3938.5667576566557;4037.3338399164395;9551.856722149174;10631.734206644896;11021.614243501439;11048.06091482233;11113.94443027796;9396.624135034659;11007.724806879893;11019.669409917715;11022.669319920415;11109.166812489095;8330.791730206793;8387.83224335522;8410.915890841014;8411.83176336482;8414.831703365899;4817.7350245736825;5070.011347787146;5917.5857689961285;5931.436513531207;6088.665123418255;9538.427694338361;9956.701298961043;10137.391756494633;10219.948900255451;10268.897311026794;9324.813503730024;9714.75713107178;10095.89904100979;10281.17750579937;10429.322094063862;6632.801015969529;6641.900371494528;6828.248892621708;7064.187618423947;7066.681999310146;8139.552324622203;8298.294644955005;8396.118407567117;8414.284785793032;8427.283680886949;7568.5837278950185;7570.9242907570215;7593.126790419173;7685.154632990255;7752.573608451589;7416.8145796353365;7450.813729656802;7467.887981680171;7475.7009719610605;7560.3573696236435;6703.363180497845;6937.236903940663;7033.120859892513;7109.608971506616;7117.786466406016;5031.3207716959205;5095.77069031887;5108.719020453762;5137.768800404066;5214.661047031931;4845.055214233191;4994.450610432777;5173.120569503269;5220.3213582235585;5307.230451584576 16:33:17 INFO - PROCESS | 1647 | |4;prototype.html;253.78681907197955;292.86820930581246;303.8966751304556;308.07116543620975;309.3796937141032;4460.620847227986;5067.873303167418;5161.071007218699;5203.271541984125;5209.7134657593815;4813.903721925568;4898.730569818658;5048.621353398491;5107.4892510748905;5178.14560597501;3489.441689329708;3917.9020524486973;3950.8740009097414;3966.603339666032;4013.71561100448;3934.6655534279475;3973.582773808752;4010.097728011189;4018.1160144768105;4036.475258216428;3623.547056617923;3693.6860366868714;3930.3121953657997;3963.8901107689894;4001.399790031504;3907.905786379818;3931.2923673738715;3933.1907322631623;3938.5076865391825;3977.880663580097;2877.2950627096498;3428.3828910796046;3441.105312618737;3450.6721861423125;3464.921047371582;4391.846285380028;4742.786574604151;5027.597792176617;5056.241563765428;5082.79668813247;3682.852685892561;4454.599086082236;4464.308032255006;4494.943688233263;4500.482444518857;4476.9552304476865;5394.676319420808;5395.9190612140455;5437.211604317352;5439.809606663787;3433.364827506894;3757.605451427588;3767.303048935928;3820.3887378019663;3826.559945606235;4740.5733483986605;5351.464853514617;5589.413111623302;5693.231413759131;5728.598998070137;3332.75004374668;3750.962490375116;3768.2651882882574;3771.358868992223;3781.2248489059675;3366.225768073357;3455.7580969332407;3779.9021543043873;3837.982934522354;3863.16941857502;3569.447844790987;4042.4744783178;4141.440905477722;4492.011757413364;4509.346144808985;5350.571954243651;5500.834974950758;5502.58730595207;5542.196381524737;5565.2486914266865;1289.5234955975543;1299.52567312932;1309.1359702596242;1315.8946484952685;1322.8677132286887;708.8481218020717;789.6604460082219;836.652789092532;853.3642436384933;863.5552690364467;4398.252297109435;4636.721796692209;5061.216331017926;5068.417132029864;5088.211327244283;4727.64542659302;4862.735688720887;5444.883798821312;5504.504639553912;5547.944520554743;4404.911901761947;4455.532169122261;4660.533946605312;4966.751662416865;5002.574781143643;2768.1833859011235;2775.403288293027;2897.8695958681487;2913.6212292402274;2925.3856690094894;5640.605157639008;5732.226149469851;5750.137479378127;5761.827145185651;5790.189373487715;4841.346418233564;4962.950370496321;5536.916946245809;5588.832335029955;5649.68926709035;4658.417697787777;5010.298558201855;5029.371328583927;5479.630092476881;5678.772849086073;4678.853680848214;5093.286939828501;5473.233747275305;5535.501804837584;5550.944490555043;5148.6910785353;5402.459754024566;5435.701036443033;5453.15476101205;5477.589180811535;5029.74851257443;5428.647137936022;5709.345154503176;5723.8855222896145;5785.56608254391;3179.936401272008;3456.1186897341017;3750.306193354238;3750.5124333836425;3757.6618104370737;4795.472498025145;5528.502434780889;5597.748101335368;5600.85997850057;5637.49262566371;5514.889702206014;5516.172574113915;5529;5535.7785688572;5544.833654990357;4240.936385954152;5290.2490704834045;5434.347878254634;5490.258815059916;5508.20131080999;5301.814436494704;5307.628466007497;5324.547413469899;5326;5336.839894803007;4743.905121897474;4781.9043619128115;5068.9239661405845;5136.897262054813;5441.7279136043835;4448.755318457516;4492.573205545468;5374.736936819766;5397.433269506802;5410.864728381822 16:33:17 INFO - PROCESS | 1647 | |5;yui.html;695.9095317608711;741.1772932045427;797.2107613462674;802.0335495727649;818.5825229133144;15161.620959476004;15720.292586833577;15726.37094516218;15732.213389330545;15732.685346293067;14141.646458838533;15181.848181518182;15521.767173492379;15779.60550986226;15791.131487768169;859.703402326198;881.3213825354474;882.7087061269798;883.8099808541162;884.0099089020306;683.5761827666829;700.4326495538604;844.9873251901226;869.4348673362302;881.5944665453899;902.8329758994574;907.5779762410477;908.6183802802806;908.9681861134861;909.3316412436853;901.7880798012462;904.4889637354906;905.2712566384058;906.071276941134;906.9546522673872;527.5779376498785;541.6371031408966;542.2137900044951;544.7630280827852;673.6093066021696;14981.550553483414;15515.301811418512;15520.456784012618;15550;15630.609234769221;13872.514461993882;14236.572902812932;14311.35598898052;14405.711885762226;14408.279586020657;11631.941840290745;11939.820902686466;11958.162928594915;11974;12435.378231088409;3782.0733920189728;3788.8105594720155;3814.675752561008;3863.343231650626;3952.130531283113;104789;111568.4421577887;112001.43999280033;113349.86650133475;113984.43007784992;10817.405042722727;10948.069414099891;10996.670099897015;11040.944795275973;11435.771284574263;21828.45428864291;22005.449863753212;22056.007479663454;22067.006984685726;22234.444138896655;7981.68073277074;7999.640016199285;8015.719449819228;8041.6381262843315;8217.671293148327;4439.200943830142;4554.590086892195;4586.289125185534;4706.647001474903;4772.284157376352;3596.5863925648887;3745.250949810049;3745.4007358823;3745.5318085239346;3752.718546108998;662.9370209830058;676.5230512488782;682.4165338635495;685.9451243900476;686.989695154573;560.6972234993058;561.1554610311455;649.191756263455;676.2527407215055;679.6635665345685;8147.633356498972;9654;9786.657466988621;9803.411795292162;9819.901800982041;571.9752077645172;618.9848648216838;629.6222266639979;645.9192600924885;696.8571442854203;4498.6626003049905;4726.976365118152;4752;4769.785359658824;4889.706617602955;111225.44387278012;132071;161623;162300.18849905912;170356.85360903002;137867;140241;140502;140502.2974885119;147230.26384868223;106688.46655766827;117069;121664.39167804104;122312;123662.38168809099;9213.723588292361;9219.354645174773;9225.86161207569;9241.861372079558;9280.164785169367;60506.39493605184;65521.344786551526;66419.66790166018;67402.32597674157;67416.6629166851;78067;91516.67866570526;94325.58511622468;96593.51703241438;97657.2811262653;10617.309874858109;11005.889941100486;11017.724556886142;11026.558937642405;12692.17500862441;12422.627321180378;15148.469803556822;15175.620609484851;15179.62050948691;15723.606909827346;13958.23229722375;14122.223277719824;14328.785068223777;14633;14788.186649734367;11935.403229838646;12649.93675031619;13165.472974647544;13352.465901363837;14342.067765595202;13688.31558422055;13843.861561384258;13943.302834858014;14310.856891430953;14499.565013049625;8271.917280827114;8562.914370856211;9985.550650220612;10367.822757221069;10952.56189752401;9964.850527241952;10960.068394186264;11519.8272025918;11532.192746507664;11548.364839933549 16:33:17 INFO - PROCESS | 1647 | __end_tp_report 16:33:17 INFO - PROCESS | 1647 | __start_cc_report 16:33:17 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,1240 16:33:17 INFO - PROCESS | 1647 | __end_cc_report 16:33:17 INFO - PROCESS | 1647 | __startTimestamp1472513597769__endTimestamp 16:33:17 INFO - PROCESS | 1647 | 16:33:17 INFO - PROCESS | 1647 | ------- Summary: start ------- 16:33:17 INFO - PROCESS | 1647 | Number of tests: 6 16:33:17 INFO - PROCESS | 1647 | 16:33:17 INFO - PROCESS | 1647 | [#0] dojo.html Cycles:165 Average:5598.48 Median:5750.63 stddev:644.03 (11.2%) stddev-sans-first:606.38 16:33:17 INFO - PROCESS | 1647 | Values: 2762.9 3275.6 3317.1 3322.0 3386.0 5421.5 5431.8 5508.2 5533.0 5551.8 4594.2 5502.0 5528.4 5530.7 5541.5 5065.3 5075.4 5091.1 5140.7 5149.9 5096.8 5157.3 5160.6 5164.6 5170.3 5068.3 5073.2 5114.3 5114.9 5155.8 5141.5 5172.3 5173.0 5185.9 5231.6 4968.2 4987.4 5031.6 5099.2 5099.8 5465.6 5539.2 5556.4 5563.2 5573.4 6058.4 6085.4 6085.8 6096.6 6099.3 5994.5 6048.8 6078.6 6154.8 6169.8 5552.9 5610.5 5617.2 5675.8 5677.4 6248.0 6262.8 6265.5 6295.9 6299.8 5790.1 5887.9 5927.6 5934.3 5936.6 6087.1 6096.3 6118.0 6145.9 6146.1 5927.9 6005.0 6014.2 6017.5 6072.0 5874.8 5891.6 6074.5 6097.3 6103.5 4774.4 4778.5 4788.3 4789.6 4796.5 4304.2 4334.1 4349.3 4350.3 4357.3 5498.7 5505.4 5525.6 5527.2 5597.4 6188.2 6196.8 6202.1 6212.4 6213.8 5592.8 5593.0 5594.2 5598.0 5626.4 5229.0 5254.7 5257.8 5299.7 5314.4 6223.7 6255.6 6288.2 6290.7 6291.9 6185.4 6190.4 6194.2 6194.5 6205.5 6309.9 6319.7 6333.2 6334.0 6336.7 5834.3 5904.7 5952.1 5967.6 5975.0 6100.6 6119.1 6128.0 6131.2 6136.5 4957.7 5745.5 5833.6 5857.8 5957.9 5750.6 5808.1 5857.3 5883.6 5890.3 5734.7 5767.8 5775.1 5813.8 5831.3 5898.0 5907.6 5919.1 5926.1 5940.8 5626.9 5690.9 5718.1 5728.6 5755.6 16:33:17 INFO - PROCESS | 1647 | 16:33:17 INFO - PROCESS | 1647 | [#1] ext.html Cycles:180 Average:66625.16 Median:35045.65 stddev:141407.97 (403.5%) stddev-sans-first:141717.42 16:33:17 INFO - PROCESS | 1647 | Values: 469.4 484.2 509.7 510.9 533.2 30098.5 30354.2 39294.4 40176.6 40364.6 41716.8 41839.2 41952.0 42073.7 42223.0 9557.0 9753.0 9760.3 9764.4 9782.4 8700.8 8923.0 11655.1 11929.6 11949.6 11845.0 11901.9 11946.8 11947.3 11952.3 11896.8 11992.5 12001.9 12005.0 12010.0 11682.5 11765.5 11773.2 11775.8 11789.0 33085.5 38209.0 39070.0 39169.0 39301.8 3335.6 3387.5 3410.3 3411.9 3519.2 30631.5 31518.0 31632.0 31732.9 32001.7 3155.4 3224.6 3271.1 3324.6 3390.2 494325.0 495150.0 497246.0 497312.0 497404.0 59974.7 60239.7 60276.7 60373.0 60396.7 62830.7 62836.7 62877.0 62893.0 63038.0 16213.3 16357.9 16363.5 16377.8 16381.3 11469.9 11631.2 12673.5 12695.6 12718.0 14789.3 14855.6 14864.3 16668.8 17012.6 6330.6 6333.5 6361.6 6423.7 6460.2 851.9 917.0 933.9 1063.3 1095.7 29112.3 29603.0 29674.0 30191.8 30317.0 1004.5 1011.1 1049.9 1128.2 1131.1 15175.8 15437.6 15514.9 15890.4 15915.3 624579.0 627640.0 629225.0 631717.7 631786.0 49643.5 50743.7 57065.0 58053.1 58072.0 1316.6 1353.2 1369.2 1374.7 1378.4 29559.6 30283.7 31342.5 31933.8 31990.0 53377.7 56513.2 56557.2 56613.0 56631.4 402930.0 412098.0 414622.0 501752.0 502120.0 25965.1 26293.0 26304.5 26388.3 26457.5 46840.5 47402.3 47940.0 48295.5 49078.5 33063.2 39927.6 40456.6 40503.6 41145.2 38875.8 39041.6 39599.0 39709.8 40442.0 38152.6 38276.4 39091.0 39121.2 39151.2 33016.8 34332.1 35009.6 35081.6 35193.6 25192.9 25428.1 25477.9 26312.5 26702.6 16:33:17 INFO - PROCESS | 1647 | 16:33:17 INFO - PROCESS | 1647 | [#2] jquery.html Cycles:180 Average:33418.60 Median:353.66 stddev:92950.67 (26282.2%) stddev-sans-first:93178.25 16:33:17 INFO - PROCESS | 1647 | Values: 344.2 363.1 365.5 378.2 395.5 15530.8 16330.3 19866.9 20364.3 20460.8 19817.0 20447.4 21381.8 21491.7 21689.8 5478.3 5656.7 5661.5 5707.9 6147.6 6064.5 6174.0 6181.3 6185.9 6192.0 3948.5 5877.1 6129.7 6184.5 6242.9 5707.6 5865.9 5926.6 6127.0 6209.3 6016.6 6017.3 6018.2 6026.8 6037.1 13504.7 23260.5 25421.4 26099.3 26725.5 2254.6 2286.9 2407.5 2459.2 2501.3 9569.4 10032.8 10108.5 10446.6 10523.7 1704.9 1822.2 1879.4 1886.7 1912.3 100570.5 123419.0 129330.4 129618.0 131873.3 11033.1 11054.4 11395.9 11420.7 11618.8 14458.0 14472.8 14487.6 14505.5 14508.0 8164.8 8213.1 8483.9 8501.4 8654.4 8495.2 8506.5 8510.1 8684.9 8717.4 3793.5 4398.0 4507.9 4565.6 4613.9 1820.5 1877.7 1877.7 1878.1 1888.5 804.3 867.0 867.5 894.1 989.1 20587.8 21389.0 21459.2 21508.9 21545.5 894.4 911.1 929.7 931.9 978.8 7840.5 11079.9 11185.2 11302.6 11314.7 543586.0 549463.0 556737.0 559560.0 561019.0 89094.0 129531.0 129646.0 131441.0 131509.3 910.5 937.2 940.5 941.1 949.3 16575.9 19025.0 19071.3 19433.6 19546.0 38474.6 47430.0 50181.7 53501.7 54771.5 69734.0 76692.0 77222.8 77777.0 78693.2 7028.3 7241.4 7253.6 7440.2 7888.4 11457.9 12713.0 12898.7 13179.2 13208.7 5426.8 5505.8 11069.4 11108.3 11437.3 10144.1 11022.2 11384.5 11414.8 11420.4 11115.3 11135.8 11307.8 11624.7 11700.5 7024.9 7109.8 7233.5 7234.1 7359.5 10385.5 11073.4 11385.4 11431.8 11566.8 16:33:17 INFO - PROCESS | 1647 | 16:33:17 INFO - PROCESS | 1647 | [#3] mootools.html Cycles:180 Average:5672.96 Median:4983.39 stddev:3237.61 (65.0%) stddev-sans-first:3230.36 16:33:17 INFO - PROCESS | 1647 | Values: 1346.2 1400.6 1532.8 1727.3 1945.1 7854.0 8665.4 8929.7 8932.0 9052.9 8793.9 8883.6 8883.9 8893.9 8910.4 2366.4 2390.5 2390.9 2391.9 2396.8 2331.7 2375.0 2377.4 2378.1 2382.2 2337.6 2356.2 2363.7 2379.8 2380.8 2303.7 2326.4 2342.3 2343.2 2345.5 2005.0 2328.4 2332.1 2335.6 2341.7 8644.8 8840.9 8871.1 8935.4 8935.5 4496.3 5435.5 5437.6 5439.8 5442.7 3502.3 3503.5 3569.5 3574.4 3586.4 1070.7 1093.3 1103.0 1105.7 1119.3 11899.5 11935.4 11981.3 11998.0 12017.4 4399.6 4486.4 4646.4 4666.1 4674.9 9282.0 9282.4 9284.6 9292.4 9292.4 5304.6 5304.8 5309.2 5309.5 5310.5 4337.3 4447.4 4604.2 4934.2 4972.3 3703.4 3703.5 3792.5 3794.2 3802.0 954.8 1131.9 1140.3 1142.8 1148.2 850.2 865.9 873.8 920.3 958.7 5644.5 5721.2 5953.8 6088.9 6148.6 368.4 432.6 441.6 450.8 450.9 3637.7 3913.4 3934.0 3938.6 4037.3 9551.9 10631.7 11021.6 11048.1 11113.9 9396.6 11007.7 11019.7 11022.7 11109.2 8330.8 8387.8 8410.9 8411.8 8414.8 4817.7 5070.0 5917.6 5931.4 6088.7 9538.4 9956.7 10137.4 10219.9 10268.9 9324.8 9714.8 10095.9 10281.2 10429.3 6632.8 6641.9 6828.2 7064.2 7066.7 8139.6 8298.3 8396.1 8414.3 8427.3 7568.6 7570.9 7593.1 7685.2 7752.6 7416.8 7450.8 7467.9 7475.7 7560.4 6703.4 6937.2 7033.1 7109.6 7117.8 5031.3 5095.8 5108.7 5137.8 5214.7 4845.1 4994.5 5173.1 5220.3 5307.2 16:33:17 INFO - PROCESS | 1647 | 16:33:17 INFO - PROCESS | 1647 | [#4] prototype.html Cycles:180 Average:4363.67 Median:4852.04 stddev:1336.79 (27.6%) stddev-sans-first:1304.46 16:33:17 INFO - PROCESS | 1647 | Values: 253.8 292.9 303.9 308.1 309.4 4460.6 5067.9 5161.1 5203.3 5209.7 4813.9 4898.7 5048.6 5107.5 5178.1 3489.4 3917.9 3950.9 3966.6 4013.7 3934.7 3973.6 4010.1 4018.1 4036.5 3623.5 3693.7 3930.3 3963.9 4001.4 3907.9 3931.3 3933.2 3938.5 3977.9 2877.3 3428.4 3441.1 3450.7 3464.9 4391.8 4742.8 5027.6 5056.2 5082.8 3682.9 4454.6 4464.3 4494.9 4500.5 4477.0 5394.7 5395.9 5437.2 5439.8 3433.4 3757.6 3767.3 3820.4 3826.6 4740.6 5351.5 5589.4 5693.2 5728.6 3332.8 3751.0 3768.3 3771.4 3781.2 3366.2 3455.8 3779.9 3838.0 3863.2 3569.4 4042.5 4141.4 4492.0 4509.3 5350.6 5500.8 5502.6 5542.2 5565.2 1289.5 1299.5 1309.1 1315.9 1322.9 708.8 789.7 836.7 853.4 863.6 4398.3 4636.7 5061.2 5068.4 5088.2 4727.6 4862.7 5444.9 5504.5 5547.9 4404.9 4455.5 4660.5 4966.8 5002.6 2768.2 2775.4 2897.9 2913.6 2925.4 5640.6 5732.2 5750.1 5761.8 5790.2 4841.3 4963.0 5536.9 5588.8 5649.7 4658.4 5010.3 5029.4 5479.6 5678.8 4678.9 5093.3 5473.2 5535.5 5550.9 5148.7 5402.5 5435.7 5453.2 5477.6 5029.7 5428.6 5709.3 5723.9 5785.6 3179.9 3456.1 3750.3 3750.5 3757.7 4795.5 5528.5 5597.7 5600.9 5637.5 5514.9 5516.2 5529.0 5535.8 5544.8 4240.9 5290.2 5434.3 5490.3 5508.2 5301.8 5307.6 5324.5 5326.0 5336.8 4743.9 4781.9 5068.9 5136.9 5441.7 4448.8 4492.6 5374.7 5397.4 5410.9 16:33:17 INFO - PROCESS | 1647 | 16:33:17 INFO - PROCESS | 1647 | [#5] yui.html Cycles:180 Average:25543.88 Median:3767.40 stddev:41276.10 (1095.6%) stddev-sans-first:41349.73 16:33:17 INFO - PROCESS | 1647 | Values: 695.9 741.2 797.2 802.0 818.6 15161.6 15720.3 15726.4 15732.2 15732.7 14141.6 15181.8 15521.8 15779.6 15791.1 859.7 881.3 882.7 883.8 884.0 683.6 700.4 845.0 869.4 881.6 902.8 907.6 908.6 909.0 909.3 901.8 904.5 905.3 906.1 907.0 527.6 541.6 542.2 544.8 673.6 14981.6 15515.3 15520.5 15550.0 15630.6 13872.5 14236.6 14311.4 14405.7 14408.3 11631.9 11939.8 11958.2 11974.0 12435.4 3782.1 3788.8 3814.7 3863.3 3952.1 104789.0 111568.4 112001.4 113349.9 113984.4 10817.4 10948.1 10996.7 11040.9 11435.8 21828.5 22005.4 22056.0 22067.0 22234.4 7981.7 7999.6 8015.7 8041.6 8217.7 4439.2 4554.6 4586.3 4706.6 4772.3 3596.6 3745.3 3745.4 3745.5 3752.7 662.9 676.5 682.4 685.9 687.0 560.7 561.2 649.2 676.3 679.7 8147.6 9654.0 9786.7 9803.4 9819.9 572.0 619.0 629.6 645.9 696.9 4498.7 4727.0 4752.0 4769.8 4889.7 111225.4 132071.0 161623.0 162300.2 170356.9 137867.0 140241.0 140502.0 140502.3 147230.3 106688.5 117069.0 121664.4 122312.0 123662.4 9213.7 9219.4 9225.9 9241.9 9280.2 60506.4 65521.3 66419.7 67402.3 67416.7 78067.0 91516.7 94325.6 96593.5 97657.3 10617.3 11005.9 11017.7 11026.6 12692.2 12422.6 15148.5 15175.6 15179.6 15723.6 13958.2 14122.2 14328.8 14633.0 14788.2 11935.4 12649.9 13165.5 13352.5 14342.1 13688.3 13843.9 13943.3 14310.9 14499.6 8271.9 8562.9 9985.6 10367.8 10952.6 9964.9 10960.1 11519.8 11532.2 11548.4 16:33:17 INFO - PROCESS | 1647 | -------- Summary: end -------- 16:33:17 INFO - PROCESS | 1647 | 16:33:18 INFO - TEST-INFO | 1647: exit 0 16:33:18 INFO - TEST-OK | dromaeo_css | took 1117810ms 16:33:18 INFO - TEST-START | kraken 16:33:18 INFO - operating with platform_type : mac_ 16:33:18 INFO - Initialising browser for kraken test... 16:33:18 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/tmpx0yWrb/profile http://localhost:49265/getInfo.html) 16:33:20 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 16:33:20 INFO - PROCESS | 1655 | colorDepth:24 16:33:20 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 16:33:20 INFO - PROCESS | 1655 | __metrics 16:33:20 INFO - PROCESS | 1655 | 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:33:20 INFO - PROCESS | 1655 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:33:21 INFO - TEST-INFO | 1655: exit 0 16:33:21 INFO - Browser initialized. 16:33:21 INFO - Running cycle 1/1 for kraken test... 16:33:21 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/tmpx0yWrb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:33:33 INFO - PROCESS | 1656 | RSS: Main: 234602496 16:33:33 INFO - PROCESS | 1656 | 16:33:35 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-beat-detection) 16:33:36 INFO - PROCESS | 1656 | RSS: Main: 291786752 16:33:36 INFO - PROCESS | 1656 | 16:33:39 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-dft) 16:33:40 INFO - PROCESS | 1656 | RSS: Main: 288686080 16:33:40 INFO - PROCESS | 1656 | 16:33:43 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-fft) 16:33:44 INFO - PROCESS | 1656 | RSS: Main: 380600320 16:33:44 INFO - PROCESS | 1656 | 16:33:46 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-oscillator) 16:33:46 INFO - PROCESS | 1656 | RSS: Main: 282587136 16:33:46 INFO - PROCESS | 1656 | 16:33:48 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49265/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:33:49 INFO - PROCESS | 1656 | RSS: Main: 284127232 16:33:49 INFO - PROCESS | 1656 | 16:33:52 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49265/tests/kraken/driver.html?testName=imaging-darkroom) 16:33:52 INFO - PROCESS | 1656 | RSS: Main: 321445888 16:33:52 INFO - PROCESS | 1656 | 16:33:55 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49265/tests/kraken/driver.html?testName=imaging-desaturate) 16:33:56 INFO - PROCESS | 1656 | RSS: Main: 324087808 16:33:56 INFO - PROCESS | 1656 | 16:33:59 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49265/tests/kraken/driver.html?testName=json-parse-financial) 16:33:59 INFO - PROCESS | 1656 | RSS: Main: 321183744 16:33:59 INFO - PROCESS | 1656 | 16:34:01 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49265/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:34:02 INFO - PROCESS | 1656 | RSS: Main: 297377792 16:34:02 INFO - PROCESS | 1656 | 16:34:04 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:34:04 INFO - PROCESS | 1656 | RSS: Main: 302518272 16:34:04 INFO - PROCESS | 1656 | 16:34:06 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:34:07 INFO - PROCESS | 1656 | RSS: Main: 303702016 16:34:07 INFO - PROCESS | 1656 | 16:34:10 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:34:11 INFO - PROCESS | 1656 | RSS: Main: 322813952 16:34:11 INFO - PROCESS | 1656 | 16:34:14 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:34:14 INFO - PROCESS | 1656 | RSS: Main: 318779392 16:34:14 INFO - PROCESS | 1656 | 16:34:16 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49265/tests/kraken/driver.html?testName=ai-astar) 16:34:16 INFO - PROCESS | 1656 | RSS: Main: 362389504 16:34:16 INFO - PROCESS | 1656 | 16:34:16 INFO - PROCESS | 1656 | __start_tp_report 16:34:16 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 16:34:16 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 16:34:16 INFO - PROCESS | 1656 | |i|pagename|runs| 16:34:16 INFO - PROCESS | 1656 | |0;ai-astar;113;112;131;112;113;113;112;113;112;113 16:34:16 INFO - PROCESS | 1656 | |1;audio-beat-detection;147;112;133;162;109;131;130;108;164;128 16:34:16 INFO - PROCESS | 1656 | |2;audio-dft;184;183;183;182;183;180;180;179;184;183 16:34:16 INFO - PROCESS | 1656 | |3;audio-fft;78;99;76;150;58;60;58;69;138;57 16:34:16 INFO - PROCESS | 1656 | |4;audio-oscillator;82;83;133;70;77;84;114;67;70;116 16:34:16 INFO - PROCESS | 1656 | |5;imaging-gaussian-blur;88;85;92;128;132;85;89;85;88;85 16:34:16 INFO - PROCESS | 1656 | |6;imaging-darkroom;98;98;97;97;97;106;91;152;154;97 16:34:16 INFO - PROCESS | 1656 | |7;imaging-desaturate;84;87;84;87;84;84;86;84;84;85 16:34:16 INFO - PROCESS | 1656 | |8;json-parse-financial;55;55;57;54;55;56;56;54;54;55 16:34:16 INFO - PROCESS | 1656 | |9;json-stringify-tinderbox;68;64;77;113;108;81;71;73;71;71 16:34:16 INFO - PROCESS | 1656 | |10;stanford-crypto-aes;88;86;86;84;84;85;84;87;85;91 16:34:16 INFO - PROCESS | 1656 | |11;stanford-crypto-ccm;138;137;140;145;145;139;140;143;182;139 16:34:16 INFO - PROCESS | 1656 | |12;stanford-crypto-pbkdf2;169;164;171;169;168;169;169;169;168;167 16:34:16 INFO - PROCESS | 1656 | |13;stanford-crypto-sha256-iterative;68;67;68;64;69;61;68;66;67;68 16:34:16 INFO - PROCESS | 1656 | __end_tp_report 16:34:16 INFO - PROCESS | 1656 | __start_cc_report 16:34:16 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,2063 16:34:16 INFO - PROCESS | 1656 | __end_cc_report 16:34:16 INFO - PROCESS | 1656 | __startTimestamp1472513656612__endTimestamp 16:34:16 INFO - PROCESS | 1656 | 16:34:16 INFO - PROCESS | 1656 | ------- Summary: start ------- 16:34:16 INFO - PROCESS | 1656 | Number of tests: 14 16:34:16 INFO - PROCESS | 1656 | 16:34:16 INFO - PROCESS | 1656 | [#0] ai-astar Cycles:10 Average:114.40 Median:113.00 stddev:5.85 (5.2%) stddev-sans-first:6.19 16:34:16 INFO - PROCESS | 1656 | Values: 113.0 112.0 131.0 112.0 113.0 113.0 112.0 113.0 112.0 113.0 16:34:16 INFO - PROCESS | 1656 | 16:34:16 INFO - PROCESS | 1656 | [#1] audio-beat-detection Cycles:10 Average:132.40 Median:132.00 stddev:20.21 (15.3%) stddev-sans-first:20.73 16:34:16 INFO - PROCESS | 1656 | Values: 147.0 112.0 133.0 162.0 109.0 131.0 130.0 108.0 164.0 128.0 16:34:16 INFO - PROCESS | 1656 | 16:34:16 INFO - PROCESS | 1656 | [#2] audio-dft Cycles:10 Average:182.10 Median:183.00 stddev:1.79 (1.0%) stddev-sans-first:1.76 16:34:16 INFO - PROCESS | 1656 | Values: 184.0 183.0 183.0 182.0 183.0 180.0 180.0 179.0 184.0 183.0 16:34:16 INFO - PROCESS | 1656 | 16:34:16 INFO - PROCESS | 1656 | [#3] audio-fft Cycles:10 Average:84.30 Median:64.50 stddev:34.12 (52.9%) stddev-sans-first:36.11 16:34:16 INFO - PROCESS | 1656 | Values: 78.0 99.0 76.0 150.0 58.0 60.0 58.0 69.0 138.0 57.0 16:34:16 INFO - PROCESS | 1656 | 16:34:16 INFO - PROCESS | 1656 | [#4] audio-oscillator Cycles:10 Average:89.60 Median:73.50 stddev:22.96 (31.2%) stddev-sans-first:24.19 16:34:16 INFO - PROCESS | 1656 | Values: 82.0 83.0 133.0 70.0 77.0 84.0 114.0 67.0 70.0 116.0 16:34:16 INFO - PROCESS | 1656 | 16:34:16 INFO - PROCESS | 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:95.70 Median:86.50 stddev:18.25 (21.1%) stddev-sans-first:19.14 16:34:16 INFO - PROCESS | 1656 | Values: 88.0 85.0 92.0 128.0 132.0 85.0 89.0 85.0 88.0 85.0 16:34:16 INFO - PROCESS | 1656 | 16:34:16 INFO - PROCESS | 1656 | [#6] imaging-darkroom Cycles:10 Average:108.70 Median:97.00 stddev:23.63 (24.4%) stddev-sans-first:24.74 16:34:16 INFO - PROCESS | 1656 | Values: 98.0 98.0 97.0 97.0 97.0 106.0 91.0 152.0 154.0 97.0 16:34:16 INFO - PROCESS | 1656 | 16:34:16 INFO - PROCESS | 1656 | [#7] imaging-desaturate Cycles:10 Average:84.90 Median:84.50 stddev:1.29 (1.5%) stddev-sans-first:1.32 16:34:16 INFO - PROCESS | 1656 | Values: 84.0 87.0 84.0 87.0 84.0 84.0 86.0 84.0 84.0 85.0 16:34:16 INFO - PROCESS | 1656 | 16:34:16 INFO - PROCESS | 1656 | [#8] json-parse-financial Cycles:10 Average:55.10 Median:55.00 stddev:0.99 (1.8%) stddev-sans-first:1.05 16:34:16 INFO - PROCESS | 1656 | Values: 55.0 55.0 57.0 54.0 55.0 56.0 56.0 54.0 54.0 55.0 16:34:16 INFO - PROCESS | 1656 | 16:34:16 INFO - PROCESS | 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:79.70 Median:71.00 stddev:16.91 (23.8%) stddev-sans-first:17.40 16:34:16 INFO - PROCESS | 1656 | Values: 68.0 64.0 77.0 113.0 108.0 81.0 71.0 73.0 71.0 71.0 16:34:16 INFO - PROCESS | 1656 | 16:34:16 INFO - PROCESS | 1656 | [#10] stanford-crypto-aes Cycles:10 Average:86.00 Median:86.00 stddev:2.21 (2.6%) stddev-sans-first:2.22 16:34:16 INFO - PROCESS | 1656 | Values: 88.0 86.0 86.0 84.0 84.0 85.0 84.0 87.0 85.0 91.0 16:34:16 INFO - PROCESS | 1656 | 16:34:16 INFO - PROCESS | 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:144.80 Median:141.50 stddev:13.36 (9.4%) stddev-sans-first:13.95 16:34:16 INFO - PROCESS | 1656 | Values: 138.0 137.0 140.0 145.0 145.0 139.0 140.0 143.0 182.0 139.0 16:34:16 INFO - PROCESS | 1656 | 16:34:16 INFO - PROCESS | 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.30 Median:169.00 stddev:1.83 (1.1%) stddev-sans-first:1.92 16:34:16 INFO - PROCESS | 1656 | Values: 169.0 164.0 171.0 169.0 168.0 169.0 169.0 169.0 168.0 167.0 16:34:16 INFO - PROCESS | 1656 | 16:34:16 INFO - PROCESS | 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:68.00 stddev:2.41 (3.5%) stddev-sans-first:2.51 16:34:16 INFO - PROCESS | 1656 | Values: 68.0 67.0 68.0 64.0 69.0 61.0 68.0 66.0 67.0 68.0 16:34:16 INFO - PROCESS | 1656 | -------- Summary: end -------- 16:34:16 INFO - PROCESS | 1656 | 16:34:17 INFO - TEST-INFO | 1656: exit 0 16:34:17 INFO - TEST-OK | kraken | took 59029ms 16:34:17 INFO - SUITE-END | took 1176s 16:34:17 INFO - Completed test suite (00:19:37) 16:34:17 INFO - javascript benchmark 16:34:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2762.944741105178, 3275.557799697042, 3317.1209629448185, 3321.966780332196, 3386.0349800306917, 5421.484958928905, 5431.809886653963, 5508.173773933912, 5533.031719449095, 5551.77792888285, 4594.196015697256, 5502.0, 5528.391876893538, 5530.723463826813, 5541.501264886159, 5065.316182315396, 5075.4163271223915, 5091.0581542414775, 5140.7172605506685, 5149.897002059956, 5096.79612815487, 5157.252198431225, 5160.612954028463, 5164.561012313958, 5170.2503137045105, 5068.341115654978, 5073.162928116856, 5114.258432527281, 5114.923276150861, 5155.81954631588, 5141.511556402154, 5172.302513665537, 5173.0, 5185.948140518622, 5231.555317797992, 4968.205087186033, 4987.376577927759, 5031.572316353115, 5099.209622508517, 5099.847004589868, 5465.617406781527, 5539.169124631298, 5556.444355564452, 5563.165525171216, 5573.359063707644, 6058.363871793486, 6085.421884920926, 6085.817425477243, 6096.634201947897, 6099.268087829444, 5994.490468310199, 6048.818535443944, 6078.635281883057, 6154.784582539634, 6169.845753856144, 5552.861178470571, 5610.523105535994, 5617.21359009739, 5675.801346952837, 5677.37548869624, 6248.0468653118605, 6262.780802671885, 6265.467435267962, 6295.937040629627, 6299.779507717208, 5790.073588225948, 5887.882242355129, 5927.644341339534, 5934.3175534814045, 5936.643801371845, 6087.05650624154, 6096.298925623521, 6118.010103775246, 6145.877082458326, 6146.078088286793, 5927.911081333784, 6005.039193728982, 6014.1580178775, 6017.458428741346, 6071.969640151771, 5874.794382196688, 5891.646501209942, 6074.54440916933, 6097.298810636745, 6103.450689437972, 4774.427068751772, 4778.546038126373, 4788.281757736298, 4789.616830653539, 4796.544328288807, 4304.225239456865, 4334.13317336534, 4349.304111342233, 4350.260455722535, 4357.302831546937, 5498.697571633598, 5505.394406615269, 5525.613207075547, 5527.198556209408, 5597.412271711493, 6188.226471691039, 6196.845078873064, 6202.131701561785, 6212.4408803207925, 6213.844653883689, 5592.832215033555, 5592.972035139798, 5594.216809646653, 5597.972010140004, 5626.352969408488, 5228.973855130776, 5254.684718916801, 5257.76340064698, 5299.682019078868, 5314.441983591667, 6223.688815559204, 6255.562110652301, 6288.213973253343, 6290.685465726604, 6291.937080629226, 6185.41238582352, 6190.350013248544, 6194.194754681863, 6194.504439644727, 6205.472534834409, 6309.90535141964, 6319.747210111728, 6333.20834895638, 6334.049892516159, 6336.7465301389275, 5834.299884013945, 5904.675242861684, 5952.107183922273, 5967.6419414835245, 5975.0, 6100.611450805989, 6119.112728654409, 6127.953177891246, 6131.193168498027, 6136.519078024374, 4957.652964292465, 5745.454181852718, 5833.562482813891, 5857.824265272048, 5957.851053723692, 5750.6262092963825, 5808.099744539603, 5857.26784151981, 5883.558733094949, 5890.263717035371, 5734.741936612946, 5767.769289228384, 5775.104858746901, 5813.82558523245, 5831.271091113611, 5898.0, 5907.556933229939, 5919.112133180058, 5926.081457374114, 5940.821775346746, 5626.88746225065, 5690.914636280542, 5718.085106383125, 5728.627639203439, 5755.597108202385], "value": 5555.781630076348, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [469.4413647759166, 484.176899271239, 509.7247486357367, 510.85058618109275, 533.2294833964921, 30098.548521772165, 30354.241143971383, 39294.42822287105, 40176.59823401765, 40364.596354036454, 41716.79141604288, 41839.163216735644, 41951.99999999993, 42073.73778786634, 42223.0, 9557.044295570457, 9752.951235243814, 9760.316777825556, 9764.414135151914, 9782.41305521671, 8700.825983480327, 8923.0, 11655.125865560074, 11929.58246461374, 11949.58176463824, 11845.0, 11901.94049029758, 11946.761064778699, 11947.283163010205, 11952.282863028202, 11896.82154767675, 11992.520299188021, 12001.939990300036, 12005.039596832232, 12010.0, 11682.474288657031, 11765.529378824836, 11773.175877688565, 11775.764484710344, 11789.0, 33085.503717444255, 38209.0, 39070.02324941871, 39169.0, 39301.803490982646, 3335.6497567755277, 3387.5257463955068, 3410.334984677964, 3411.8635254589785, 3519.225770330523, 30631.540526892113, 31518.0, 31632.0, 31732.88934887268, 32001.679983200334, 3155.368926214743, 3224.629167645727, 3271.1167984644253, 3324.6010478742705, 3390.1524618845287, 494325.0, 495150.0, 497246.0, 497312.0, 497404.0, 59974.70012649909, 60239.698801505714, 60276.698616506634, 60373.0, 60396.69801650964, 62830.68584657047, 62836.68581657062, 62877.0, 62893.0, 63038.0, 16213.270402831666, 16357.918210408872, 16363.509094727176, 16377.754333685005, 16381.344746210256, 11469.885301146882, 11631.243969142148, 12673.493060277486, 12695.555655552196, 12718.0, 14789.260536973323, 14855.554333370017, 14864.331105100297, 16668.833311666727, 17012.57468563271, 6330.556861019684, 6333.46165575922, 6361.586496877688, 6423.678816059179, 6460.192475940507, 851.8892543969245, 916.9770755731026, 933.9206167475704, 1063.3407287481812, 1095.6986828622194, 29112.272193194916, 29603.0, 29674.0, 30191.849040754656, 30317.0, 1004.467632154959, 1011.145581983223, 1049.8530205771199, 1128.2215271462665, 1131.1403333466624, 15175.772363414557, 15437.614059648375, 15514.9224253878, 15890.44383446597, 15915.283812228477, 624579.0, 627640.0, 629225.0, 631717.6828231751, 631786.0, 49643.50356496461, 50743.74628126836, 57065.0, 58053.12920306199, 58072.0, 1316.644505983379, 1353.2421843767522, 1369.2400717601827, 1374.7456720506739, 1378.4348417148922, 29559.556606650487, 30283.69716302897, 31342.52986205163, 31933.840330798197, 31990.0, 53377.73311133419, 56513.15230271467, 56557.15164272622, 56613.0, 56631.43368566426, 402930.0, 412098.0, 414622.0, 501752.0, 502120.0, 25965.091221807146, 26293.0, 26304.4739105213, 26388.340291492867, 26457.470850582496, 46840.53159468498, 47402.288965664855, 47940.041199175124, 48295.5170448291, 49078.5092149074, 33063.17342066467, 39927.600723992386, 40456.59543404528, 40503.596406577475, 41145.17709645731, 38875.80562097171, 39041.60958390493, 39599.0, 39709.80145099256, 40441.98895027648, 38152.61847381491, 38276.425853612775, 39091.0, 39121.217575648894, 39151.2169756609, 33016.83491582623, 34332.14169645779, 35009.64990350064, 35081.64918350784, 35193.648063520064, 25192.874035629706, 25428.110016149345, 25477.872610636827, 26312.473750525267, 26702.59946100771], "value": 20064.59202852571, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.2237753865034, 363.1031352559179, 365.46459436924897, 378.17369048628746, 395.4957429277671, 15530.84469155308, 16330.346786128555, 19866.92727990762, 20364.287249946254, 20460.795392046093, 19817.0, 20447.386578402635, 21381.786182138174, 21491.67762483564, 21689.783102168974, 5478.287822583068, 5656.717164141797, 5661.46216109471, 5707.942920570804, 6147.60040597362, 6064.545159113062, 6174.012158054689, 6181.258249010125, 6185.907211391833, 6191.969040154793, 3948.545917219528, 5877.089051197071, 6129.662868542228, 6184.5052395808225, 6242.875142497147, 5707.571932105088, 5865.912011319833, 5926.614770039977, 6127.0, 6209.285932117774, 6016.639001659871, 6017.338092809788, 6018.247719035121, 6026.819195424145, 6037.064255040432, 13504.729905401935, 23260.53478930429, 25421.364465888317, 26099.347516312053, 26725.465490690272, 2254.5716313900307, 2286.931392058241, 2407.4703565215623, 2459.1761759810483, 2501.2871331670385, 9569.37799043067, 10032.79934401308, 10108.545115469822, 10446.582136714522, 10523.684289471328, 1704.9403270885584, 1822.2346614421974, 1879.4173806120145, 1886.6608891412416, 1912.2733361322748, 100570.49714751453, 123419.0, 129330.35334823359, 129618.0, 131873.34063329716, 11033.062189713964, 11054.392008439612, 11395.886041139649, 11420.657380278604, 11618.825717614243, 14457.98794084404, 14472.782908256384, 14487.637809054859, 14505.492307769178, 14508.0, 8164.75505734817, 8213.137620549876, 8483.915160848312, 8501.429479034303, 8654.35092368075, 8495.235428811437, 8506.532140732319, 8510.063892971768, 8684.913150868537, 8717.389782715276, 3793.5447746270625, 4397.978010109929, 4507.864764057083, 4565.634749220055, 4613.907721845544, 1820.4902627264387, 1877.6995680691298, 1877.7183422486462, 1878.1454438230571, 1888.4712280561464, 804.3324041045918, 866.9826603467895, 867.5185272173878, 894.0657013420861, 989.0949780950493, 20587.79412205859, 21389.0, 21459.248926287506, 21508.89245553762, 21545.49181557276, 894.4275663575187, 911.1389736698756, 929.7303781903307, 931.9394239374419, 978.8384916488842, 7840.490368126168, 11079.889201107886, 11185.217034807649, 11302.604408845651, 11314.717132071764, 543586.0, 549463.0, 556737.0, 559560.0, 561019.0, 89094.0, 129531.0, 129646.0, 131441.0, 131509.34245328713, 910.4764760262743, 937.1612406895927, 940.5438362394233, 941.0636416765362, 949.2927768812141, 16575.91712041432, 19025.0, 19071.33250336287, 19433.611327773648, 19546.0, 38474.61525384822, 47430.0, 50181.74909125431, 53501.732491337294, 54771.45228547663, 69734.0, 76692.0, 77222.84165737631, 77777.0, 78693.2130678686, 7028.262032486516, 7241.384482318851, 7253.56478611285, 7440.218777028334, 7888.408369372435, 11457.885421145682, 12713.0, 12898.742025159632, 13179.209247445184, 13208.735825283633, 5426.755795989111, 5505.834824955257, 11069.446527673423, 11108.333499990027, 11437.313761174357, 10144.087032167141, 11022.228444008842, 11384.544618215179, 11414.82877756851, 11420.371879546705, 11115.333080015225, 11135.83296250573, 11307.830382544433, 11624.745071380026, 11700.47347869365, 7024.859502810017, 7109.82225444368, 7233.4574906883245, 7234.131904171322, 7359.521631093961, 10385.480725963822, 11073.446327683423, 11385.430728463378, 11431.771364572829, 11566.826497602375], "value": 9343.018749693621, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1346.2259200959452, 1400.6133927411863, 1532.8467153284669, 1727.2745446912304, 1945.0566475259504, 7854.0, 8665.350098742589, 8929.732108036753, 8931.972823125334, 9052.90947090529, 8793.912060879406, 8883.55582220888, 8883.911160888405, 8893.91106088939, 8910.376273660846, 2366.4438856868624, 2390.5457962986975, 2390.8565486070893, 2391.892364843586, 2396.7842894139526, 2331.650252462136, 2375.0262392419113, 2377.405648587853, 2378.120095564635, 2382.17814853876, 2337.590921588715, 2356.15178535727, 2363.669086327907, 2379.809615230786, 2380.8095352371856, 2303.6544518322385, 2326.441654003044, 2342.2856028911174, 2343.2267351773876, 2345.5308938212256, 2004.959900801976, 2328.382978510696, 2332.1137967572386, 2335.649652552114, 2341.6604592334015, 8644.783880402978, 8840.8673869892, 8871.112888711143, 8935.419197752126, 8935.463872167626, 4496.348029535699, 5435.537979271767, 5437.619366644337, 5439.755211015514, 5442.673439593637, 3502.317048175607, 3503.509508668763, 3569.4824250483534, 3574.392353299945, 3586.372384832644, 1070.7216123807789, 1093.2675107677876, 1102.9834552481718, 1105.6682995101519, 1119.283658458587, 11899.524019039229, 11935.403229838474, 11981.28112313264, 11998.0, 12017.399130043463, 4399.5820397062225, 4486.371907932892, 4646.395968524138, 4666.0901124280435, 4674.883127921829, 9281.95359023214, 9282.443053416682, 9284.62861485533, 9292.39599426035, 9292.442453452815, 5304.575633949275, 5304.761285742151, 5309.150535914312, 5309.522143007071, 5310.522053015247, 4337.349397590387, 4447.3773671685985, 4604.217283061887, 4934.18585933317, 4972.328735620645, 3703.388940824788, 3703.4629978652943, 3792.50697409335, 3794.1652836376506, 3801.9619803801606, 954.7994921066654, 1131.9490622921987, 1140.2645293785463, 1142.782871254459, 1148.1675785055934, 850.1753299299669, 865.9350548708873, 873.7859224489913, 920.2836709536666, 958.7459323279337, 5644.5484361251, 5721.1990321354215, 5953.821385358447, 6088.878222435526, 6148.631082135086, 368.40134780980907, 432.57607544606464, 441.58270434439146, 450.776865451597, 450.88051666308456, 3637.6907962823457, 3913.412988051815, 3933.9968308081257, 3938.5667576566557, 4037.3338399164395, 9551.856722149174, 10631.734206644896, 11021.614243501439, 11048.06091482233, 11113.94443027796, 9396.624135034659, 11007.724806879893, 11019.669409917715, 11022.669319920415, 11109.166812489095, 8330.791730206793, 8387.83224335522, 8410.915890841014, 8411.83176336482, 8414.831703365899, 4817.7350245736825, 5070.011347787146, 5917.5857689961285, 5931.436513531207, 6088.665123418255, 9538.427694338361, 9956.701298961043, 10137.391756494633, 10219.948900255451, 10268.897311026794, 9324.813503730024, 9714.75713107178, 10095.89904100979, 10281.17750579937, 10429.322094063862, 6632.801015969529, 6641.900371494528, 6828.248892621708, 7064.187618423947, 7066.681999310146, 8139.552324622203, 8298.294644955005, 8396.118407567117, 8414.284785793032, 8427.283680886949, 7568.5837278950185, 7570.9242907570215, 7593.126790419173, 7685.154632990255, 7752.573608451589, 7416.8145796353365, 7450.813729656802, 7467.887981680171, 7475.7009719610605, 7560.3573696236435, 6703.363180497845, 6937.236903940663, 7033.120859892513, 7109.608971506616, 7117.786466406016, 5031.3207716959205, 5095.77069031887, 5108.719020453762, 5137.768800404066, 5214.661047031931, 4845.055214233191, 4994.450610432777, 5173.120569503269, 5220.3213582235585, 5307.230451584576], "value": 4455.725779418633, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [253.78681907197955, 292.86820930581246, 303.8966751304556, 308.07116543620975, 309.3796937141032, 4460.620847227986, 5067.873303167418, 5161.071007218699, 5203.271541984125, 5209.7134657593815, 4813.903721925568, 4898.730569818658, 5048.621353398491, 5107.4892510748905, 5178.14560597501, 3489.441689329708, 3917.9020524486973, 3950.8740009097414, 3966.603339666032, 4013.71561100448, 3934.6655534279475, 3973.582773808752, 4010.097728011189, 4018.1160144768105, 4036.475258216428, 3623.547056617923, 3693.6860366868714, 3930.3121953657997, 3963.8901107689894, 4001.399790031504, 3907.905786379818, 3931.2923673738715, 3933.1907322631623, 3938.5076865391825, 3977.880663580097, 2877.2950627096498, 3428.3828910796046, 3441.105312618737, 3450.6721861423125, 3464.921047371582, 4391.846285380028, 4742.786574604151, 5027.597792176617, 5056.241563765428, 5082.79668813247, 3682.852685892561, 4454.599086082236, 4464.308032255006, 4494.943688233263, 4500.482444518857, 4476.9552304476865, 5394.676319420808, 5395.9190612140455, 5437.211604317352, 5439.809606663787, 3433.364827506894, 3757.605451427588, 3767.303048935928, 3820.3887378019663, 3826.559945606235, 4740.5733483986605, 5351.464853514617, 5589.413111623302, 5693.231413759131, 5728.598998070137, 3332.75004374668, 3750.962490375116, 3768.2651882882574, 3771.358868992223, 3781.2248489059675, 3366.225768073357, 3455.7580969332407, 3779.9021543043873, 3837.982934522354, 3863.16941857502, 3569.447844790987, 4042.4744783178, 4141.440905477722, 4492.011757413364, 4509.346144808985, 5350.571954243651, 5500.834974950758, 5502.58730595207, 5542.196381524737, 5565.2486914266865, 1289.5234955975543, 1299.52567312932, 1309.1359702596242, 1315.8946484952685, 1322.8677132286887, 708.8481218020717, 789.6604460082219, 836.652789092532, 853.3642436384933, 863.5552690364467, 4398.252297109435, 4636.721796692209, 5061.216331017926, 5068.417132029864, 5088.211327244283, 4727.64542659302, 4862.735688720887, 5444.883798821312, 5504.504639553912, 5547.944520554743, 4404.911901761947, 4455.532169122261, 4660.533946605312, 4966.751662416865, 5002.574781143643, 2768.1833859011235, 2775.403288293027, 2897.8695958681487, 2913.6212292402274, 2925.3856690094894, 5640.605157639008, 5732.226149469851, 5750.137479378127, 5761.827145185651, 5790.189373487715, 4841.346418233564, 4962.950370496321, 5536.916946245809, 5588.832335029955, 5649.68926709035, 4658.417697787777, 5010.298558201855, 5029.371328583927, 5479.630092476881, 5678.772849086073, 4678.853680848214, 5093.286939828501, 5473.233747275305, 5535.501804837584, 5550.944490555043, 5148.6910785353, 5402.459754024566, 5435.701036443033, 5453.15476101205, 5477.589180811535, 5029.74851257443, 5428.647137936022, 5709.345154503176, 5723.8855222896145, 5785.56608254391, 3179.936401272008, 3456.1186897341017, 3750.306193354238, 3750.5124333836425, 3757.6618104370737, 4795.472498025145, 5528.502434780889, 5597.748101335368, 5600.85997850057, 5637.49262566371, 5514.889702206014, 5516.172574113915, 5529.0, 5535.7785688572, 5544.833654990357, 4240.936385954152, 5290.2490704834045, 5434.347878254634, 5490.258815059916, 5508.20131080999, 5301.814436494704, 5307.628466007497, 5324.547413469899, 5326.0, 5336.839894803007, 4743.905121897474, 4781.9043619128115, 5068.9239661405845, 5136.897262054813, 5441.7279136043835, 4448.755318457516, 4492.573205545468, 5374.736936819766, 5397.433269506802, 5410.864728381822], "value": 3935.287908368, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [695.9095317608711, 741.1772932045427, 797.2107613462674, 802.0335495727649, 818.5825229133144, 15161.620959476004, 15720.292586833577, 15726.37094516218, 15732.213389330545, 15732.685346293067, 14141.646458838533, 15181.848181518182, 15521.767173492379, 15779.60550986226, 15791.131487768169, 859.703402326198, 881.3213825354474, 882.7087061269798, 883.8099808541162, 884.0099089020306, 683.5761827666829, 700.4326495538604, 844.9873251901226, 869.4348673362302, 881.5944665453899, 902.8329758994574, 907.5779762410477, 908.6183802802806, 908.9681861134861, 909.3316412436853, 901.7880798012462, 904.4889637354906, 905.2712566384058, 906.071276941134, 906.9546522673872, 527.5779376498785, 541.6371031408966, 542.2137900044951, 544.7630280827852, 673.6093066021696, 14981.550553483414, 15515.301811418512, 15520.456784012618, 15550.0, 15630.609234769221, 13872.514461993882, 14236.572902812932, 14311.35598898052, 14405.711885762226, 14408.279586020657, 11631.941840290745, 11939.820902686466, 11958.162928594915, 11974.0, 12435.378231088409, 3782.0733920189728, 3788.8105594720155, 3814.675752561008, 3863.343231650626, 3952.130531283113, 104789.0, 111568.4421577887, 112001.43999280033, 113349.86650133475, 113984.43007784992, 10817.405042722727, 10948.069414099891, 10996.670099897015, 11040.944795275973, 11435.771284574263, 21828.45428864291, 22005.449863753212, 22056.007479663454, 22067.006984685726, 22234.444138896655, 7981.68073277074, 7999.640016199285, 8015.719449819228, 8041.6381262843315, 8217.671293148327, 4439.200943830142, 4554.590086892195, 4586.289125185534, 4706.647001474903, 4772.284157376352, 3596.5863925648887, 3745.250949810049, 3745.4007358823, 3745.5318085239346, 3752.718546108998, 662.9370209830058, 676.5230512488782, 682.4165338635495, 685.9451243900476, 686.989695154573, 560.6972234993058, 561.1554610311455, 649.191756263455, 676.2527407215055, 679.6635665345685, 8147.633356498972, 9654.0, 9786.657466988621, 9803.411795292162, 9819.901800982041, 571.9752077645172, 618.9848648216838, 629.6222266639979, 645.9192600924885, 696.8571442854203, 4498.6626003049905, 4726.976365118152, 4752.0, 4769.785359658824, 4889.706617602955, 111225.44387278012, 132071.0, 161623.0, 162300.18849905912, 170356.85360903002, 137867.0, 140241.0, 140502.0, 140502.2974885119, 147230.26384868223, 106688.46655766827, 117069.0, 121664.39167804104, 122312.0, 123662.38168809099, 9213.723588292361, 9219.354645174773, 9225.86161207569, 9241.861372079558, 9280.164785169367, 60506.39493605184, 65521.344786551526, 66419.66790166018, 67402.32597674157, 67416.6629166851, 78067.0, 91516.67866570526, 94325.58511622468, 96593.51703241438, 97657.2811262653, 10617.309874858109, 11005.889941100486, 11017.724556886142, 11026.558937642405, 12692.17500862441, 12422.627321180378, 15148.469803556822, 15175.620609484851, 15179.62050948691, 15723.606909827346, 13958.23229722375, 14122.223277719824, 14328.785068223777, 14633.0, 14788.186649734367, 11935.403229838646, 12649.93675031619, 13165.472974647544, 13352.465901363837, 14342.067765595202, 13688.31558422055, 13843.861561384258, 13943.302834858014, 14310.856891430953, 14499.565013049625, 8271.917280827114, 8562.914370856211, 9985.550650220612, 10367.822757221069, 10952.56189752401, 9964.850527241952, 10960.068394186264, 11519.8272025918, 11532.192746507664, 11548.364839933549], "value": 7864.541305724012, "unit": "score"}], "name": "dromaeo_css", "value": 7236.80257032008, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 131.0, 112.0, 113.0, 113.0, 112.0, 113.0, 112.0, 113.0], "value": 114.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 112.0, 133.0, 162.0, 109.0, 131.0, 130.0, 108.0, 164.0, 128.0], "value": 132.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 183.0, 182.0, 183.0, 180.0, 180.0, 179.0, 184.0, 183.0], "value": 182.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 99.0, 76.0, 150.0, 58.0, 60.0, 58.0, 69.0, 138.0, 57.0], "value": 84.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 133.0, 70.0, 77.0, 84.0, 114.0, 67.0, 70.0, 116.0], "value": 89.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 92.0, 128.0, 132.0, 85.0, 89.0, 85.0, 88.0, 85.0], "value": 95.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 98.0, 97.0, 97.0, 97.0, 106.0, 91.0, 152.0, 154.0, 97.0], "value": 108.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 87.0, 84.0, 87.0, 84.0, 84.0, 86.0, 84.0, 84.0, 85.0], "value": 84.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 57.0, 54.0, 55.0, 56.0, 56.0, 54.0, 54.0, 55.0], "value": 55.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 64.0, 77.0, 113.0, 108.0, 81.0, 71.0, 73.0, 71.0, 71.0], "value": 79.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 86.0, 84.0, 84.0, 85.0, 84.0, 87.0, 85.0, 91.0], "value": 86.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 137.0, 140.0, 145.0, 145.0, 139.0, 140.0, 143.0, 182.0, 139.0], "value": 144.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 164.0, 171.0, 169.0, 168.0, 169.0, 169.0, 169.0, 168.0, 167.0], "value": 168.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 64.0, 69.0, 61.0, 68.0, 66.0, 67.0, 68.0], "value": 66.6, "unit": "score"}], "name": "kraken", "value": 1492.6, "alertThreshold": 2.0}]} 16:34:17 INFO - Return code: 0 16:34:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:34:17 INFO - # TBPL SUCCESS # 16:34:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:34:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:34:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:34:17 INFO - 2016-08-29 16:34:17.960 screenresolution[1660:10216] starting screenresolution argv=screenresolution get 16:34:17 INFO - 2016-08-29 16:34:17.962 screenresolution[1660:10216] Display 0: 1600x1200x32@60 16:34:17 INFO - 2016-08-29 16:34:17.968 screenresolution[1661:10219] starting screenresolution argv=screenresolution list 16:34:17 INFO - Available Modes on Display 0 16:34:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:34:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:34:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:34:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:34:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:34:18 INFO - Intel Iris: 16:34:18 INFO - Chipset Model: Intel Iris 16:34:18 INFO - Type: GPU 16:34:18 INFO - Bus: Built-In 16:34:18 INFO - VRAM (Dynamic, Max): 1536 MB 16:34:18 INFO - Vendor: Intel (0x8086) 16:34:18 INFO - Device ID: 0x0a2e 16:34:18 INFO - Revision ID: 0x0009 16:34:18 INFO - Displays: 16:34:18 INFO - Display: 16:34:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:34:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:34:18 INFO - Display Serial Number: 1600x1200 60 16:34:18 INFO - Main Display: Yes 16:34:18 INFO - Mirror: Off 16:34:18 INFO - Online: Yes 16:34:18 INFO - Rotation: Supported 16:34:18 INFO - Return code: 0 16:34:18 INFO - Running post-action listener: _resource_record_post_action 16:34:18 INFO - [mozharness: 2016-08-29 23:34:18.203358Z] Finished run-tests step (success) 16:34:18 INFO - Running post-run listener: _resource_record_post_run 16:34:18 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 37262336; Write bytes: 706750464; Read time: 238; Write time: 1949 16:34:18 INFO - TinderboxPrint: CPU usage
53.5% 16:34:18 INFO - TinderboxPrint: I/O read bytes / time
37,262,336 / 238 16:34:18 INFO - TinderboxPrint: I/O write bytes / time
706,750,464 / 1,949 16:34:18 INFO - TinderboxPrint: CPU idle
2,255.3 (46.5%) 16:34:18 INFO - TinderboxPrint: CPU system
970.2 (20.0%) 16:34:18 INFO - TinderboxPrint: CPU user
1,628.3 (33.5%) 16:34:18 INFO - TinderboxPrint: Swap in / out
443,334,656 / 0 16:34:18 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 404140544; Write bytes: 394618368; Read time: 30586; Write time: 787 16:34:18 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 29973504; Write bytes: 295485952; Read time: 178; Write time: 1119 16:34:18 INFO - Running post-run listener: _upload_blobber_files 16:34:18 INFO - Blob upload gear active. 16:34:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:34:18 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:34:18 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:34:18 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:34:19 INFO - (blobuploader) - INFO - Open directory for files ... 16:34:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:34:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:34:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:34:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:34:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:34:19 INFO - (blobuploader) - INFO - Done attempting. 16:34:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:34:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:34:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:34:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:34:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:34:20 INFO - (blobuploader) - INFO - Done attempting. 16:34:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:34:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:34:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:34:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:34:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:34:23 INFO - (blobuploader) - INFO - Done attempting. 16:34:23 INFO - (blobuploader) - INFO - Iteration through files over. 16:34:23 INFO - Return code: 0 16:34:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:34:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:34:23 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c50804099b25dc9e848e2f4c385d4567700bdcf216d0df417208e4184218845ae93d5dcdc529b478bbde62a717e35fc847b0aae3e71ad8292bec3a67106da80d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fab4c4f84fcf032557acff30dd88772b7666b60ec0a3562f8be956c9fc4a7d63fe2035cee6e43fbd24fd825a1f79de377754511a2bbd1b8b0d75fb279ca58178", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/70e439407fdc01bd4fc25d44c7fe79d19a3180892c1323f9fa02b218962dff78d8917cc9f680801f79c65ea453c859e0d0f57214f0643d06f1c7479bc426cfa6"} 16:34:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:34:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:34:23 INFO - Contents: 16:34:23 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c50804099b25dc9e848e2f4c385d4567700bdcf216d0df417208e4184218845ae93d5dcdc529b478bbde62a717e35fc847b0aae3e71ad8292bec3a67106da80d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fab4c4f84fcf032557acff30dd88772b7666b60ec0a3562f8be956c9fc4a7d63fe2035cee6e43fbd24fd825a1f79de377754511a2bbd1b8b0d75fb279ca58178", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/70e439407fdc01bd4fc25d44c7fe79d19a3180892c1323f9fa02b218962dff78d8917cc9f680801f79c65ea453c859e0d0f57214f0643d06f1c7479bc426cfa6"} 16:34:23 INFO - Running post-run listener: copy_logs_to_upload_dir 16:34:23 INFO - Copying logs to upload dir... 16:34:23 INFO - mkdir: /builds/slave/test/build/upload/logs 16:34:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1336.080305 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 16 secs) (at 2016-08-29 16:34:23.253074) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-29 16:34:23.257018) ========= 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.m8opt3Knae/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.p1Rxy3dBrm/Listeners TMPDIR=/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/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/Autoland/sha512/c50804099b25dc9e848e2f4c385d4567700bdcf216d0df417208e4184218845ae93d5dcdc529b478bbde62a717e35fc847b0aae3e71ad8292bec3a67106da80d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fab4c4f84fcf032557acff30dd88772b7666b60ec0a3562f8be956c9fc4a7d63fe2035cee6e43fbd24fd825a1f79de377754511a2bbd1b8b0d75fb279ca58178", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/70e439407fdc01bd4fc25d44c7fe79d19a3180892c1323f9fa02b218962dff78d8917cc9f680801f79c65ea453c859e0d0f57214f0643d06f1c7479bc426cfa6"} build_url:https://queue.taskcluster.net/v1/task/cSlvNJf9R7-SlC7kg8mnTA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011441 build_url: 'https://queue.taskcluster.net/v1/task/cSlvNJf9R7-SlC7kg8mnTA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c50804099b25dc9e848e2f4c385d4567700bdcf216d0df417208e4184218845ae93d5dcdc529b478bbde62a717e35fc847b0aae3e71ad8292bec3a67106da80d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fab4c4f84fcf032557acff30dd88772b7666b60ec0a3562f8be956c9fc4a7d63fe2035cee6e43fbd24fd825a1f79de377754511a2bbd1b8b0d75fb279ca58178", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/70e439407fdc01bd4fc25d44c7fe79d19a3180892c1323f9fa02b218962dff78d8917cc9f680801f79c65ea453c859e0d0f57214f0643d06f1c7479bc426cfa6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-29 16:34:23.290435) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 16:34:23.290749) ========= 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.m8opt3Knae/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.p1Rxy3dBrm/Listeners TMPDIR=/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/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.005246 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 16:34:23.339337) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-29 16:34:23.339611) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-29 16:34:23.339930) ========= ========= Total master_lag: 0.17 =========