builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0097 starttime: 1471524522.47 results: success (0) buildid: 20160817135515 builduid: 9a767e780fe448339518c3edc7e9a4fc revision: 46132442a61f61af64ebb0234392d51379fa40d4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 05:48:42.472884) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 05:48:42.473326) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 05:48:42.473619) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 05:48:42.503486) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 05:48:42.503882) ========= 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.HLtUu5Au1X/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.90y1QBqSLO/Listeners TMPDIR=/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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-18 05:48:42-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 29.3M=0s 2016-08-18 05:48:42 (29.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.107101 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 05:48:42.634404) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 05:48:42.634795) ========= 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.HLtUu5Au1X/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.90y1QBqSLO/Listeners TMPDIR=/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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.028662 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 05:48:42.683887) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-08-18 05:48:42.684260) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 46132442a61f61af64ebb0234392d51379fa40d4 --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 46132442a61f61af64ebb0234392d51379fa40d4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HLtUu5Au1X/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.90y1QBqSLO/Listeners TMPDIR=/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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-18 05:48:42,762 truncating revision to first 12 chars 2016-08-18 05:48:42,762 Setting DEBUG logging. 2016-08-18 05:48:42,762 attempt 1/10 2016-08-18 05:48:42,762 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/46132442a61f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-18 05:48:44,408 unpacking tar archive at: autoland-46132442a61f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.905851 ========= master_lag: 2.36 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-08-18 05:48:46.945653) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 05:48:46.946012) ========= script_repo_revision: 46132442a61f61af64ebb0234392d51379fa40d4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 05:48:46.946454) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 05:48:46.946728) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 05:48:46.968995) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-18 05:48:46.969408) ========= /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.HLtUu5Au1X/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.90y1QBqSLO/Listeners TMPDIR=/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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 05:48:47 INFO - MultiFileLogger online at 20160818 05:48:47 in /builds/slave/test 05:48:47 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 05:48:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:48:47 INFO - {'append_to_log': False, 05:48:47 INFO - 'base_work_dir': '/builds/slave/test', 05:48:47 INFO - 'blob_upload_branch': 'Autoland', 05:48:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:48:47 INFO - 'branch': 'Autoland', 05:48:47 INFO - 'buildbot_json_path': 'buildprops.json', 05:48:47 INFO - 'config_files': ('talos/mac_config.py',), 05:48:47 INFO - 'default_actions': ('clobber', 05:48:47 INFO - 'read-buildbot-config', 05:48:47 INFO - 'download-and-extract', 05:48:47 INFO - 'populate-webroot', 05:48:47 INFO - 'create-virtualenv', 05:48:47 INFO - 'install', 05:48:47 INFO - 'run-tests'), 05:48:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:48:47 INFO - 'download_minidump_stackwalk': True, 05:48:47 INFO - 'download_symbols': 'ondemand', 05:48:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:48:47 INFO - 'tooltool.py': '/tools/tooltool.py', 05:48:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:48:47 INFO - '/tools/misc-python/virtualenv.py')}, 05:48:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:48:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:48:47 INFO - 'installer_path': 'installer.exe', 05:48:47 INFO - 'log_level': 'info', 05:48:47 INFO - 'log_name': 'talos', 05:48:47 INFO - 'log_to_console': True, 05:48:47 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:48:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:48:47 INFO - 'opt_config_files': (), 05:48:47 INFO - 'pip_index': False, 05:48:47 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:48:47 INFO - 'cmd': ('bash', 05:48:47 INFO - '-c', 05:48:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:48:47 INFO - 'enabled': True, 05:48:47 INFO - 'halt_on_failure': False, 05:48:47 INFO - 'name': 'check_screen_resolution'},), 05:48:47 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:48:47 INFO - 'cmd': ('bash', 05:48:47 INFO - '-c', 05:48:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:48:47 INFO - 'enabled': True, 05:48:47 INFO - 'halt_on_failure': False, 05:48:47 INFO - 'name': 'check_screen_resolution'},), 05:48:47 INFO - 'run_cmd_checks_enabled': True, 05:48:47 INFO - 'sps_profile': False, 05:48:47 INFO - 'sps_profile_interval': 0, 05:48:47 INFO - 'suite': 'dromaeojs', 05:48:47 INFO - 'system_bits': '32', 05:48:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:48:47 INFO - 'title': 't-yosemite-r7-0097', 05:48:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:48:47 INFO - 'use_talos_json': True, 05:48:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:48:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:48:47 INFO - 'work_dir': 'build'} 05:48:47 INFO - [mozharness: 2016-08-18 12:48:47.168647Z] Running clobber step. 05:48:47 INFO - Running pre-action listener: _resource_record_pre_action 05:48:47 INFO - Running main action method: clobber 05:48:47 INFO - rmtree: /builds/slave/test/build 05:48:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:48:49 INFO - Running post-action listener: _resource_record_post_action 05:48:49 INFO - [mozharness: 2016-08-18 12:48:49.396734Z] Finished clobber step (success) 05:48:49 INFO - [mozharness: 2016-08-18 12:48:49.396982Z] Running read-buildbot-config step. 05:48:49 INFO - Running pre-action listener: _resource_record_pre_action 05:48:49 INFO - Running main action method: read_buildbot_config 05:48:49 INFO - Using buildbot properties: 05:48:49 INFO - { 05:48:49 INFO - "project": "", 05:48:49 INFO - "product": "firefox", 05:48:49 INFO - "script_repo_revision": "production", 05:48:49 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 05:48:49 INFO - "repository": "", 05:48:49 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 05:48:49 INFO - "buildid": "20160817135515", 05:48:49 INFO - "pgo_build": "False", 05:48:49 INFO - "basedir": "/builds/slave/test", 05:48:49 INFO - "buildnumber": 170, 05:48:49 INFO - "slavename": "t-yosemite-r7-0097", 05:48:49 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 05:48:49 INFO - "platform": "yosemite_r7", 05:48:49 INFO - "scheduler": "tests-autoland-macosx64-talos", 05:48:49 INFO - "branch": "autoland", 05:48:49 INFO - "repo_path": "integration/autoland", 05:48:49 INFO - "revision": "46132442a61f61af64ebb0234392d51379fa40d4", 05:48:49 INFO - "stage_platform": "macosx64", 05:48:49 INFO - "builduid": "9a767e780fe448339518c3edc7e9a4fc", 05:48:49 INFO - "slavebuilddir": "test" 05:48:49 INFO - } 05:48:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/LUg-yIxMTgC5Koy2y6SI4Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 05:48:49 INFO - Running post-action listener: _resource_record_post_action 05:48:49 INFO - [mozharness: 2016-08-18 12:48:49.402521Z] Finished read-buildbot-config step (success) 05:48:49 INFO - [mozharness: 2016-08-18 12:48:49.402741Z] Running download-and-extract step. 05:48:49 INFO - Running pre-action listener: _resource_record_pre_action 05:48:49 INFO - Running main action method: download_and_extract 05:48:49 INFO - mkdir: /builds/slave/test/build/tests 05:48: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')]} 05:48:49 INFO - trying https://queue.taskcluster.net/v1/task/LUg-yIxMTgC5Koy2y6SI4Q/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 05:48:49 INFO - Downloading https://queue.taskcluster.net/v1/task/LUg-yIxMTgC5Koy2y6SI4Q/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 05:48:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LUg-yIxMTgC5Koy2y6SI4Q/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 05:48:49 INFO - Downloaded 1199 bytes. 05:48:49 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 05:48:49 INFO - Using the following test package requirements: 05:48:49 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:48:49 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:48:49 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 05:48:49 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:48:49 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 05:48:49 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:48:49 INFO - u'jsshell-mac.zip'], 05:48:49 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:48:49 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 05:48:49 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:48:49 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:48:49 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 05:48:49 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:48:49 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 05:48:49 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:48:49 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 05:48:49 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:48:49 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 05:48:49 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:48: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')]} 05:48:49 INFO - trying https://queue.taskcluster.net/v1/task/LUg-yIxMTgC5Koy2y6SI4Q/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:48:49 INFO - Downloading https://queue.taskcluster.net/v1/task/LUg-yIxMTgC5Koy2y6SI4Q/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 05:48:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LUg-yIxMTgC5Koy2y6SI4Q/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 05:48:50 INFO - Downloaded 16338748 bytes. 05:48:50 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 05:48:58 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 05:48: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')]} 05:48:58 INFO - trying https://queue.taskcluster.net/v1/task/LUg-yIxMTgC5Koy2y6SI4Q/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:48:58 INFO - Downloading https://queue.taskcluster.net/v1/task/LUg-yIxMTgC5Koy2y6SI4Q/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 05:48:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LUg-yIxMTgC5Koy2y6SI4Q/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 05:48:58 INFO - Downloaded 16338748 bytes. 05:48:58 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 05:49:05 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')]} 05:49:05 INFO - trying https://queue.taskcluster.net/v1/task/LUg-yIxMTgC5Koy2y6SI4Q/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 05:49:05 INFO - Downloading https://queue.taskcluster.net/v1/task/LUg-yIxMTgC5Koy2y6SI4Q/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 05:49:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LUg-yIxMTgC5Koy2y6SI4Q/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 05:49:06 INFO - Downloaded 14413719 bytes. 05:49:06 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 05:49:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:49:07 INFO - trying https://queue.taskcluster.net/v1/task/LUg-yIxMTgC5Koy2y6SI4Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:49:07 INFO - Downloading https://queue.taskcluster.net/v1/task/LUg-yIxMTgC5Koy2y6SI4Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 05:49:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LUg-yIxMTgC5Koy2y6SI4Q/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 05:49:10 INFO - Downloaded 111262230 bytes. 05:49:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LUg-yIxMTgC5Koy2y6SI4Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:49:10 INFO - mkdir: /builds/slave/test/properties 05:49:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:49:10 INFO - Writing to file /builds/slave/test/properties/build_url 05:49:10 INFO - Contents: 05:49:10 INFO - build_url:https://queue.taskcluster.net/v1/task/LUg-yIxMTgC5Koy2y6SI4Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:49:10 INFO - Running post-action listener: _resource_record_post_action 05:49:10 INFO - Running post-action listener: set_extra_try_arguments 05:49:10 INFO - [mozharness: 2016-08-18 12:49:10.834607Z] Finished download-and-extract step (success) 05:49:10 INFO - [mozharness: 2016-08-18 12:49:10.834731Z] Running populate-webroot step. 05:49:10 INFO - Running pre-action listener: _resource_record_pre_action 05:49:10 INFO - Running main action method: populate_webroot 05:49:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:49:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:49:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:49:10 INFO - '--tppagecycles', 05:49:10 INFO - '7'], 05:49:10 INFO - 'tests': ['tsvgm']}}, 05:49:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:49:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:49:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:49:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:49:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:49:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:49:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:49:10 INFO - 'talos_options': ['--disable-e10s'], 05:49:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:49:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:49:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:49:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:49:10 INFO - 'talos_options': ['--disable-e10s'], 05:49:10 INFO - 'tests': ['damp', 'tps']}, 05:49:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:49:10 INFO - 'tests': ['damp', 'tps']}, 05:49:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:49:10 INFO - 'tests': ['dromaeo_dom']}, 05:49:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:49:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:49:10 INFO - 'tests': ['basic_compositor_video']}, 05:49:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:49:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:49:10 INFO - 'tests': ['a11yr', 05:49:10 INFO - 'ts_paint', 05:49:10 INFO - 'tpaint', 05:49:10 INFO - 'sessionrestore', 05:49:10 INFO - 'sessionrestore_no_auto_restore', 05:49:10 INFO - 'tabpaint']}, 05:49:10 INFO - 'other-e10s': {'tests': ['a11yr', 05:49:10 INFO - 'ts_paint', 05:49:10 INFO - 'tpaint', 05:49:10 INFO - 'sessionrestore', 05:49:10 INFO - 'sessionrestore_no_auto_restore', 05:49:10 INFO - 'tabpaint']}, 05:49:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:49:10 INFO - 'ts_paint', 05:49:10 INFO - 'tpaint', 05:49:10 INFO - 'sessionrestore', 05:49:10 INFO - 'sessionrestore_no_auto_restore', 05:49:10 INFO - 'tabpaint']}, 05:49:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:49:10 INFO - 'ts_paint', 05:49:10 INFO - 'tpaint', 05:49:10 INFO - 'sessionrestore', 05:49:10 INFO - 'sessionrestore_no_auto_restore', 05:49:10 INFO - 'tabpaint']}, 05:49:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:49:10 INFO - 'tests': ['a11yr', 05:49:10 INFO - 'ts_paint', 05:49:10 INFO - 'tpaint', 05:49:10 INFO - 'sessionrestore', 05:49:10 INFO - 'sessionrestore_no_auto_restore', 05:49:10 INFO - 'tabpaint']}, 05:49:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:49:10 INFO - 'tests': ['a11yr', 05:49:10 INFO - 'ts_paint', 05:49:10 INFO - 'tpaint', 05:49:10 INFO - 'sessionrestore', 05:49:10 INFO - 'sessionrestore_no_auto_restore', 05:49:10 INFO - 'tabpaint']}, 05:49:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:49:10 INFO - 'tests': ['tsvgx', 05:49:10 INFO - 'tsvgr_opacity', 05:49:10 INFO - 'tart', 05:49:10 INFO - 'tscrollx', 05:49:10 INFO - 'cart']}, 05:49:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:49:10 INFO - 'tsvgr_opacity', 05:49:10 INFO - 'tart', 05:49:10 INFO - 'tscrollx', 05:49:10 INFO - 'cart']}, 05:49:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:49:10 INFO - 'talos_options': ['--disable-e10s'], 05:49:10 INFO - 'tests': ['tp5o']}, 05:49:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:49:10 INFO - 'tests': ['tp5o']}, 05:49:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:49:10 INFO - 'talos_options': ['--disable-e10s', 05:49:10 INFO - '--xperf_path', 05:49:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:49:10 INFO - 'tests': ['tp5n']}, 05:49:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:49:10 INFO - 'talos_options': ['--xperf_path', 05:49:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:49:10 INFO - 'tests': ['tp5n']}}, 05:49:10 INFO - 'talos.zip': {'path': '', 05:49:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:49:10 INFO - Running post-action listener: _resource_record_post_action 05:49:10 INFO - [mozharness: 2016-08-18 12:49:10.853121Z] Finished populate-webroot step (success) 05:49:10 INFO - [mozharness: 2016-08-18 12:49:10.853229Z] Running create-virtualenv step. 05:49:10 INFO - Running pre-action listener: _resource_record_pre_action 05:49:10 INFO - Running main action method: create_virtualenv 05:49:10 INFO - Creating virtualenv /builds/slave/test/build/venv 05:49:10 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 05:49:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:49:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:49:10 INFO - Using real prefix '/tools/python27' 05:49:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:49:11 INFO - Installing distribute.............................................................................................................................................................................................done. 05:49:14 INFO - Installing pip.................done. 05:49:14 INFO - Return code: 0 05:49:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:49:14 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')]} 05:49:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:49:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:14 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')]} 05:49:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:49:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:14 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 0x103feba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104080618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa8cac8cba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f4fd30>, '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 0x103cb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f47b20>, '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.HLtUu5Au1X/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.90y1QBqSLO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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 05:49:14 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 05:49:14 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 05:49:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HLtUu5Au1X/Render', 05:49:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:49:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:49:14 INFO - 'HOME': '/Users/cltbld', 05:49:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:49:14 INFO - 'LOGNAME': 'cltbld', 05:49:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:49:14 INFO - 'MOZ_NO_REMOTE': '1', 05:49:14 INFO - 'NO_EM_RESTART': '1', 05:49:14 INFO - 'PAGER': '/bin/cat', 05:49:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:49:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:49:14 INFO - 'PWD': '/builds/slave/test', 05:49:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:49:14 INFO - 'SHELL': '/bin/bash', 05:49:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.90y1QBqSLO/Listeners', 05:49:14 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 05:49:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:49:14 INFO - 'USER': 'cltbld', 05:49:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:49:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:49:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:49:14 INFO - 'XPC_FLAGS': '0x0', 05:49:14 INFO - 'XPC_SERVICE_NAME': '0', 05:49:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:49:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:49:14 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 05:49:14 INFO - Cleaning up... 05:49:14 INFO - Return code: 0 05:49:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:49:14 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')]} 05:49:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:49:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:14 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')]} 05:49:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:49:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:14 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 0x103feba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104080618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa8cac8cba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f4fd30>, '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 0x103cb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f47b20>, '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.HLtUu5Au1X/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.90y1QBqSLO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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 05:49:14 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 05:49:14 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 05:49:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HLtUu5Au1X/Render', 05:49:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:49:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:49:14 INFO - 'HOME': '/Users/cltbld', 05:49:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:49:14 INFO - 'LOGNAME': 'cltbld', 05:49:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:49:14 INFO - 'MOZ_NO_REMOTE': '1', 05:49:14 INFO - 'NO_EM_RESTART': '1', 05:49:14 INFO - 'PAGER': '/bin/cat', 05:49:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:49:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:49:14 INFO - 'PWD': '/builds/slave/test', 05:49:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:49:14 INFO - 'SHELL': '/bin/bash', 05:49:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.90y1QBqSLO/Listeners', 05:49:14 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 05:49:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:49:14 INFO - 'USER': 'cltbld', 05:49:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:49:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:49:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:49:14 INFO - 'XPC_FLAGS': '0x0', 05:49:14 INFO - 'XPC_SERVICE_NAME': '0', 05:49:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:49:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:49:15 INFO - Downloading/unpacking psutil>=3.1.1 05:49:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:49:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:49:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:49:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:49:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:49:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:49:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:49:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:49:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:49:18 INFO - Installing collected packages: psutil 05:49:18 INFO - Running setup.py install for psutil 05:49:18 INFO - building 'psutil._psutil_osx' extension 05:49:18 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 05:49:19 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 05:49:19 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 05:49:19 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 05:49:19 INFO - building 'psutil._psutil_posix' extension 05:49:19 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 05:49:19 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:49:19 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:49:19 INFO - ^ 05:49:19 INFO - 1 warning generated. 05:49:19 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 05:49:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:49:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:49:19 INFO - Successfully installed psutil 05:49:19 INFO - Cleaning up... 05:49:19 INFO - Return code: 0 05:49:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:49:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:49:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:49:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:49:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:49:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:19 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 0x103feba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104080618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa8cac8cba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f4fd30>, '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 0x103cb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f47b20>, '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.HLtUu5Au1X/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.90y1QBqSLO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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 05:49:19 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 05:49:19 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 05:49:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HLtUu5Au1X/Render', 05:49:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:49:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:49:19 INFO - 'HOME': '/Users/cltbld', 05:49:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:49:19 INFO - 'LOGNAME': 'cltbld', 05:49:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:49:19 INFO - 'MOZ_NO_REMOTE': '1', 05:49:19 INFO - 'NO_EM_RESTART': '1', 05:49:19 INFO - 'PAGER': '/bin/cat', 05:49:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:49:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:49:19 INFO - 'PWD': '/builds/slave/test', 05:49:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:49:19 INFO - 'SHELL': '/bin/bash', 05:49:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.90y1QBqSLO/Listeners', 05:49:19 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 05:49:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:49:19 INFO - 'USER': 'cltbld', 05:49:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:49:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:49:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:49:19 INFO - 'XPC_FLAGS': '0x0', 05:49:19 INFO - 'XPC_SERVICE_NAME': '0', 05:49:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:49:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:49:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:49:20 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 05:49:20 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 05:49:20 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 05:49:20 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 05:49:20 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 05:49:20 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 05:49:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:49:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:49:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:49:22 INFO - Installing collected packages: mozsystemmonitor 05:49:22 INFO - Running setup.py install for mozsystemmonitor 05:49:22 INFO - Successfully installed mozsystemmonitor 05:49:22 INFO - Cleaning up... 05:49:22 INFO - Return code: 0 05:49:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:49:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:49:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:49:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:49:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:49:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:22 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 0x103feba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104080618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa8cac8cba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f4fd30>, '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 0x103cb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f47b20>, '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.HLtUu5Au1X/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.90y1QBqSLO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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 05:49:22 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 05:49:22 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 05:49:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HLtUu5Au1X/Render', 05:49:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:49:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:49:22 INFO - 'HOME': '/Users/cltbld', 05:49:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:49:22 INFO - 'LOGNAME': 'cltbld', 05:49:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:49:22 INFO - 'MOZ_NO_REMOTE': '1', 05:49:22 INFO - 'NO_EM_RESTART': '1', 05:49:22 INFO - 'PAGER': '/bin/cat', 05:49:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:49:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:49:22 INFO - 'PWD': '/builds/slave/test', 05:49:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:49:22 INFO - 'SHELL': '/bin/bash', 05:49:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.90y1QBqSLO/Listeners', 05:49:22 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 05:49:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:49:22 INFO - 'USER': 'cltbld', 05:49:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:49:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:49:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:49:22 INFO - 'XPC_FLAGS': '0x0', 05:49:22 INFO - 'XPC_SERVICE_NAME': '0', 05:49:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:49:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:49:22 INFO - Downloading/unpacking jsonschema==2.5.1 05:49:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:49:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:49:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:49:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:49:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:49:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:49:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:49:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:49:25 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 05:49:25 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 05:49:25 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 05:49:25 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 05:49:25 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 05:49:25 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 05:49:25 INFO - Downloading functools32-3.2.3-2.tar.gz 05:49:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:49:25 INFO - warning: no files found matching '*.txt' 05:49:25 INFO - no previously-included directories found matching 'build' 05:49:25 INFO - no previously-included directories found matching 'dist' 05:49:25 INFO - no previously-included directories found matching '.git*' 05:49:25 INFO - Installing collected packages: jsonschema, functools32 05:49:25 INFO - Running setup.py install for functools32 05:49:25 INFO - warning: no files found matching '*.txt' 05:49:25 INFO - no previously-included directories found matching 'build' 05:49:25 INFO - no previously-included directories found matching 'dist' 05:49:25 INFO - no previously-included directories found matching '.git*' 05:49:26 INFO - Successfully installed jsonschema functools32 05:49:26 INFO - Cleaning up... 05:49:26 INFO - Return code: 0 05:49:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:49:26 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')]} 05:49:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:49:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:26 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')]} 05:49:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:49:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:26 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 0x103feba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104080618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa8cac8cba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f4fd30>, '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 0x103cb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f47b20>, '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.HLtUu5Au1X/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.90y1QBqSLO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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 05:49:26 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 05:49:26 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 05:49:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HLtUu5Au1X/Render', 05:49:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:49:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:49:26 INFO - 'HOME': '/Users/cltbld', 05:49:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:49:26 INFO - 'LOGNAME': 'cltbld', 05:49:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:49:26 INFO - 'MOZ_NO_REMOTE': '1', 05:49:26 INFO - 'NO_EM_RESTART': '1', 05:49:26 INFO - 'PAGER': '/bin/cat', 05:49:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:49:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:49:26 INFO - 'PWD': '/builds/slave/test', 05:49:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:49:26 INFO - 'SHELL': '/bin/bash', 05:49:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.90y1QBqSLO/Listeners', 05:49:26 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 05:49:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:49:26 INFO - 'USER': 'cltbld', 05:49:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:49:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:49:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:49:26 INFO - 'XPC_FLAGS': '0x0', 05:49:26 INFO - 'XPC_SERVICE_NAME': '0', 05:49:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:49:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:49:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:49:26 INFO - Cleaning up... 05:49:26 INFO - Return code: 0 05:49:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:49:26 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')]} 05:49:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:49:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:26 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')]} 05:49:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:49:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:26 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 0x103feba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104080618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa8cac8cba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f4fd30>, '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 0x103cb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f47b20>, '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.HLtUu5Au1X/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.90y1QBqSLO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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 05:49:26 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 05:49:26 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 05:49:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HLtUu5Au1X/Render', 05:49:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:49:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:49:26 INFO - 'HOME': '/Users/cltbld', 05:49:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:49:26 INFO - 'LOGNAME': 'cltbld', 05:49:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:49:26 INFO - 'MOZ_NO_REMOTE': '1', 05:49:26 INFO - 'NO_EM_RESTART': '1', 05:49:26 INFO - 'PAGER': '/bin/cat', 05:49:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:49:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:49:26 INFO - 'PWD': '/builds/slave/test', 05:49:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:49:26 INFO - 'SHELL': '/bin/bash', 05:49:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.90y1QBqSLO/Listeners', 05:49:26 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 05:49:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:49:26 INFO - 'USER': 'cltbld', 05:49:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:49:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:49:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:49:26 INFO - 'XPC_FLAGS': '0x0', 05:49:26 INFO - 'XPC_SERVICE_NAME': '0', 05:49:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:49:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:49:26 INFO - Downloading/unpacking blobuploader==1.2.4 05:49:26 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 05:49:26 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 05:49:26 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 05:49:26 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 05:49:26 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 05:49:26 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 05:49:29 INFO - Downloading blobuploader-1.2.4.tar.gz 05:49:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:49:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:49:29 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 05:49:29 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 05:49:29 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 05:49:29 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 05:49:29 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 05:49:29 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 05:49:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:49:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:49:29 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 05:49:29 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 05:49:29 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 05:49:29 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 05:49:29 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 05:49:29 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 05:49:30 INFO - Downloading docopt-0.6.1.tar.gz 05:49:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:49:30 INFO - Installing collected packages: blobuploader, requests, docopt 05:49:30 INFO - Running setup.py install for blobuploader 05:49:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:49:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:49:30 INFO - Running setup.py install for requests 05:49:30 INFO - Running setup.py install for docopt 05:49:31 INFO - Successfully installed blobuploader requests docopt 05:49:31 INFO - Cleaning up... 05:49:31 INFO - Return code: 0 05:49:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:49:31 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')]} 05:49:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:49:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:31 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')]} 05:49:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:49:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:31 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 0x103feba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104080618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa8cac8cba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f4fd30>, '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 0x103cb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f47b20>, '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.HLtUu5Au1X/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.90y1QBqSLO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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 05:49:31 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 05:49:31 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 05:49:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HLtUu5Au1X/Render', 05:49:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:49:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:49:31 INFO - 'HOME': '/Users/cltbld', 05:49:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:49:31 INFO - 'LOGNAME': 'cltbld', 05:49:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:49:31 INFO - 'MOZ_NO_REMOTE': '1', 05:49:31 INFO - 'NO_EM_RESTART': '1', 05:49:31 INFO - 'PAGER': '/bin/cat', 05:49:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:49:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:49:31 INFO - 'PWD': '/builds/slave/test', 05:49:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:49:31 INFO - 'SHELL': '/bin/bash', 05:49:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.90y1QBqSLO/Listeners', 05:49:31 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 05:49:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:49:31 INFO - 'USER': 'cltbld', 05:49:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:49:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:49:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:49:31 INFO - 'XPC_FLAGS': '0x0', 05:49:31 INFO - 'XPC_SERVICE_NAME': '0', 05:49:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:49:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:49:31 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-aUk_iE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:49:31 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-sfCkaG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:49:31 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-k48mZy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:49:31 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-OwRlVg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:49:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:49:31 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-LPdOEu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:49:32 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-riUfEU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:49:32 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-EHrxoe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:49:32 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-8HvjS5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:49:32 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-lggWt7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:49:32 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-BcgFv7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:49:32 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-WXUlOS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:49:32 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-FDLKkS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:49:32 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-ZHtWoZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:49:32 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-0PvaxG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:49:32 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-0BkDMK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:49:33 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-w3x902-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:49:33 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-G2F3DU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:49:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:49:33 INFO - Running setup.py install for manifestparser 05:49:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:49:33 INFO - Running setup.py install for mozcrash 05:49:33 INFO - Running setup.py install for mozdebug 05:49:33 INFO - Running setup.py install for mozdevice 05:49:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:49:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:49:33 INFO - Running setup.py install for mozfile 05:49:34 INFO - Running setup.py install for mozhttpd 05:49:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:49:34 INFO - Running setup.py install for mozinfo 05:49:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:49:34 INFO - Running setup.py install for mozInstall 05:49:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:49:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:49:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:49:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:49:34 INFO - Running setup.py install for mozleak 05:49:34 INFO - Running setup.py install for mozlog 05:49:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:49:34 INFO - Running setup.py install for moznetwork 05:49:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:49:34 INFO - Running setup.py install for mozprocess 05:49:35 INFO - Running setup.py install for mozprofile 05:49:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:49:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:49:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:49:35 INFO - Running setup.py install for mozrunner 05:49:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:49:35 INFO - Running setup.py install for mozscreenshot 05:49:35 INFO - Running setup.py install for moztest 05:49:35 INFO - Running setup.py install for mozversion 05:49:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:49:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:49:35 INFO - Cleaning up... 05:49:35 INFO - Return code: 0 05:49:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:49:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:49:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:49:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:49:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:49:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:35 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 0x103feba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104080618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa8cac8cba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f4fd30>, '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 0x103cb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f47b20>, '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.HLtUu5Au1X/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.90y1QBqSLO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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 05:49:35 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 05:49:35 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 05:49:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HLtUu5Au1X/Render', 05:49:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:49:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:49:35 INFO - 'HOME': '/Users/cltbld', 05:49:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:49:35 INFO - 'LOGNAME': 'cltbld', 05:49:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:49:35 INFO - 'MOZ_NO_REMOTE': '1', 05:49:35 INFO - 'NO_EM_RESTART': '1', 05:49:35 INFO - 'PAGER': '/bin/cat', 05:49:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:49:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:49:35 INFO - 'PWD': '/builds/slave/test', 05:49:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:49:35 INFO - 'SHELL': '/bin/bash', 05:49:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.90y1QBqSLO/Listeners', 05:49:35 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 05:49:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:49:35 INFO - 'USER': 'cltbld', 05:49:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:49:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:49:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:49:35 INFO - 'XPC_FLAGS': '0x0', 05:49:35 INFO - 'XPC_SERVICE_NAME': '0', 05:49:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:49:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:49:36 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-53deEe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:49:36 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)) 05:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:49:36 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-_Nklep-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:49:36 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)) 05:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:49:36 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-EqvwjN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:49:36 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)) 05:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:49:36 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-Rop1Ag-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:49:36 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)) 05:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:49:36 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-ugT3Bf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:49:36 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)) 05:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:49:36 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-B2SEeE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:49:36 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)) 05:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:49:36 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-FU8okx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:49:36 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)) 05:49:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:49:36 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-TRdnrB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:49:37 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)) 05:49:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:49:37 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-F2tQUG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:49:37 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)) 05:49:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:49:37 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-6064ni-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:49:37 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)) 05:49:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:49:37 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-4ELaEz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:49:37 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)) 05:49:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:49:37 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-LuK3Uk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:49:37 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)) 05:49:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:49:37 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-uuJ76B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:49:37 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)) 05:49:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:49:37 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-0WZD5I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:49:37 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)) 05:49:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:49:37 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-YKY0Md-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:49:37 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)) 05:49:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:49:37 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-Pzuvjd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:49:37 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)) 05:49:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:49:37 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-VNMnF2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:49:38 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)) 05:49:38 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)) 05:49:38 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)) 05:49:38 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)) 05:49:38 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)) 05:49:38 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)) 05:49:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:49:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:49:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:49:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:49:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:49:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:49:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:49:40 INFO - Downloading blessings-1.6.tar.gz 05:49:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:49:40 INFO - Installing collected packages: blessings 05:49:40 INFO - Running setup.py install for blessings 05:49:40 INFO - Successfully installed blessings 05:49:40 INFO - Cleaning up... 05:49:40 INFO - Return code: 0 05:49:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:49:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:49:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:49:41 INFO - Reading from file tmpfile_stdout 05:49:41 INFO - Current package versions: 05:49:41 INFO - blessings == 1.6 05:49:41 INFO - blobuploader == 1.2.4 05:49:41 INFO - docopt == 0.6.1 05:49:41 INFO - functools32 == 3.2.3-2 05:49:41 INFO - jsonschema == 2.5.1 05:49:41 INFO - manifestparser == 1.1 05:49:41 INFO - mozInstall == 1.12 05:49:41 INFO - mozcrash == 0.17 05:49:41 INFO - mozdebug == 0.1 05:49:41 INFO - mozdevice == 0.48 05:49:41 INFO - mozfile == 1.2 05:49:41 INFO - mozhttpd == 0.7 05:49:41 INFO - mozinfo == 0.9 05:49:41 INFO - mozleak == 0.1 05:49:41 INFO - mozlog == 3.3 05:49:41 INFO - moznetwork == 0.27 05:49:41 INFO - mozprocess == 0.23 05:49:41 INFO - mozprofile == 0.28 05:49:41 INFO - mozrunner == 6.12 05:49:41 INFO - mozscreenshot == 0.1 05:49:41 INFO - mozsystemmonitor == 0.3 05:49:41 INFO - moztest == 0.7 05:49:41 INFO - mozversion == 1.4 05:49:41 INFO - psutil == 3.1.1 05:49:41 INFO - requests == 1.2.3 05:49:41 INFO - wsgiref == 0.1.2 05:49:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:49:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:49:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:49:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:49:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:49:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:41 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 0x103feba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104080618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa8cac8cba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f4fd30>, '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 0x103cb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f47b20>, '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.HLtUu5Au1X/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.90y1QBqSLO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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 05:49:41 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 05:49:41 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 05:49:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HLtUu5Au1X/Render', 05:49:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:49:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:49:41 INFO - 'HOME': '/Users/cltbld', 05:49:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:49:41 INFO - 'LOGNAME': 'cltbld', 05:49:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:49:41 INFO - 'MOZ_NO_REMOTE': '1', 05:49:41 INFO - 'NO_EM_RESTART': '1', 05:49:41 INFO - 'PAGER': '/bin/cat', 05:49:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:49:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:49:41 INFO - 'PWD': '/builds/slave/test', 05:49:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:49:41 INFO - 'SHELL': '/bin/bash', 05:49:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.90y1QBqSLO/Listeners', 05:49:41 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 05:49:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:49:41 INFO - 'USER': 'cltbld', 05:49:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:49:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:49:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:49:41 INFO - 'XPC_FLAGS': '0x0', 05:49:41 INFO - 'XPC_SERVICE_NAME': '0', 05:49:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:49:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:49:41 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)) 05:49:41 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)) 05:49:41 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)) 05:49:41 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)) 05:49:41 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)) 05:49:41 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)) 05:49:41 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)) 05:49:41 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)) 05:49:41 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)) 05:49:41 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)) 05:49:41 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)) 05:49:41 INFO - Cleaning up... 05:49:41 INFO - Return code: 0 05:49:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:49:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:49:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:49:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:49:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:49:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:49:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103feba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104080618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa8cac8cba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f4fd30>, '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 0x103cb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f47b20>, '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.HLtUu5Au1X/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.90y1QBqSLO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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 05:49:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:49:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:49:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HLtUu5Au1X/Render', 05:49:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:49:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:49:41 INFO - 'HOME': '/Users/cltbld', 05:49:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:49:41 INFO - 'LOGNAME': 'cltbld', 05:49:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:49:41 INFO - 'MOZ_NO_REMOTE': '1', 05:49:41 INFO - 'NO_EM_RESTART': '1', 05:49:41 INFO - 'PAGER': '/bin/cat', 05:49:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:49:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:49:41 INFO - 'PWD': '/builds/slave/test', 05:49:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:49:41 INFO - 'SHELL': '/bin/bash', 05:49:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.90y1QBqSLO/Listeners', 05:49:41 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 05:49:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:49:41 INFO - 'USER': 'cltbld', 05:49:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:49:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:49:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:49:41 INFO - 'XPC_FLAGS': '0x0', 05:49:41 INFO - 'XPC_SERVICE_NAME': '0', 05:49:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:49:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:49:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:49:41 INFO - Cleaning up... 05:49:41 INFO - Return code: 0 05:49:41 INFO - Running post-action listener: _resource_record_post_action 05:49:41 INFO - Running post-action listener: _start_resource_monitoring 05:49:41 INFO - Starting resource monitoring. 05:49:41 INFO - [mozharness: 2016-08-18 12:49:41.983020Z] Finished create-virtualenv step (success) 05:49:41 INFO - [mozharness: 2016-08-18 12:49:41.984070Z] Running install step. 05:49:41 INFO - Running pre-action listener: _resource_record_pre_action 05:49:41 INFO - Running main action method: install 05:49:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:49:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:49:42 INFO - Reading from file tmpfile_stdout 05:49:42 INFO - Detecting whether we're running mozinstall >=1.0... 05:49:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:49:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:49:42 INFO - Reading from file tmpfile_stdout 05:49:42 INFO - Output received: 05:49:42 INFO - Usage: mozinstall [options] installer 05:49:42 INFO - Options: 05:49:42 INFO - -h, --help show this help message and exit 05:49:42 INFO - -d DEST, --destination=DEST 05:49:42 INFO - Directory to install application into. [default: 05:49:42 INFO - "/builds/slave/test"] 05:49:42 INFO - --app=APP Application being installed. [default: firefox] 05:49:42 INFO - mkdir: /builds/slave/test/build/application 05:49:42 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'] 05:49:42 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 05:50:16 INFO - Reading from file tmpfile_stdout 05:50:16 INFO - Output received: 05:50:16 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:50:16 INFO - Running post-action listener: _resource_record_post_action 05:50:16 INFO - [mozharness: 2016-08-18 12:50:16.488521Z] Finished install step (success) 05:50:16 INFO - [mozharness: 2016-08-18 12:50:16.488655Z] Running run-tests step. 05:50:16 INFO - Running pre-action listener: _resource_record_pre_action 05:50:16 INFO - Running main action method: run_tests 05:50:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:50:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:50:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:50:16 INFO - 2016-08-18 05:50:16.502 screenresolution[1630:7419] starting screenresolution argv=screenresolution get 05:50:16 INFO - 2016-08-18 05:50:16.504 screenresolution[1630:7419] Display 0: 1600x1200x32@60 05:50:16 INFO - 2016-08-18 05:50:16.509 screenresolution[1631:7421] starting screenresolution argv=screenresolution list 05:50:16 INFO - Available Modes on Display 0 05:50:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:50:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:50:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:50:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:50:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:50:16 INFO - Intel Iris: 05:50:16 INFO - Chipset Model: Intel Iris 05:50:16 INFO - Type: GPU 05:50:16 INFO - Bus: Built-In 05:50:16 INFO - VRAM (Dynamic, Max): 1536 MB 05:50:16 INFO - Vendor: Intel (0x8086) 05:50:16 INFO - Device ID: 0x0a2e 05:50:16 INFO - Revision ID: 0x0009 05:50:16 INFO - Displays: 05:50:16 INFO - Display: 05:50:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:50:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:50:16 INFO - Display Serial Number: 1600x1200 60 05:50:16 INFO - Main Display: Yes 05:50:16 INFO - Mirror: Off 05:50:16 INFO - Online: Yes 05:50:16 INFO - Rotation: Supported 05:50:16 INFO - Return code: 0 05:50:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:50:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:50:16 INFO - Python 2.7.3 05:50:16 INFO - Return code: 0 05:50:16 INFO - grabbing minidump binary from tooltool 05:50:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:50:16 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f4fd30>, '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 0x103cb4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f47b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:50:16 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:50:16 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:50:16 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 05:50:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:50:16 INFO - Return code: 0 05:50:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:50:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:50:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:50:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:50:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:50:16 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/LUg-yIxMTgC5Koy2y6SI4Q/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0097', '--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 05:50:16 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/LUg-yIxMTgC5Koy2y6SI4Q/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0097 --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 05:50:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HLtUu5Au1X/Render', 05:50:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:50:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:50:16 INFO - 'HOME': '/Users/cltbld', 05:50:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:50:16 INFO - 'LOGNAME': 'cltbld', 05:50:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:50:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:50:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:50:16 INFO - 'MOZ_NO_REMOTE': '1', 05:50:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:50:16 INFO - 'NO_EM_RESTART': '1', 05:50:16 INFO - 'PAGER': '/bin/cat', 05:50:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:50:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:50:16 INFO - 'PWD': '/builds/slave/test', 05:50:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:50:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:50:16 INFO - 'SHELL': '/bin/bash', 05:50:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.90y1QBqSLO/Listeners', 05:50:16 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 05:50:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:50:16 INFO - 'USER': 'cltbld', 05:50:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:50:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:50:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:50:16 INFO - 'XPC_FLAGS': '0x0', 05:50:16 INFO - 'XPC_SERVICE_NAME': '0', 05:50:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:50:16 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/LUg-yIxMTgC5Koy2y6SI4Q/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0097', '--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 05:50:17 INFO - mozversion application_buildid: 20160817135515 05:50:17 INFO - mozversion application_changeset: 46132442a61f61af64ebb0234392d51379fa40d4 05:50:17 INFO - mozversion application_display_name: Nightly 05:50:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:50:17 INFO - mozversion application_name: Firefox 05:50:17 INFO - mozversion application_remotingname: firefox 05:50:17 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 05:50:17 INFO - mozversion application_vendor: Mozilla 05:50:17 INFO - mozversion application_version: 51.0a1 05:50:17 INFO - mozversion platform_buildid: 20160817135515 05:50:17 INFO - mozversion platform_changeset: 46132442a61f61af64ebb0234392d51379fa40d4 05:50:17 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 05:50:17 INFO - mozversion platform_version: 51.0a1 05:50:17 INFO - using testdate: 1471524617 05:50:17 INFO - actual date: 1471524617 05:50:17 INFO - starting webserver on 'localhost:49275' 05:50:17 INFO - SUITE-START | Running 2 tests 05:50:17 INFO - TEST-START | dromaeo_css 05:50:17 INFO - operating with platform_type : mac_ 05:50:17 INFO - Initialising browser for dromaeo_css test... 05:50:17 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/tmpAM_AN6/profile http://localhost:49275/getInfo.html) 05:50:19 INFO - PROCESS | 1641 | __metrics Screen width/height:1600/1200 05:50:19 INFO - PROCESS | 1641 | colorDepth:24 05:50:19 INFO - PROCESS | 1641 | Browser inner width/height: 1024/689 05:50:19 INFO - PROCESS | 1641 | __metrics 05:50:19 INFO - PROCESS | 1641 | 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" 05:50:19 INFO - PROCESS | 1641 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:50:20 INFO - TEST-INFO | 1641: exit 0 05:50:20 INFO - Browser initialized. 05:50:20 INFO - Running cycle 1/1 for dromaeo_css test... 05:50:20 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/tmpAM_AN6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:50:32 INFO - PROCESS | 1643 | RSS: Main: 228163584 05:50:32 INFO - PROCESS | 1643 | 05:51:23 INFO - PROCESS | 1643 | 1471524683668 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] 05:53:36 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 05:53:37 INFO - PROCESS | 1643 | RSS: Main: 356323328 05:53:37 INFO - PROCESS | 1643 | 05:56:41 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 05:56:41 INFO - PROCESS | 1643 | RSS: Main: 370630656 05:56:41 INFO - PROCESS | 1643 | 05:59:44 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 05:59:44 INFO - PROCESS | 1643 | RSS: Main: 336048128 05:59:44 INFO - PROCESS | 1643 | 06:02:47 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 06:02:47 INFO - PROCESS | 1643 | RSS: Main: 360439808 06:02:47 INFO - PROCESS | 1643 | 06:05:51 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 06:05:52 INFO - PROCESS | 1643 | RSS: Main: 331313152 06:05:52 INFO - PROCESS | 1643 | 06:08:55 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 06:08:55 INFO - PROCESS | 1643 | RSS: Main: 374865920 06:08:55 INFO - PROCESS | 1643 | 06:08:55 INFO - PROCESS | 1643 | __start_tp_report 06:08:55 INFO - PROCESS | 1643 | _x_x_mozilla_page_load 06:08:55 INFO - PROCESS | 1643 | _x_x_mozilla_page_load_details 06:08:55 INFO - PROCESS | 1643 | |i|pagename|runs| 06:08:55 INFO - PROCESS | 1643 | |0;dojo.html;3352.3965686176484;3391.0166051844967;3392.779469334493;3404.1829960809414;3452.5339079224295;5595.468430499096;5667.263255776748;5742.425757424256;5743.4543718346695;5750.079987202043;5688.715564221783;5725.026745453262;5815.098659707742;5858.648481091138;5860.472557469838;5132;5222.608304377168;5226.7909283628605;5229.294045303893;5321.654092483996;5336.17289320156;5341.985022845666;5343.946560534403;5351.197320401932;5372.811951581695;5171.276021357013;5226.973865130688;5227.242049902781;5244.606654500909;5287.814926477593;5189.091908915924;5265.684058956436;5266.578673706094;5279.947200527984;5319.042572336978;5186.403563590197;5190.558802501791;5206.947930520684;5252.553532949704;5301.151815709468;5724.341700704431;5774.105013722879;5793.797217097382;5831.300243970708;5834.3290521589715;5988.790392336291;6264.686765661744;6272.529560282997;6288.52836037299;6294.3705629437145;6071.362506936796;6135.171751813493;6228.781992630282;6271.560990730651;6301.054841773725;5864.091065884794;5904.852378690524;5909.586328957018;5911.586188966817;5916.645001299849;6331.68341582919;6335.081413195153;6348.650824204713;6354.745810167634;6361.681915904186;6024.126501657235;6127.080937859357;6147.877042459126;6179.3820617937845;6194.946859033975;5280.366356037223;6316.431521163117;6322.683865806691;6329.240491141092;6341.904871426932;6139.386061393825;6190.81427557174;6244.219472565929;6245.937540624535;6273.560850740404;5867.14926335679;5910.379410161957;5913.290405151323;5975.820725378246;6257.374262573706;4464.2633965396;4841.8547443576745;4937.333459982929;4983.90032199354;4990.176620857518;4422.49141348749;4449.510553839075;4465.0400163964905;4468.888277793016;4494.775261236925;5687.857803554862;5707.68607726579;5711.628744131701;5714.257146570919;5755.309362876482;6115.209918062534;6214.316425193225;6236.251649802053;6249.093881387264;6309.49524038085;5710.114932185518;5727;5744.195812586241;5749.281339832521;5762.711864406847;5463.972680136574;5467.371252306036;5476.945230547723;5477.096279113902;5479.6164268500825;6272.18461599989;6288.874222515616;6297.496200304056;6342.809715708536;6406.679666016774;6263.091851681572;6333.96833015832;6362.013887847391;6373.904391434225;6377.23473183221;6313.96843015782;6316.242050953915;6332.461740752087;6339.809805705836;6345.524085693499;5745.224394706828;6032.75868965254;6191.9690401547705;6234.470070044097;6239.1577137085915;5415.6479828811;5510.7049843285995;5861.824145275648;5912.172295878667;5992.730327135377;5735.7418916148035;5778.56660750437;5779.075347944308;5783.710814459176;5787.7395517200985;5755.8561035974435;5767.307923049261;5773.711314434346;5988.461038506555;6084.513238940963;5330.413654498079;5788.160716695971;5978.641281523122;6049.758009679564;6053.697315134314;5044.798208071783;6163.106349579196;6164.013757798731;6168.19813424252;6193.81418557444;5837.124431335334;5906.675132867734;5911.527077833677;6004.789832356021;6010.579259451796 06:08:55 INFO - PROCESS | 1643 | |1;ext.html;464.6306186581667;506.8783491961929;512.4209643103295;514.770926937513;523.0819911056096;31068.844655776717;41994;43095.78452107743;43104.3534346985;43146.56853431465;44189.337159942625;44458;44494;44540.777296113556;44541.55458445423;8703.782405439882;9845.015498450151;10036.096751292382;10039.648612298572;10067.848982265235;8738.344624153182;11994;12262.019038476945;12266.69333266671;12522.499100035988;12343.580488243877;12390.256584604953;12422.751544969095;12438.81341779874;12457.750844983095;10573.62542869422;12121;12217.450214740313;12309.199902006341;12324.014078873714;12031.458584363638;12129;12129.939350303192;12130.029597632169;12135.332556709378;41717.165656687;41944.74165775032;41984.79007604973;41994;42000.78999605013;3316.137804170933;3474.7915125092322;3559.6440355964455;3806.467094606757;3821.5032045833864;31789.682103178904;32897.342053159045;32919;32998.83500582506;33037.33925321504;3241.2383089973837;3372.0726800129914;3422.2299982503987;3451.0854623524788;3454.827258637083;531769;534047;534700;534935.3253233709;543366.2831685856;62321.75356492845;62410.37589624136;62804.685976569825;62955.05567416493;63062;66288;66874;67028.66485667638;67065;67099.32900671028;16676.16619169037;16874.325026999028;16911.830881691025;16925.322987080624;16942;11621.418929053514;11800.940995294968;11840.70398240051;12802.42389092493;12891.097623166475;16021.356476181532;16176.27206775698;16198.514044578682;17918.91040544815;18303.176357063963;5773.364929857712;6650.334966503311;6876.828079298058;6946;6952.687129079203;890.2210565754964;956.3687965942445;999.3354419311077;1063.3991794636006;1073.978520429587;30798.076057718303;30958.53562196569;31207;31308.060758177293;31719.04842854718;865.3250464637592;1009.742870126697;1126.0428635659623;1146.2835727670206;1158.7334912970064;15320.693586128438;16103.19484025794;16124.193790310437;16155.353785848669;16225.2698628562;670173.4387264812;699028;701166;701251;922284;61875;62715.68642156851;62745;62810;62957;1241.85097788266;1289.3553223388305;1374.7594171020032;1394.7001394700187;1420.928953552339;29803;31016;32558;32962.67037329692;33172;59380.70309648424;60166;60263.49341266503;60268.39731602803;60329;513798;592631;597280;604420;605001;25933.6109958347;26897.86551067232;27038.783254753977;27376.726232738216;27454.901803928416;37673.81163094167;41166.58833411628;41772;46399.84000400017;51465.485345146066;40472.595274046886;41710.210001390595;41736.165276694905;42608.934776630835;42660.57339426566;36044.4593331095;40490.785276441755;40877.978050548976;42109;42349;39046.21907561776;39807.20385592214;39875.800620996706;40352.39471407873;40594.79702601468;35055.298894021464;35817.283654327286;36691.74435751935;36717.44923826198;36836.447453287685;22091.337259882228;25798.87100564485;26203;26530.469390612467;27472.582055153398 06:08:55 INFO - PROCESS | 1643 | |2;jquery.html;341.84787769442596;377.3170561284076;383.91745774658443;397.2829043576344;407.45401162442323;15286.84713152868;16062.919685401557;19047.52381190469;21598.676019859715;21848.781512184873;20539.691904621443;21544;21604;21624.675629865564;21728.782712172873;5792.594518383715;5894.17481552583;5934.465898069173;6009.969950150243;6078.392160783908;5005.174146265862;6012.3085845127935;6016.879662406771;6030.185924900126;6038.0942858571125;4038.495188101487;5957.046872500401;6263.404976527223;6520.902186467207;6577.112089867878;6340.682965851735;6527.543071984963;6586.670666466705;6604.150837765434;6614.47084233265;5679.858003549903;5695.572832037614;5696.401877802854;5700.401457846908;5701.486866182022;25596.488070238676;26265.868670656717;26440.471190576274;27074.323141921413;27387.04145354903;2254.966175507369;2283.5090455552136;2487.9751202487923;3084.1981084917857;3192.9201769955703;10652.201084918584;14235.359408826627;15145.621359465991;15486.225688715633;15999.840001599952;1745.7267623206487;1799.631075629493;1850.7871594766639;1911.4934542346289;1924.769027716669;95172.10348706412;130810;132334;132845.38700742912;134111.32944335314;9359.719208423758;11166.218364714392;11513.597024104116;11578.594749183907;11741.354225517507;14520;14558.417663293561;14562.053466524641;14574.854251457562;14578.979471436898;8347.66609335632;8669.089745576624;8694.30445564366;8769.342299327576;8811.955940220258;6966.5471744336455;8338.374621903382;8517;8524.06235314115;8602.18279263469;4002.2995975704134;4363.909022744314;4429.224885645;4575.702579332333;4671.575169573245;1869.4111354923157;1903.790583035865;1905.59982403694;1906.8474522038205;1907.189444486088;870.2472361407464;969.7187815533416;976.5751897924425;992.1764935103846;1017.7964407118606;20022.59954800925;21477.46306342354;21480.892595536923;21500.569988600142;21596.892015539823;932.7947851472666;958.4249450329746;966.2849491376471;973.770066547598;1039.9376037437778;10137.949310253549;10698.358098514114;11251.09991200702;11874.168808183516;12092.697682558006;551583;557354;616659;623793;633720;144052;145765;149768;150269;153740;970.1559643110398;983.434525148057;1015.9949200253951;1016.6899095775859;1021.632212403549;15100.848991509944;17843.572514198575;19155.808441915404;19302.4209273722;19366.93481858455;44883.77558112189;55446.72276638591;56100.71949640226;56276;56724.71637641951;76534.23465765493;77154.6142269285;79592.60203698944;85697;87437.56281218553;7910.129885712452;8094.554799485849;8206.343492520704;8228.465149765247;8542.40203185771;9835.704928852147;11239.55041798319;13190.010749194042;13441.395137218653;13523.323833808467;5447.754851031634;5609.971950140386;11095.780843831104;11155.498002590066;11533.308001519936;11161;11454.14093942941;11611.709707257387;11675.672433356793;11836.82244766312;9650.420974741539;11283.266587671775;11373.886261137613;11809.409529523318;11871.762564748484;7155.856882862209;7388.150362708142;8121.147279535564;8201.466904651179;8420.62107205165;11218.607348742755;11376.488058037574;11533.00141963079;11544.480498377425;11628.186026978368 06:08:55 INFO - PROCESS | 1643 | |3;mootools.html;1386.029779154592;1550.961225969351;1581.1066747287782;1742.7560141580182;1855.4433669899036;7804.960975195123;8295.336373090153;8592.01191862936;8858.468491890475;8876.600552975115;7820.960895195531;8517.850090237816;8717.128287171296;8786.86819697705;8803.867941980876;2386.7971222446113;2415.516896620683;2415.8188135889877;2417.5648383291;2421.527802078587;2364.13708996216;2398.448356877919;2419.9395015124674;2424.0667343072955;2426.9271921842374;2373.0745009446246;2399.1483023526657;2409.1929203716777;2416.598372943697;2419.41933935855;2356.104680221522;2381.3927448500694;2389.474315650554;2393.0188622664623;2407.41018450479;2004.1181879972764;2351.882405879699;2391.2467572714713;2391.4021494626345;2399.3042017814814;8706.477611343276;8774.122587741238;8833.205011548991;8833.60248788806;8838.469691818447;4539.72761634303;5228.895422091575;5396.298481197458;5398.433164517708;5399.244105825188;3203.1511649412923;3636.5999740028574;3640.854365825364;3646.361886669849;3652.251288485854;1105.5688281570194;1112.3270421395102;1140.1107136433511;1140.4069883660534;1146.2492067695673;11737.119716021332;11865.288082715064;11878.287302761863;11882.940585297018;11910.987566056809;4481.282994720894;4573.611243044312;4602.907941841145;4712.81148754046;4713.670043097019;9259.35184537076;9260.212881905114;9287.071292870658;9288.907110928803;9291.303152263607;5286.233496143038;5289.68261904287;5294.629375943647;5295.391030031596;5305.734713264322;4070.430139780433;4426.800793964215;4845.781939812717;4931.2849636803185;4944.332515110414;3783.1677031053123;3792.962070379316;3849.4995650565247;3888.6111388860886;3891.7081218908697;1181.149572307937;1193.40926241511;1200.7298357869408;1205.6503613951857;1218.5125949620224;905.2848249882651;918.5177781664546;923.8937522185034;940.6942743608355;950.496236994394;5734.2258795062135;5991.281046274475;6016.09758536223;6208.596441231396;6212.844678883064;438.4716416717847;459.8781322949354;468.60871172570603;468.85934219733946;965.7633879699519;3909.179072394772;3966.4446977423186;4058.5941405859176;4116.75299482032;4124.1957818225155;10629.5216715248;10686.893131068746;10969.670909872717;11015.779684406267;11062.446877656084;10789.946050269855;10993.505292261867;11001.17491188164;11015.063719583924;11019.669409917715;7875.724349647735;8216.424850260544;8243.464174828618;8364.456310339809;8431.620577074047;5713.485786279089;5870.26621672291;5907.320658124198;5931.169636250842;6019.367966363468;10052.64815731446;10361.119304859172;10385.273030888067;10406.947965260126;10408.895911040792;9423.528823558932;9796.265280104164;9807.411555306704;9882.407055576688;10393.688189354332;7047.647617619201;7055.082839230867;7116.395106416013;7210.74762383335;7214.531055481377;8343.290820280345;8417.747467575982;8428.536430496137;8433.83132337362;8440.83118337642;7444.888326674996;7542.019537460095;7545.962270188614;7627.00848889663;7698.615069246627;7279.781606551812;7322.670479828314;7367.631618419165;7469.7385591504035;7554.508956917783;6074.878502430015;6965.407940325129;7114.395276401564;7119.288071192839;7120;4867.805287788448;5040.773165207648;5071.39143302806;5074.873128171826;5125.077486052546;4995.350604421547;5004.699718016931;5032.989425578463;5321.866953326043;5333.413324534221 06:08:55 INFO - PROCESS | 1643 | |4;prototype.html;246.6916354556804;299.902531677205;316.64061290435365;318.68131868131866;319.64838677454804;4409.603135717784;4812.879678008047;5114.820981265647;5173.3533308336455;5212.921806172911;4525.547445255473;4867.05317872851;4998.850034498962;5042.773075211615;5155.664881782691;3415.351083294166;3758.6053464386255;3894.4158376243577;3952.5454572724216;3987.8803635890968;3790.620937906201;3898.9805050974705;3988.1824226033596;3991;3996.920061598766;3098.295937234523;3558.6690717580027;3925.7045175092153;3945.191235796655;3957.4855268815013;3629.310431018098;3950.3335248707294;3953.62440568147;3967.226390853785;4005.6380830517473;2920.73713365796;3392.5080863274934;3482.564679415073;3500.702440292578;3510.4558793387064;4167;4990.575801056914;4991.800327986876;4996.950640365528;5014.774335154927;3930.76415415076;4430.844920427802;4481;4495.527969563214;4498.640108791289;5290.9735451322495;5310.44240354761;5311.203319502067;5339.67961922282;5353.384360798519;3206.1824234819974;3822.9044273893096;3833.367494363427;3838.6756568983656;3843.442700808395;4645.837395691168;5103.719295438749;5217.087009773312;5586.525145362609;5685.180742162468;3683.3001729671278;3712.925741485155;3723.6090210527504;3741.5136032315627;3773;3324.401607710587;3569.6430356964615;3795.3168429682923;3812.8284227209842;3830.865919692853;3332.6334103248623;3555.1823080691584;3610.7653002555276;4172.269852775752;4358.910272431892;4700.918788678555;4942.283368911488;5255.211718242294;5297.913927644823;5415.48552887475;1093.5243169221415;1191.9821202681967;1255.813256472643;1328.1499840102342;1349.9122557033959;837.8743188521648;876.5222953490363;910.2945217456391;914.1772404835701;937.1191080384417;4926.605871530341;4945.431275403303;4950.292149208517;4971.328870602422;4999.500049994971;5371.4628537146755;5385.407605163429;5418;5426.701531415731;5449.482299181572;4264.360345948071;4450.821967121279;4605.746683932416;4925.236588328743;4938.654294199443;2820.5346117890726;2839.929001774972;2849.6437945256844;2851.959034952269;2860.3707184419395;4467.419235499365;5030.89938201234;5281.419043905167;5523.7790488380015;5577.330720313507;4538.931916021263;5204.505571970657;5244.842654720364;5680.488756011979;5707.972564938268;4682.391289132391;4950.88605063865;5695.487406133468;5722.4277572242445;5740.454656807764;4174.123434078878;4742.739149346819;4837.903241935212;5574.637648552831;5594.832155035356;4286.5070516890955;4334.006357075655;5479.808206712746;5492.313460817398;5549;5289;5670.092785154356;5708.942910571007;5764.674124951201;5800.76796928135;3316.8009919404913;3770.6606405423645;3806.371948628556;3806.980965095157;3826.559945606291;5130.563902068366;5502;5533.043873630374;5541.778328866962;5614.719264036864;4753.334533165291;4782.593479554277;4971.6022718183185;5060.342155519759;5407.891842163213;4954.840762114907;5018.372703412074;5279.630425870152;5430.02259593277;5486.835394938158;4972.428170760264;5264.921026184534;5272;5286.259923610774;5338.733063346895;4464.986134359229;4494.528074552125;4765.69023013503;5148.819791307286;5388.245645609696;4411.492678341903;4520.982778874727;4757.857264282077;4846.055019271349;5366.221897824716 06:08:55 INFO - PROCESS | 1643 | |5;yui.html;722.8373615936412;725.7351066860595;742.2392048150641;775.4106878772135;815.2296080204208;14576;15369.615759606002;16254.268557914878;16297.674046519092;16371.836281637181;13631;14808.25958702066;14815.925920370382;14835.554933352018;14844.183569903651;667.5260564998845;672.4284358295434;694.0144994108379;837.3636036612157;861.6338056326036;853.8249658819927;858.8969323681167;866.0084203586889;866.1598249697784;871.1941454154913;908.1009800297724;908.209857424042;908.6592527802075;910.5720311453606;911.8404279251138;900.8288425199224;903.2277402820625;903.2457897655465;903.7650210945202;905.728281515546;529.0450736420759;595.693217992734;657.1030543308398;679.2290749998738;680.1056610557101;14628.268586570628;14660.120392776467;14661.34023968924;14683.339249733788;14684.853151468455;12590.937045314806;12827.970853698747;12929.612111636667;13205.669858253525;13242.139260948104;12183.234417686752;12291.493323824268;12312.468693120747;12478.18891772032;12761.425735841909;4375.25620644488;4385.2106620808245;4416.138852923681;4456.108778244364;4642.65180111493;121373;122282;123311;124649.37675311565;124982.3750881258;8973.564229723213;10298;10326.586936522455;10379.85251147898;10823.458827058615;19189.520261993563;19202.615947681246;20433.89783051105;21234.150633974776;21260.78739212588;7984.520928744294;8114.675412983533;8187.95906020478;8193.877091843628;8268;4238.36424536322;4483.058557702919;4507.616852567503;4631.536846315409;4645.837395691135;3599.9100022499647;3745.3071181830906;3839.7120215983914;4017.83928642851;4023.114914718757;698.4831224893644;705.6612825843521;713.578988396838;720;722.458156382713;479.43426756427056;617.9073139029182;633.9366063393716;650.2067477677226;654.1626717801222;9708.223342132613;9745;10136.506270973443;10357.171426285879;10390.402399400149;580.7967211476034;622.3527531367328;629.3329071184559;673.5619452469014;680.0988689985788;4489.506154323022;4697.718136911796;4704.835330763407;4707.293905914072;4743.193657078305;138326.6167338334;139041;140160;141359;144250;110795.23011924766;113026.30460543098;123380.38309808573;123804.38097809633;125776;71748.2825171752;106698;108672.36991445292;109033.99999999841;113122;8728.301735861243;9034.81930361376;9035.367524273237;9043.095690430904;9089.909100908906;54553.908921822134;56916;56960;57786.422135778106;62778.372216277254;80773.19226807657;84346.57826711073;91149;93098.53450732703;94083.52958235439;10059.497025148567;11931.463084161407;12048.216865903689;12615.729168749713;12843.42204600814;12622.431990560262;14757.852421475647;14877.588516174545;15067.321970511133;15087.622809429851;13328.666783330495;14167.504137355143;14209.076410033314;14521.782173267198;14529.564113076625;13761.862381376059;13774.380152893344;14191.858081419054;14273.429062837371;14374.209418481665;13458.327083645974;13485.258310793;13779.53148197974;14280.357383917957;14418.927905360406;11189.664310070712;11526.13874352068;11766.52933882635;11794.29234245948;11824.172307938707;9857.866345370374;11055.336679799238;11451.25566838153;11810.704732381759;11854.288742675468 06:08:55 INFO - PROCESS | 1643 | __end_tp_report 06:08:55 INFO - PROCESS | 1643 | __start_cc_report 06:08:55 INFO - PROCESS | 1643 | _x_x_mozilla_cycle_collect,1185 06:08:55 INFO - PROCESS | 1643 | __end_cc_report 06:08:55 INFO - PROCESS | 1643 | __startTimestamp1471525735636__endTimestamp 06:08:55 INFO - PROCESS | 1643 | 06:08:55 INFO - PROCESS | 1643 | ------- Summary: start ------- 06:08:55 INFO - PROCESS | 1643 | Number of tests: 6 06:08:55 INFO - PROCESS | 1643 | 06:08:55 INFO - PROCESS | 1643 | [#0] dojo.html Cycles:165 Average:5719.70 Median:5834.33 stddev:624.09 (10.7%) stddev-sans-first:597.73 06:08:55 INFO - PROCESS | 1643 | Values: 3352.4 3391.0 3392.8 3404.2 3452.5 5595.5 5667.3 5742.4 5743.5 5750.1 5688.7 5725.0 5815.1 5858.6 5860.5 5132.0 5222.6 5226.8 5229.3 5321.7 5336.2 5342.0 5343.9 5351.2 5372.8 5171.3 5227.0 5227.2 5244.6 5287.8 5189.1 5265.7 5266.6 5279.9 5319.0 5186.4 5190.6 5206.9 5252.6 5301.2 5724.3 5774.1 5793.8 5831.3 5834.3 5988.8 6264.7 6272.5 6288.5 6294.4 6071.4 6135.2 6228.8 6271.6 6301.1 5864.1 5904.9 5909.6 5911.6 5916.6 6331.7 6335.1 6348.7 6354.7 6361.7 6024.1 6127.1 6147.9 6179.4 6194.9 5280.4 6316.4 6322.7 6329.2 6341.9 6139.4 6190.8 6244.2 6245.9 6273.6 5867.1 5910.4 5913.3 5975.8 6257.4 4464.3 4841.9 4937.3 4983.9 4990.2 4422.5 4449.5 4465.0 4468.9 4494.8 5687.9 5707.7 5711.6 5714.3 5755.3 6115.2 6214.3 6236.3 6249.1 6309.5 5710.1 5727.0 5744.2 5749.3 5762.7 5464.0 5467.4 5476.9 5477.1 5479.6 6272.2 6288.9 6297.5 6342.8 6406.7 6263.1 6334.0 6362.0 6373.9 6377.2 6314.0 6316.2 6332.5 6339.8 6345.5 5745.2 6032.8 6192.0 6234.5 6239.2 5415.6 5510.7 5861.8 5912.2 5992.7 5735.7 5778.6 5779.1 5783.7 5787.7 5755.9 5767.3 5773.7 5988.5 6084.5 5330.4 5788.2 5978.6 6049.8 6053.7 5044.8 6163.1 6164.0 6168.2 6193.8 5837.1 5906.7 5911.5 6004.8 6010.6 06:08:55 INFO - PROCESS | 1643 | 06:08:55 INFO - PROCESS | 1643 | [#1] ext.html Cycles:180 Average:75666.30 Median:36704.60 stddev:168105.27 (458.0%) stddev-sans-first:168482.03 06:08:55 INFO - PROCESS | 1643 | Values: 464.6 506.9 512.4 514.8 523.1 31068.8 41994.0 43095.8 43104.4 43146.6 44189.3 44458.0 44494.0 44540.8 44541.6 8703.8 9845.0 10036.1 10039.6 10067.8 8738.3 11994.0 12262.0 12266.7 12522.5 12343.6 12390.3 12422.8 12438.8 12457.8 10573.6 12121.0 12217.5 12309.2 12324.0 12031.5 12129.0 12129.9 12130.0 12135.3 41717.2 41944.7 41984.8 41994.0 42000.8 3316.1 3474.8 3559.6 3806.5 3821.5 31789.7 32897.3 32919.0 32998.8 33037.3 3241.2 3372.1 3422.2 3451.1 3454.8 531769.0 534047.0 534700.0 534935.3 543366.3 62321.8 62410.4 62804.7 62955.1 63062.0 66288.0 66874.0 67028.7 67065.0 67099.3 16676.2 16874.3 16911.8 16925.3 16942.0 11621.4 11800.9 11840.7 12802.4 12891.1 16021.4 16176.3 16198.5 17918.9 18303.2 5773.4 6650.3 6876.8 6946.0 6952.7 890.2 956.4 999.3 1063.4 1074.0 30798.1 30958.5 31207.0 31308.1 31719.0 865.3 1009.7 1126.0 1146.3 1158.7 15320.7 16103.2 16124.2 16155.4 16225.3 670173.4 699028.0 701166.0 701251.0 922284.0 61875.0 62715.7 62745.0 62810.0 62957.0 1241.9 1289.4 1374.8 1394.7 1420.9 29803.0 31016.0 32558.0 32962.7 33172.0 59380.7 60166.0 60263.5 60268.4 60329.0 513798.0 592631.0 597280.0 604420.0 605001.0 25933.6 26897.9 27038.8 27376.7 27454.9 37673.8 41166.6 41772.0 46399.8 51465.5 40472.6 41710.2 41736.2 42608.9 42660.6 36044.5 40490.8 40878.0 42109.0 42349.0 39046.2 39807.2 39875.8 40352.4 40594.8 35055.3 35817.3 36691.7 36717.4 36836.4 22091.3 25798.9 26203.0 26530.5 27472.6 06:08:55 INFO - PROCESS | 1643 | 06:08:55 INFO - PROCESS | 1643 | [#2] jquery.html Cycles:180 Average:35975.90 Median:27230.68 stddev:100587.69 (369.4%) stddev-sans-first:100834.28 06:08:55 INFO - PROCESS | 1643 | Values: 341.8 377.3 383.9 397.3 407.5 15286.8 16062.9 19047.5 21598.7 21848.8 20539.7 21544.0 21604.0 21624.7 21728.8 5792.6 5894.2 5934.5 6010.0 6078.4 5005.2 6012.3 6016.9 6030.2 6038.1 4038.5 5957.0 6263.4 6520.9 6577.1 6340.7 6527.5 6586.7 6604.2 6614.5 5679.9 5695.6 5696.4 5700.4 5701.5 25596.5 26265.9 26440.5 27074.3 27387.0 2255.0 2283.5 2488.0 3084.2 3192.9 10652.2 14235.4 15145.6 15486.2 15999.8 1745.7 1799.6 1850.8 1911.5 1924.8 95172.1 130810.0 132334.0 132845.4 134111.3 9359.7 11166.2 11513.6 11578.6 11741.4 14520.0 14558.4 14562.1 14574.9 14579.0 8347.7 8669.1 8694.3 8769.3 8812.0 6966.5 8338.4 8517.0 8524.1 8602.2 4002.3 4363.9 4429.2 4575.7 4671.6 1869.4 1903.8 1905.6 1906.8 1907.2 870.2 969.7 976.6 992.2 1017.8 20022.6 21477.5 21480.9 21500.6 21596.9 932.8 958.4 966.3 973.8 1039.9 10137.9 10698.4 11251.1 11874.2 12092.7 551583.0 557354.0 616659.0 623793.0 633720.0 144052.0 145765.0 149768.0 150269.0 153740.0 970.2 983.4 1016.0 1016.7 1021.6 15100.8 17843.6 19155.8 19302.4 19366.9 44883.8 55446.7 56100.7 56276.0 56724.7 76534.2 77154.6 79592.6 85697.0 87437.6 7910.1 8094.6 8206.3 8228.5 8542.4 9835.7 11239.6 13190.0 13441.4 13523.3 5447.8 5610.0 11095.8 11155.5 11533.3 11161.0 11454.1 11611.7 11675.7 11836.8 9650.4 11283.3 11373.9 11809.4 11871.8 7155.9 7388.2 8121.1 8201.5 8420.6 11218.6 11376.5 11533.0 11544.5 11628.2 06:08:55 INFO - PROCESS | 1643 | 06:08:55 INFO - PROCESS | 1643 | [#3] mootools.html Cycles:180 Average:5700.31 Median:4937.81 stddev:3216.11 (65.1%) stddev-sans-first:3208.79 06:08:55 INFO - PROCESS | 1643 | Values: 1386.0 1551.0 1581.1 1742.8 1855.4 7805.0 8295.3 8592.0 8858.5 8876.6 7821.0 8517.9 8717.1 8786.9 8803.9 2386.8 2415.5 2415.8 2417.6 2421.5 2364.1 2398.4 2419.9 2424.1 2426.9 2373.1 2399.1 2409.2 2416.6 2419.4 2356.1 2381.4 2389.5 2393.0 2407.4 2004.1 2351.9 2391.2 2391.4 2399.3 8706.5 8774.1 8833.2 8833.6 8838.5 4539.7 5228.9 5396.3 5398.4 5399.2 3203.2 3636.6 3640.9 3646.4 3652.3 1105.6 1112.3 1140.1 1140.4 1146.2 11737.1 11865.3 11878.3 11882.9 11911.0 4481.3 4573.6 4602.9 4712.8 4713.7 9259.4 9260.2 9287.1 9288.9 9291.3 5286.2 5289.7 5294.6 5295.4 5305.7 4070.4 4426.8 4845.8 4931.3 4944.3 3783.2 3793.0 3849.5 3888.6 3891.7 1181.1 1193.4 1200.7 1205.7 1218.5 905.3 918.5 923.9 940.7 950.5 5734.2 5991.3 6016.1 6208.6 6212.8 438.5 459.9 468.6 468.9 965.8 3909.2 3966.4 4058.6 4116.8 4124.2 10629.5 10686.9 10969.7 11015.8 11062.4 10789.9 10993.5 11001.2 11015.1 11019.7 7875.7 8216.4 8243.5 8364.5 8431.6 5713.5 5870.3 5907.3 5931.2 6019.4 10052.6 10361.1 10385.3 10406.9 10408.9 9423.5 9796.3 9807.4 9882.4 10393.7 7047.6 7055.1 7116.4 7210.7 7214.5 8343.3 8417.7 8428.5 8433.8 8440.8 7444.9 7542.0 7546.0 7627.0 7698.6 7279.8 7322.7 7367.6 7469.7 7554.5 6074.9 6965.4 7114.4 7119.3 7120.0 4867.8 5040.8 5071.4 5074.9 5125.1 4995.4 5004.7 5033.0 5321.9 5333.4 06:08:55 INFO - PROCESS | 1643 | 06:08:55 INFO - PROCESS | 1643 | [#4] prototype.html Cycles:180 Average:4288.18 Median:4761.77 stddev:1299.60 (27.3%) stddev-sans-first:1267.35 06:08:55 INFO - PROCESS | 1643 | Values: 246.7 299.9 316.6 318.7 319.6 4409.6 4812.9 5114.8 5173.4 5212.9 4525.5 4867.1 4998.9 5042.8 5155.7 3415.4 3758.6 3894.4 3952.5 3987.9 3790.6 3899.0 3988.2 3991.0 3996.9 3098.3 3558.7 3925.7 3945.2 3957.5 3629.3 3950.3 3953.6 3967.2 4005.6 2920.7 3392.5 3482.6 3500.7 3510.5 4167.0 4990.6 4991.8 4997.0 5014.8 3930.8 4430.8 4481.0 4495.5 4498.6 5291.0 5310.4 5311.2 5339.7 5353.4 3206.2 3822.9 3833.4 3838.7 3843.4 4645.8 5103.7 5217.1 5586.5 5685.2 3683.3 3712.9 3723.6 3741.5 3773.0 3324.4 3569.6 3795.3 3812.8 3830.9 3332.6 3555.2 3610.8 4172.3 4358.9 4700.9 4942.3 5255.2 5297.9 5415.5 1093.5 1192.0 1255.8 1328.1 1349.9 837.9 876.5 910.3 914.2 937.1 4926.6 4945.4 4950.3 4971.3 4999.5 5371.5 5385.4 5418.0 5426.7 5449.5 4264.4 4450.8 4605.7 4925.2 4938.7 2820.5 2839.9 2849.6 2852.0 2860.4 4467.4 5030.9 5281.4 5523.8 5577.3 4538.9 5204.5 5244.8 5680.5 5708.0 4682.4 4950.9 5695.5 5722.4 5740.5 4174.1 4742.7 4837.9 5574.6 5594.8 4286.5 4334.0 5479.8 5492.3 5549.0 5289.0 5670.1 5708.9 5764.7 5800.8 3316.8 3770.7 3806.4 3807.0 3826.6 5130.6 5502.0 5533.0 5541.8 5614.7 4753.3 4782.6 4971.6 5060.3 5407.9 4954.8 5018.4 5279.6 5430.0 5486.8 4972.4 5264.9 5272.0 5286.3 5338.7 4465.0 4494.5 4765.7 5148.8 5388.2 4411.5 4521.0 4757.9 4846.1 5366.2 06:08:55 INFO - PROCESS | 1643 | 06:08:55 INFO - PROCESS | 1643 | [#5] yui.html Cycles:180 Average:24311.59 Median:3672.61 stddev:38437.97 (1046.6%) stddev-sans-first:38504.99 06:08:55 INFO - PROCESS | 1643 | Values: 722.8 725.7 742.2 775.4 815.2 14576.0 15369.6 16254.3 16297.7 16371.8 13631.0 14808.3 14815.9 14835.6 14844.2 667.5 672.4 694.0 837.4 861.6 853.8 858.9 866.0 866.2 871.2 908.1 908.2 908.7 910.6 911.8 900.8 903.2 903.2 903.8 905.7 529.0 595.7 657.1 679.2 680.1 14628.3 14660.1 14661.3 14683.3 14684.9 12590.9 12828.0 12929.6 13205.7 13242.1 12183.2 12291.5 12312.5 12478.2 12761.4 4375.3 4385.2 4416.1 4456.1 4642.7 121373.0 122282.0 123311.0 124649.4 124982.4 8973.6 10298.0 10326.6 10379.9 10823.5 19189.5 19202.6 20433.9 21234.2 21260.8 7984.5 8114.7 8188.0 8193.9 8268.0 4238.4 4483.1 4507.6 4631.5 4645.8 3599.9 3745.3 3839.7 4017.8 4023.1 698.5 705.7 713.6 720.0 722.5 479.4 617.9 633.9 650.2 654.2 9708.2 9745.0 10136.5 10357.2 10390.4 580.8 622.4 629.3 673.6 680.1 4489.5 4697.7 4704.8 4707.3 4743.2 138326.6 139041.0 140160.0 141359.0 144250.0 110795.2 113026.3 123380.4 123804.4 125776.0 71748.3 106698.0 108672.4 109034.0 113122.0 8728.3 9034.8 9035.4 9043.1 9089.9 54553.9 56916.0 56960.0 57786.4 62778.4 80773.2 84346.6 91149.0 93098.5 94083.5 10059.5 11931.5 12048.2 12615.7 12843.4 12622.4 14757.9 14877.6 15067.3 15087.6 13328.7 14167.5 14209.1 14521.8 14529.6 13761.9 13774.4 14191.9 14273.4 14374.2 13458.3 13485.3 13779.5 14280.4 14418.9 11189.7 11526.1 11766.5 11794.3 11824.2 9857.9 11055.3 11451.3 11810.7 11854.3 06:08:55 INFO - PROCESS | 1643 | -------- Summary: end -------- 06:08:55 INFO - PROCESS | 1643 | 06:08:56 INFO - TEST-INFO | 1643: exit 0 06:08:56 INFO - TEST-OK | dromaeo_css | took 1119143ms 06:08:56 INFO - TEST-START | kraken 06:08:56 INFO - operating with platform_type : mac_ 06:08:56 INFO - Initialising browser for kraken test... 06:08:56 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/tmpLzCZsl/profile http://localhost:49275/getInfo.html) 06:08:58 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 06:08:58 INFO - PROCESS | 1653 | colorDepth:24 06:08:58 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 06:08:58 INFO - PROCESS | 1653 | __metrics 06:08:58 INFO - PROCESS | 1653 | 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" 06:08:58 INFO - PROCESS | 1653 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:08:59 INFO - TEST-INFO | 1653: exit 0 06:08:59 INFO - Browser initialized. 06:08:59 INFO - Running cycle 1/1 for kraken test... 06:08:59 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/tmpLzCZsl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:09:11 INFO - PROCESS | 1654 | RSS: Main: 228380672 06:09:11 INFO - PROCESS | 1654 | 06:09:14 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 06:09:15 INFO - PROCESS | 1654 | RSS: Main: 285016064 06:09:15 INFO - PROCESS | 1654 | 06:09:17 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 06:09:18 INFO - PROCESS | 1654 | RSS: Main: 293658624 06:09:18 INFO - PROCESS | 1654 | 06:09:21 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 06:09:22 INFO - PROCESS | 1654 | RSS: Main: 387452928 06:09:22 INFO - PROCESS | 1654 | 06:09:24 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 06:09:25 INFO - PROCESS | 1654 | RSS: Main: 290271232 06:09:25 INFO - PROCESS | 1654 | 06:09:27 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:09:27 INFO - PROCESS | 1654 | RSS: Main: 294494208 06:09:27 INFO - PROCESS | 1654 | 06:09:30 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 06:09:31 INFO - PROCESS | 1654 | RSS: Main: 330252288 06:09:31 INFO - PROCESS | 1654 | 06:09:34 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 06:09:34 INFO - PROCESS | 1654 | RSS: Main: 332816384 06:09:34 INFO - PROCESS | 1654 | 06:09:37 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 06:09:38 INFO - PROCESS | 1654 | RSS: Main: 333103104 06:09:38 INFO - PROCESS | 1654 | 06:09:39 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:09:40 INFO - PROCESS | 1654 | RSS: Main: 300462080 06:09:40 INFO - PROCESS | 1654 | 06:09:42 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:09:42 INFO - PROCESS | 1654 | RSS: Main: 307380224 06:09:42 INFO - PROCESS | 1654 | 06:09:44 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:09:45 INFO - PROCESS | 1654 | RSS: Main: 300457984 06:09:45 INFO - PROCESS | 1654 | 06:09:48 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:09:49 INFO - PROCESS | 1654 | RSS: Main: 311713792 06:09:49 INFO - PROCESS | 1654 | 06:09:52 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:09:52 INFO - PROCESS | 1654 | RSS: Main: 313036800 06:09:52 INFO - PROCESS | 1654 | 06:09:54 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 06:09:54 INFO - PROCESS | 1654 | RSS: Main: 356073472 06:09:54 INFO - PROCESS | 1654 | 06:09:54 INFO - PROCESS | 1654 | __start_tp_report 06:09:54 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 06:09:54 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 06:09:54 INFO - PROCESS | 1654 | |i|pagename|runs| 06:09:54 INFO - PROCESS | 1654 | |0;ai-astar;103;113;113;113;107;114;112;112;112;112 06:09:54 INFO - PROCESS | 1654 | |1;audio-beat-detection;143;114;108;165;108;107;127;106;133;113 06:09:54 INFO - PROCESS | 1654 | |2;audio-dft;185;185;183;182;183;183;183;185;182;183 06:09:54 INFO - PROCESS | 1654 | |3;audio-fft;78;57;60;86;59;57;69;66;88;77 06:09:54 INFO - PROCESS | 1654 | |4;audio-oscillator;84;91;135;101;72;68;96;70;68;103 06:09:54 INFO - PROCESS | 1654 | |5;imaging-gaussian-blur;85;84;85;83;86;83;87;83;86;83 06:09:54 INFO - PROCESS | 1654 | |6;imaging-darkroom;93;93;93;94;103;92;93;92;92;91 06:09:54 INFO - PROCESS | 1654 | |7;imaging-desaturate;88;85;87;84;86;85;84;84;86;86 06:09:54 INFO - PROCESS | 1654 | |8;json-parse-financial;57;55;56;56;55;56;53;53;56;54 06:09:54 INFO - PROCESS | 1654 | |9;json-stringify-tinderbox;64;68;71;74;72;67;67;67;67;70 06:09:54 INFO - PROCESS | 1654 | |10;stanford-crypto-aes;87;98;75;87;95;104;82;86;86;84 06:09:54 INFO - PROCESS | 1654 | |11;stanford-crypto-ccm;138;139;135;136;139;138;136;138;157;138 06:09:54 INFO - PROCESS | 1654 | |12;stanford-crypto-pbkdf2;167;167;168;168;172;168;165;226;165;167 06:09:54 INFO - PROCESS | 1654 | |13;stanford-crypto-sha256-iterative;66;67;71;90;87;88;62;59;58;66 06:09:54 INFO - PROCESS | 1654 | __end_tp_report 06:09:54 INFO - PROCESS | 1654 | __start_cc_report 06:09:54 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,2084 06:09:54 INFO - PROCESS | 1654 | __end_cc_report 06:09:54 INFO - PROCESS | 1654 | __startTimestamp1471525794691__endTimestamp 06:09:54 INFO - PROCESS | 1654 | 06:09:54 INFO - PROCESS | 1654 | ------- Summary: start ------- 06:09:54 INFO - PROCESS | 1654 | Number of tests: 14 06:09:54 INFO - PROCESS | 1654 | 06:09:54 INFO - PROCESS | 1654 | [#0] ai-astar Cycles:10 Average:111.10 Median:112.50 stddev:3.41 (3.0%) stddev-sans-first:2.00 06:09:54 INFO - PROCESS | 1654 | Values: 103.0 113.0 113.0 113.0 107.0 114.0 112.0 112.0 112.0 112.0 06:09:54 INFO - PROCESS | 1654 | 06:09:54 INFO - PROCESS | 1654 | [#1] audio-beat-detection Cycles:10 Average:122.40 Median:120.50 stddev:19.59 (16.3%) stddev-sans-first:19.30 06:09:54 INFO - PROCESS | 1654 | Values: 143.0 114.0 108.0 165.0 108.0 107.0 127.0 106.0 133.0 113.0 06:09:54 INFO - PROCESS | 1654 | 06:09:54 INFO - PROCESS | 1654 | [#2] audio-dft Cycles:10 Average:183.40 Median:183.00 stddev:1.17 (0.6%) stddev-sans-first:1.09 06:09:54 INFO - PROCESS | 1654 | Values: 185.0 185.0 183.0 182.0 183.0 183.0 183.0 185.0 182.0 183.0 06:09:54 INFO - PROCESS | 1654 | 06:09:54 INFO - PROCESS | 1654 | [#3] audio-fft Cycles:10 Average:69.70 Median:73.00 stddev:11.87 (16.3%) stddev-sans-first:12.20 06:09:54 INFO - PROCESS | 1654 | Values: 78.0 57.0 60.0 86.0 59.0 57.0 69.0 66.0 88.0 77.0 06:09:54 INFO - PROCESS | 1654 | 06:09:54 INFO - PROCESS | 1654 | [#4] audio-oscillator Cycles:10 Average:88.80 Median:71.00 stddev:21.25 (29.9%) stddev-sans-first:22.47 06:09:54 INFO - PROCESS | 1654 | Values: 84.0 91.0 135.0 101.0 72.0 68.0 96.0 70.0 68.0 103.0 06:09:54 INFO - PROCESS | 1654 | 06:09:54 INFO - PROCESS | 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:84.50 Median:85.00 stddev:1.51 (1.8%) stddev-sans-first:1.59 06:09:54 INFO - PROCESS | 1654 | Values: 85.0 84.0 85.0 83.0 86.0 83.0 87.0 83.0 86.0 83.0 06:09:54 INFO - PROCESS | 1654 | 06:09:54 INFO - PROCESS | 1654 | [#6] imaging-darkroom Cycles:10 Average:93.60 Median:93.00 stddev:3.41 (3.7%) stddev-sans-first:3.61 06:09:54 INFO - PROCESS | 1654 | Values: 93.0 93.0 93.0 94.0 103.0 92.0 93.0 92.0 92.0 91.0 06:09:54 INFO - PROCESS | 1654 | 06:09:54 INFO - PROCESS | 1654 | [#7] imaging-desaturate Cycles:10 Average:85.50 Median:86.00 stddev:1.35 (1.6%) stddev-sans-first:1.09 06:09:54 INFO - PROCESS | 1654 | Values: 88.0 85.0 87.0 84.0 86.0 85.0 84.0 84.0 86.0 86.0 06:09:54 INFO - PROCESS | 1654 | 06:09:54 INFO - PROCESS | 1654 | [#8] json-parse-financial Cycles:10 Average:55.10 Median:56.00 stddev:1.37 (2.4%) stddev-sans-first:1.27 06:09:54 INFO - PROCESS | 1654 | Values: 57.0 55.0 56.0 56.0 55.0 56.0 53.0 53.0 56.0 54.0 06:09:54 INFO - PROCESS | 1654 | 06:09:54 INFO - PROCESS | 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:68.70 Median:69.00 stddev:2.98 (4.3%) stddev-sans-first:2.64 06:09:54 INFO - PROCESS | 1654 | Values: 64.0 68.0 71.0 74.0 72.0 67.0 67.0 67.0 67.0 70.0 06:09:54 INFO - PROCESS | 1654 | 06:09:54 INFO - PROCESS | 1654 | [#10] stanford-crypto-aes Cycles:10 Average:88.40 Median:86.50 stddev:8.40 (9.7%) stddev-sans-first:8.89 06:09:54 INFO - PROCESS | 1654 | Values: 87.0 98.0 75.0 87.0 95.0 104.0 82.0 86.0 86.0 84.0 06:09:54 INFO - PROCESS | 1654 | 06:09:54 INFO - PROCESS | 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:139.40 Median:138.00 stddev:6.33 (4.6%) stddev-sans-first:6.69 06:09:54 INFO - PROCESS | 1654 | Values: 138.0 139.0 135.0 136.0 139.0 138.0 136.0 138.0 157.0 138.0 06:09:54 INFO - PROCESS | 1654 | 06:09:54 INFO - PROCESS | 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.30 Median:168.00 stddev:18.62 (11.1%) stddev-sans-first:19.61 06:09:54 INFO - PROCESS | 1654 | Values: 167.0 167.0 168.0 168.0 172.0 168.0 165.0 226.0 165.0 167.0 06:09:54 INFO - PROCESS | 1654 | 06:09:54 INFO - PROCESS | 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.40 Median:69.00 stddev:12.31 (17.8%) stddev-sans-first:12.90 06:09:54 INFO - PROCESS | 1654 | Values: 66.0 67.0 71.0 90.0 87.0 88.0 62.0 59.0 58.0 66.0 06:09:54 INFO - PROCESS | 1654 | -------- Summary: end -------- 06:09:54 INFO - PROCESS | 1654 | 06:09:55 INFO - TEST-INFO | 1654: exit 0 06:09:55 INFO - TEST-OK | kraken | took 59413ms 06:09:55 INFO - SUITE-END | took 1178s 06:09:55 INFO - Completed test suite (00:19:38) 06:09:55 INFO - javascript benchmark 06:09:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3352.3965686176484, 3391.0166051844967, 3392.779469334493, 3404.1829960809414, 3452.5339079224295, 5595.468430499096, 5667.263255776748, 5742.425757424256, 5743.4543718346695, 5750.079987202043, 5688.715564221783, 5725.026745453262, 5815.098659707742, 5858.648481091138, 5860.472557469838, 5132.0, 5222.608304377168, 5226.7909283628605, 5229.294045303893, 5321.654092483996, 5336.17289320156, 5341.985022845666, 5343.946560534403, 5351.197320401932, 5372.811951581695, 5171.276021357013, 5226.973865130688, 5227.242049902781, 5244.606654500909, 5287.814926477593, 5189.091908915924, 5265.684058956436, 5266.578673706094, 5279.947200527984, 5319.042572336978, 5186.403563590197, 5190.558802501791, 5206.947930520684, 5252.553532949704, 5301.151815709468, 5724.341700704431, 5774.105013722879, 5793.797217097382, 5831.300243970708, 5834.3290521589715, 5988.790392336291, 6264.686765661744, 6272.529560282997, 6288.52836037299, 6294.3705629437145, 6071.362506936796, 6135.171751813493, 6228.781992630282, 6271.560990730651, 6301.054841773725, 5864.091065884794, 5904.852378690524, 5909.586328957018, 5911.586188966817, 5916.645001299849, 6331.68341582919, 6335.081413195153, 6348.650824204713, 6354.745810167634, 6361.681915904186, 6024.126501657235, 6127.080937859357, 6147.877042459126, 6179.3820617937845, 6194.946859033975, 5280.366356037223, 6316.431521163117, 6322.683865806691, 6329.240491141092, 6341.904871426932, 6139.386061393825, 6190.81427557174, 6244.219472565929, 6245.937540624535, 6273.560850740404, 5867.14926335679, 5910.379410161957, 5913.290405151323, 5975.820725378246, 6257.374262573706, 4464.2633965396, 4841.8547443576745, 4937.333459982929, 4983.90032199354, 4990.176620857518, 4422.49141348749, 4449.510553839075, 4465.0400163964905, 4468.888277793016, 4494.775261236925, 5687.857803554862, 5707.68607726579, 5711.628744131701, 5714.257146570919, 5755.309362876482, 6115.209918062534, 6214.316425193225, 6236.251649802053, 6249.093881387264, 6309.49524038085, 5710.114932185518, 5727.0, 5744.195812586241, 5749.281339832521, 5762.711864406847, 5463.972680136574, 5467.371252306036, 5476.945230547723, 5477.096279113902, 5479.6164268500825, 6272.18461599989, 6288.874222515616, 6297.496200304056, 6342.809715708536, 6406.679666016774, 6263.091851681572, 6333.96833015832, 6362.013887847391, 6373.904391434225, 6377.23473183221, 6313.96843015782, 6316.242050953915, 6332.461740752087, 6339.809805705836, 6345.524085693499, 5745.224394706828, 6032.75868965254, 6191.9690401547705, 6234.470070044097, 6239.1577137085915, 5415.6479828811, 5510.7049843285995, 5861.824145275648, 5912.172295878667, 5992.730327135377, 5735.7418916148035, 5778.56660750437, 5779.075347944308, 5783.710814459176, 5787.7395517200985, 5755.8561035974435, 5767.307923049261, 5773.711314434346, 5988.461038506555, 6084.513238940963, 5330.413654498079, 5788.160716695971, 5978.641281523122, 6049.758009679564, 6053.697315134314, 5044.798208071783, 6163.106349579196, 6164.013757798731, 6168.19813424252, 6193.81418557444, 5837.124431335334, 5906.675132867734, 5911.527077833677, 6004.789832356021, 6010.579259451796], "value": 5681.5102423573435, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [464.6306186581667, 506.8783491961929, 512.4209643103295, 514.770926937513, 523.0819911056096, 31068.844655776717, 41994.0, 43095.78452107743, 43104.3534346985, 43146.56853431465, 44189.337159942625, 44458.0, 44494.0, 44540.777296113556, 44541.55458445423, 8703.782405439882, 9845.015498450151, 10036.096751292382, 10039.648612298572, 10067.848982265235, 8738.344624153182, 11994.0, 12262.019038476945, 12266.69333266671, 12522.499100035988, 12343.580488243877, 12390.256584604953, 12422.751544969095, 12438.81341779874, 12457.750844983095, 10573.62542869422, 12121.0, 12217.450214740313, 12309.199902006341, 12324.014078873714, 12031.458584363638, 12129.0, 12129.939350303192, 12130.029597632169, 12135.332556709378, 41717.165656687, 41944.74165775032, 41984.79007604973, 41994.0, 42000.78999605013, 3316.137804170933, 3474.7915125092322, 3559.6440355964455, 3806.467094606757, 3821.5032045833864, 31789.682103178904, 32897.342053159045, 32919.0, 32998.83500582506, 33037.33925321504, 3241.2383089973837, 3372.0726800129914, 3422.2299982503987, 3451.0854623524788, 3454.827258637083, 531769.0, 534047.0, 534700.0, 534935.3253233709, 543366.2831685856, 62321.75356492845, 62410.37589624136, 62804.685976569825, 62955.05567416493, 63062.0, 66288.0, 66874.0, 67028.66485667638, 67065.0, 67099.32900671028, 16676.16619169037, 16874.325026999028, 16911.830881691025, 16925.322987080624, 16942.0, 11621.418929053514, 11800.940995294968, 11840.70398240051, 12802.42389092493, 12891.097623166475, 16021.356476181532, 16176.27206775698, 16198.514044578682, 17918.91040544815, 18303.176357063963, 5773.364929857712, 6650.334966503311, 6876.828079298058, 6946.0, 6952.687129079203, 890.2210565754964, 956.3687965942445, 999.3354419311077, 1063.3991794636006, 1073.978520429587, 30798.076057718303, 30958.53562196569, 31207.0, 31308.060758177293, 31719.04842854718, 865.3250464637592, 1009.742870126697, 1126.0428635659623, 1146.2835727670206, 1158.7334912970064, 15320.693586128438, 16103.19484025794, 16124.193790310437, 16155.353785848669, 16225.2698628562, 670173.4387264812, 699028.0, 701166.0, 701251.0, 922284.0, 61875.0, 62715.68642156851, 62745.0, 62810.0, 62957.0, 1241.85097788266, 1289.3553223388305, 1374.7594171020032, 1394.7001394700187, 1420.928953552339, 29803.0, 31016.0, 32558.0, 32962.67037329692, 33172.0, 59380.70309648424, 60166.0, 60263.49341266503, 60268.39731602803, 60329.0, 513798.0, 592631.0, 597280.0, 604420.0, 605001.0, 25933.6109958347, 26897.86551067232, 27038.783254753977, 27376.726232738216, 27454.901803928416, 37673.81163094167, 41166.58833411628, 41772.0, 46399.84000400017, 51465.485345146066, 40472.595274046886, 41710.210001390595, 41736.165276694905, 42608.934776630835, 42660.57339426566, 36044.4593331095, 40490.785276441755, 40877.978050548976, 42109.0, 42349.0, 39046.21907561776, 39807.20385592214, 39875.800620996706, 40352.39471407873, 40594.79702601468, 35055.298894021464, 35817.283654327286, 36691.74435751935, 36717.44923826198, 36836.447453287685, 22091.337259882228, 25798.87100564485, 26203.0, 26530.469390612467, 27472.582055153398], "value": 21045.70934719055, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.84787769442596, 377.3170561284076, 383.91745774658443, 397.2829043576344, 407.45401162442323, 15286.84713152868, 16062.919685401557, 19047.52381190469, 21598.676019859715, 21848.781512184873, 20539.691904621443, 21544.0, 21604.0, 21624.675629865564, 21728.782712172873, 5792.594518383715, 5894.17481552583, 5934.465898069173, 6009.969950150243, 6078.392160783908, 5005.174146265862, 6012.3085845127935, 6016.879662406771, 6030.185924900126, 6038.0942858571125, 4038.495188101487, 5957.046872500401, 6263.404976527223, 6520.902186467207, 6577.112089867878, 6340.682965851735, 6527.543071984963, 6586.670666466705, 6604.150837765434, 6614.47084233265, 5679.858003549903, 5695.572832037614, 5696.401877802854, 5700.401457846908, 5701.486866182022, 25596.488070238676, 26265.868670656717, 26440.471190576274, 27074.323141921413, 27387.04145354903, 2254.966175507369, 2283.5090455552136, 2487.9751202487923, 3084.1981084917857, 3192.9201769955703, 10652.201084918584, 14235.359408826627, 15145.621359465991, 15486.225688715633, 15999.840001599952, 1745.7267623206487, 1799.631075629493, 1850.7871594766639, 1911.4934542346289, 1924.769027716669, 95172.10348706412, 130810.0, 132334.0, 132845.38700742912, 134111.32944335314, 9359.719208423758, 11166.218364714392, 11513.597024104116, 11578.594749183907, 11741.354225517507, 14520.0, 14558.417663293561, 14562.053466524641, 14574.854251457562, 14578.979471436898, 8347.66609335632, 8669.089745576624, 8694.30445564366, 8769.342299327576, 8811.955940220258, 6966.5471744336455, 8338.374621903382, 8517.0, 8524.06235314115, 8602.18279263469, 4002.2995975704134, 4363.909022744314, 4429.224885645, 4575.702579332333, 4671.575169573245, 1869.4111354923157, 1903.790583035865, 1905.59982403694, 1906.8474522038205, 1907.189444486088, 870.2472361407464, 969.7187815533416, 976.5751897924425, 992.1764935103846, 1017.7964407118606, 20022.59954800925, 21477.46306342354, 21480.892595536923, 21500.569988600142, 21596.892015539823, 932.7947851472666, 958.4249450329746, 966.2849491376471, 973.770066547598, 1039.9376037437778, 10137.949310253549, 10698.358098514114, 11251.09991200702, 11874.168808183516, 12092.697682558006, 551583.0, 557354.0, 616659.0, 623793.0, 633720.0, 144052.0, 145765.0, 149768.0, 150269.0, 153740.0, 970.1559643110398, 983.434525148057, 1015.9949200253951, 1016.6899095775859, 1021.632212403549, 15100.848991509944, 17843.572514198575, 19155.808441915404, 19302.4209273722, 19366.93481858455, 44883.77558112189, 55446.72276638591, 56100.71949640226, 56276.0, 56724.71637641951, 76534.23465765493, 77154.6142269285, 79592.60203698944, 85697.0, 87437.56281218553, 7910.129885712452, 8094.554799485849, 8206.343492520704, 8228.465149765247, 8542.40203185771, 9835.704928852147, 11239.55041798319, 13190.010749194042, 13441.395137218653, 13523.323833808467, 5447.754851031634, 5609.971950140386, 11095.780843831104, 11155.498002590066, 11533.308001519936, 11161.0, 11454.14093942941, 11611.709707257387, 11675.672433356793, 11836.82244766312, 9650.420974741539, 11283.266587671775, 11373.886261137613, 11809.409529523318, 11871.762564748484, 7155.856882862209, 7388.150362708142, 8121.147279535564, 8201.466904651179, 8420.62107205165, 11218.607348742755, 11376.488058037574, 11533.00141963079, 11544.480498377425, 11628.186026978368], "value": 9768.994445258897, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1386.029779154592, 1550.961225969351, 1581.1066747287782, 1742.7560141580182, 1855.4433669899036, 7804.960975195123, 8295.336373090153, 8592.01191862936, 8858.468491890475, 8876.600552975115, 7820.960895195531, 8517.850090237816, 8717.128287171296, 8786.86819697705, 8803.867941980876, 2386.7971222446113, 2415.516896620683, 2415.8188135889877, 2417.5648383291, 2421.527802078587, 2364.13708996216, 2398.448356877919, 2419.9395015124674, 2424.0667343072955, 2426.9271921842374, 2373.0745009446246, 2399.1483023526657, 2409.1929203716777, 2416.598372943697, 2419.41933935855, 2356.104680221522, 2381.3927448500694, 2389.474315650554, 2393.0188622664623, 2407.41018450479, 2004.1181879972764, 2351.882405879699, 2391.2467572714713, 2391.4021494626345, 2399.3042017814814, 8706.477611343276, 8774.122587741238, 8833.205011548991, 8833.60248788806, 8838.469691818447, 4539.72761634303, 5228.895422091575, 5396.298481197458, 5398.433164517708, 5399.244105825188, 3203.1511649412923, 3636.5999740028574, 3640.854365825364, 3646.361886669849, 3652.251288485854, 1105.5688281570194, 1112.3270421395102, 1140.1107136433511, 1140.4069883660534, 1146.2492067695673, 11737.119716021332, 11865.288082715064, 11878.287302761863, 11882.940585297018, 11910.987566056809, 4481.282994720894, 4573.611243044312, 4602.907941841145, 4712.81148754046, 4713.670043097019, 9259.35184537076, 9260.212881905114, 9287.071292870658, 9288.907110928803, 9291.303152263607, 5286.233496143038, 5289.68261904287, 5294.629375943647, 5295.391030031596, 5305.734713264322, 4070.430139780433, 4426.800793964215, 4845.781939812717, 4931.2849636803185, 4944.332515110414, 3783.1677031053123, 3792.962070379316, 3849.4995650565247, 3888.6111388860886, 3891.7081218908697, 1181.149572307937, 1193.40926241511, 1200.7298357869408, 1205.6503613951857, 1218.5125949620224, 905.2848249882651, 918.5177781664546, 923.8937522185034, 940.6942743608355, 950.496236994394, 5734.2258795062135, 5991.281046274475, 6016.09758536223, 6208.596441231396, 6212.844678883064, 438.4716416717847, 459.8781322949354, 468.60871172570603, 468.85934219733946, 965.7633879699519, 3909.179072394772, 3966.4446977423186, 4058.5941405859176, 4116.75299482032, 4124.1957818225155, 10629.5216715248, 10686.893131068746, 10969.670909872717, 11015.779684406267, 11062.446877656084, 10789.946050269855, 10993.505292261867, 11001.17491188164, 11015.063719583924, 11019.669409917715, 7875.724349647735, 8216.424850260544, 8243.464174828618, 8364.456310339809, 8431.620577074047, 5713.485786279089, 5870.26621672291, 5907.320658124198, 5931.169636250842, 6019.367966363468, 10052.64815731446, 10361.119304859172, 10385.273030888067, 10406.947965260126, 10408.895911040792, 9423.528823558932, 9796.265280104164, 9807.411555306704, 9882.407055576688, 10393.688189354332, 7047.647617619201, 7055.082839230867, 7116.395106416013, 7210.74762383335, 7214.531055481377, 8343.290820280345, 8417.747467575982, 8428.536430496137, 8433.83132337362, 8440.83118337642, 7444.888326674996, 7542.019537460095, 7545.962270188614, 7627.00848889663, 7698.615069246627, 7279.781606551812, 7322.670479828314, 7367.631618419165, 7469.7385591504035, 7554.508956917783, 6074.878502430015, 6965.407940325129, 7114.395276401564, 7119.288071192839, 7120.0, 4867.805287788448, 5040.773165207648, 5071.39143302806, 5074.873128171826, 5125.077486052546, 4995.350604421547, 5004.699718016931, 5032.989425578463, 5321.866953326043, 5333.413324534221], "value": 4532.496812726551, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [246.6916354556804, 299.902531677205, 316.64061290435365, 318.68131868131866, 319.64838677454804, 4409.603135717784, 4812.879678008047, 5114.820981265647, 5173.3533308336455, 5212.921806172911, 4525.547445255473, 4867.05317872851, 4998.850034498962, 5042.773075211615, 5155.664881782691, 3415.351083294166, 3758.6053464386255, 3894.4158376243577, 3952.5454572724216, 3987.8803635890968, 3790.620937906201, 3898.9805050974705, 3988.1824226033596, 3991.0, 3996.920061598766, 3098.295937234523, 3558.6690717580027, 3925.7045175092153, 3945.191235796655, 3957.4855268815013, 3629.310431018098, 3950.3335248707294, 3953.62440568147, 3967.226390853785, 4005.6380830517473, 2920.73713365796, 3392.5080863274934, 3482.564679415073, 3500.702440292578, 3510.4558793387064, 4167.0, 4990.575801056914, 4991.800327986876, 4996.950640365528, 5014.774335154927, 3930.76415415076, 4430.844920427802, 4481.0, 4495.527969563214, 4498.640108791289, 5290.9735451322495, 5310.44240354761, 5311.203319502067, 5339.67961922282, 5353.384360798519, 3206.1824234819974, 3822.9044273893096, 3833.367494363427, 3838.6756568983656, 3843.442700808395, 4645.837395691168, 5103.719295438749, 5217.087009773312, 5586.525145362609, 5685.180742162468, 3683.3001729671278, 3712.925741485155, 3723.6090210527504, 3741.5136032315627, 3773.0, 3324.401607710587, 3569.6430356964615, 3795.3168429682923, 3812.8284227209842, 3830.865919692853, 3332.6334103248623, 3555.1823080691584, 3610.7653002555276, 4172.269852775752, 4358.910272431892, 4700.918788678555, 4942.283368911488, 5255.211718242294, 5297.913927644823, 5415.48552887475, 1093.5243169221415, 1191.9821202681967, 1255.813256472643, 1328.1499840102342, 1349.9122557033959, 837.8743188521648, 876.5222953490363, 910.2945217456391, 914.1772404835701, 937.1191080384417, 4926.605871530341, 4945.431275403303, 4950.292149208517, 4971.328870602422, 4999.500049994971, 5371.4628537146755, 5385.407605163429, 5418.0, 5426.701531415731, 5449.482299181572, 4264.360345948071, 4450.821967121279, 4605.746683932416, 4925.236588328743, 4938.654294199443, 2820.5346117890726, 2839.929001774972, 2849.6437945256844, 2851.959034952269, 2860.3707184419395, 4467.419235499365, 5030.89938201234, 5281.419043905167, 5523.7790488380015, 5577.330720313507, 4538.931916021263, 5204.505571970657, 5244.842654720364, 5680.488756011979, 5707.972564938268, 4682.391289132391, 4950.88605063865, 5695.487406133468, 5722.4277572242445, 5740.454656807764, 4174.123434078878, 4742.739149346819, 4837.903241935212, 5574.637648552831, 5594.832155035356, 4286.5070516890955, 4334.006357075655, 5479.808206712746, 5492.313460817398, 5549.0, 5289.0, 5670.092785154356, 5708.942910571007, 5764.674124951201, 5800.76796928135, 3316.8009919404913, 3770.6606405423645, 3806.371948628556, 3806.980965095157, 3826.559945606291, 5130.563902068366, 5502.0, 5533.043873630374, 5541.778328866962, 5614.719264036864, 4753.334533165291, 4782.593479554277, 4971.6022718183185, 5060.342155519759, 5407.891842163213, 4954.840762114907, 5018.372703412074, 5279.630425870152, 5430.02259593277, 5486.835394938158, 4972.428170760264, 5264.921026184534, 5272.0, 5286.259923610774, 5338.733063346895, 4464.986134359229, 4494.528074552125, 4765.69023013503, 5148.819791307286, 5388.245645609696, 4411.492678341903, 4520.982778874727, 4757.857264282077, 4846.055019271349, 5366.221897824716], "value": 3882.869092665514, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [722.8373615936412, 725.7351066860595, 742.2392048150641, 775.4106878772135, 815.2296080204208, 14576.0, 15369.615759606002, 16254.268557914878, 16297.674046519092, 16371.836281637181, 13631.0, 14808.25958702066, 14815.925920370382, 14835.554933352018, 14844.183569903651, 667.5260564998845, 672.4284358295434, 694.0144994108379, 837.3636036612157, 861.6338056326036, 853.8249658819927, 858.8969323681167, 866.0084203586889, 866.1598249697784, 871.1941454154913, 908.1009800297724, 908.209857424042, 908.6592527802075, 910.5720311453606, 911.8404279251138, 900.8288425199224, 903.2277402820625, 903.2457897655465, 903.7650210945202, 905.728281515546, 529.0450736420759, 595.693217992734, 657.1030543308398, 679.2290749998738, 680.1056610557101, 14628.268586570628, 14660.120392776467, 14661.34023968924, 14683.339249733788, 14684.853151468455, 12590.937045314806, 12827.970853698747, 12929.612111636667, 13205.669858253525, 13242.139260948104, 12183.234417686752, 12291.493323824268, 12312.468693120747, 12478.18891772032, 12761.425735841909, 4375.25620644488, 4385.2106620808245, 4416.138852923681, 4456.108778244364, 4642.65180111493, 121373.0, 122282.0, 123311.0, 124649.37675311565, 124982.3750881258, 8973.564229723213, 10298.0, 10326.586936522455, 10379.85251147898, 10823.458827058615, 19189.520261993563, 19202.615947681246, 20433.89783051105, 21234.150633974776, 21260.78739212588, 7984.520928744294, 8114.675412983533, 8187.95906020478, 8193.877091843628, 8268.0, 4238.36424536322, 4483.058557702919, 4507.616852567503, 4631.536846315409, 4645.837395691135, 3599.9100022499647, 3745.3071181830906, 3839.7120215983914, 4017.83928642851, 4023.114914718757, 698.4831224893644, 705.6612825843521, 713.578988396838, 720.0, 722.458156382713, 479.43426756427056, 617.9073139029182, 633.9366063393716, 650.2067477677226, 654.1626717801222, 9708.223342132613, 9745.0, 10136.506270973443, 10357.171426285879, 10390.402399400149, 580.7967211476034, 622.3527531367328, 629.3329071184559, 673.5619452469014, 680.0988689985788, 4489.506154323022, 4697.718136911796, 4704.835330763407, 4707.293905914072, 4743.193657078305, 138326.6167338334, 139041.0, 140160.0, 141359.0, 144250.0, 110795.23011924766, 113026.30460543098, 123380.38309808573, 123804.38097809633, 125776.0, 71748.2825171752, 106698.0, 108672.36991445292, 109033.99999999841, 113122.0, 8728.301735861243, 9034.81930361376, 9035.367524273237, 9043.095690430904, 9089.909100908906, 54553.908921822134, 56916.0, 56960.0, 57786.422135778106, 62778.372216277254, 80773.19226807657, 84346.57826711073, 91149.0, 93098.53450732703, 94083.52958235439, 10059.497025148567, 11931.463084161407, 12048.216865903689, 12615.729168749713, 12843.42204600814, 12622.431990560262, 14757.852421475647, 14877.588516174545, 15067.321970511133, 15087.622809429851, 13328.666783330495, 14167.504137355143, 14209.076410033314, 14521.782173267198, 14529.564113076625, 13761.862381376059, 13774.380152893344, 14191.858081419054, 14273.429062837371, 14374.209418481665, 13458.327083645974, 13485.258310793, 13779.53148197974, 14280.357383917957, 14418.927905360406, 11189.664310070712, 11526.13874352068, 11766.52933882635, 11794.29234245948, 11824.172307938707, 9857.866345370374, 11055.336679799238, 11451.25566838153, 11810.704732381759, 11854.288742675468], "value": 7814.043499431572, "unit": "score"}], "name": "dromaeo_css", "value": 7373.082762351, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 113.0, 113.0, 113.0, 107.0, 114.0, 112.0, 112.0, 112.0, 112.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 114.0, 108.0, 165.0, 108.0, 107.0, 127.0, 106.0, 133.0, 113.0], "value": 122.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 183.0, 182.0, 183.0, 183.0, 183.0, 185.0, 182.0, 183.0], "value": 183.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 57.0, 60.0, 86.0, 59.0, 57.0, 69.0, 66.0, 88.0, 77.0], "value": 69.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 91.0, 135.0, 101.0, 72.0, 68.0, 96.0, 70.0, 68.0, 103.0], "value": 88.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 85.0, 83.0, 86.0, 83.0, 87.0, 83.0, 86.0, 83.0], "value": 84.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 94.0, 103.0, 92.0, 93.0, 92.0, 92.0, 91.0], "value": 93.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 87.0, 84.0, 86.0, 85.0, 84.0, 84.0, 86.0, 86.0], "value": 85.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 55.0, 56.0, 56.0, 55.0, 56.0, 53.0, 53.0, 56.0, 54.0], "value": 55.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 68.0, 71.0, 74.0, 72.0, 67.0, 67.0, 67.0, 67.0, 70.0], "value": 68.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 98.0, 75.0, 87.0, 95.0, 104.0, 82.0, 86.0, 86.0, 84.0], "value": 88.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 139.0, 135.0, 136.0, 139.0, 138.0, 136.0, 138.0, 157.0, 138.0], "value": 139.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 167.0, 168.0, 168.0, 172.0, 168.0, 165.0, 226.0, 165.0, 167.0], "value": 173.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 71.0, 90.0, 87.0, 88.0, 62.0, 59.0, 58.0, 66.0], "value": 71.4, "unit": "score"}], "name": "kraken", "value": 1435.3000000000002, "alertThreshold": 2.0}]} 06:09:55 INFO - Return code: 0 06:09:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:09:56 INFO - # TBPL SUCCESS # 06:09:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:09:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:09:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:09:56 INFO - 2016-08-18 06:09:56.019 screenresolution[1658:9762] starting screenresolution argv=screenresolution get 06:09:56 INFO - 2016-08-18 06:09:56.023 screenresolution[1658:9762] Display 0: 1600x1200x32@60 06:09:56 INFO - 2016-08-18 06:09:56.029 screenresolution[1659:9765] starting screenresolution argv=screenresolution list 06:09:56 INFO - Available Modes on Display 0 06:09:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:09:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:09:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:09:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:09:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:09:56 INFO - Intel Iris: 06:09:56 INFO - Chipset Model: Intel Iris 06:09:56 INFO - Type: GPU 06:09:56 INFO - Bus: Built-In 06:09:56 INFO - VRAM (Dynamic, Max): 1536 MB 06:09:56 INFO - Vendor: Intel (0x8086) 06:09:56 INFO - Device ID: 0x0a2e 06:09:56 INFO - Revision ID: 0x0009 06:09:56 INFO - Displays: 06:09:56 INFO - Display: 06:09:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:09:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:09:56 INFO - Display Serial Number: 1600x1200 60 06:09:56 INFO - Main Display: Yes 06:09:56 INFO - Mirror: Off 06:09:56 INFO - Online: Yes 06:09:56 INFO - Rotation: Supported 06:09:56 INFO - Return code: 0 06:09:56 INFO - Running post-action listener: _resource_record_post_action 06:09:56 INFO - [mozharness: 2016-08-18 13:09:56.269281Z] Finished run-tests step (success) 06:09:56 INFO - Running post-run listener: _resource_record_post_run 06:09:56 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 36770816; Write bytes: 702750208; Read time: 385; Write time: 2206 06:09:56 INFO - TinderboxPrint: CPU usage
53.4% 06:09:56 INFO - TinderboxPrint: I/O read bytes / time
36,770,816 / 385 06:09:56 INFO - TinderboxPrint: I/O write bytes / time
702,750,208 / 2,206 06:09:56 INFO - TinderboxPrint: CPU idle
2,259.4 (46.6%) 06:09:56 INFO - TinderboxPrint: CPU system
968.3 (19.9%) 06:09:56 INFO - TinderboxPrint: CPU user
1,626.0 (33.5%) 06:09:56 INFO - TinderboxPrint: Swap in / out
442,343,424 / 0 06:09:56 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 396782080; Write bytes: 391669760; Read time: 29636; Write time: 763 06:09:56 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 34747392; Write bytes: 293156352; Read time: 349; Write time: 1400 06:09:56 INFO - Running post-run listener: _upload_blobber_files 06:09:56 INFO - Blob upload gear active. 06:09:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:09:56 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 06:09:56 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'] 06:09:56 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 06:09:57 INFO - (blobuploader) - INFO - Open directory for files ... 06:09:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:09:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:09:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:09:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:09:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:09:57 INFO - (blobuploader) - INFO - Done attempting. 06:09:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:09:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:09:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:09:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:09:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:09:58 INFO - (blobuploader) - INFO - Done attempting. 06:09:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:09:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:09:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:10:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:10:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:10:01 INFO - (blobuploader) - INFO - Done attempting. 06:10:01 INFO - (blobuploader) - INFO - Iteration through files over. 06:10:01 INFO - Return code: 0 06:10:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:10:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:10:01 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4d359f9abbb8727411315544f00681118cb9ff35324967632449bd7440ecfec5c189e7d2e21f153c5b9250056f7fc185572394488ff7ec31d815819d19e2a077", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8af754a72437f842c609f8c5ff82078f212dc9199cb2d6f3ba601211d533929139b01d18aa21898866ff46e655e997497567bdfdfd2583defd6ae597a23375bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3144518700578546e4e2ab76d7008a2ed3d38bfd1fd760d340f14d829e985684a07156d9bc8d0f1e3c8d3c88609059bf8fc1c4bfa0264defd7ea5a1cd7131d28"} 06:10:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:10:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:10:01 INFO - Contents: 06:10:01 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4d359f9abbb8727411315544f00681118cb9ff35324967632449bd7440ecfec5c189e7d2e21f153c5b9250056f7fc185572394488ff7ec31d815819d19e2a077", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8af754a72437f842c609f8c5ff82078f212dc9199cb2d6f3ba601211d533929139b01d18aa21898866ff46e655e997497567bdfdfd2583defd6ae597a23375bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3144518700578546e4e2ab76d7008a2ed3d38bfd1fd760d340f14d829e985684a07156d9bc8d0f1e3c8d3c88609059bf8fc1c4bfa0264defd7ea5a1cd7131d28"} 06:10:01 INFO - Running post-run listener: copy_logs_to_upload_dir 06:10:01 INFO - Copying logs to upload dir... 06:10:01 INFO - mkdir: /builds/slave/test/build/upload/logs 06:10:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.278338 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-18 06:10:01.286479) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 06:10:01.290035) ========= 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.HLtUu5Au1X/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.90y1QBqSLO/Listeners TMPDIR=/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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/4d359f9abbb8727411315544f00681118cb9ff35324967632449bd7440ecfec5c189e7d2e21f153c5b9250056f7fc185572394488ff7ec31d815819d19e2a077", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8af754a72437f842c609f8c5ff82078f212dc9199cb2d6f3ba601211d533929139b01d18aa21898866ff46e655e997497567bdfdfd2583defd6ae597a23375bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3144518700578546e4e2ab76d7008a2ed3d38bfd1fd760d340f14d829e985684a07156d9bc8d0f1e3c8d3c88609059bf8fc1c4bfa0264defd7ea5a1cd7131d28"} build_url:https://queue.taskcluster.net/v1/task/LUg-yIxMTgC5Koy2y6SI4Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011242 build_url: 'https://queue.taskcluster.net/v1/task/LUg-yIxMTgC5Koy2y6SI4Q/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/4d359f9abbb8727411315544f00681118cb9ff35324967632449bd7440ecfec5c189e7d2e21f153c5b9250056f7fc185572394488ff7ec31d815819d19e2a077", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8af754a72437f842c609f8c5ff82078f212dc9199cb2d6f3ba601211d533929139b01d18aa21898866ff46e655e997497567bdfdfd2583defd6ae597a23375bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3144518700578546e4e2ab76d7008a2ed3d38bfd1fd760d340f14d829e985684a07156d9bc8d0f1e3c8d3c88609059bf8fc1c4bfa0264defd7ea5a1cd7131d28"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 06:10:01.323673) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 06:10:01.324037) ========= 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.HLtUu5Au1X/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.90y1QBqSLO/Listeners TMPDIR=/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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.005127 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 06:10:01.372467) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 06:10:01.372840) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 06:10:01.373264) ========= ========= Total master_lag: 2.50 =========