builder: larch_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0312 starttime: 1471908104.18 results: success (0) buildid: 20160822114323 builduid: 87e75c49d6124abbbfc44d93c6b480cc revision: 4138eb4850bdccab04bca921966d715d31da43ee ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-22 16:21:44.184842) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-22 16:21:44.185256) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-22 16:21:44.185533) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-22 16:21:44.217875) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 16:21:44.218258) ========= 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.eL4rUgHkee/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.J8U3lE652J/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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-22 16:21:44-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.8M=0.001s 2016-08-22 16:21:44 (18.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.117176 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 16:21:44.361498) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 16:21:44.362049) ========= 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.eL4rUgHkee/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.J8U3lE652J/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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.031395 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 16:21:44.414392) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-22 16:21:44.414709) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 4138eb4850bdccab04bca921966d715d31da43ee --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev 4138eb4850bdccab04bca921966d715d31da43ee --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.eL4rUgHkee/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.J8U3lE652J/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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-22 16:21:44,494 truncating revision to first 12 chars 2016-08-22 16:21:44,495 Setting DEBUG logging. 2016-08-22 16:21:44,495 attempt 1/10 2016-08-22 16:21:44,495 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/4138eb4850bd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-22 16:21:45,764 unpacking tar archive at: larch-4138eb4850bd/testing/mozharness/ program finished with exit code 0 elapsedTime=1.529252 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-22 16:21:45.966683) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-22 16:21:45.967044) ========= script_repo_revision: 4138eb4850bdccab04bca921966d715d31da43ee ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-22 16:21:45.967487) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-22 16:21:45.967838) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-22 16:21:45.986800) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-08-22 16:21:45.987137) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 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', 'Larch', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.eL4rUgHkee/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.J8U3lE652J/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 16:21:46 INFO - MultiFileLogger online at 20160822 16:21:46 in /builds/slave/test 16:21:46 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 16:21:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:21:46 INFO - {'append_to_log': False, 16:21:46 INFO - 'base_work_dir': '/builds/slave/test', 16:21:46 INFO - 'blob_upload_branch': 'Larch', 16:21:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:21:46 INFO - 'branch': 'Larch', 16:21:46 INFO - 'buildbot_json_path': 'buildprops.json', 16:21:46 INFO - 'config_files': ('talos/mac_config.py',), 16:21:46 INFO - 'default_actions': ('clobber', 16:21:46 INFO - 'read-buildbot-config', 16:21:46 INFO - 'download-and-extract', 16:21:46 INFO - 'populate-webroot', 16:21:46 INFO - 'create-virtualenv', 16:21:46 INFO - 'install', 16:21:46 INFO - 'run-tests'), 16:21:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:21:46 INFO - 'download_minidump_stackwalk': True, 16:21:46 INFO - 'download_symbols': 'ondemand', 16:21:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:21:46 INFO - 'tooltool.py': '/tools/tooltool.py', 16:21:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:21:46 INFO - '/tools/misc-python/virtualenv.py')}, 16:21:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:21:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:21:46 INFO - 'installer_path': 'installer.exe', 16:21:46 INFO - 'log_level': 'info', 16:21:46 INFO - 'log_name': 'talos', 16:21:46 INFO - 'log_to_console': True, 16:21:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:21:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:21:46 INFO - 'opt_config_files': (), 16:21:46 INFO - 'pip_index': False, 16:21:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:21:46 INFO - 'cmd': ('bash', 16:21:46 INFO - '-c', 16:21:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:21:46 INFO - 'enabled': True, 16:21:46 INFO - 'halt_on_failure': False, 16:21:46 INFO - 'name': 'check_screen_resolution'},), 16:21:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:21:46 INFO - 'cmd': ('bash', 16:21:46 INFO - '-c', 16:21:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:21:46 INFO - 'enabled': True, 16:21:46 INFO - 'halt_on_failure': False, 16:21:46 INFO - 'name': 'check_screen_resolution'},), 16:21:46 INFO - 'run_cmd_checks_enabled': True, 16:21:46 INFO - 'sps_profile': False, 16:21:46 INFO - 'sps_profile_interval': 0, 16:21:46 INFO - 'suite': 'dromaeojs', 16:21:46 INFO - 'system_bits': '32', 16:21:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:21:46 INFO - 'title': 't-yosemite-r7-0312', 16:21:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:21:46 INFO - 'use_talos_json': True, 16:21:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:21:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:21:46 INFO - 'work_dir': 'build'} 16:21:46 INFO - [mozharness: 2016-08-22 23:21:46.177229Z] Running clobber step. 16:21:46 INFO - Running pre-action listener: _resource_record_pre_action 16:21:46 INFO - Running main action method: clobber 16:21:46 INFO - rmtree: /builds/slave/test/build 16:21:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:21:48 INFO - Running post-action listener: _resource_record_post_action 16:21:48 INFO - [mozharness: 2016-08-22 23:21:48.213637Z] Finished clobber step (success) 16:21:48 INFO - [mozharness: 2016-08-22 23:21:48.213834Z] Running read-buildbot-config step. 16:21:48 INFO - Running pre-action listener: _resource_record_pre_action 16:21:48 INFO - Running main action method: read_buildbot_config 16:21:48 INFO - Using buildbot properties: 16:21:48 INFO - { 16:21:48 INFO - "project": "", 16:21:48 INFO - "product": "firefox", 16:21:48 INFO - "script_repo_revision": "production", 16:21:48 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs", 16:21:48 INFO - "repository": "", 16:21:48 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs", 16:21:48 INFO - "buildid": "20160822114323", 16:21:48 INFO - "pgo_build": "False", 16:21:48 INFO - "basedir": "/builds/slave/test", 16:21:48 INFO - "buildnumber": 0, 16:21:48 INFO - "slavename": "t-yosemite-r7-0312", 16:21:48 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 16:21:48 INFO - "platform": "yosemite_r7", 16:21:48 INFO - "scheduler": "tests-larch-macosx64-talos", 16:21:48 INFO - "branch": "larch", 16:21:48 INFO - "repo_path": "projects/larch", 16:21:48 INFO - "revision": "4138eb4850bdccab04bca921966d715d31da43ee", 16:21:48 INFO - "stage_platform": "macosx64", 16:21:48 INFO - "builduid": "87e75c49d6124abbbfc44d93c6b480cc", 16:21:48 INFO - "slavebuilddir": "test" 16:21:48 INFO - } 16:21:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/Zjxa5YVHSvCe0KZHE-EQ5w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 16:21:48 INFO - Running post-action listener: _resource_record_post_action 16:21:48 INFO - [mozharness: 2016-08-22 23:21:48.217303Z] Finished read-buildbot-config step (success) 16:21:48 INFO - [mozharness: 2016-08-22 23:21:48.217424Z] Running download-and-extract step. 16:21:48 INFO - Running pre-action listener: _resource_record_pre_action 16:21:48 INFO - Running main action method: download_and_extract 16:21:48 INFO - mkdir: /builds/slave/test/build/tests 16:21:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:21:48 INFO - trying https://queue.taskcluster.net/v1/task/Zjxa5YVHSvCe0KZHE-EQ5w/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 16:21:48 INFO - Downloading https://queue.taskcluster.net/v1/task/Zjxa5YVHSvCe0KZHE-EQ5w/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 16:21:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Zjxa5YVHSvCe0KZHE-EQ5w/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 16:21:49 INFO - Downloaded 1199 bytes. 16:21:49 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 16:21:49 INFO - Using the following test package requirements: 16:21:49 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 16:21:49 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:21:49 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 16:21:49 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:21:49 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 16:21:49 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:21:49 INFO - u'jsshell-mac.zip'], 16:21:49 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:21:49 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 16:21:49 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 16:21:49 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:21:49 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 16:21:49 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:21:49 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 16:21:49 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:21:49 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 16:21:49 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:21:49 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 16:21:49 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:21:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:21:49 INFO - trying https://queue.taskcluster.net/v1/task/Zjxa5YVHSvCe0KZHE-EQ5w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 16:21:49 INFO - Downloading https://queue.taskcluster.net/v1/task/Zjxa5YVHSvCe0KZHE-EQ5w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 16:21:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Zjxa5YVHSvCe0KZHE-EQ5w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:21:50 INFO - Downloaded 16352362 bytes. 16:21: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 16:21:57 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 16:21:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:21:57 INFO - trying https://queue.taskcluster.net/v1/task/Zjxa5YVHSvCe0KZHE-EQ5w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 16:21:57 INFO - Downloading https://queue.taskcluster.net/v1/task/Zjxa5YVHSvCe0KZHE-EQ5w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 16:21:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Zjxa5YVHSvCe0KZHE-EQ5w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:21:58 INFO - Downloaded 16352362 bytes. 16:21: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 16:22: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')]} 16:22:05 INFO - trying https://queue.taskcluster.net/v1/task/Zjxa5YVHSvCe0KZHE-EQ5w/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 16:22:05 INFO - Downloading https://queue.taskcluster.net/v1/task/Zjxa5YVHSvCe0KZHE-EQ5w/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 16:22:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Zjxa5YVHSvCe0KZHE-EQ5w/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:22:06 INFO - Downloaded 14413981 bytes. 16:22: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 16:22:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:22:06 INFO - trying https://queue.taskcluster.net/v1/task/Zjxa5YVHSvCe0KZHE-EQ5w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 16:22:06 INFO - Downloading https://queue.taskcluster.net/v1/task/Zjxa5YVHSvCe0KZHE-EQ5w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 16:22:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Zjxa5YVHSvCe0KZHE-EQ5w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 16:22:09 INFO - Downloaded 111546909 bytes. 16:22:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Zjxa5YVHSvCe0KZHE-EQ5w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 16:22:09 INFO - mkdir: /builds/slave/test/properties 16:22:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:22:09 INFO - Writing to file /builds/slave/test/properties/build_url 16:22:09 INFO - Contents: 16:22:09 INFO - build_url:https://queue.taskcluster.net/v1/task/Zjxa5YVHSvCe0KZHE-EQ5w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 16:22:10 INFO - Running post-action listener: _resource_record_post_action 16:22:10 INFO - Running post-action listener: set_extra_try_arguments 16:22:10 INFO - [mozharness: 2016-08-22 23:22:10.247124Z] Finished download-and-extract step (success) 16:22:10 INFO - [mozharness: 2016-08-22 23:22:10.247258Z] Running populate-webroot step. 16:22:10 INFO - Running pre-action listener: _resource_record_pre_action 16:22:10 INFO - Running main action method: populate_webroot 16:22:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:22:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:22:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:22:10 INFO - '--tppagecycles', 16:22:10 INFO - '7'], 16:22:10 INFO - 'tests': ['tsvgm']}}, 16:22:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:22:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:22:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:22:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:22:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:22:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:22:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:22:10 INFO - 'talos_options': ['--disable-e10s'], 16:22:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:22:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:22:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:22:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:22:10 INFO - 'talos_options': ['--disable-e10s'], 16:22:10 INFO - 'tests': ['damp', 'tps']}, 16:22:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:22:10 INFO - 'tests': ['damp', 'tps']}, 16:22:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:22:10 INFO - 'tests': ['dromaeo_dom']}, 16:22:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:22:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:22:10 INFO - 'tests': ['basic_compositor_video']}, 16:22:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:22:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:22:10 INFO - 'tests': ['a11yr', 16:22:10 INFO - 'ts_paint', 16:22:10 INFO - 'tpaint', 16:22:10 INFO - 'sessionrestore', 16:22:10 INFO - 'sessionrestore_no_auto_restore', 16:22:10 INFO - 'tabpaint']}, 16:22:10 INFO - 'other-e10s': {'tests': ['a11yr', 16:22:10 INFO - 'ts_paint', 16:22:10 INFO - 'tpaint', 16:22:10 INFO - 'sessionrestore', 16:22:10 INFO - 'sessionrestore_no_auto_restore', 16:22:10 INFO - 'tabpaint']}, 16:22:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:22:10 INFO - 'ts_paint', 16:22:10 INFO - 'tpaint', 16:22:10 INFO - 'sessionrestore', 16:22:10 INFO - 'sessionrestore_no_auto_restore', 16:22:10 INFO - 'tabpaint']}, 16:22:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:22:10 INFO - 'ts_paint', 16:22:10 INFO - 'tpaint', 16:22:10 INFO - 'sessionrestore', 16:22:10 INFO - 'sessionrestore_no_auto_restore', 16:22:10 INFO - 'tabpaint']}, 16:22:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:22:10 INFO - 'tests': ['a11yr', 16:22:10 INFO - 'ts_paint', 16:22:10 INFO - 'tpaint', 16:22:10 INFO - 'sessionrestore', 16:22:10 INFO - 'sessionrestore_no_auto_restore', 16:22:10 INFO - 'tabpaint']}, 16:22:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:22:10 INFO - 'tests': ['a11yr', 16:22:10 INFO - 'ts_paint', 16:22:10 INFO - 'tpaint', 16:22:10 INFO - 'sessionrestore', 16:22:10 INFO - 'sessionrestore_no_auto_restore', 16:22:10 INFO - 'tabpaint']}, 16:22:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:22:10 INFO - 'tests': ['tsvgx', 16:22:10 INFO - 'tsvgr_opacity', 16:22:10 INFO - 'tart', 16:22:10 INFO - 'tscrollx', 16:22:10 INFO - 'cart']}, 16:22:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:22:10 INFO - 'tsvgr_opacity', 16:22:10 INFO - 'tart', 16:22:10 INFO - 'tscrollx', 16:22:10 INFO - 'cart']}, 16:22:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:22:10 INFO - 'talos_options': ['--disable-e10s'], 16:22:10 INFO - 'tests': ['tp5o']}, 16:22:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:22:10 INFO - 'tests': ['tp5o']}, 16:22:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:22:10 INFO - 'talos_options': ['--disable-e10s', 16:22:10 INFO - '--xperf_path', 16:22:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:22:10 INFO - 'tests': ['tp5n']}, 16:22:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:22:10 INFO - 'talos_options': ['--xperf_path', 16:22:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:22:10 INFO - 'tests': ['tp5n']}}, 16:22:10 INFO - 'talos.zip': {'path': '', 16:22:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:22:10 INFO - Running post-action listener: _resource_record_post_action 16:22:10 INFO - [mozharness: 2016-08-22 23:22:10.265982Z] Finished populate-webroot step (success) 16:22:10 INFO - [mozharness: 2016-08-22 23:22:10.266090Z] Running create-virtualenv step. 16:22:10 INFO - Running pre-action listener: _resource_record_pre_action 16:22:10 INFO - Running main action method: create_virtualenv 16:22:10 INFO - Creating virtualenv /builds/slave/test/build/venv 16:22: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 16:22:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:22:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:22:10 INFO - Using real prefix '/tools/python27' 16:22:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:22:11 INFO - Installing distribute.............................................................................................................................................................................................done. 16:22:13 INFO - Installing pip.................done. 16:22:13 INFO - Return code: 0 16:22:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:22:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:22:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:22:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:22:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:22:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:22:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:22:13 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 0x104df3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e88618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0914ba250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d57d30>, '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 0x104abcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d4fb20>, '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.eL4rUgHkee/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.J8U3lE652J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:22:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:22:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:22:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eL4rUgHkee/Render', 16:22:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:22:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:22:13 INFO - 'HOME': '/Users/cltbld', 16:22:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:22:13 INFO - 'LOGNAME': 'cltbld', 16:22:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:22:13 INFO - 'MOZ_NO_REMOTE': '1', 16:22:13 INFO - 'NO_EM_RESTART': '1', 16:22:13 INFO - 'PAGER': '/bin/cat', 16:22:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:22:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:22:13 INFO - 'PWD': '/builds/slave/test', 16:22:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:22:13 INFO - 'SHELL': '/bin/bash', 16:22:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.J8U3lE652J/Listeners', 16:22:13 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 16:22:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:22:13 INFO - 'USER': 'cltbld', 16:22:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:22:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:22:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:22:13 INFO - 'XPC_FLAGS': '0x0', 16:22:13 INFO - 'XPC_SERVICE_NAME': '0', 16:22:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:22:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:22: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 16:22:14 INFO - Cleaning up... 16:22:14 INFO - Return code: 0 16:22:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:22: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')]} 16:22:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:22:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:22: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')]} 16:22:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:22:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:22: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 0x104df3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e88618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0914ba250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d57d30>, '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 0x104abcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d4fb20>, '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.eL4rUgHkee/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.J8U3lE652J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:22: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 16:22: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 16:22:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eL4rUgHkee/Render', 16:22:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:22:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:22:14 INFO - 'HOME': '/Users/cltbld', 16:22:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:22:14 INFO - 'LOGNAME': 'cltbld', 16:22:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:22:14 INFO - 'MOZ_NO_REMOTE': '1', 16:22:14 INFO - 'NO_EM_RESTART': '1', 16:22:14 INFO - 'PAGER': '/bin/cat', 16:22:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:22:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:22:14 INFO - 'PWD': '/builds/slave/test', 16:22:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:22:14 INFO - 'SHELL': '/bin/bash', 16:22:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.J8U3lE652J/Listeners', 16:22:14 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 16:22:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:22:14 INFO - 'USER': 'cltbld', 16:22:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:22:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:22:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:22:14 INFO - 'XPC_FLAGS': '0x0', 16:22:14 INFO - 'XPC_SERVICE_NAME': '0', 16:22:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:22:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:22:14 INFO - Downloading/unpacking psutil>=3.1.1 16:22:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:22:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:22:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:22:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:22:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:22:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:22:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:22:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:22:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:22:17 INFO - Installing collected packages: psutil 16:22:17 INFO - Running setup.py install for psutil 16:22:17 INFO - building 'psutil._psutil_osx' extension 16:22:17 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 16:22: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_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 16:22: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/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 16:22:18 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 16:22:18 INFO - building 'psutil._psutil_posix' extension 16:22:18 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 16:22:18 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:22:18 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:22:18 INFO - ^ 16:22:18 INFO - 1 warning generated. 16:22:18 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 16:22:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:22:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:22:18 INFO - Successfully installed psutil 16:22:18 INFO - Cleaning up... 16:22:19 INFO - Return code: 0 16:22:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:22: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')]} 16:22:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:22:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:22: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')]} 16:22:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:22:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:22: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 0x104df3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e88618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0914ba250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d57d30>, '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 0x104abcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d4fb20>, '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.eL4rUgHkee/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.J8U3lE652J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:22: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 16:22: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 16:22:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eL4rUgHkee/Render', 16:22:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:22:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:22:19 INFO - 'HOME': '/Users/cltbld', 16:22:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:22:19 INFO - 'LOGNAME': 'cltbld', 16:22:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:22:19 INFO - 'MOZ_NO_REMOTE': '1', 16:22:19 INFO - 'NO_EM_RESTART': '1', 16:22:19 INFO - 'PAGER': '/bin/cat', 16:22:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:22:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:22:19 INFO - 'PWD': '/builds/slave/test', 16:22:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:22:19 INFO - 'SHELL': '/bin/bash', 16:22:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.J8U3lE652J/Listeners', 16:22:19 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 16:22:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:22:19 INFO - 'USER': 'cltbld', 16:22:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:22:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:22:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:22:19 INFO - 'XPC_FLAGS': '0x0', 16:22:19 INFO - 'XPC_SERVICE_NAME': '0', 16:22:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:22:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:22:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:22:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:22:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:22:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:22:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:22:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:22:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:22:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:22:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:22:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:22:21 INFO - Installing collected packages: mozsystemmonitor 16:22:21 INFO - Running setup.py install for mozsystemmonitor 16:22:21 INFO - Successfully installed mozsystemmonitor 16:22:21 INFO - Cleaning up... 16:22:22 INFO - Return code: 0 16:22:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:22: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')]} 16:22:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:22:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:22: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')]} 16:22:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:22:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:22: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 0x104df3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e88618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0914ba250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d57d30>, '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 0x104abcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d4fb20>, '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.eL4rUgHkee/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.J8U3lE652J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:22: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 16:22: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 16:22:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eL4rUgHkee/Render', 16:22:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:22:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:22:22 INFO - 'HOME': '/Users/cltbld', 16:22:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:22:22 INFO - 'LOGNAME': 'cltbld', 16:22:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:22:22 INFO - 'MOZ_NO_REMOTE': '1', 16:22:22 INFO - 'NO_EM_RESTART': '1', 16:22:22 INFO - 'PAGER': '/bin/cat', 16:22:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:22:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:22:22 INFO - 'PWD': '/builds/slave/test', 16:22:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:22:22 INFO - 'SHELL': '/bin/bash', 16:22:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.J8U3lE652J/Listeners', 16:22:22 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 16:22:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:22:22 INFO - 'USER': 'cltbld', 16:22:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:22:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:22:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:22:22 INFO - 'XPC_FLAGS': '0x0', 16:22:22 INFO - 'XPC_SERVICE_NAME': '0', 16:22:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:22:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:22:22 INFO - Downloading/unpacking jsonschema==2.5.1 16:22: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 16:22: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 16:22: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 16:22: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 16:22: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 16:22: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 16:22:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:22:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:22: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 16:22: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 16:22: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 16:22: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 16:22: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 16:22: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 16:22:26 INFO - Downloading functools32-3.2.3-2.tar.gz 16:22:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:22:26 INFO - warning: no files found matching '*.txt' 16:22:26 INFO - no previously-included directories found matching 'build' 16:22:26 INFO - no previously-included directories found matching 'dist' 16:22:26 INFO - no previously-included directories found matching '.git*' 16:22:26 INFO - Installing collected packages: jsonschema, functools32 16:22:26 INFO - Running setup.py install for functools32 16:22:26 INFO - warning: no files found matching '*.txt' 16:22:26 INFO - no previously-included directories found matching 'build' 16:22:26 INFO - no previously-included directories found matching 'dist' 16:22:26 INFO - no previously-included directories found matching '.git*' 16:22:26 INFO - Successfully installed jsonschema functools32 16:22:26 INFO - Cleaning up... 16:22:26 INFO - Return code: 0 16:22:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:22: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')]} 16:22:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:22:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:22: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')]} 16:22:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:22:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:22: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 0x104df3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e88618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0914ba250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d57d30>, '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 0x104abcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d4fb20>, '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.eL4rUgHkee/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.J8U3lE652J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:22: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 16:22: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 16:22:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eL4rUgHkee/Render', 16:22:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:22:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:22:26 INFO - 'HOME': '/Users/cltbld', 16:22:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:22:26 INFO - 'LOGNAME': 'cltbld', 16:22:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:22:26 INFO - 'MOZ_NO_REMOTE': '1', 16:22:26 INFO - 'NO_EM_RESTART': '1', 16:22:26 INFO - 'PAGER': '/bin/cat', 16:22:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:22:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:22:26 INFO - 'PWD': '/builds/slave/test', 16:22:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:22:26 INFO - 'SHELL': '/bin/bash', 16:22:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.J8U3lE652J/Listeners', 16:22:26 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 16:22:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:22:26 INFO - 'USER': 'cltbld', 16:22:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:22:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:22:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:22:26 INFO - 'XPC_FLAGS': '0x0', 16:22:26 INFO - 'XPC_SERVICE_NAME': '0', 16:22:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:22:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:22:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:22:26 INFO - Cleaning up... 16:22:26 INFO - Return code: 0 16:22:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:22: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')]} 16:22:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:22:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:22: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')]} 16:22:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:22:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:22: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 0x104df3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e88618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0914ba250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d57d30>, '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 0x104abcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d4fb20>, '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.eL4rUgHkee/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.J8U3lE652J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:22: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 16:22: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 16:22:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eL4rUgHkee/Render', 16:22:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:22:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:22:26 INFO - 'HOME': '/Users/cltbld', 16:22:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:22:26 INFO - 'LOGNAME': 'cltbld', 16:22:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:22:26 INFO - 'MOZ_NO_REMOTE': '1', 16:22:26 INFO - 'NO_EM_RESTART': '1', 16:22:26 INFO - 'PAGER': '/bin/cat', 16:22:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:22:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:22:26 INFO - 'PWD': '/builds/slave/test', 16:22:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:22:26 INFO - 'SHELL': '/bin/bash', 16:22:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.J8U3lE652J/Listeners', 16:22:26 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 16:22:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:22:26 INFO - 'USER': 'cltbld', 16:22:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:22:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:22:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:22:26 INFO - 'XPC_FLAGS': '0x0', 16:22:26 INFO - 'XPC_SERVICE_NAME': '0', 16:22:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:22:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:22:26 INFO - Downloading/unpacking blobuploader==1.2.4 16:22: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 16:22: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 16:22: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 16:22: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 16:22: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 16:22: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 16:22:29 INFO - Downloading blobuploader-1.2.4.tar.gz 16:22:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:22:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:22: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 16:22: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 16:22: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 16:22: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 16:22: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 16:22: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 16:22:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:22:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:22:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:22:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:22:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:22:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:22:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:22:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:22:30 INFO - Downloading docopt-0.6.1.tar.gz 16:22:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:22:30 INFO - Installing collected packages: blobuploader, requests, docopt 16:22:30 INFO - Running setup.py install for blobuploader 16:22:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:22:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:22:30 INFO - Running setup.py install for requests 16:22:31 INFO - Running setup.py install for docopt 16:22:31 INFO - Successfully installed blobuploader requests docopt 16:22:31 INFO - Cleaning up... 16:22:31 INFO - Return code: 0 16:22:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:22: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')]} 16:22:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:22:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:22: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')]} 16:22:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:22:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:22: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 0x104df3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e88618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0914ba250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d57d30>, '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 0x104abcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d4fb20>, '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.eL4rUgHkee/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.J8U3lE652J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:22: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 16:22: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 16:22:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eL4rUgHkee/Render', 16:22:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:22:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:22:31 INFO - 'HOME': '/Users/cltbld', 16:22:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:22:31 INFO - 'LOGNAME': 'cltbld', 16:22:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:22:31 INFO - 'MOZ_NO_REMOTE': '1', 16:22:31 INFO - 'NO_EM_RESTART': '1', 16:22:31 INFO - 'PAGER': '/bin/cat', 16:22:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:22:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:22:31 INFO - 'PWD': '/builds/slave/test', 16:22:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:22:31 INFO - 'SHELL': '/bin/bash', 16:22:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.J8U3lE652J/Listeners', 16:22:31 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 16:22:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:22:31 INFO - 'USER': 'cltbld', 16:22:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:22:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:22:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:22:31 INFO - 'XPC_FLAGS': '0x0', 16:22:31 INFO - 'XPC_SERVICE_NAME': '0', 16:22:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:22:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:22:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:22:31 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-DtIKaC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:22:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:22:31 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-cWjJaE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:22:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:22:31 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-Bt9G7d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:22:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:22:32 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-ZqosKs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:22:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:22:32 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-YmGrcs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:22:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:22:32 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-nD5f_z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:22:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:22:32 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-JIqGCT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:22:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:22:32 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-1d_qxV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:22:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:22:32 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-gMUl5K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:22:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:22:32 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-W1ON6B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:22:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:22:32 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-ICdgDb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:22:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:22:32 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-g2hheC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:22:33 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-Z8dQZk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:22:33 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-BEiH2X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:22:33 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-aVnMh3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:22:33 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-jZlXDk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:22:33 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-AX9Grj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:22:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:22:33 INFO - Running setup.py install for manifestparser 16:22:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:22:33 INFO - Running setup.py install for mozcrash 16:22:33 INFO - Running setup.py install for mozdebug 16:22:33 INFO - Running setup.py install for mozdevice 16:22:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:22:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:22:34 INFO - Running setup.py install for mozfile 16:22:34 INFO - Running setup.py install for mozhttpd 16:22:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:22:34 INFO - Running setup.py install for mozinfo 16:22:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:22:34 INFO - Running setup.py install for mozInstall 16:22:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:22:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:22:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:22:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:22:34 INFO - Running setup.py install for mozleak 16:22:34 INFO - Running setup.py install for mozlog 16:22:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:22:35 INFO - Running setup.py install for moznetwork 16:22:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:22:35 INFO - Running setup.py install for mozprocess 16:22:35 INFO - Running setup.py install for mozprofile 16:22:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:22:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:22:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:22:35 INFO - Running setup.py install for mozrunner 16:22:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:22:35 INFO - Running setup.py install for mozscreenshot 16:22:35 INFO - Running setup.py install for moztest 16:22:35 INFO - Running setup.py install for mozversion 16:22:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:22:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:22:36 INFO - Cleaning up... 16:22:36 INFO - Return code: 0 16:22:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:22:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:22:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:22:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:22:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:22:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:22:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:22:36 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 0x104df3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e88618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0914ba250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d57d30>, '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 0x104abcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d4fb20>, '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.eL4rUgHkee/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.J8U3lE652J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:22:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:22:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:22:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eL4rUgHkee/Render', 16:22:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:22:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:22:36 INFO - 'HOME': '/Users/cltbld', 16:22:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:22:36 INFO - 'LOGNAME': 'cltbld', 16:22:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:22:36 INFO - 'MOZ_NO_REMOTE': '1', 16:22:36 INFO - 'NO_EM_RESTART': '1', 16:22:36 INFO - 'PAGER': '/bin/cat', 16:22:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:22:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:22:36 INFO - 'PWD': '/builds/slave/test', 16:22:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:22:36 INFO - 'SHELL': '/bin/bash', 16:22:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.J8U3lE652J/Listeners', 16:22:36 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 16:22:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:22:36 INFO - 'USER': 'cltbld', 16:22:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:22:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:22:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:22:36 INFO - 'XPC_FLAGS': '0x0', 16:22:36 INFO - 'XPC_SERVICE_NAME': '0', 16:22:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:22:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:22:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:22:36 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-b2qMb7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:22: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)) 16:22:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:22:36 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-C5eX0h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:22: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)) 16:22:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:22:36 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-cdAWv2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:22: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)) 16:22:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:22:36 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-Xwj0EK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:22: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)) 16:22:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:22:36 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-xvgivE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:22: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)) 16:22:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:22:36 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-9TBsJZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:22:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:22:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:22:37 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-lPJLVK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:22:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:22:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:22:37 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-jnpAzp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:22: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)) 16:22:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:22:37 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-ZwwDCD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:22: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)) 16:22:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:22:37 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-SAY141-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:22: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)) 16:22:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:22:37 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-EjIRRz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:22: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)) 16:22:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:22:37 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-WZowOE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:22: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)) 16:22:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:22:37 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-A0mJmI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:22: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)) 16:22:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:22:37 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-X5vjKa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:22: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)) 16:22:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:22:37 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-uE9U94-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:22: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)) 16:22:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:22:37 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-UTRJyA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:22:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:22:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:22:38 INFO - Running setup.py (path:/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/pip-CcpQ8c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:22: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)) 16:22: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)) 16:22: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)) 16:22: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)) 16:22: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)) 16:22: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)) 16:22:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:22: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 16:22: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 16:22: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 16:22: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 16:22: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 16:22: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 16:22:40 INFO - Downloading blessings-1.6.tar.gz 16:22:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:22:41 INFO - Installing collected packages: blessings 16:22:41 INFO - Running setup.py install for blessings 16:22:41 INFO - Successfully installed blessings 16:22:41 INFO - Cleaning up... 16:22:41 INFO - Return code: 0 16:22:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:22:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:22:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:22:41 INFO - Reading from file tmpfile_stdout 16:22:41 INFO - Current package versions: 16:22:41 INFO - blessings == 1.6 16:22:41 INFO - blobuploader == 1.2.4 16:22:41 INFO - docopt == 0.6.1 16:22:41 INFO - functools32 == 3.2.3-2 16:22:41 INFO - jsonschema == 2.5.1 16:22:41 INFO - manifestparser == 1.1 16:22:41 INFO - mozInstall == 1.12 16:22:41 INFO - mozcrash == 0.17 16:22:41 INFO - mozdebug == 0.1 16:22:41 INFO - mozdevice == 0.48 16:22:41 INFO - mozfile == 1.2 16:22:41 INFO - mozhttpd == 0.7 16:22:41 INFO - mozinfo == 0.9 16:22:41 INFO - mozleak == 0.1 16:22:41 INFO - mozlog == 3.3 16:22:41 INFO - moznetwork == 0.27 16:22:41 INFO - mozprocess == 0.23 16:22:41 INFO - mozprofile == 0.28 16:22:41 INFO - mozrunner == 6.12 16:22:41 INFO - mozscreenshot == 0.1 16:22:41 INFO - mozsystemmonitor == 0.3 16:22:41 INFO - moztest == 0.7 16:22:41 INFO - mozversion == 1.4 16:22:41 INFO - psutil == 3.1.1 16:22:41 INFO - requests == 1.2.3 16:22:41 INFO - wsgiref == 0.1.2 16:22:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:22: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')]} 16:22:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:22:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:22: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')]} 16:22:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:22:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:22: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 0x104df3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e88618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0914ba250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d57d30>, '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 0x104abcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d4fb20>, '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.eL4rUgHkee/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.J8U3lE652J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:22: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 16:22: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 16:22:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eL4rUgHkee/Render', 16:22:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:22:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:22:41 INFO - 'HOME': '/Users/cltbld', 16:22:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:22:41 INFO - 'LOGNAME': 'cltbld', 16:22:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:22:41 INFO - 'MOZ_NO_REMOTE': '1', 16:22:41 INFO - 'NO_EM_RESTART': '1', 16:22:41 INFO - 'PAGER': '/bin/cat', 16:22:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:22:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:22:41 INFO - 'PWD': '/builds/slave/test', 16:22:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:22:41 INFO - 'SHELL': '/bin/bash', 16:22:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.J8U3lE652J/Listeners', 16:22:41 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 16:22:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:22:41 INFO - 'USER': 'cltbld', 16:22:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:22:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:22:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:22:41 INFO - 'XPC_FLAGS': '0x0', 16:22:41 INFO - 'XPC_SERVICE_NAME': '0', 16:22:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:22:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:22:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:22:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 16:22:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 16:22:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:22:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 16:22:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 16:22:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 16:22:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 16:22:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 16:22:42 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:22:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:22:42 INFO - Cleaning up... 16:22:42 INFO - Return code: 0 16:22:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:22:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:22:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:22:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:22:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:22:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:22:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:22:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104df3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e88618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0914ba250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d57d30>, '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 0x104abcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d4fb20>, '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.eL4rUgHkee/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.J8U3lE652J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:22:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:22:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:22:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eL4rUgHkee/Render', 16:22:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:22:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:22:42 INFO - 'HOME': '/Users/cltbld', 16:22:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:22:42 INFO - 'LOGNAME': 'cltbld', 16:22:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:22:42 INFO - 'MOZ_NO_REMOTE': '1', 16:22:42 INFO - 'NO_EM_RESTART': '1', 16:22:42 INFO - 'PAGER': '/bin/cat', 16:22:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:22:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:22:42 INFO - 'PWD': '/builds/slave/test', 16:22:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:22:42 INFO - 'SHELL': '/bin/bash', 16:22:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.J8U3lE652J/Listeners', 16:22:42 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 16:22:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:22:42 INFO - 'USER': 'cltbld', 16:22:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:22:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:22:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:22:42 INFO - 'XPC_FLAGS': '0x0', 16:22:42 INFO - 'XPC_SERVICE_NAME': '0', 16:22:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:22:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:22:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:22:42 INFO - Cleaning up... 16:22:42 INFO - Return code: 0 16:22:42 INFO - Running post-action listener: _resource_record_post_action 16:22:42 INFO - Running post-action listener: _start_resource_monitoring 16:22:42 INFO - Starting resource monitoring. 16:22:42 INFO - [mozharness: 2016-08-22 23:22:42.486543Z] Finished create-virtualenv step (success) 16:22:42 INFO - [mozharness: 2016-08-22 23:22:42.487482Z] Running install step. 16:22:42 INFO - Running pre-action listener: _resource_record_pre_action 16:22:42 INFO - Running main action method: install 16:22:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:22:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:22:42 INFO - Reading from file tmpfile_stdout 16:22:42 INFO - Detecting whether we're running mozinstall >=1.0... 16:22:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:22:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:22:42 INFO - Reading from file tmpfile_stdout 16:22:42 INFO - Output received: 16:22:42 INFO - Usage: mozinstall [options] installer 16:22:42 INFO - Options: 16:22:42 INFO - -h, --help show this help message and exit 16:22:42 INFO - -d DEST, --destination=DEST 16:22:42 INFO - Directory to install application into. [default: 16:22:42 INFO - "/builds/slave/test"] 16:22:42 INFO - --app=APP Application being installed. [default: firefox] 16:22:42 INFO - mkdir: /builds/slave/test/build/application 16:22: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'] 16:22: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 16:23:18 INFO - Reading from file tmpfile_stdout 16:23:18 INFO - Output received: 16:23:18 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:23:18 INFO - Running post-action listener: _resource_record_post_action 16:23:18 INFO - [mozharness: 2016-08-22 23:23:18.315416Z] Finished install step (success) 16:23:18 INFO - [mozharness: 2016-08-22 23:23:18.315547Z] Running run-tests step. 16:23:18 INFO - Running pre-action listener: _resource_record_pre_action 16:23:18 INFO - Running main action method: run_tests 16:23:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:23:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:23:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:23:18 INFO - 2016-08-22 16:23:18.330 screenresolution[1636:7443] starting screenresolution argv=screenresolution get 16:23:18 INFO - 2016-08-22 16:23:18.332 screenresolution[1636:7443] Display 0: 1600x1200x32@60 16:23:18 INFO - 2016-08-22 16:23:18.340 screenresolution[1637:7445] starting screenresolution argv=screenresolution list 16:23:18 INFO - Available Modes on Display 0 16:23:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:23:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:23:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:23:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:23:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:23:18 INFO - Intel Iris: 16:23:18 INFO - Chipset Model: Intel Iris 16:23:18 INFO - Type: GPU 16:23:18 INFO - Bus: Built-In 16:23:18 INFO - VRAM (Dynamic, Max): 1536 MB 16:23:18 INFO - Vendor: Intel (0x8086) 16:23:18 INFO - Device ID: 0x0a2e 16:23:18 INFO - Revision ID: 0x0009 16:23:18 INFO - Displays: 16:23:18 INFO - Display: 16:23:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:23:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:23:18 INFO - Display Serial Number: 1600x1200 60 16:23:18 INFO - Main Display: Yes 16:23:18 INFO - Mirror: Off 16:23:18 INFO - Online: Yes 16:23:18 INFO - Rotation: Supported 16:23:18 INFO - Return code: 0 16:23:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:23:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:23:18 INFO - Python 2.7.3 16:23:18 INFO - Return code: 0 16:23:18 INFO - grabbing minidump binary from tooltool 16:23:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:23:18 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 0x104d57d30>, '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 0x104abcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104d4fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:23:18 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 16:23:18 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 16:23:18 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 16:23:18 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:23:18 INFO - Return code: 0 16:23:18 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:23:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:23:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:23:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:23:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:23:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Zjxa5YVHSvCe0KZHE-EQ5w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0312', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 16:23:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Zjxa5YVHSvCe0KZHE-EQ5w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0312 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 16:23:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eL4rUgHkee/Render', 16:23:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:23:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:23:18 INFO - 'HOME': '/Users/cltbld', 16:23:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:23:18 INFO - 'LOGNAME': 'cltbld', 16:23:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:23:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:23:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:23:18 INFO - 'MOZ_NO_REMOTE': '1', 16:23:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:23:18 INFO - 'NO_EM_RESTART': '1', 16:23:18 INFO - 'PAGER': '/bin/cat', 16:23:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:23:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:23:18 INFO - 'PWD': '/builds/slave/test', 16:23:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:23:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:23:18 INFO - 'SHELL': '/bin/bash', 16:23:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.J8U3lE652J/Listeners', 16:23:18 INFO - 'TMPDIR': '/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/', 16:23:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:23:18 INFO - 'USER': 'cltbld', 16:23:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:23:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:23:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:23:18 INFO - 'XPC_FLAGS': '0x0', 16:23:18 INFO - 'XPC_SERVICE_NAME': '0', 16:23:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:23:18 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Zjxa5YVHSvCe0KZHE-EQ5w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0312', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:23:18 INFO - mozversion application_buildid: 20160822114323 16:23:18 INFO - mozversion application_changeset: 4138eb4850bdccab04bca921966d715d31da43ee 16:23:18 INFO - mozversion application_display_name: Nightly 16:23:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:23:18 INFO - mozversion application_name: Firefox 16:23:18 INFO - mozversion application_remotingname: firefox 16:23:18 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 16:23:18 INFO - mozversion application_vendor: Mozilla 16:23:18 INFO - mozversion application_version: 51.0a1 16:23:18 INFO - mozversion platform_buildid: 20160822114323 16:23:18 INFO - mozversion platform_changeset: 4138eb4850bdccab04bca921966d715d31da43ee 16:23:18 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 16:23:18 INFO - mozversion platform_version: 51.0a1 16:23:18 INFO - using testdate: 1471908198 16:23:18 INFO - actual date: 1471908198 16:23:18 INFO - starting webserver on 'localhost:49263' 16:23:18 INFO - SUITE-START | Running 2 tests 16:23:18 INFO - TEST-START | dromaeo_css 16:23:18 INFO - operating with platform_type : mac_ 16:23:18 INFO - Initialising browser for dromaeo_css test... 16:23:18 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/tmpXqsSFQ/profile http://localhost:49263/getInfo.html) 16:23:21 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 16:23:21 INFO - PROCESS | 1647 | colorDepth:24 16:23:21 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 16:23:21 INFO - PROCESS | 1647 | __metrics 16:23:21 INFO - PROCESS | 1647 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:23:21 INFO - PROCESS | 1647 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:23:22 INFO - TEST-INFO | 1647: exit 0 16:23:22 INFO - Browser initialized. 16:23:22 INFO - Running cycle 1/1 for dromaeo_css test... 16:23:22 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/tmpXqsSFQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:23:34 INFO - PROCESS | 1649 | RSS: Main: 231559168 16:23:34 INFO - PROCESS | 1649 | 16:24:25 INFO - PROCESS | 1649 | 1471908265170 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 16:26:37 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49263/tests/dromaeo/cssquery-ext.html) 16:26:39 INFO - PROCESS | 1649 | RSS: Main: 376233984 16:26:39 INFO - PROCESS | 1649 | 16:29:42 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-ext.html (next: http://localhost:49263/tests/dromaeo/cssquery-jquery.html) 16:29:42 INFO - PROCESS | 1649 | RSS: Main: 373854208 16:29:42 INFO - PROCESS | 1649 | 16:32:45 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49263/tests/dromaeo/cssquery-mootools.html) 16:32:45 INFO - PROCESS | 1649 | RSS: Main: 367476736 16:32:45 INFO - PROCESS | 1649 | 16:35:48 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49263/tests/dromaeo/cssquery-prototype.html) 16:35:48 INFO - PROCESS | 1649 | RSS: Main: 344576000 16:35:48 INFO - PROCESS | 1649 | 16:38:52 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49263/tests/dromaeo/cssquery-yui.html) 16:38:52 INFO - PROCESS | 1649 | RSS: Main: 331022336 16:38:52 INFO - PROCESS | 1649 | 16:41:56 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-yui.html (next: http://localhost:49263/tests/dromaeo/cssquery-dojo.html) 16:41:56 INFO - PROCESS | 1649 | RSS: Main: 401739776 16:41:56 INFO - PROCESS | 1649 | 16:41:56 INFO - PROCESS | 1649 | __start_tp_report 16:41:56 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 16:41:56 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 16:41:56 INFO - PROCESS | 1649 | |i|pagename|runs| 16:41:56 INFO - PROCESS | 1649 | |0;dojo.html;2923.9415211695764;3353.8323083845803;3361.9495707564374;3366.966330336696;3398.929337258763;5532.889342213153;5614.1298098794705;5621.988042152411;5673.716314184295;5709.229254050707;5602.299712535933;5655.660660360381;5732.7133643317875;5747.396523365049;5781.4317304860415;5131.127708289581;5136.717480538568;5212.139996900526;5237.476252374751;5242.7640756166065;5324.866878328054;5334.893302133955;5339.519443250107;5345;5357.223202635615;4321.459817522809;5023.372078490189;5111.591072714174;5133.230015497668;5210.192420174879;5077.111505486525;5121.513456221653;5174.896502069938;5191.480851914778;5202.765875535572;5113.130767769489;5139.665921715076;5141.280220769096;5199.428062913076;5212.895742085175;5693.658380497183;5704.087346024658;5723.084306510938;5727.513161381287;5736.74184661687;6031.276246850362;6078.209832721762;6150.538709596798;6157.876842463171;6229.563930524865;4632.675712700112;6201.069839524062;6209.596376235575;6224.097505861625;6225.595336303171;5609.80365687199;5811.82564523065;5832.416758324133;5843.2695913010875;5845.678487683218;6145.231846019247;6186.4741496972365;6204.100405441186;6214.1921550198185;6265.65538895356;6066.363031881677;6125.387461253839;6133.570650054453;6135.233095863017;6138.1099740537375;5419.430959749169;6199.845003874939;6218.9378106219265;6219.40915613016;6223.751049958041;5985;6057.273127224761;6089.51283897296;6133.3866613338305;6160.907586386208;6141.723622436912;6209.099680546386;6210.037444196157;6213.471854892385;6235.282942461675;4919.0407870464915;4937.506249375034;4938.987507560942;4941.382327209099;4946;4314.870553883388;4356.063446358985;4409.228385032607;4410.3825464435;4432.645388368923;5025.522575355335;5664.575156863251;5675;5678.517326027251;5750.88498230033;6186.226721659793;6191.4118158774845;6200.3179650238435;6201.162843016225;6213.968930155411;5812.128180772834;5823.5341172706085;5825.941740582624;5828.242328497353;5830.854228644318;5237.16205407133;5306.6550674206055;5310.230016647564;5389.218563308298;5430.70131142791;6349.301576826638;6350.428461438492;6359.6184228945485;6364.936350636527;6366.5938722783085;6186.979148440637;6190.350013248544;6198.1012753149635;6206.348333424925;6219.844503887439;6124.663143527148;6259.061140828912;6279.058141278845;6281.59169653971;6288.465480434214;6157.599756015845;6177.320494745484;6206.162168107247;6210.875782484235;6217.098520714561;5289.338832645919;6166.321704612579;6178.135061091541;6181.876362472635;6191.690415479118;5929.495992840484;5944.078667806497;6019.668918209541;6061.3029501606115;6127.387261273837;6002.249718785152;6088;6100.389961003864;6164.815055548341;6166.1983942087245;6015.608985416109;6022.307434644897;6023.608465449732;6025.849353766191;6034.517238620988;5538.670719027485;6005.1892994446935;6108.541859360477;6116.113163591343;6161.270018882482;5767.452092051249;5856;5911.881762364643;5929.703514824155;6021.518278537794 16:41:56 INFO - PROCESS | 1649 | |1;ext.html;469.75337947577515;493.750655918761;529.713954464589;535.6009772719325;539.6168720208651;30707;40224.396634050514;41384.79307603458;41391.58608413915;43737.90655233613;43348;43669.781651091704;43701.7814910925;43731;43778.78110609442;9720.125188732995;9881.456519891402;9883.962183970687;9885.406875587452;9888.258380621483;8840;9077.909220907772;11951.701207469796;12214.816777748341;12263.080268979818;12241.93879030608;12311.199772014836;12311.261324320569;12315.5073797048;12332.568360107398;12206.206596571194;12290.016798656086;12292.201006934522;12298.139130260884;12349.93825030878;10394.16846652266;11808.763824723543;11814.763704725858;12085.879141208563;12113.939430302793;40820.38769418431;41056.38415423771;41184.97037574085;41216;41415.79292103551;3666.578343490506;3996.4205190247544;4059.472268605092;4105.220008198433;4204.579542045802;32384.514232286536;32394.838025809957;32473.675263247536;32486.512702309483;32674.509882351547;3221.4201443740117;3330.150811543065;3380.7802492837886;3435.055359776057;3439.2949445363643;509437;510362;511616.65150045557;511636.41817908955;522882;60440.69779651162;60673;60694.393056069755;60749.696251519345;60865.69567152136;63060.738785224035;63227.051594226126;63354.68322658449;63399.68300158469;63399.732005359634;16260;16509.752353714703;16520.6695866082;16523.586910327336;16545.586360341087;11344.092472602337;11615.245009074382;12661.873381266254;12662.873371266354;12703.174293670882;14609.488667896785;16434.83565164333;16839.494815155565;16847.83152168487;16998.065106419264;6534.509911756637;6554.934450655433;6593.109930159463;6626.966865165739;6631.6841579210395;778.7780482562442;917.4128557723062;1003.4631472162358;1046.3460337289193;1080.2492267873909;29989.70010299869;30190.84904575507;30458.086257412313;31224.219394514865;31266.843665781525;934.4206591913057;984.7144328144901;1050.5850189175233;1071.6410002649045;1079.8056349856945;15093.849061509463;15783.210839457981;15819.288132034086;15843.603909902115;16247.431339903047;647728;654655;654781;655622;858166;57957.420425796045;58939.41060589425;59463.40536594665;62902.68548657227;63167.36832631615;1352.2427440633278;1385.5843247025853;1403.1651167555287;1409.584172669065;1409.7885317202501;30732.385352292382;31912.52131217987;32039.199020024687;32390.838045810564;32539.18652033718;51985;52467.47532524626;52532.737336313076;52588.73705631447;52633.7368313156;582775;582943;583561;584749;587036;37639.87080387593;39709.80145099256;40369.39445908255;40876;40951.59048409478;47772.28341574949;47893;49051.754741227494;49122.75438622784;49247.26129108138;38380.80809595934;39510.2097958045;39849.2030159401;40780;41355.96610084772;38955.610443895195;39636;40198.79900600478;40435.3934690974;40637.59362406338;37936.24127517489;38297;38899.41650875183;39143.80428097955;39237.607623923395;32102.374775494474;34662.480062798575;35034.474482883285;35501;36077.63922360848;25841.095561655253;25927.092551760597;25931;26279.868600656875;26635.067772627866 16:41:56 INFO - PROCESS | 1649 | |2;jquery.html;318.96650851660576;348.72799216611054;364.8868850656298;375.6844250829304;400.1077596958782;15838.366465341387;17769;20647.793522064774;21323.040463179157;21403.35789926303;19880.602387952233;20495.07772150253;21726;21895.343139705794;22161.66757498639;5457.754401051942;5615.410381909902;5632.183333416652;5811.186433899257;5817.040188368914;5630.380658127603;5694.629849059818;5726.856828579277;5738.885222295572;5743.994800909845;3988.162485877969;5974.372690867462;6214.384260092386;6269.310375858652;6334.6199228046235;6028.336882942873;6266.717997690115;6272.341404152567;6290.62256264621;6293.9370606293805;6112.235970503687;6127.417895299939;6132.356102609251;6152.784652537184;6162.8767424651705;13623.523176688868;13865.09876858001;22839.31482055541;24406.755932440625;25517;1872.4008317338457;2164.469704922288;2269.3305474884946;2398.304491697406;2917.978707452396;9826.16477599405;10198.388096714147;10467.953204679547;11685.824712629317;15833.287502062434;1673.3306677329044;1820.7724034495689;1835.174171622775;1860.3302810988034;1865.7947625761155;119812.40093799563;134068;135547;137389;138154.30922845422;11190.720231994264;11362.772744545064;11437;11580.710482238012;11668.76662466746;14620.268986550629;14627.19550424737;14651;14658.926705366404;14665.266736663125;8427.578621068922;8579.227869491775;8594.312455003585;8614.483131012159;8722.694705685271;8467.137229809432;8474.88127968008;8482.618282177316;8643.870341944876;8657.52383618907;4531.524189960007;4539.0921815637;4556;4576.176288268143;4650.837220697327;1867.2997625890291;1922.2407149175997;1922.2983610982171;1922.7596550431197;1927.8264956154014;836.5315423362936;885.4130436942783;918.6095909238547;982.4203719805206;985.989360905078;20613.896930515253;21336.466588335417;21383.786162138178;21393.25123620666;21467.892660536596;813.3086876155221;916.3585490156917;943.4716558727134;950.1591091883732;1023.3041531758327;10863.891361086446;11184.552617895193;11286.774264514665;11289.887101129048;11290.943545282222;587403.1966963132;593543;595568;611737;613691;69989;70478.64760676163;92357;111014.44492777485;111909;962.8459446488528;982.111189373618;1002.3935519011039;1005.4319309590059;1005.8088963096989;13067.934660326637;18759.623267811923;18918;19156.616867662848;19311.806881931;54859.45140548544;54958.45041549533;55149.72425137849;55378;56041.15938261011;76660;78042;79184.60407697925;79220;79993.60003199946;8009;8099.190080991853;8234.547099909323;8314.62584183701;8326.625301861552;11794.823077654013;12955.611331660066;12992.545260915822;13307.800382994457;13353.332333383098;10894.945525272324;11182;11227.21409501327;11349.829752553553;11427.828582571101;10721.731956701145;10900.454977250947;10915.072218861485;11062.059724923472;11389.715257118638;11320.37737924422;11523.769524609628;11776.116791240862;11807.586734464252;11838.171328006956;8034.437589368922;8131.83736325282;8146.981627296588;8215;8235;9576.712698619052;10906.890931090587;11161.944190278997;11462.541498340306;11509.136814739095 16:41:56 INFO - PROCESS | 1649 | |3;mootools.html;1245.9875401245986;1379.3379177994561;1478.556433070079;1637.4841924793689;1852.6757817381963;7803.648835802388;8278.75163745087;8281.461704989171;8283.75148745537;8301.377396695249;8472.237498625123;8734.301255899529;8782.692605758799;8939.418937769056;8974;2223.110755697726;2377.6433534969765;2414.9396265093424;2416.8429052111555;2416.8429052111646;2289.5649826532904;2373.5727569037567;2418.2503423938633;2421.9757802422014;2427.5873101572774;2383.7377888432256;2409.0484258717797;2420.8910599022997;2421.1646981791255;2421.7699318564796;2236.552689462106;2241.1035585765662;2247.820174386045;2250.797428231459;2346.835721499496;2343.9648405273933;2365.2194775724142;2367.6566897799894;2376.7266764322153;2377.215518878766;8850.336224783103;8910;8964.686235981775;8967.372283940127;8973.910260897374;5238.188080847474;5285.048691235576;5448.210009548633;5451;5451.836444906659;3656.2687462507606;3730.8321125549146;3735.682466990309;3761.2101458693705;3762.3415902217002;1085.6525911708256;1101.509828126484;1118.6364431559775;1123.5000424810962;1133.1501373969522;11756.412179390996;11853.933146016814;11870.52517899283;11876.52493900243;11899.881001189964;4316.7409955402145;4631.467381251132;4672.95327046732;4674.813007479731;4681.32120842476;9341.439513629204;9346.672866449642;9347.252219822398;9348.252139828797;9377.578008989612;5305.283786688825;5315.28243687105;5316.441773613715;5316.627836051517;5317.54800841925;3970.4441378207075;4388.934165987513;4920.384951881015;4983.8006479741125;5027.1705168647495;3817.255635151119;3848.037990502374;3857.209272099269;3933.8426462941734;3935.370340745467;1038.3665963762098;1161.047940688644;1180.6162997026001;1186.9465874035689;1203.6629743671785;855.3072011670566;857.5840717252128;859.5100792548187;887.7070566712969;970.6020531582159;5890.676012819336;6071.423214794588;6129.08063790435;6132.754689812357;6202.224721909762;920.1810388754014;967.3180407812475;986.0878687214441;987.9456629885425;998.8501724741346;3844.7308688392104;4063.3701776224734;4079.3880917862557;4102.2410853992105;4183.163367326547;9320.580573874191;10087.949560252298;10468.371897686164;10634;11032.889671103347;10753.516091775733;10767.569297228023;10770.353778773298;10787.298825576469;10798.406087665255;7396.112466504054;8092.433529652985;8115.837683246302;8387.66449342044;8400.495970241805;5891.086881533369;5955.940440595538;5991.940080599138;6142.57002009855;6192.287886892885;10099.495025248854;10153.79692406133;10191.286609937233;10214.438205898747;10231.283810133516;9299.349045567016;9600;9805.2646051545;10174.796504069729;10190.592376304865;7411.444141689287;7561.735339263099;7629.961850190713;7635.23647635232;7712.614369281626;8237;8491.065982742017;8557.31541476668;8732.388732788644;8755.868661969947;7540.396768258636;7613.923860761543;7699.769006929801;7741.690332386866;7794.610269486617;7500.62496875165;7528.811779705551;7538.660760265691;7541.660625271767;7586.696532138652;7087.503874728925;7096.112985876765;7166.928330716626;7172.820679483055;7173.390261827803;5097.413797413345;5108.055009823194;5131.846044618667;5175.792968281377;5232.895342093213;5101.260317254051;5113.565346945403;5128.076946149728;5240.109181439088;5377.193420986883 16:41:56 INFO - PROCESS | 1649 | |4;prototype.html;292.4866858663046;299.57759559021775;308.1387521876356;309.1190108191654;310.62414478481037;4467.7542735149555;4838.419389673235;5482.396936337;5537.640053396532;5551.972240138799;4494.842680506183;4554.339620754997;5020.849374518771;5113.616478764098;5350.946490535093;3702.48231202782;3932.803359832011;4060.4518390017265;4077;4104.384342348641;3316.303576248991;4166.583361657036;4184.121334519755;4184.937225941613;4203.7057405981595;3391.86432542698;3453.3611281912927;3769.321522126016;4108.938365924513;4150.398192262121;4119.711620186588;4137.117611486767;4153.335466325373;4173.290540608104;4202.285611446062;3573.446115852047;3597.4244120940525;3598.992282160999;3605.7295702822394;3610.7833529988284;4325.494185466675;4617.7691115444095;4926.98996705678;5288.629795914288;5314.468553144693;4678.742669153229;4747.477777444478;4767.713937163781;4768.213244814601;4787.8563643090765;5663.886722265573;5734.483896449298;5739.196512488255;5750;5763;3522.7005704515145;3948.368261078214;3974.642282194616;3982.920341593181;3987.3819557968563;4429.6899217054815;5101.066886540254;5456.372517160498;5820.825375238707;6021.2774467063355;4007.73949693269;4037.66756970205;4047.9070650924086;4075.673946084306;4096.856610018635;3479.3215323012278;3589.6769290763955;4092.447519584818;4161.3549899765585;4163.417121602978;4532.637389008872;4728.571785455586;4769.523047695272;4786.282057691375;4797.616190704735;4986.526280003394;5653.434656534314;5688.9715551422805;5693.5445164387575;5701.458361455655;1232.5624403336865;1321.722438287951;1369.4795977528674;1421.5380001499555;1424.3448013913508;891.5631340643168;904.6878826804742;917;924.2744445610164;927.2026057590467;4887.169181239198;5291.417944026154;5320.680759154463;5334.946650533445;5416.431274716177;4945.4807245238735;5331.3868905076415;5813.970930145407;5817.7382017809305;5822.650640961471;5023.673461225008;5268.78924843002;5346.652467589594;5436.401995780461;5460.781568737286;2718.823276487022;2796.2663148847437;3073.800202986799;3083.9691603083684;3085.4291955987765;5305.734713264322;5772.575518282331;5879.794207202727;6115;6125.754969801247;4878.731250062467;4935.259711043373;5834.94165058344;5957.76168953237;6074.787382441594;4539.72761634303;5865.442782935615;6113.388661134029;6129.1419201311855;6159.168512250879;4642.721436713673;5178.689278643294;5327.9733601331745;5825.883482330414;5881.353051164283;5776.220210271559;5780.248567686174;5787.768489260551;5810.331811841692;5840.2991641003355;5594.496495315441;6003.459688628044;6095.110515740134;6124.265088189446;6134.147621356091;3368.494725791053;3853.2871418787613;3945.171513982096;3946.5461471931094;3964.4053391991433;4707.976460117678;5783.479486846204;5997.13041608973;5997.400259973968;6133.509319254538;4829.4062959222665;5636.633618814764;5705.7147142643535;5841.795537156179;5857;4820.614350851994;5738.368779434176;5754.798582049608;5763.279590051244;5776.306843178845;4377.518472968035;5564.610477266552;5635.9436405635415;5651.773929042793;5665.830025099253;3772.5095737553943;5243.764030618708;5402.8649283766645;5519.9088510434285;5656.228448060338;5279;5520.779168833202;5531.612787104864;5532.3637781654015;5596.748146333343 16:41:56 INFO - PROCESS | 1649 | |5;yui.html;589.6756783768927;684.459277171035;754.3964828137492;767.1676231289055;816.4040250617046;14836.703265934675;15088.094714317149;15103.244837758095;15126.697466050673;15131.470398536054;14097.154170749736;14967.925160374183;15243.39026438941;15251.771223431657;15281.617959551017;829.2909562324202;837.5728378526965;840.1136800675285;840.4873027453249;841.5750046226677;665.9900101498481;687.8761822871882;830.7466222802043;854.2055888024136;862.4178679391417;906.6056265524485;910.199024858124;910.2354022620997;910.836049511091;911.6444586611194;901.0673952459206;901.7880798012462;902.9909700902973;903.0788595632483;903.7514683462035;514.5009340939283;642.5213216153995;664.0968283134932;665.70043480434;667.5594107888819;14828.332725027292;14886.70226595462;14898.553043408716;14906.403743850233;14909.95630305879;13773.517926872511;14084.788728169086;14133.222672752994;14171.149731016172;14408.567742967727;12738.74522509554;12761.361931903368;12796.424160912782;12971.351432428435;13001.296862779978;4524.276115821485;4646.372739680134;4652.813887444532;4685.601723853477;4772.73749943753;121170.43541676001;125010.37494812558;127824;130775;130993;10870.18473614482;11142.220044596801;11342.886571134182;11355.886441135648;11521.078313735048;21275.680864787042;21826.454338641663;21984.45038874009;21986.780132198794;22110.22614208495;7802.29779319864;7805.41459390548;7889.368850491946;8005.07941586725;8068;4346;4408.316710909814;4413.977930110329;4568.33759104928;4675.462321833033;3425.8972230833115;3578.5884623268657;3587.766795158306;3589.1924317028984;3595.94606080909;547.8003173051005;630.0549176235646;635.7997230872261;648.7015972652632;658.1707049118146;592.599995003371;593.9435753603401;682.4646438481067;686.8454597715574;690.6325475558423;8985.820283594423;9662.227021838236;9796.461194634221;9868.407895526292;9939.254555908336;605.5367643752533;610.8930937085992;648.3225029843825;683.1631251716608;692.2938602625294;4390.758508282075;4668.626509879269;4684.765761711901;4691.483936766953;4759.976200119047;90741;120839;127913.53383458602;152481;161390;135315;135510;136443;137028.31485842707;138903;112446;116370;117530.41234793772;119426.44588432905;123046.38476807559;9065.138811812867;9073.092690730875;9090.545472726206;9190.49452280131;9286.814263714823;53863.192052118466;57825.421745782005;58713.41286587079;60880.78238435295;65966.68066638736;84513;95597;98224.50887745516;99082;100429.99570004207;11895.286282823059;12000.819987700017;12049.51801927913;12091.153619246568;12258.938705306417;13600;13606.183628982293;13648;13674.31628418595;14231.64420889486;12452.128351015705;12487.687807804878;12723.80914286268;12877.35613219354;12877.935610321889;12212.273030607988;12655.557055503014;12662.620121396372;13045.347732613522;13050.477980880658;12230.394840302275;12322.260664360167;12327.506899724269;12441;12850.42173102194;10369.325993810378;10579.259451838298;10595.364278143028;10609.416482093251;10792.56829726802;10270.6405275815;10928.836067458835;10949.781004379709;10950.56197752081;10974.615888444185 16:41:56 INFO - PROCESS | 1649 | __end_tp_report 16:41:56 INFO - PROCESS | 1649 | __start_cc_report 16:41:56 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,1480 16:41:56 INFO - PROCESS | 1649 | __end_cc_report 16:41:56 INFO - PROCESS | 1649 | __startTimestamp1471909316451__endTimestamp 16:41:56 INFO - PROCESS | 1649 | 16:41:56 INFO - PROCESS | 1649 | ------- Summary: start ------- 16:41:56 INFO - PROCESS | 1649 | Number of tests: 6 16:41:56 INFO - PROCESS | 1649 | 16:41:56 INFO - PROCESS | 1649 | [#0] dojo.html Cycles:165 Average:5693.60 Median:5929.50 stddev:654.64 (11.0%) stddev-sans-first:619.54 16:41:56 INFO - PROCESS | 1649 | Values: 2923.9 3353.8 3361.9 3367.0 3398.9 5532.9 5614.1 5622.0 5673.7 5709.2 5602.3 5655.7 5732.7 5747.4 5781.4 5131.1 5136.7 5212.1 5237.5 5242.8 5324.9 5334.9 5339.5 5345.0 5357.2 4321.5 5023.4 5111.6 5133.2 5210.2 5077.1 5121.5 5174.9 5191.5 5202.8 5113.1 5139.7 5141.3 5199.4 5212.9 5693.7 5704.1 5723.1 5727.5 5736.7 6031.3 6078.2 6150.5 6157.9 6229.6 4632.7 6201.1 6209.6 6224.1 6225.6 5609.8 5811.8 5832.4 5843.3 5845.7 6145.2 6186.5 6204.1 6214.2 6265.7 6066.4 6125.4 6133.6 6135.2 6138.1 5419.4 6199.8 6218.9 6219.4 6223.8 5985.0 6057.3 6089.5 6133.4 6160.9 6141.7 6209.1 6210.0 6213.5 6235.3 4919.0 4937.5 4939.0 4941.4 4946.0 4314.9 4356.1 4409.2 4410.4 4432.6 5025.5 5664.6 5675.0 5678.5 5750.9 6186.2 6191.4 6200.3 6201.2 6214.0 5812.1 5823.5 5825.9 5828.2 5830.9 5237.2 5306.7 5310.2 5389.2 5430.7 6349.3 6350.4 6359.6 6364.9 6366.6 6187.0 6190.4 6198.1 6206.3 6219.8 6124.7 6259.1 6279.1 6281.6 6288.5 6157.6 6177.3 6206.2 6210.9 6217.1 5289.3 6166.3 6178.1 6181.9 6191.7 5929.5 5944.1 6019.7 6061.3 6127.4 6002.2 6088.0 6100.4 6164.8 6166.2 6015.6 6022.3 6023.6 6025.8 6034.5 5538.7 6005.2 6108.5 6116.1 6161.3 5767.5 5856.0 5911.9 5929.7 6021.5 16:41:56 INFO - PROCESS | 1649 | 16:41:56 INFO - PROCESS | 1649 | [#1] ext.html Cycles:180 Average:73648.63 Median:39672.90 stddev:161397.01 (406.8%) stddev-sans-first:161756.25 16:41:56 INFO - PROCESS | 1649 | Values: 469.8 493.8 529.7 535.6 539.6 30707.0 40224.4 41384.8 41391.6 43737.9 43348.0 43669.8 43701.8 43731.0 43778.8 9720.1 9881.5 9884.0 9885.4 9888.3 8840.0 9077.9 11951.7 12214.8 12263.1 12241.9 12311.2 12311.3 12315.5 12332.6 12206.2 12290.0 12292.2 12298.1 12349.9 10394.2 11808.8 11814.8 12085.9 12113.9 40820.4 41056.4 41185.0 41216.0 41415.8 3666.6 3996.4 4059.5 4105.2 4204.6 32384.5 32394.8 32473.7 32486.5 32674.5 3221.4 3330.2 3380.8 3435.1 3439.3 509437.0 510362.0 511616.7 511636.4 522882.0 60440.7 60673.0 60694.4 60749.7 60865.7 63060.7 63227.1 63354.7 63399.7 63399.7 16260.0 16509.8 16520.7 16523.6 16545.6 11344.1 11615.2 12661.9 12662.9 12703.2 14609.5 16434.8 16839.5 16847.8 16998.1 6534.5 6554.9 6593.1 6627.0 6631.7 778.8 917.4 1003.5 1046.3 1080.2 29989.7 30190.8 30458.1 31224.2 31266.8 934.4 984.7 1050.6 1071.6 1079.8 15093.8 15783.2 15819.3 15843.6 16247.4 647728.0 654655.0 654781.0 655622.0 858166.0 57957.4 58939.4 59463.4 62902.7 63167.4 1352.2 1385.6 1403.2 1409.6 1409.8 30732.4 31912.5 32039.2 32390.8 32539.2 51985.0 52467.5 52532.7 52588.7 52633.7 582775.0 582943.0 583561.0 584749.0 587036.0 37639.9 39709.8 40369.4 40876.0 40951.6 47772.3 47893.0 49051.8 49122.8 49247.3 38380.8 39510.2 39849.2 40780.0 41356.0 38955.6 39636.0 40198.8 40435.4 40637.6 37936.2 38297.0 38899.4 39143.8 39237.6 32102.4 34662.5 35034.5 35501.0 36077.6 25841.1 25927.1 25931.0 26279.9 26635.1 16:41:56 INFO - PROCESS | 1649 | 16:41:56 INFO - PROCESS | 1649 | [#2] jquery.html Cycles:180 Average:34487.30 Median:13402.53 stddev:99886.17 (745.3%) stddev-sans-first:100133.42 16:41:56 INFO - PROCESS | 1649 | Values: 319.0 348.7 364.9 375.7 400.1 15838.4 17769.0 20647.8 21323.0 21403.4 19880.6 20495.1 21726.0 21895.3 22161.7 5457.8 5615.4 5632.2 5811.2 5817.0 5630.4 5694.6 5726.9 5738.9 5744.0 3988.2 5974.4 6214.4 6269.3 6334.6 6028.3 6266.7 6272.3 6290.6 6293.9 6112.2 6127.4 6132.4 6152.8 6162.9 13623.5 13865.1 22839.3 24406.8 25517.0 1872.4 2164.5 2269.3 2398.3 2918.0 9826.2 10198.4 10468.0 11685.8 15833.3 1673.3 1820.8 1835.2 1860.3 1865.8 119812.4 134068.0 135547.0 137389.0 138154.3 11190.7 11362.8 11437.0 11580.7 11668.8 14620.3 14627.2 14651.0 14658.9 14665.3 8427.6 8579.2 8594.3 8614.5 8722.7 8467.1 8474.9 8482.6 8643.9 8657.5 4531.5 4539.1 4556.0 4576.2 4650.8 1867.3 1922.2 1922.3 1922.8 1927.8 836.5 885.4 918.6 982.4 986.0 20613.9 21336.5 21383.8 21393.3 21467.9 813.3 916.4 943.5 950.2 1023.3 10863.9 11184.6 11286.8 11289.9 11290.9 587403.2 593543.0 595568.0 611737.0 613691.0 69989.0 70478.6 92357.0 111014.4 111909.0 962.8 982.1 1002.4 1005.4 1005.8 13067.9 18759.6 18918.0 19156.6 19311.8 54859.5 54958.5 55149.7 55378.0 56041.2 76660.0 78042.0 79184.6 79220.0 79993.6 8009.0 8099.2 8234.5 8314.6 8326.6 11794.8 12955.6 12992.5 13307.8 13353.3 10894.9 11182.0 11227.2 11349.8 11427.8 10721.7 10900.5 10915.1 11062.1 11389.7 11320.4 11523.8 11776.1 11807.6 11838.2 8034.4 8131.8 8147.0 8215.0 8235.0 9576.7 10906.9 11161.9 11462.5 11509.1 16:41:56 INFO - PROCESS | 1649 | 16:41:56 INFO - PROCESS | 1649 | [#3] mootools.html Cycles:180 Average:5729.77 Median:5129.96 stddev:3183.68 (62.1%) stddev-sans-first:3174.78 16:41:56 INFO - PROCESS | 1649 | Values: 1246.0 1379.3 1478.6 1637.5 1852.7 7803.6 8278.8 8281.5 8283.8 8301.4 8472.2 8734.3 8782.7 8939.4 8974.0 2223.1 2377.6 2414.9 2416.8 2416.8 2289.6 2373.6 2418.3 2422.0 2427.6 2383.7 2409.0 2420.9 2421.2 2421.8 2236.6 2241.1 2247.8 2250.8 2346.8 2344.0 2365.2 2367.7 2376.7 2377.2 8850.3 8910.0 8964.7 8967.4 8973.9 5238.2 5285.0 5448.2 5451.0 5451.8 3656.3 3730.8 3735.7 3761.2 3762.3 1085.7 1101.5 1118.6 1123.5 1133.2 11756.4 11853.9 11870.5 11876.5 11899.9 4316.7 4631.5 4673.0 4674.8 4681.3 9341.4 9346.7 9347.3 9348.3 9377.6 5305.3 5315.3 5316.4 5316.6 5317.5 3970.4 4388.9 4920.4 4983.8 5027.2 3817.3 3848.0 3857.2 3933.8 3935.4 1038.4 1161.0 1180.6 1186.9 1203.7 855.3 857.6 859.5 887.7 970.6 5890.7 6071.4 6129.1 6132.8 6202.2 920.2 967.3 986.1 987.9 998.9 3844.7 4063.4 4079.4 4102.2 4183.2 9320.6 10087.9 10468.4 10634.0 11032.9 10753.5 10767.6 10770.4 10787.3 10798.4 7396.1 8092.4 8115.8 8387.7 8400.5 5891.1 5955.9 5991.9 6142.6 6192.3 10099.5 10153.8 10191.3 10214.4 10231.3 9299.3 9600.0 9805.3 10174.8 10190.6 7411.4 7561.7 7630.0 7635.2 7712.6 8237.0 8491.1 8557.3 8732.4 8755.9 7540.4 7613.9 7699.8 7741.7 7794.6 7500.6 7528.8 7538.7 7541.7 7586.7 7087.5 7096.1 7166.9 7172.8 7173.4 5097.4 5108.1 5131.8 5175.8 5232.9 5101.3 5113.6 5128.1 5240.1 5377.2 16:41:56 INFO - PROCESS | 1649 | 16:41:56 INFO - PROCESS | 1649 | [#4] prototype.html Cycles:180 Average:4584.42 Median:5022.26 stddev:1411.23 (28.1%) stddev-sans-first:1377.93 16:41:56 INFO - PROCESS | 1649 | Values: 292.5 299.6 308.1 309.1 310.6 4467.8 4838.4 5482.4 5537.6 5552.0 4494.8 4554.3 5020.8 5113.6 5350.9 3702.5 3932.8 4060.5 4077.0 4104.4 3316.3 4166.6 4184.1 4184.9 4203.7 3391.9 3453.4 3769.3 4108.9 4150.4 4119.7 4137.1 4153.3 4173.3 4202.3 3573.4 3597.4 3599.0 3605.7 3610.8 4325.5 4617.8 4927.0 5288.6 5314.5 4678.7 4747.5 4767.7 4768.2 4787.9 5663.9 5734.5 5739.2 5750.0 5763.0 3522.7 3948.4 3974.6 3982.9 3987.4 4429.7 5101.1 5456.4 5820.8 6021.3 4007.7 4037.7 4047.9 4075.7 4096.9 3479.3 3589.7 4092.4 4161.4 4163.4 4532.6 4728.6 4769.5 4786.3 4797.6 4986.5 5653.4 5689.0 5693.5 5701.5 1232.6 1321.7 1369.5 1421.5 1424.3 891.6 904.7 917.0 924.3 927.2 4887.2 5291.4 5320.7 5334.9 5416.4 4945.5 5331.4 5814.0 5817.7 5822.7 5023.7 5268.8 5346.7 5436.4 5460.8 2718.8 2796.3 3073.8 3084.0 3085.4 5305.7 5772.6 5879.8 6115.0 6125.8 4878.7 4935.3 5834.9 5957.8 6074.8 4539.7 5865.4 6113.4 6129.1 6159.2 4642.7 5178.7 5328.0 5825.9 5881.4 5776.2 5780.2 5787.8 5810.3 5840.3 5594.5 6003.5 6095.1 6124.3 6134.1 3368.5 3853.3 3945.2 3946.5 3964.4 4708.0 5783.5 5997.1 5997.4 6133.5 4829.4 5636.6 5705.7 5841.8 5857.0 4820.6 5738.4 5754.8 5763.3 5776.3 4377.5 5564.6 5635.9 5651.8 5665.8 3772.5 5243.8 5402.9 5519.9 5656.2 5279.0 5520.8 5531.6 5532.4 5596.7 16:41:56 INFO - PROCESS | 1649 | 16:41:56 INFO - PROCESS | 1649 | [#5] yui.html Cycles:180 Average:25222.67 Median:3502.24 stddev:40682.10 (1161.6%) stddev-sans-first:40754.18 16:41:56 INFO - PROCESS | 1649 | Values: 589.7 684.5 754.4 767.2 816.4 14836.7 15088.1 15103.2 15126.7 15131.5 14097.2 14967.9 15243.4 15251.8 15281.6 829.3 837.6 840.1 840.5 841.6 666.0 687.9 830.7 854.2 862.4 906.6 910.2 910.2 910.8 911.6 901.1 901.8 903.0 903.1 903.8 514.5 642.5 664.1 665.7 667.6 14828.3 14886.7 14898.6 14906.4 14910.0 13773.5 14084.8 14133.2 14171.1 14408.6 12738.7 12761.4 12796.4 12971.4 13001.3 4524.3 4646.4 4652.8 4685.6 4772.7 121170.4 125010.4 127824.0 130775.0 130993.0 10870.2 11142.2 11342.9 11355.9 11521.1 21275.7 21826.5 21984.5 21986.8 22110.2 7802.3 7805.4 7889.4 8005.1 8068.0 4346.0 4408.3 4414.0 4568.3 4675.5 3425.9 3578.6 3587.8 3589.2 3595.9 547.8 630.1 635.8 648.7 658.2 592.6 593.9 682.5 686.8 690.6 8985.8 9662.2 9796.5 9868.4 9939.3 605.5 610.9 648.3 683.2 692.3 4390.8 4668.6 4684.8 4691.5 4760.0 90741.0 120839.0 127913.5 152481.0 161390.0 135315.0 135510.0 136443.0 137028.3 138903.0 112446.0 116370.0 117530.4 119426.4 123046.4 9065.1 9073.1 9090.5 9190.5 9286.8 53863.2 57825.4 58713.4 60880.8 65966.7 84513.0 95597.0 98224.5 99082.0 100430.0 11895.3 12000.8 12049.5 12091.2 12258.9 13600.0 13606.2 13648.0 13674.3 14231.6 12452.1 12487.7 12723.8 12877.4 12877.9 12212.3 12655.6 12662.6 13045.3 13050.5 12230.4 12322.3 12327.5 12441.0 12850.4 10369.3 10579.3 10595.4 10609.4 10792.6 10270.6 10928.8 10949.8 10950.6 10974.6 16:41:56 INFO - PROCESS | 1649 | -------- Summary: end -------- 16:41:56 INFO - PROCESS | 1649 | 16:41:57 INFO - TEST-INFO | 1649: exit 0 16:41:57 INFO - TEST-OK | dromaeo_css | took 1118204ms 16:41:57 INFO - TEST-START | kraken 16:41:57 INFO - operating with platform_type : mac_ 16:41:57 INFO - Initialising browser for kraken test... 16:41:57 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/tmp5S8CJ_/profile http://localhost:49263/getInfo.html) 16:41:59 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 16:41:59 INFO - PROCESS | 1657 | colorDepth:24 16:41:59 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 16:41:59 INFO - PROCESS | 1657 | __metrics 16:41:59 INFO - PROCESS | 1657 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:41:59 INFO - PROCESS | 1657 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:42:00 INFO - TEST-INFO | 1657: exit 0 16:42:00 INFO - Browser initialized. 16:42:00 INFO - Running cycle 1/1 for kraken test... 16:42:00 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/T/tmp5S8CJ_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:42:12 INFO - PROCESS | 1658 | RSS: Main: 231141376 16:42:12 INFO - PROCESS | 1658 | 16:42:14 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49263/tests/kraken/driver.html?testName=audio-beat-detection) 16:42:15 INFO - PROCESS | 1658 | RSS: Main: 296435712 16:42:15 INFO - PROCESS | 1658 | 16:42:18 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49263/tests/kraken/driver.html?testName=audio-dft) 16:42:18 INFO - PROCESS | 1658 | RSS: Main: 296062976 16:42:18 INFO - PROCESS | 1658 | 16:42:22 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49263/tests/kraken/driver.html?testName=audio-fft) 16:42:23 INFO - PROCESS | 1658 | RSS: Main: 381415424 16:42:23 INFO - PROCESS | 1658 | 16:42:25 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49263/tests/kraken/driver.html?testName=audio-oscillator) 16:42:25 INFO - PROCESS | 1658 | RSS: Main: 282558464 16:42:25 INFO - PROCESS | 1658 | 16:42:27 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49263/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:42:28 INFO - PROCESS | 1658 | RSS: Main: 287928320 16:42:28 INFO - PROCESS | 1658 | 16:42:31 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49263/tests/kraken/driver.html?testName=imaging-darkroom) 16:42:31 INFO - PROCESS | 1658 | RSS: Main: 324530176 16:42:31 INFO - PROCESS | 1658 | 16:42:34 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49263/tests/kraken/driver.html?testName=imaging-desaturate) 16:42:35 INFO - PROCESS | 1658 | RSS: Main: 324374528 16:42:35 INFO - PROCESS | 1658 | 16:42:38 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49263/tests/kraken/driver.html?testName=json-parse-financial) 16:42:38 INFO - PROCESS | 1658 | RSS: Main: 323928064 16:42:38 INFO - PROCESS | 1658 | 16:42:40 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49263/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:42:41 INFO - PROCESS | 1658 | RSS: Main: 302170112 16:42:41 INFO - PROCESS | 1658 | 16:42:43 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:42:43 INFO - PROCESS | 1658 | RSS: Main: 302874624 16:42:43 INFO - PROCESS | 1658 | 16:42:45 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:42:46 INFO - PROCESS | 1658 | RSS: Main: 309407744 16:42:46 INFO - PROCESS | 1658 | 16:42:49 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:42:50 INFO - PROCESS | 1658 | RSS: Main: 319119360 16:42:50 INFO - PROCESS | 1658 | 16:42:53 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:42:53 INFO - PROCESS | 1658 | RSS: Main: 321925120 16:42:53 INFO - PROCESS | 1658 | 16:42:55 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49263/tests/kraken/driver.html?testName=ai-astar) 16:42:55 INFO - PROCESS | 1658 | RSS: Main: 365142016 16:42:55 INFO - PROCESS | 1658 | 16:42:55 INFO - PROCESS | 1658 | __start_tp_report 16:42:55 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 16:42:55 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 16:42:55 INFO - PROCESS | 1658 | |i|pagename|runs| 16:42:55 INFO - PROCESS | 1658 | |0;ai-astar;98;113;107;114;113;114;115;114;113;113 16:42:55 INFO - PROCESS | 1658 | |1;audio-beat-detection;147;108;130;161;107;129;128;108;159;103 16:42:55 INFO - PROCESS | 1658 | |2;audio-dft;185;184;184;183;184;184;181;184;183;182 16:42:55 INFO - PROCESS | 1658 | |3;audio-fft;76;73;106;94;58;60;64;84;87;67 16:42:55 INFO - PROCESS | 1658 | |4;audio-oscillator;82;87;136;73;70;76;119;72;70;146 16:42:55 INFO - PROCESS | 1658 | |5;imaging-gaussian-blur;92;88;91;88;92;87;92;89;92;88 16:42:55 INFO - PROCESS | 1658 | |6;imaging-darkroom;96;110;145;96;96;95;96;96;96;95 16:42:55 INFO - PROCESS | 1658 | |7;imaging-desaturate;87;86;98;138;85;87;86;84;85;100 16:42:55 INFO - PROCESS | 1658 | |8;json-parse-financial;54;55;56;57;56;56;56;56;56;55 16:42:55 INFO - PROCESS | 1658 | |9;json-stringify-tinderbox;65;67;70;81;72;72;70;70;70;71 16:42:55 INFO - PROCESS | 1658 | |10;stanford-crypto-aes;86;86;85;85;88;88;89;85;90;87 16:42:55 INFO - PROCESS | 1658 | |11;stanford-crypto-ccm;138;161;147;143;142;134;143;145;224;137 16:42:55 INFO - PROCESS | 1658 | |12;stanford-crypto-pbkdf2;172;170;166;170;168;170;168;167;164;167 16:42:55 INFO - PROCESS | 1658 | |13;stanford-crypto-sha256-iterative;66;67;68;75;85;85;85;75;67;66 16:42:55 INFO - PROCESS | 1658 | __end_tp_report 16:42:55 INFO - PROCESS | 1658 | __start_cc_report 16:42:55 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,2165 16:42:55 INFO - PROCESS | 1658 | __end_cc_report 16:42:55 INFO - PROCESS | 1658 | __startTimestamp1471909375812__endTimestamp 16:42:55 INFO - PROCESS | 1658 | 16:42:55 INFO - PROCESS | 1658 | ------- Summary: start ------- 16:42:55 INFO - PROCESS | 1658 | Number of tests: 14 16:42:55 INFO - PROCESS | 1658 | 16:42:55 INFO - PROCESS | 1658 | [#0] ai-astar Cycles:10 Average:111.40 Median:114.00 stddev:5.19 (4.6%) stddev-sans-first:2.32 16:42:55 INFO - PROCESS | 1658 | Values: 98.0 113.0 107.0 114.0 113.0 114.0 115.0 114.0 113.0 113.0 16:42:55 INFO - PROCESS | 1658 | 16:42:55 INFO - PROCESS | 1658 | [#1] audio-beat-detection Cycles:10 Average:128.00 Median:129.50 stddev:21.81 (16.8%) stddev-sans-first:22.03 16:42:55 INFO - PROCESS | 1658 | Values: 147.0 108.0 130.0 161.0 107.0 129.0 128.0 108.0 159.0 103.0 16:42:55 INFO - PROCESS | 1658 | 16:42:55 INFO - PROCESS | 1658 | [#2] audio-dft Cycles:10 Average:183.40 Median:184.00 stddev:1.17 (0.6%) stddev-sans-first:1.09 16:42:55 INFO - PROCESS | 1658 | Values: 185.0 184.0 184.0 183.0 184.0 184.0 181.0 184.0 183.0 182.0 16:42:55 INFO - PROCESS | 1658 | 16:42:55 INFO - PROCESS | 1658 | [#3] audio-fft Cycles:10 Average:76.90 Median:74.50 stddev:15.69 (21.1%) stddev-sans-first:16.64 16:42:55 INFO - PROCESS | 1658 | Values: 76.0 73.0 106.0 94.0 58.0 60.0 64.0 84.0 87.0 67.0 16:42:55 INFO - PROCESS | 1658 | 16:42:55 INFO - PROCESS | 1658 | [#4] audio-oscillator Cycles:10 Average:93.10 Median:72.50 stddev:29.21 (40.3%) stddev-sans-first:30.70 16:42:55 INFO - PROCESS | 1658 | Values: 82.0 87.0 136.0 73.0 70.0 76.0 119.0 72.0 70.0 146.0 16:42:55 INFO - PROCESS | 1658 | 16:42:55 INFO - PROCESS | 1658 | [#5] imaging-gaussian-blur Cycles:10 Average:89.90 Median:91.50 stddev:2.08 (2.3%) stddev-sans-first:2.06 16:42:55 INFO - PROCESS | 1658 | Values: 92.0 88.0 91.0 88.0 92.0 87.0 92.0 89.0 92.0 88.0 16:42:55 INFO - PROCESS | 1658 | 16:42:55 INFO - PROCESS | 1658 | [#6] imaging-darkroom Cycles:10 Average:102.10 Median:96.00 stddev:15.73 (16.4%) stddev-sans-first:16.53 16:42:55 INFO - PROCESS | 1658 | Values: 96.0 110.0 145.0 96.0 96.0 95.0 96.0 96.0 96.0 95.0 16:42:55 INFO - PROCESS | 1658 | 16:42:55 INFO - PROCESS | 1658 | [#7] imaging-desaturate Cycles:10 Average:93.60 Median:86.00 stddev:16.58 (19.3%) stddev-sans-first:17.41 16:42:55 INFO - PROCESS | 1658 | Values: 87.0 86.0 98.0 138.0 85.0 87.0 86.0 84.0 85.0 100.0 16:42:55 INFO - PROCESS | 1658 | 16:42:55 INFO - PROCESS | 1658 | [#8] json-parse-financial Cycles:10 Average:55.70 Median:56.00 stddev:0.82 (1.5%) stddev-sans-first:0.60 16:42:55 INFO - PROCESS | 1658 | Values: 54.0 55.0 56.0 57.0 56.0 56.0 56.0 56.0 56.0 55.0 16:42:55 INFO - PROCESS | 1658 | 16:42:55 INFO - PROCESS | 1658 | [#9] json-stringify-tinderbox Cycles:10 Average:70.80 Median:70.50 stddev:4.18 (5.9%) stddev-sans-first:3.88 16:42:55 INFO - PROCESS | 1658 | Values: 65.0 67.0 70.0 81.0 72.0 72.0 70.0 70.0 70.0 71.0 16:42:55 INFO - PROCESS | 1658 | 16:42:55 INFO - PROCESS | 1658 | [#10] stanford-crypto-aes Cycles:10 Average:86.90 Median:87.50 stddev:1.79 (2.0%) stddev-sans-first:1.87 16:42:55 INFO - PROCESS | 1658 | Values: 86.0 86.0 85.0 85.0 88.0 88.0 89.0 85.0 90.0 87.0 16:42:55 INFO - PROCESS | 1658 | 16:42:55 INFO - PROCESS | 1658 | [#11] stanford-crypto-ccm Cycles:10 Average:151.40 Median:144.00 stddev:26.55 (18.4%) stddev-sans-first:27.71 16:42:55 INFO - PROCESS | 1658 | Values: 138.0 161.0 147.0 143.0 142.0 134.0 143.0 145.0 224.0 137.0 16:42:55 INFO - PROCESS | 1658 | 16:42:55 INFO - PROCESS | 1658 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.20 Median:169.00 stddev:2.35 (1.4%) stddev-sans-first:2.05 16:42:55 INFO - PROCESS | 1658 | Values: 172.0 170.0 166.0 170.0 168.0 170.0 168.0 167.0 164.0 167.0 16:42:55 INFO - PROCESS | 1658 | 16:42:55 INFO - PROCESS | 1658 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.90 Median:75.00 stddev:8.35 (11.1%) stddev-sans-first:8.35 16:42:55 INFO - PROCESS | 1658 | Values: 66.0 67.0 68.0 75.0 85.0 85.0 85.0 75.0 67.0 66.0 16:42:55 INFO - PROCESS | 1658 | -------- Summary: end -------- 16:42:55 INFO - PROCESS | 1658 | 16:42:56 INFO - TEST-INFO | 1658: exit 0 16:42:56 INFO - TEST-OK | kraken | took 59510ms 16:42:56 INFO - SUITE-END | took 1177s 16:42:56 INFO - Completed test suite (00:19:38) 16:42:56 INFO - javascript benchmark 16:42:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2923.9415211695764, 3353.8323083845803, 3361.9495707564374, 3366.966330336696, 3398.929337258763, 5532.889342213153, 5614.1298098794705, 5621.988042152411, 5673.716314184295, 5709.229254050707, 5602.299712535933, 5655.660660360381, 5732.7133643317875, 5747.396523365049, 5781.4317304860415, 5131.127708289581, 5136.717480538568, 5212.139996900526, 5237.476252374751, 5242.7640756166065, 5324.866878328054, 5334.893302133955, 5339.519443250107, 5345.0, 5357.223202635615, 4321.459817522809, 5023.372078490189, 5111.591072714174, 5133.230015497668, 5210.192420174879, 5077.111505486525, 5121.513456221653, 5174.896502069938, 5191.480851914778, 5202.765875535572, 5113.130767769489, 5139.665921715076, 5141.280220769096, 5199.428062913076, 5212.895742085175, 5693.658380497183, 5704.087346024658, 5723.084306510938, 5727.513161381287, 5736.74184661687, 6031.276246850362, 6078.209832721762, 6150.538709596798, 6157.876842463171, 6229.563930524865, 4632.675712700112, 6201.069839524062, 6209.596376235575, 6224.097505861625, 6225.595336303171, 5609.80365687199, 5811.82564523065, 5832.416758324133, 5843.2695913010875, 5845.678487683218, 6145.231846019247, 6186.4741496972365, 6204.100405441186, 6214.1921550198185, 6265.65538895356, 6066.363031881677, 6125.387461253839, 6133.570650054453, 6135.233095863017, 6138.1099740537375, 5419.430959749169, 6199.845003874939, 6218.9378106219265, 6219.40915613016, 6223.751049958041, 5985.0, 6057.273127224761, 6089.51283897296, 6133.3866613338305, 6160.907586386208, 6141.723622436912, 6209.099680546386, 6210.037444196157, 6213.471854892385, 6235.282942461675, 4919.0407870464915, 4937.506249375034, 4938.987507560942, 4941.382327209099, 4946.0, 4314.870553883388, 4356.063446358985, 4409.228385032607, 4410.3825464435, 4432.645388368923, 5025.522575355335, 5664.575156863251, 5675.0, 5678.517326027251, 5750.88498230033, 6186.226721659793, 6191.4118158774845, 6200.3179650238435, 6201.162843016225, 6213.968930155411, 5812.128180772834, 5823.5341172706085, 5825.941740582624, 5828.242328497353, 5830.854228644318, 5237.16205407133, 5306.6550674206055, 5310.230016647564, 5389.218563308298, 5430.70131142791, 6349.301576826638, 6350.428461438492, 6359.6184228945485, 6364.936350636527, 6366.5938722783085, 6186.979148440637, 6190.350013248544, 6198.1012753149635, 6206.348333424925, 6219.844503887439, 6124.663143527148, 6259.061140828912, 6279.058141278845, 6281.59169653971, 6288.465480434214, 6157.599756015845, 6177.320494745484, 6206.162168107247, 6210.875782484235, 6217.098520714561, 5289.338832645919, 6166.321704612579, 6178.135061091541, 6181.876362472635, 6191.690415479118, 5929.495992840484, 5944.078667806497, 6019.668918209541, 6061.3029501606115, 6127.387261273837, 6002.249718785152, 6088.0, 6100.389961003864, 6164.815055548341, 6166.1983942087245, 6015.608985416109, 6022.307434644897, 6023.608465449732, 6025.849353766191, 6034.517238620988, 5538.670719027485, 6005.1892994446935, 6108.541859360477, 6116.113163591343, 6161.270018882482, 5767.452092051249, 5856.0, 5911.881762364643, 5929.703514824155, 6021.518278537794], "value": 5651.555294633423, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [469.75337947577515, 493.750655918761, 529.713954464589, 535.6009772719325, 539.6168720208651, 30707.0, 40224.396634050514, 41384.79307603458, 41391.58608413915, 43737.90655233613, 43348.0, 43669.781651091704, 43701.7814910925, 43731.0, 43778.78110609442, 9720.125188732995, 9881.456519891402, 9883.962183970687, 9885.406875587452, 9888.258380621483, 8840.0, 9077.909220907772, 11951.701207469796, 12214.816777748341, 12263.080268979818, 12241.93879030608, 12311.199772014836, 12311.261324320569, 12315.5073797048, 12332.568360107398, 12206.206596571194, 12290.016798656086, 12292.201006934522, 12298.139130260884, 12349.93825030878, 10394.16846652266, 11808.763824723543, 11814.763704725858, 12085.879141208563, 12113.939430302793, 40820.38769418431, 41056.38415423771, 41184.97037574085, 41216.0, 41415.79292103551, 3666.578343490506, 3996.4205190247544, 4059.472268605092, 4105.220008198433, 4204.579542045802, 32384.514232286536, 32394.838025809957, 32473.675263247536, 32486.512702309483, 32674.509882351547, 3221.4201443740117, 3330.150811543065, 3380.7802492837886, 3435.055359776057, 3439.2949445363643, 509437.0, 510362.0, 511616.65150045557, 511636.41817908955, 522882.0, 60440.69779651162, 60673.0, 60694.393056069755, 60749.696251519345, 60865.69567152136, 63060.738785224035, 63227.051594226126, 63354.68322658449, 63399.68300158469, 63399.732005359634, 16260.0, 16509.752353714703, 16520.6695866082, 16523.586910327336, 16545.586360341087, 11344.092472602337, 11615.245009074382, 12661.873381266254, 12662.873371266354, 12703.174293670882, 14609.488667896785, 16434.83565164333, 16839.494815155565, 16847.83152168487, 16998.065106419264, 6534.509911756637, 6554.934450655433, 6593.109930159463, 6626.966865165739, 6631.6841579210395, 778.7780482562442, 917.4128557723062, 1003.4631472162358, 1046.3460337289193, 1080.2492267873909, 29989.70010299869, 30190.84904575507, 30458.086257412313, 31224.219394514865, 31266.843665781525, 934.4206591913057, 984.7144328144901, 1050.5850189175233, 1071.6410002649045, 1079.8056349856945, 15093.849061509463, 15783.210839457981, 15819.288132034086, 15843.603909902115, 16247.431339903047, 647728.0, 654655.0, 654781.0, 655622.0, 858166.0, 57957.420425796045, 58939.41060589425, 59463.40536594665, 62902.68548657227, 63167.36832631615, 1352.2427440633278, 1385.5843247025853, 1403.1651167555287, 1409.584172669065, 1409.7885317202501, 30732.385352292382, 31912.52131217987, 32039.199020024687, 32390.838045810564, 32539.18652033718, 51985.0, 52467.47532524626, 52532.737336313076, 52588.73705631447, 52633.7368313156, 582775.0, 582943.0, 583561.0, 584749.0, 587036.0, 37639.87080387593, 39709.80145099256, 40369.39445908255, 40876.0, 40951.59048409478, 47772.28341574949, 47893.0, 49051.754741227494, 49122.75438622784, 49247.26129108138, 38380.80809595934, 39510.2097958045, 39849.2030159401, 40780.0, 41355.96610084772, 38955.610443895195, 39636.0, 40198.79900600478, 40435.3934690974, 40637.59362406338, 37936.24127517489, 38297.0, 38899.41650875183, 39143.80428097955, 39237.607623923395, 32102.374775494474, 34662.480062798575, 35034.474482883285, 35501.0, 36077.63922360848, 25841.095561655253, 25927.092551760597, 25931.0, 26279.868600656875, 26635.067772627866], "value": 20975.275525995818, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.96650851660576, 348.72799216611054, 364.8868850656298, 375.6844250829304, 400.1077596958782, 15838.366465341387, 17769.0, 20647.793522064774, 21323.040463179157, 21403.35789926303, 19880.602387952233, 20495.07772150253, 21726.0, 21895.343139705794, 22161.66757498639, 5457.754401051942, 5615.410381909902, 5632.183333416652, 5811.186433899257, 5817.040188368914, 5630.380658127603, 5694.629849059818, 5726.856828579277, 5738.885222295572, 5743.994800909845, 3988.162485877969, 5974.372690867462, 6214.384260092386, 6269.310375858652, 6334.6199228046235, 6028.336882942873, 6266.717997690115, 6272.341404152567, 6290.62256264621, 6293.9370606293805, 6112.235970503687, 6127.417895299939, 6132.356102609251, 6152.784652537184, 6162.8767424651705, 13623.523176688868, 13865.09876858001, 22839.31482055541, 24406.755932440625, 25517.0, 1872.4008317338457, 2164.469704922288, 2269.3305474884946, 2398.304491697406, 2917.978707452396, 9826.16477599405, 10198.388096714147, 10467.953204679547, 11685.824712629317, 15833.287502062434, 1673.3306677329044, 1820.7724034495689, 1835.174171622775, 1860.3302810988034, 1865.7947625761155, 119812.40093799563, 134068.0, 135547.0, 137389.0, 138154.30922845422, 11190.720231994264, 11362.772744545064, 11437.0, 11580.710482238012, 11668.76662466746, 14620.268986550629, 14627.19550424737, 14651.0, 14658.926705366404, 14665.266736663125, 8427.578621068922, 8579.227869491775, 8594.312455003585, 8614.483131012159, 8722.694705685271, 8467.137229809432, 8474.88127968008, 8482.618282177316, 8643.870341944876, 8657.52383618907, 4531.524189960007, 4539.0921815637, 4556.0, 4576.176288268143, 4650.837220697327, 1867.2997625890291, 1922.2407149175997, 1922.2983610982171, 1922.7596550431197, 1927.8264956154014, 836.5315423362936, 885.4130436942783, 918.6095909238547, 982.4203719805206, 985.989360905078, 20613.896930515253, 21336.466588335417, 21383.786162138178, 21393.25123620666, 21467.892660536596, 813.3086876155221, 916.3585490156917, 943.4716558727134, 950.1591091883732, 1023.3041531758327, 10863.891361086446, 11184.552617895193, 11286.774264514665, 11289.887101129048, 11290.943545282222, 587403.1966963132, 593543.0, 595568.0, 611737.0, 613691.0, 69989.0, 70478.64760676163, 92357.0, 111014.44492777485, 111909.0, 962.8459446488528, 982.111189373618, 1002.3935519011039, 1005.4319309590059, 1005.8088963096989, 13067.934660326637, 18759.623267811923, 18918.0, 19156.616867662848, 19311.806881931, 54859.45140548544, 54958.45041549533, 55149.72425137849, 55378.0, 56041.15938261011, 76660.0, 78042.0, 79184.60407697925, 79220.0, 79993.60003199946, 8009.0, 8099.190080991853, 8234.547099909323, 8314.62584183701, 8326.625301861552, 11794.823077654013, 12955.611331660066, 12992.545260915822, 13307.800382994457, 13353.332333383098, 10894.945525272324, 11182.0, 11227.21409501327, 11349.829752553553, 11427.828582571101, 10721.731956701145, 10900.454977250947, 10915.072218861485, 11062.059724923472, 11389.715257118638, 11320.37737924422, 11523.769524609628, 11776.116791240862, 11807.586734464252, 11838.171328006956, 8034.437589368922, 8131.83736325282, 8146.981627296588, 8215.0, 8235.0, 9576.712698619052, 10906.890931090587, 11161.944190278997, 11462.541498340306, 11509.136814739095], "value": 9524.271825807426, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1245.9875401245986, 1379.3379177994561, 1478.556433070079, 1637.4841924793689, 1852.6757817381963, 7803.648835802388, 8278.75163745087, 8281.461704989171, 8283.75148745537, 8301.377396695249, 8472.237498625123, 8734.301255899529, 8782.692605758799, 8939.418937769056, 8974.0, 2223.110755697726, 2377.6433534969765, 2414.9396265093424, 2416.8429052111555, 2416.8429052111646, 2289.5649826532904, 2373.5727569037567, 2418.2503423938633, 2421.9757802422014, 2427.5873101572774, 2383.7377888432256, 2409.0484258717797, 2420.8910599022997, 2421.1646981791255, 2421.7699318564796, 2236.552689462106, 2241.1035585765662, 2247.820174386045, 2250.797428231459, 2346.835721499496, 2343.9648405273933, 2365.2194775724142, 2367.6566897799894, 2376.7266764322153, 2377.215518878766, 8850.336224783103, 8910.0, 8964.686235981775, 8967.372283940127, 8973.910260897374, 5238.188080847474, 5285.048691235576, 5448.210009548633, 5451.0, 5451.836444906659, 3656.2687462507606, 3730.8321125549146, 3735.682466990309, 3761.2101458693705, 3762.3415902217002, 1085.6525911708256, 1101.509828126484, 1118.6364431559775, 1123.5000424810962, 1133.1501373969522, 11756.412179390996, 11853.933146016814, 11870.52517899283, 11876.52493900243, 11899.881001189964, 4316.7409955402145, 4631.467381251132, 4672.95327046732, 4674.813007479731, 4681.32120842476, 9341.439513629204, 9346.672866449642, 9347.252219822398, 9348.252139828797, 9377.578008989612, 5305.283786688825, 5315.28243687105, 5316.441773613715, 5316.627836051517, 5317.54800841925, 3970.4441378207075, 4388.934165987513, 4920.384951881015, 4983.8006479741125, 5027.1705168647495, 3817.255635151119, 3848.037990502374, 3857.209272099269, 3933.8426462941734, 3935.370340745467, 1038.3665963762098, 1161.047940688644, 1180.6162997026001, 1186.9465874035689, 1203.6629743671785, 855.3072011670566, 857.5840717252128, 859.5100792548187, 887.7070566712969, 970.6020531582159, 5890.676012819336, 6071.423214794588, 6129.08063790435, 6132.754689812357, 6202.224721909762, 920.1810388754014, 967.3180407812475, 986.0878687214441, 987.9456629885425, 998.8501724741346, 3844.7308688392104, 4063.3701776224734, 4079.3880917862557, 4102.2410853992105, 4183.163367326547, 9320.580573874191, 10087.949560252298, 10468.371897686164, 10634.0, 11032.889671103347, 10753.516091775733, 10767.569297228023, 10770.353778773298, 10787.298825576469, 10798.406087665255, 7396.112466504054, 8092.433529652985, 8115.837683246302, 8387.66449342044, 8400.495970241805, 5891.086881533369, 5955.940440595538, 5991.940080599138, 6142.57002009855, 6192.287886892885, 10099.495025248854, 10153.79692406133, 10191.286609937233, 10214.438205898747, 10231.283810133516, 9299.349045567016, 9600.0, 9805.2646051545, 10174.796504069729, 10190.592376304865, 7411.444141689287, 7561.735339263099, 7629.961850190713, 7635.23647635232, 7712.614369281626, 8237.0, 8491.065982742017, 8557.31541476668, 8732.388732788644, 8755.868661969947, 7540.396768258636, 7613.923860761543, 7699.769006929801, 7741.690332386866, 7794.610269486617, 7500.62496875165, 7528.811779705551, 7538.660760265691, 7541.660625271767, 7586.696532138652, 7087.503874728925, 7096.112985876765, 7166.928330716626, 7172.820679483055, 7173.390261827803, 5097.413797413345, 5108.055009823194, 5131.846044618667, 5175.792968281377, 5232.895342093213, 5101.260317254051, 5113.565346945403, 5128.076946149728, 5240.109181439088, 5377.193420986883], "value": 4603.298394571076, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [292.4866858663046, 299.57759559021775, 308.1387521876356, 309.1190108191654, 310.62414478481037, 4467.7542735149555, 4838.419389673235, 5482.396936337, 5537.640053396532, 5551.972240138799, 4494.842680506183, 4554.339620754997, 5020.849374518771, 5113.616478764098, 5350.946490535093, 3702.48231202782, 3932.803359832011, 4060.4518390017265, 4077.0, 4104.384342348641, 3316.303576248991, 4166.583361657036, 4184.121334519755, 4184.937225941613, 4203.7057405981595, 3391.86432542698, 3453.3611281912927, 3769.321522126016, 4108.938365924513, 4150.398192262121, 4119.711620186588, 4137.117611486767, 4153.335466325373, 4173.290540608104, 4202.285611446062, 3573.446115852047, 3597.4244120940525, 3598.992282160999, 3605.7295702822394, 3610.7833529988284, 4325.494185466675, 4617.7691115444095, 4926.98996705678, 5288.629795914288, 5314.468553144693, 4678.742669153229, 4747.477777444478, 4767.713937163781, 4768.213244814601, 4787.8563643090765, 5663.886722265573, 5734.483896449298, 5739.196512488255, 5750.0, 5763.0, 3522.7005704515145, 3948.368261078214, 3974.642282194616, 3982.920341593181, 3987.3819557968563, 4429.6899217054815, 5101.066886540254, 5456.372517160498, 5820.825375238707, 6021.2774467063355, 4007.73949693269, 4037.66756970205, 4047.9070650924086, 4075.673946084306, 4096.856610018635, 3479.3215323012278, 3589.6769290763955, 4092.447519584818, 4161.3549899765585, 4163.417121602978, 4532.637389008872, 4728.571785455586, 4769.523047695272, 4786.282057691375, 4797.616190704735, 4986.526280003394, 5653.434656534314, 5688.9715551422805, 5693.5445164387575, 5701.458361455655, 1232.5624403336865, 1321.722438287951, 1369.4795977528674, 1421.5380001499555, 1424.3448013913508, 891.5631340643168, 904.6878826804742, 917.0, 924.2744445610164, 927.2026057590467, 4887.169181239198, 5291.417944026154, 5320.680759154463, 5334.946650533445, 5416.431274716177, 4945.4807245238735, 5331.3868905076415, 5813.970930145407, 5817.7382017809305, 5822.650640961471, 5023.673461225008, 5268.78924843002, 5346.652467589594, 5436.401995780461, 5460.781568737286, 2718.823276487022, 2796.2663148847437, 3073.800202986799, 3083.9691603083684, 3085.4291955987765, 5305.734713264322, 5772.575518282331, 5879.794207202727, 6115.0, 6125.754969801247, 4878.731250062467, 4935.259711043373, 5834.94165058344, 5957.76168953237, 6074.787382441594, 4539.72761634303, 5865.442782935615, 6113.388661134029, 6129.1419201311855, 6159.168512250879, 4642.721436713673, 5178.689278643294, 5327.9733601331745, 5825.883482330414, 5881.353051164283, 5776.220210271559, 5780.248567686174, 5787.768489260551, 5810.331811841692, 5840.2991641003355, 5594.496495315441, 6003.459688628044, 6095.110515740134, 6124.265088189446, 6134.147621356091, 3368.494725791053, 3853.2871418787613, 3945.171513982096, 3946.5461471931094, 3964.4053391991433, 4707.976460117678, 5783.479486846204, 5997.13041608973, 5997.400259973968, 6133.509319254538, 4829.4062959222665, 5636.633618814764, 5705.7147142643535, 5841.795537156179, 5857.0, 4820.614350851994, 5738.368779434176, 5754.798582049608, 5763.279590051244, 5776.306843178845, 4377.518472968035, 5564.610477266552, 5635.9436405635415, 5651.773929042793, 5665.830025099253, 3772.5095737553943, 5243.764030618708, 5402.8649283766645, 5519.9088510434285, 5656.228448060338, 5279.0, 5520.779168833202, 5531.612787104864, 5532.3637781654015, 5596.748146333343], "value": 4138.565163364887, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [589.6756783768927, 684.459277171035, 754.3964828137492, 767.1676231289055, 816.4040250617046, 14836.703265934675, 15088.094714317149, 15103.244837758095, 15126.697466050673, 15131.470398536054, 14097.154170749736, 14967.925160374183, 15243.39026438941, 15251.771223431657, 15281.617959551017, 829.2909562324202, 837.5728378526965, 840.1136800675285, 840.4873027453249, 841.5750046226677, 665.9900101498481, 687.8761822871882, 830.7466222802043, 854.2055888024136, 862.4178679391417, 906.6056265524485, 910.199024858124, 910.2354022620997, 910.836049511091, 911.6444586611194, 901.0673952459206, 901.7880798012462, 902.9909700902973, 903.0788595632483, 903.7514683462035, 514.5009340939283, 642.5213216153995, 664.0968283134932, 665.70043480434, 667.5594107888819, 14828.332725027292, 14886.70226595462, 14898.553043408716, 14906.403743850233, 14909.95630305879, 13773.517926872511, 14084.788728169086, 14133.222672752994, 14171.149731016172, 14408.567742967727, 12738.74522509554, 12761.361931903368, 12796.424160912782, 12971.351432428435, 13001.296862779978, 4524.276115821485, 4646.372739680134, 4652.813887444532, 4685.601723853477, 4772.73749943753, 121170.43541676001, 125010.37494812558, 127824.0, 130775.0, 130993.0, 10870.18473614482, 11142.220044596801, 11342.886571134182, 11355.886441135648, 11521.078313735048, 21275.680864787042, 21826.454338641663, 21984.45038874009, 21986.780132198794, 22110.22614208495, 7802.29779319864, 7805.41459390548, 7889.368850491946, 8005.07941586725, 8068.0, 4346.0, 4408.316710909814, 4413.977930110329, 4568.33759104928, 4675.462321833033, 3425.8972230833115, 3578.5884623268657, 3587.766795158306, 3589.1924317028984, 3595.94606080909, 547.8003173051005, 630.0549176235646, 635.7997230872261, 648.7015972652632, 658.1707049118146, 592.599995003371, 593.9435753603401, 682.4646438481067, 686.8454597715574, 690.6325475558423, 8985.820283594423, 9662.227021838236, 9796.461194634221, 9868.407895526292, 9939.254555908336, 605.5367643752533, 610.8930937085992, 648.3225029843825, 683.1631251716608, 692.2938602625294, 4390.758508282075, 4668.626509879269, 4684.765761711901, 4691.483936766953, 4759.976200119047, 90741.0, 120839.0, 127913.53383458602, 152481.0, 161390.0, 135315.0, 135510.0, 136443.0, 137028.31485842707, 138903.0, 112446.0, 116370.0, 117530.41234793772, 119426.44588432905, 123046.38476807559, 9065.138811812867, 9073.092690730875, 9090.545472726206, 9190.49452280131, 9286.814263714823, 53863.192052118466, 57825.421745782005, 58713.41286587079, 60880.78238435295, 65966.68066638736, 84513.0, 95597.0, 98224.50887745516, 99082.0, 100429.99570004207, 11895.286282823059, 12000.819987700017, 12049.51801927913, 12091.153619246568, 12258.938705306417, 13600.0, 13606.183628982293, 13648.0, 13674.31628418595, 14231.64420889486, 12452.128351015705, 12487.687807804878, 12723.80914286268, 12877.35613219354, 12877.935610321889, 12212.273030607988, 12655.557055503014, 12662.620121396372, 13045.347732613522, 13050.477980880658, 12230.394840302275, 12322.260664360167, 12327.506899724269, 12441.0, 12850.42173102194, 10369.325993810378, 10579.259451838298, 10595.364278143028, 10609.416482093251, 10792.56829726802, 10270.6405275815, 10928.836067458835, 10949.781004379709, 10950.56197752081, 10974.615888444185], "value": 7807.475159486468, "unit": "score"}], "name": "dromaeo_css", "value": 7427.865561384917, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 113.0, 107.0, 114.0, 113.0, 114.0, 115.0, 114.0, 113.0, 113.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 108.0, 130.0, 161.0, 107.0, 129.0, 128.0, 108.0, 159.0, 103.0], "value": 128.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 184.0, 183.0, 184.0, 184.0, 181.0, 184.0, 183.0, 182.0], "value": 183.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 73.0, 106.0, 94.0, 58.0, 60.0, 64.0, 84.0, 87.0, 67.0], "value": 76.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 87.0, 136.0, 73.0, 70.0, 76.0, 119.0, 72.0, 70.0, 146.0], "value": 93.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 88.0, 91.0, 88.0, 92.0, 87.0, 92.0, 89.0, 92.0, 88.0], "value": 89.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 110.0, 145.0, 96.0, 96.0, 95.0, 96.0, 96.0, 96.0, 95.0], "value": 102.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 98.0, 138.0, 85.0, 87.0, 86.0, 84.0, 85.0, 100.0], "value": 93.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 56.0, 57.0, 56.0, 56.0, 56.0, 56.0, 56.0, 55.0], "value": 55.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 70.0, 81.0, 72.0, 72.0, 70.0, 70.0, 70.0, 71.0], "value": 70.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 85.0, 85.0, 88.0, 88.0, 89.0, 85.0, 90.0, 87.0], "value": 86.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 161.0, 147.0, 143.0, 142.0, 134.0, 143.0, 145.0, 224.0, 137.0], "value": 151.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 170.0, 166.0, 170.0, 168.0, 170.0, 168.0, 167.0, 164.0, 167.0], "value": 168.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 68.0, 75.0, 85.0, 85.0, 85.0, 75.0, 67.0, 66.0], "value": 73.9, "unit": "score"}], "name": "kraken", "value": 1485.3000000000004, "alertThreshold": 2.0}]} 16:42:57 INFO - Return code: 0 16:42:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:42:57 INFO - # TBPL SUCCESS # 16:42:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:42:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:42:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:42:57 INFO - 2016-08-22 16:42:57.173 screenresolution[1662:9710] starting screenresolution argv=screenresolution get 16:42:57 INFO - 2016-08-22 16:42:57.175 screenresolution[1662:9710] Display 0: 1600x1200x32@60 16:42:57 INFO - 2016-08-22 16:42:57.181 screenresolution[1663:9713] starting screenresolution argv=screenresolution list 16:42:57 INFO - Available Modes on Display 0 16:42:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:42:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:42:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:42:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:42:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:42:57 INFO - Intel Iris: 16:42:57 INFO - Chipset Model: Intel Iris 16:42:57 INFO - Type: GPU 16:42:57 INFO - Bus: Built-In 16:42:57 INFO - VRAM (Dynamic, Max): 1536 MB 16:42:57 INFO - Vendor: Intel (0x8086) 16:42:57 INFO - Device ID: 0x0a2e 16:42:57 INFO - Revision ID: 0x0009 16:42:57 INFO - Displays: 16:42:57 INFO - Display: 16:42:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:42:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:42:57 INFO - Display Serial Number: 1600x1200 60 16:42:57 INFO - Main Display: Yes 16:42:57 INFO - Mirror: Off 16:42:57 INFO - Online: Yes 16:42:57 INFO - Rotation: Supported 16:42:57 INFO - Return code: 0 16:42:57 INFO - Running post-action listener: _resource_record_post_action 16:42:57 INFO - [mozharness: 2016-08-22 23:42:57.416451Z] Finished run-tests step (success) 16:42:57 INFO - Running post-run listener: _resource_record_post_run 16:42:57 INFO - Total resource usage - Wall time: 1214s; CPU: 53.0%; Read bytes: 36892672; Write bytes: 706576384; Read time: 245; Write time: 1959 16:42:57 INFO - TinderboxPrint: CPU usage
53.4% 16:42:57 INFO - TinderboxPrint: I/O read bytes / time
36,892,672 / 245 16:42:57 INFO - TinderboxPrint: I/O write bytes / time
706,576,384 / 1,959 16:42:57 INFO - TinderboxPrint: CPU idle
2,261.7 (46.6%) 16:42:57 INFO - TinderboxPrint: CPU system
967.7 (19.9%) 16:42:57 INFO - TinderboxPrint: CPU user
1,628.3 (33.5%) 16:42:57 INFO - TinderboxPrint: Swap in / out
442,966,016 / 0 16:42:57 INFO - install - Wall time: 36s; CPU: 55.0%; Read bytes: 395901952; Write bytes: 386209280; Read time: 30389; Write time: 782 16:42:57 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 30989312; Write bytes: 296884736; Read time: 186; Write time: 1124 16:42:57 INFO - Running post-run listener: _upload_blobber_files 16:42:57 INFO - Blob upload gear active. 16:42:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:42:57 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:42:57 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', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:42:57 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 Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:42:58 INFO - (blobuploader) - INFO - Open directory for files ... 16:42:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:42:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:42:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:42:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:42:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:42:59 INFO - (blobuploader) - INFO - Done attempting. 16:42:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:42:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:42:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:43:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:43:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:43:00 INFO - (blobuploader) - INFO - Done attempting. 16:43:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:43:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:43:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:43:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:43:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:43:03 INFO - (blobuploader) - INFO - Done attempting. 16:43:03 INFO - (blobuploader) - INFO - Iteration through files over. 16:43:03 INFO - Return code: 0 16:43:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:43:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:43:03 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/be600fb0d0489663e3ee67e8d1998ec862beeebe4e65d1f4353949ec61d0858fc048d5d348ac798b522ed322fe8443a23885b40cdf3b913a5c8f29020ee2d289", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/00a861098feb6d44828ba9dc597afedea68df7e1063a01d26efc197e88eb14bd81c48320b0e66e1b2debd46d5a0f4f1dd2c8dc0236f6ab69cf389295db6bc56e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/f922806d77e10135c6f5f9a4041dcc4558b4feeefc34e586081d2cceac5cf21fe61d8547e1a934704ab0662752c202b882b08084abc2bc1ddd22e1be7e107047"} 16:43:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:43:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:43:03 INFO - Contents: 16:43:03 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/be600fb0d0489663e3ee67e8d1998ec862beeebe4e65d1f4353949ec61d0858fc048d5d348ac798b522ed322fe8443a23885b40cdf3b913a5c8f29020ee2d289", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/00a861098feb6d44828ba9dc597afedea68df7e1063a01d26efc197e88eb14bd81c48320b0e66e1b2debd46d5a0f4f1dd2c8dc0236f6ab69cf389295db6bc56e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/f922806d77e10135c6f5f9a4041dcc4558b4feeefc34e586081d2cceac5cf21fe61d8547e1a934704ab0662752c202b882b08084abc2bc1ddd22e1be7e107047"} 16:43:03 INFO - Running post-run listener: copy_logs_to_upload_dir 16:43:03 INFO - Copying logs to upload dir... 16:43:03 INFO - mkdir: /builds/slave/test/build/upload/logs 16:43:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.379231 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-08-22 16:43:03.401876) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-22 16:43:03.405082) ========= 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.eL4rUgHkee/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.J8U3lE652J/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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/Larch/sha512/be600fb0d0489663e3ee67e8d1998ec862beeebe4e65d1f4353949ec61d0858fc048d5d348ac798b522ed322fe8443a23885b40cdf3b913a5c8f29020ee2d289", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/00a861098feb6d44828ba9dc597afedea68df7e1063a01d26efc197e88eb14bd81c48320b0e66e1b2debd46d5a0f4f1dd2c8dc0236f6ab69cf389295db6bc56e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/f922806d77e10135c6f5f9a4041dcc4558b4feeefc34e586081d2cceac5cf21fe61d8547e1a934704ab0662752c202b882b08084abc2bc1ddd22e1be7e107047"} build_url:https://queue.taskcluster.net/v1/task/Zjxa5YVHSvCe0KZHE-EQ5w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010852 build_url: 'https://queue.taskcluster.net/v1/task/Zjxa5YVHSvCe0KZHE-EQ5w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/be600fb0d0489663e3ee67e8d1998ec862beeebe4e65d1f4353949ec61d0858fc048d5d348ac798b522ed322fe8443a23885b40cdf3b913a5c8f29020ee2d289", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/00a861098feb6d44828ba9dc597afedea68df7e1063a01d26efc197e88eb14bd81c48320b0e66e1b2debd46d5a0f4f1dd2c8dc0236f6ab69cf389295db6bc56e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/f922806d77e10135c6f5f9a4041dcc4558b4feeefc34e586081d2cceac5cf21fe61d8547e1a934704ab0662752c202b882b08084abc2bc1ddd22e1be7e107047"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-22 16:43:03.437830) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 16:43:03.438150) ========= 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.eL4rUgHkee/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.J8U3lE652J/Listeners TMPDIR=/var/folders/kx/2q83flzx3dn01nvjsllhhyd000000w/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.004666 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 16:43:03.486585) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-22 16:43:03.486905) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-22 16:43:03.487233) ========= ========= Total master_lag: 0.17 =========