builder: larch_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0195 starttime: 1472519264.29 results: success (0) buildid: 20160829145731 builduid: abd1db8157124b098f748bfcd580f142 revision: e980aee95cb2ab7503daa5575dac1e649fbac4d0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-29 18:07:44.293597) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-29 18:07:44.294030) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 3 secs) (at 2016-08-29 18:07:44.294304) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 3 secs) (at 2016-08-29 18:07:48.139234) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 18:07:48.139582) ========= 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.XCu3OLqzb4/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.4xn40rzCup/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-08-29 18:07:48-- 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% 165M=0s 2016-08-29 18:07:48 (165 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.159270 ========= master_lag: 0.67 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 18:07:48.965078) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 18:07:48.965414) ========= 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.XCu3OLqzb4/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.4xn40rzCup/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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.027881 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 18:07:49.017991) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 18:07:49.018307) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev e980aee95cb2ab7503daa5575dac1e649fbac4d0 --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 e980aee95cb2ab7503daa5575dac1e649fbac4d0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.XCu3OLqzb4/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.4xn40rzCup/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-08-29 18:07:49,092 truncating revision to first 12 chars 2016-08-29 18:07:49,092 Setting DEBUG logging. 2016-08-29 18:07:49,092 attempt 1/10 2016-08-29 18:07:49,092 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/e980aee95cb2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-29 18:07:49,371 unpacking tar archive at: larch-e980aee95cb2/testing/mozharness/ program finished with exit code 0 elapsedTime=0.529234 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 18:07:49.570444) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-29 18:07:49.570754) ========= script_repo_revision: e980aee95cb2ab7503daa5575dac1e649fbac4d0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-29 18:07:49.571159) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-29 18:07:49.571447) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-29 18:07:49.589988) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-08-29 18:07:49.590294) ========= /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.XCu3OLqzb4/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.4xn40rzCup/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 18:07:49 INFO - MultiFileLogger online at 20160829 18:07:49 in /builds/slave/test 18:07:49 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 18:07:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:07:49 INFO - {'append_to_log': False, 18:07:49 INFO - 'base_work_dir': '/builds/slave/test', 18:07:49 INFO - 'blob_upload_branch': 'Larch', 18:07:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:07:49 INFO - 'branch': 'Larch', 18:07:49 INFO - 'buildbot_json_path': 'buildprops.json', 18:07:49 INFO - 'config_files': ('talos/mac_config.py',), 18:07:49 INFO - 'default_actions': ('clobber', 18:07:49 INFO - 'read-buildbot-config', 18:07:49 INFO - 'download-and-extract', 18:07:49 INFO - 'populate-webroot', 18:07:49 INFO - 'create-virtualenv', 18:07:49 INFO - 'install', 18:07:49 INFO - 'run-tests'), 18:07:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:07:49 INFO - 'download_minidump_stackwalk': True, 18:07:49 INFO - 'download_symbols': 'ondemand', 18:07:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:07:49 INFO - 'tooltool.py': '/tools/tooltool.py', 18:07:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:07:49 INFO - '/tools/misc-python/virtualenv.py')}, 18:07:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:07:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:07:49 INFO - 'installer_path': 'installer.exe', 18:07:49 INFO - 'log_level': 'info', 18:07:49 INFO - 'log_name': 'talos', 18:07:49 INFO - 'log_to_console': True, 18:07:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:07:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:07:49 INFO - 'opt_config_files': (), 18:07:49 INFO - 'pip_index': False, 18:07:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:07:49 INFO - 'cmd': ('bash', 18:07:49 INFO - '-c', 18:07:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:07:49 INFO - 'enabled': True, 18:07:49 INFO - 'halt_on_failure': False, 18:07:49 INFO - 'name': 'check_screen_resolution'},), 18:07:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:07:49 INFO - 'cmd': ('bash', 18:07:49 INFO - '-c', 18:07:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:07:49 INFO - 'enabled': True, 18:07:49 INFO - 'halt_on_failure': False, 18:07:49 INFO - 'name': 'check_screen_resolution'},), 18:07:49 INFO - 'run_cmd_checks_enabled': True, 18:07:49 INFO - 'sps_profile': False, 18:07:49 INFO - 'sps_profile_interval': 0, 18:07:49 INFO - 'suite': 'dromaeojs', 18:07:49 INFO - 'system_bits': '32', 18:07:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:07:49 INFO - 'title': 't-yosemite-r7-0195', 18:07:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:07:49 INFO - 'use_talos_json': True, 18:07:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:07:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:07:49 INFO - 'work_dir': 'build'} 18:07:49 INFO - [mozharness: 2016-08-30 01:07:49.787680Z] Running clobber step. 18:07:49 INFO - Running pre-action listener: _resource_record_pre_action 18:07:49 INFO - Running main action method: clobber 18:07:49 INFO - rmtree: /builds/slave/test/build 18:07:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:07:51 INFO - Running post-action listener: _resource_record_post_action 18:07:51 INFO - [mozharness: 2016-08-30 01:07:51.899217Z] Finished clobber step (success) 18:07:51 INFO - [mozharness: 2016-08-30 01:07:51.899338Z] Running read-buildbot-config step. 18:07:51 INFO - Running pre-action listener: _resource_record_pre_action 18:07:51 INFO - Running main action method: read_buildbot_config 18:07:51 INFO - Using buildbot properties: 18:07:51 INFO - { 18:07:51 INFO - "project": "", 18:07:51 INFO - "product": "firefox", 18:07:51 INFO - "script_repo_revision": "production", 18:07:51 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs", 18:07:51 INFO - "repository": "", 18:07:51 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs", 18:07:51 INFO - "buildid": "20160829145731", 18:07:51 INFO - "pgo_build": "False", 18:07:51 INFO - "basedir": "/builds/slave/test", 18:07:51 INFO - "buildnumber": 0, 18:07:51 INFO - "slavename": "t-yosemite-r7-0195", 18:07:51 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 18:07:51 INFO - "platform": "yosemite_r7", 18:07:51 INFO - "scheduler": "tests-larch-macosx64-talos", 18:07:51 INFO - "branch": "larch", 18:07:51 INFO - "repo_path": "projects/larch", 18:07:51 INFO - "revision": "e980aee95cb2ab7503daa5575dac1e649fbac4d0", 18:07:51 INFO - "stage_platform": "macosx64", 18:07:51 INFO - "builduid": "abd1db8157124b098f748bfcd580f142", 18:07:51 INFO - "slavebuilddir": "test" 18:07:51 INFO - } 18:07:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/Ysc2XOXESZ-vFMAoqKpTwA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 18:07:51 INFO - Running post-action listener: _resource_record_post_action 18:07:51 INFO - [mozharness: 2016-08-30 01:07:51.902603Z] Finished read-buildbot-config step (success) 18:07:51 INFO - [mozharness: 2016-08-30 01:07:51.902732Z] Running download-and-extract step. 18:07:51 INFO - Running pre-action listener: _resource_record_pre_action 18:07:51 INFO - Running main action method: download_and_extract 18:07:51 INFO - mkdir: /builds/slave/test/build/tests 18:07:51 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')]} 18:07:51 INFO - trying https://queue.taskcluster.net/v1/task/Ysc2XOXESZ-vFMAoqKpTwA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 18:07:51 INFO - Downloading https://queue.taskcluster.net/v1/task/Ysc2XOXESZ-vFMAoqKpTwA/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 18:07:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ysc2XOXESZ-vFMAoqKpTwA/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 18:07:53 INFO - Downloaded 1199 bytes. 18:07:53 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 18:07:53 INFO - Using the following test package requirements: 18:07:53 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 18:07:53 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:07:53 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 18:07:53 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:07:53 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 18:07:53 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:07:53 INFO - u'jsshell-mac.zip'], 18:07:53 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:07:53 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 18:07:53 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 18:07:53 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:07:53 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 18:07:53 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:07:53 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 18:07:53 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:07:53 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 18:07:53 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:07:53 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 18:07:53 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:07:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:07:53 INFO - trying https://queue.taskcluster.net/v1/task/Ysc2XOXESZ-vFMAoqKpTwA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:07:53 INFO - Downloading https://queue.taskcluster.net/v1/task/Ysc2XOXESZ-vFMAoqKpTwA/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 18:07:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ysc2XOXESZ-vFMAoqKpTwA/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 18:07:54 INFO - Downloaded 16369901 bytes. 18:07:54 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 18:08:01 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 18:08:01 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')]} 18:08:01 INFO - trying https://queue.taskcluster.net/v1/task/Ysc2XOXESZ-vFMAoqKpTwA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:08:01 INFO - Downloading https://queue.taskcluster.net/v1/task/Ysc2XOXESZ-vFMAoqKpTwA/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 18:08:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ysc2XOXESZ-vFMAoqKpTwA/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 18:08:01 INFO - Downloaded 16369901 bytes. 18:08:01 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 18:08:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:08:08 INFO - trying https://queue.taskcluster.net/v1/task/Ysc2XOXESZ-vFMAoqKpTwA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 18:08:08 INFO - Downloading https://queue.taskcluster.net/v1/task/Ysc2XOXESZ-vFMAoqKpTwA/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 18:08:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ysc2XOXESZ-vFMAoqKpTwA/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 18:08:09 INFO - Downloaded 14413998 bytes. 18:08:09 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 18:08:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:08:09 INFO - trying https://queue.taskcluster.net/v1/task/Ysc2XOXESZ-vFMAoqKpTwA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:08:09 INFO - Downloading https://queue.taskcluster.net/v1/task/Ysc2XOXESZ-vFMAoqKpTwA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 18:08:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ysc2XOXESZ-vFMAoqKpTwA/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 18:08:12 INFO - Downloaded 111607536 bytes. 18:08:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ysc2XOXESZ-vFMAoqKpTwA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:08:12 INFO - mkdir: /builds/slave/test/properties 18:08:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:08:12 INFO - Writing to file /builds/slave/test/properties/build_url 18:08:12 INFO - Contents: 18:08:12 INFO - build_url:https://queue.taskcluster.net/v1/task/Ysc2XOXESZ-vFMAoqKpTwA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:08:13 INFO - Running post-action listener: _resource_record_post_action 18:08:13 INFO - Running post-action listener: set_extra_try_arguments 18:08:13 INFO - [mozharness: 2016-08-30 01:08:13.355954Z] Finished download-and-extract step (success) 18:08:13 INFO - [mozharness: 2016-08-30 01:08:13.356082Z] Running populate-webroot step. 18:08:13 INFO - Running pre-action listener: _resource_record_pre_action 18:08:13 INFO - Running main action method: populate_webroot 18:08:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:08:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:08:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:08:13 INFO - '--tppagecycles', 18:08:13 INFO - '7'], 18:08:13 INFO - 'tests': ['tsvgm']}}, 18:08:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:08:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:08:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:08:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:08:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:08:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:08:13 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:08:13 INFO - 'talos_options': ['--disable-e10s'], 18:08:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:08:13 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:08:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:08:13 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:08:13 INFO - 'talos_options': ['--disable-e10s'], 18:08:13 INFO - 'tests': ['damp', 'tps']}, 18:08:13 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:08:13 INFO - 'tests': ['damp', 'tps']}, 18:08:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:08:13 INFO - 'tests': ['dromaeo_dom']}, 18:08:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:08:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:08:13 INFO - 'tests': ['basic_compositor_video']}, 18:08:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:08:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:08:13 INFO - 'tests': ['a11yr', 18:08:13 INFO - 'ts_paint', 18:08:13 INFO - 'tpaint', 18:08:13 INFO - 'sessionrestore', 18:08:13 INFO - 'sessionrestore_no_auto_restore', 18:08:13 INFO - 'tabpaint']}, 18:08:13 INFO - 'other-e10s': {'tests': ['a11yr', 18:08:13 INFO - 'ts_paint', 18:08:13 INFO - 'tpaint', 18:08:13 INFO - 'sessionrestore', 18:08:13 INFO - 'sessionrestore_no_auto_restore', 18:08:13 INFO - 'tabpaint']}, 18:08:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:08:13 INFO - 'ts_paint', 18:08:13 INFO - 'tpaint', 18:08:13 INFO - 'sessionrestore', 18:08:13 INFO - 'sessionrestore_no_auto_restore', 18:08:13 INFO - 'tabpaint']}, 18:08:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:08:13 INFO - 'ts_paint', 18:08:13 INFO - 'tpaint', 18:08:13 INFO - 'sessionrestore', 18:08:13 INFO - 'sessionrestore_no_auto_restore', 18:08:13 INFO - 'tabpaint']}, 18:08:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:08:13 INFO - 'tests': ['a11yr', 18:08:13 INFO - 'ts_paint', 18:08:13 INFO - 'tpaint', 18:08:13 INFO - 'sessionrestore', 18:08:13 INFO - 'sessionrestore_no_auto_restore', 18:08:13 INFO - 'tabpaint']}, 18:08:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:08:13 INFO - 'tests': ['a11yr', 18:08:13 INFO - 'ts_paint', 18:08:13 INFO - 'tpaint', 18:08:13 INFO - 'sessionrestore', 18:08:13 INFO - 'sessionrestore_no_auto_restore', 18:08:13 INFO - 'tabpaint']}, 18:08:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:08:13 INFO - 'tests': ['tsvgx', 18:08:13 INFO - 'tsvgr_opacity', 18:08:13 INFO - 'tart', 18:08:13 INFO - 'tscrollx', 18:08:13 INFO - 'cart']}, 18:08:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:08:13 INFO - 'tsvgr_opacity', 18:08:13 INFO - 'tart', 18:08:13 INFO - 'tscrollx', 18:08:13 INFO - 'cart']}, 18:08:13 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:08:13 INFO - 'talos_options': ['--disable-e10s'], 18:08:13 INFO - 'tests': ['tp5o']}, 18:08:13 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:08:13 INFO - 'tests': ['tp5o']}, 18:08:13 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:08:13 INFO - 'talos_options': ['--disable-e10s', 18:08:13 INFO - '--xperf_path', 18:08:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:08:13 INFO - 'tests': ['tp5n']}, 18:08:13 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:08:13 INFO - 'talos_options': ['--xperf_path', 18:08:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:08:13 INFO - 'tests': ['tp5n']}}, 18:08:13 INFO - 'talos.zip': {'path': '', 18:08:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:08:13 INFO - Running post-action listener: _resource_record_post_action 18:08:13 INFO - [mozharness: 2016-08-30 01:08:13.375330Z] Finished populate-webroot step (success) 18:08:13 INFO - [mozharness: 2016-08-30 01:08:13.375448Z] Running create-virtualenv step. 18:08:13 INFO - Running pre-action listener: _resource_record_pre_action 18:08:13 INFO - Running main action method: create_virtualenv 18:08:13 INFO - Creating virtualenv /builds/slave/test/build/venv 18:08:13 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 18:08:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:08:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:08:13 INFO - Using real prefix '/tools/python27' 18:08:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:08:14 INFO - Installing distribute.............................................................................................................................................................................................done. 18:08:17 INFO - Installing pip.................done. 18:08:17 INFO - Return code: 0 18:08:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:08:17 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')]} 18:08:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:17 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')]} 18:08:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:17 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 0x103b9fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c34618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd830ce36a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b03d30>, '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 0x103868db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103afbb20>, '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.XCu3OLqzb4/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.4xn40rzCup/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 18:08:17 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 18:08:17 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 18:08:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XCu3OLqzb4/Render', 18:08:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:08:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:08:17 INFO - 'HOME': '/Users/cltbld', 18:08:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:08:17 INFO - 'LOGNAME': 'cltbld', 18:08:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:17 INFO - 'MOZ_NO_REMOTE': '1', 18:08:17 INFO - 'NO_EM_RESTART': '1', 18:08:17 INFO - 'PAGER': '/bin/cat', 18:08:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:08:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:17 INFO - 'PWD': '/builds/slave/test', 18:08:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:08:17 INFO - 'SHELL': '/bin/bash', 18:08:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4xn40rzCup/Listeners', 18:08:17 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 18:08:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:08:17 INFO - 'USER': 'cltbld', 18:08:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:08:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:08:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:17 INFO - 'XPC_FLAGS': '0x0', 18:08:17 INFO - 'XPC_SERVICE_NAME': '0', 18:08:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:08:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:08:17 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 18:08:17 INFO - Cleaning up... 18:08:17 INFO - Return code: 0 18:08:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:08:17 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')]} 18:08:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:17 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')]} 18:08:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:17 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 0x103b9fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c34618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd830ce36a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b03d30>, '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 0x103868db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103afbb20>, '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.XCu3OLqzb4/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.4xn40rzCup/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 18:08:17 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 18:08:17 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 18:08:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XCu3OLqzb4/Render', 18:08:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:08:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:08:17 INFO - 'HOME': '/Users/cltbld', 18:08:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:08:17 INFO - 'LOGNAME': 'cltbld', 18:08:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:17 INFO - 'MOZ_NO_REMOTE': '1', 18:08:17 INFO - 'NO_EM_RESTART': '1', 18:08:17 INFO - 'PAGER': '/bin/cat', 18:08:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:08:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:17 INFO - 'PWD': '/builds/slave/test', 18:08:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:08:17 INFO - 'SHELL': '/bin/bash', 18:08:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4xn40rzCup/Listeners', 18:08:17 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 18:08:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:08:17 INFO - 'USER': 'cltbld', 18:08:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:08:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:08:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:17 INFO - 'XPC_FLAGS': '0x0', 18:08:17 INFO - 'XPC_SERVICE_NAME': '0', 18:08:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:08:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:08:17 INFO - Downloading/unpacking psutil>=3.1.1 18:08:17 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 18:08:17 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 18:08:17 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 18:08:17 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 18:08:17 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 18:08:17 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 18:08:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:08:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:08:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:08:20 INFO - Installing collected packages: psutil 18:08:20 INFO - Running setup.py install for psutil 18:08:21 INFO - building 'psutil._psutil_osx' extension 18:08:21 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 18:08:21 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 18:08:21 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 18:08:21 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 18:08:21 INFO - building 'psutil._psutil_posix' extension 18:08:21 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 18:08:21 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:08:21 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:08:21 INFO - ^ 18:08:21 INFO - 1 warning generated. 18:08:21 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 18:08:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:08:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:08:22 INFO - Successfully installed psutil 18:08:22 INFO - Cleaning up... 18:08:22 INFO - Return code: 0 18:08:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:08: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')]} 18:08:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08: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')]} 18:08:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103b9fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c34618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd830ce36a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b03d30>, '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 0x103868db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103afbb20>, '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.XCu3OLqzb4/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.4xn40rzCup/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 18:08: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:08: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 mozsystemmonitor==0.3 18:08:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XCu3OLqzb4/Render', 18:08:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:08:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:08:22 INFO - 'HOME': '/Users/cltbld', 18:08:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:08:22 INFO - 'LOGNAME': 'cltbld', 18:08:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:22 INFO - 'MOZ_NO_REMOTE': '1', 18:08:22 INFO - 'NO_EM_RESTART': '1', 18:08:22 INFO - 'PAGER': '/bin/cat', 18:08:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:08:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:22 INFO - 'PWD': '/builds/slave/test', 18:08:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:08:22 INFO - 'SHELL': '/bin/bash', 18:08:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4xn40rzCup/Listeners', 18:08:22 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 18:08:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:08:22 INFO - 'USER': 'cltbld', 18:08:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:08:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:08:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:22 INFO - 'XPC_FLAGS': '0x0', 18:08:22 INFO - 'XPC_SERVICE_NAME': '0', 18:08:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:08:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:08:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:08: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 18:08: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 18:08: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 18:08: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 18:08: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 18:08: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 18:08:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:08:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:08:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:08:25 INFO - Installing collected packages: mozsystemmonitor 18:08:25 INFO - Running setup.py install for mozsystemmonitor 18:08:25 INFO - Successfully installed mozsystemmonitor 18:08:25 INFO - Cleaning up... 18:08:25 INFO - Return code: 0 18:08:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:08:25 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')]} 18:08:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:25 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')]} 18:08:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:25 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 0x103b9fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c34618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd830ce36a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b03d30>, '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 0x103868db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103afbb20>, '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.XCu3OLqzb4/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.4xn40rzCup/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 18:08:25 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 18:08:25 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 18:08:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XCu3OLqzb4/Render', 18:08:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:08:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:08:25 INFO - 'HOME': '/Users/cltbld', 18:08:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:08:25 INFO - 'LOGNAME': 'cltbld', 18:08:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:25 INFO - 'MOZ_NO_REMOTE': '1', 18:08:25 INFO - 'NO_EM_RESTART': '1', 18:08:25 INFO - 'PAGER': '/bin/cat', 18:08:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:08:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:25 INFO - 'PWD': '/builds/slave/test', 18:08:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:08:25 INFO - 'SHELL': '/bin/bash', 18:08:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4xn40rzCup/Listeners', 18:08:25 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 18:08:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:08:25 INFO - 'USER': 'cltbld', 18:08:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:08:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:08:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:25 INFO - 'XPC_FLAGS': '0x0', 18:08:25 INFO - 'XPC_SERVICE_NAME': '0', 18:08:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:08:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:08:25 INFO - Downloading/unpacking jsonschema==2.5.1 18:08: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 18:08: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 18:08: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 18:08: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 18:08: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 18:08: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 18:08:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:08:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:08:28 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 18:08:28 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 18:08:28 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 18:08:28 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 18:08:28 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 18:08:28 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 18:08:28 INFO - Downloading functools32-3.2.3-2.tar.gz 18:08:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:08:28 INFO - warning: no files found matching '*.txt' 18:08:28 INFO - no previously-included directories found matching 'build' 18:08:28 INFO - no previously-included directories found matching 'dist' 18:08:28 INFO - no previously-included directories found matching '.git*' 18:08:28 INFO - Installing collected packages: jsonschema, functools32 18:08:28 INFO - Running setup.py install for functools32 18:08:28 INFO - warning: no files found matching '*.txt' 18:08:28 INFO - no previously-included directories found matching 'build' 18:08:28 INFO - no previously-included directories found matching 'dist' 18:08:28 INFO - no previously-included directories found matching '.git*' 18:08:28 INFO - Successfully installed jsonschema functools32 18:08:28 INFO - Cleaning up... 18:08:28 INFO - Return code: 0 18:08:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:08:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:08:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:08:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:28 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 0x103b9fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c34618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd830ce36a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b03d30>, '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 0x103868db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103afbb20>, '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.XCu3OLqzb4/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.4xn40rzCup/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 18:08:28 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 18:08:28 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 18:08:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XCu3OLqzb4/Render', 18:08:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:08:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:08:28 INFO - 'HOME': '/Users/cltbld', 18:08:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:08:28 INFO - 'LOGNAME': 'cltbld', 18:08:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:28 INFO - 'MOZ_NO_REMOTE': '1', 18:08:28 INFO - 'NO_EM_RESTART': '1', 18:08:28 INFO - 'PAGER': '/bin/cat', 18:08:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:08:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:28 INFO - 'PWD': '/builds/slave/test', 18:08:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:08:28 INFO - 'SHELL': '/bin/bash', 18:08:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4xn40rzCup/Listeners', 18:08:28 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 18:08:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:08:28 INFO - 'USER': 'cltbld', 18:08:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:08:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:08:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:28 INFO - 'XPC_FLAGS': '0x0', 18:08:28 INFO - 'XPC_SERVICE_NAME': '0', 18:08:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:08:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:08:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:08:29 INFO - Cleaning up... 18:08:29 INFO - Return code: 0 18:08:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:08:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:08:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:08:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:29 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 0x103b9fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c34618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd830ce36a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b03d30>, '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 0x103868db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103afbb20>, '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.XCu3OLqzb4/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.4xn40rzCup/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 18:08:29 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 18:08:29 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 18:08:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XCu3OLqzb4/Render', 18:08:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:08:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:08:29 INFO - 'HOME': '/Users/cltbld', 18:08:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:08:29 INFO - 'LOGNAME': 'cltbld', 18:08:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:29 INFO - 'MOZ_NO_REMOTE': '1', 18:08:29 INFO - 'NO_EM_RESTART': '1', 18:08:29 INFO - 'PAGER': '/bin/cat', 18:08:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:08:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:29 INFO - 'PWD': '/builds/slave/test', 18:08:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:08:29 INFO - 'SHELL': '/bin/bash', 18:08:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4xn40rzCup/Listeners', 18:08:29 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 18:08:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:08:29 INFO - 'USER': 'cltbld', 18:08:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:08:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:08:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:29 INFO - 'XPC_FLAGS': '0x0', 18:08:29 INFO - 'XPC_SERVICE_NAME': '0', 18:08:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:08:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:08:29 INFO - Downloading/unpacking blobuploader==1.2.4 18:08: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 18:08: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 18:08: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 18:08: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 18:08: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 18:08: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 18:08:31 INFO - Downloading blobuploader-1.2.4.tar.gz 18:08:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:08:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:08:31 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 18:08:31 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 18:08:31 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 18:08:31 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 18:08:31 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 18:08:31 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 18:08:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:08:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:08:32 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 18:08:32 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 18:08:32 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 18:08:32 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 18:08:32 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 18:08:32 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 18:08:32 INFO - Downloading docopt-0.6.1.tar.gz 18:08:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:08:33 INFO - Installing collected packages: blobuploader, requests, docopt 18:08:33 INFO - Running setup.py install for blobuploader 18:08:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:08:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:08:33 INFO - Running setup.py install for requests 18:08:33 INFO - Running setup.py install for docopt 18:08:33 INFO - Successfully installed blobuploader requests docopt 18:08:33 INFO - Cleaning up... 18:08:33 INFO - Return code: 0 18:08:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:08:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:08:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:08:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:33 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 0x103b9fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c34618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd830ce36a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b03d30>, '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 0x103868db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103afbb20>, '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.XCu3OLqzb4/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.4xn40rzCup/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 18:08:33 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 18:08:33 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 18:08:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XCu3OLqzb4/Render', 18:08:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:08:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:08:33 INFO - 'HOME': '/Users/cltbld', 18:08:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:08:33 INFO - 'LOGNAME': 'cltbld', 18:08:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:33 INFO - 'MOZ_NO_REMOTE': '1', 18:08:33 INFO - 'NO_EM_RESTART': '1', 18:08:33 INFO - 'PAGER': '/bin/cat', 18:08:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:08:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:33 INFO - 'PWD': '/builds/slave/test', 18:08:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:08:33 INFO - 'SHELL': '/bin/bash', 18:08:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4xn40rzCup/Listeners', 18:08:33 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 18:08:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:08:33 INFO - 'USER': 'cltbld', 18:08:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:08:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:08:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:33 INFO - 'XPC_FLAGS': '0x0', 18:08:33 INFO - 'XPC_SERVICE_NAME': '0', 18:08:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:08:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:08:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:08:34 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-KSkbcw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:08:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:08:34 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-5OPHfV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:08:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:08:34 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-G4eztj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:08:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:08:34 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-o9VBnZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:08:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:08:34 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-Y5Y1fR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:08:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:08:34 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-n0JYYF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:08:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:08:34 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-rkaYHi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:08:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:08:34 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-y5NRQN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:08:35 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-rGQEmZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:08:35 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-WEtFCU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:08:35 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-iSeeUz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:08:35 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-vhoV_H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:08:35 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-DEKrS7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:08:35 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-hMs6ob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:08:35 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-UDSy6c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:08:35 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-mYEZtV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:08:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:08:35 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-xfpUfA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:08:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:08:36 INFO - Running setup.py install for manifestparser 18:08:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:08:36 INFO - Running setup.py install for mozcrash 18:08:36 INFO - Running setup.py install for mozdebug 18:08:36 INFO - Running setup.py install for mozdevice 18:08:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:08:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:08:36 INFO - Running setup.py install for mozfile 18:08:36 INFO - Running setup.py install for mozhttpd 18:08:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:08:36 INFO - Running setup.py install for mozinfo 18:08:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:08:37 INFO - Running setup.py install for mozInstall 18:08:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:08:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:08:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:08:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:08:37 INFO - Running setup.py install for mozleak 18:08:37 INFO - Running setup.py install for mozlog 18:08:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:08:37 INFO - Running setup.py install for moznetwork 18:08:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:08:37 INFO - Running setup.py install for mozprocess 18:08:37 INFO - Running setup.py install for mozprofile 18:08:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:08:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:08:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:08:37 INFO - Running setup.py install for mozrunner 18:08:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:08:38 INFO - Running setup.py install for mozscreenshot 18:08:38 INFO - Running setup.py install for moztest 18:08:38 INFO - Running setup.py install for mozversion 18:08:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:08:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:08:38 INFO - Cleaning up... 18:08:38 INFO - Return code: 0 18:08:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:08:38 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')]} 18:08:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:08:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:39 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 0x103b9fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c34618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd830ce36a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b03d30>, '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 0x103868db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103afbb20>, '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.XCu3OLqzb4/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.4xn40rzCup/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 18:08:39 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 18:08:39 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 18:08:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XCu3OLqzb4/Render', 18:08:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:08:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:08:39 INFO - 'HOME': '/Users/cltbld', 18:08:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:08:39 INFO - 'LOGNAME': 'cltbld', 18:08:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:39 INFO - 'MOZ_NO_REMOTE': '1', 18:08:39 INFO - 'NO_EM_RESTART': '1', 18:08:39 INFO - 'PAGER': '/bin/cat', 18:08:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:08:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:39 INFO - 'PWD': '/builds/slave/test', 18:08:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:08:39 INFO - 'SHELL': '/bin/bash', 18:08:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4xn40rzCup/Listeners', 18:08:39 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 18:08:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:08:39 INFO - 'USER': 'cltbld', 18:08:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:08:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:08:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:39 INFO - 'XPC_FLAGS': '0x0', 18:08:39 INFO - 'XPC_SERVICE_NAME': '0', 18:08:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:08:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:08:39 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-IxSEa_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:08:39 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)) 18:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:08:39 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-zrx61p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:08:39 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)) 18:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:08:39 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-oHQ2XB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:08:39 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)) 18:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:08:39 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-dQgr8A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:08:39 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)) 18:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:08:40 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-Qo6__x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:08:40 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)) 18:08:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:08:40 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-YMCUlS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:08:40 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)) 18:08:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:08:40 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-0CpijB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:08:40 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)) 18:08:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:08:40 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-DaWgd3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:08:40 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)) 18:08:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:08:40 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-0U1Sl5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:08:40 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)) 18:08:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:08:40 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-d8Pn3u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:08:40 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)) 18:08:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:08:40 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-DGxjv0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:08:40 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)) 18:08:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:08:40 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-cWIfmF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:08:40 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)) 18:08:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:08:40 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-CjvGrE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:08:40 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)) 18:08:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:08:40 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-A5t0O9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:08:41 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)) 18:08:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:08:41 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-_N5GyH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:08:41 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)) 18:08:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:08:41 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-vjr7xg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:08:41 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)) 18:08:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:08:41 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-6pCLdJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:08:41 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)) 18:08:41 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)) 18:08:41 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)) 18:08:41 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)) 18:08:41 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)) 18:08:41 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)) 18:08:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:08:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:08:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:08:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:08:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:08:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:08:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:08:44 INFO - Downloading blessings-1.6.tar.gz 18:08:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:08:44 INFO - Installing collected packages: blessings 18:08:44 INFO - Running setup.py install for blessings 18:08:44 INFO - Successfully installed blessings 18:08:44 INFO - Cleaning up... 18:08:44 INFO - Return code: 0 18:08:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:08:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:08:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:08:44 INFO - Reading from file tmpfile_stdout 18:08:44 INFO - Current package versions: 18:08:44 INFO - blessings == 1.6 18:08:44 INFO - blobuploader == 1.2.4 18:08:44 INFO - docopt == 0.6.1 18:08:44 INFO - functools32 == 3.2.3-2 18:08:44 INFO - jsonschema == 2.5.1 18:08:44 INFO - manifestparser == 1.1 18:08:44 INFO - mozInstall == 1.12 18:08:44 INFO - mozcrash == 0.17 18:08:44 INFO - mozdebug == 0.1 18:08:44 INFO - mozdevice == 0.48 18:08:44 INFO - mozfile == 1.2 18:08:44 INFO - mozhttpd == 0.7 18:08:44 INFO - mozinfo == 0.9 18:08:44 INFO - mozleak == 0.1 18:08:44 INFO - mozlog == 3.3 18:08:44 INFO - moznetwork == 0.27 18:08:44 INFO - mozprocess == 0.23 18:08:44 INFO - mozprofile == 0.28 18:08:44 INFO - mozrunner == 6.12 18:08:44 INFO - mozscreenshot == 0.1 18:08:44 INFO - mozsystemmonitor == 0.3 18:08:44 INFO - moztest == 0.7 18:08:44 INFO - mozversion == 1.4 18:08:44 INFO - psutil == 3.1.1 18:08:44 INFO - requests == 1.2.3 18:08:44 INFO - wsgiref == 0.1.2 18:08:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:08:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:08:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:08:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:44 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 0x103b9fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c34618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd830ce36a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b03d30>, '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 0x103868db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103afbb20>, '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.XCu3OLqzb4/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.4xn40rzCup/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 18:08:44 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 18:08:44 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 18:08:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XCu3OLqzb4/Render', 18:08:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:08:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:08:44 INFO - 'HOME': '/Users/cltbld', 18:08:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:08:44 INFO - 'LOGNAME': 'cltbld', 18:08:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:44 INFO - 'MOZ_NO_REMOTE': '1', 18:08:44 INFO - 'NO_EM_RESTART': '1', 18:08:44 INFO - 'PAGER': '/bin/cat', 18:08:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:08:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:44 INFO - 'PWD': '/builds/slave/test', 18:08:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:08:44 INFO - 'SHELL': '/bin/bash', 18:08:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4xn40rzCup/Listeners', 18:08:44 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 18:08:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:08:44 INFO - 'USER': 'cltbld', 18:08:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:08:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:08:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:44 INFO - 'XPC_FLAGS': '0x0', 18:08:44 INFO - 'XPC_SERVICE_NAME': '0', 18:08:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:08:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:08:45 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)) 18:08:45 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)) 18:08:45 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)) 18:08:45 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)) 18:08:45 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)) 18:08:45 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)) 18:08:45 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)) 18:08:45 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)) 18:08:45 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)) 18:08:45 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)) 18:08:45 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)) 18:08:45 INFO - Cleaning up... 18:08:45 INFO - Return code: 0 18:08:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:08:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:08:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:08:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:08:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:08:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:08:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103b9fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c34618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd830ce36a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b03d30>, '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 0x103868db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103afbb20>, '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.XCu3OLqzb4/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.4xn40rzCup/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 18:08:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:08:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:08:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XCu3OLqzb4/Render', 18:08:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:08:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:08:45 INFO - 'HOME': '/Users/cltbld', 18:08:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:08:45 INFO - 'LOGNAME': 'cltbld', 18:08:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:08:45 INFO - 'MOZ_NO_REMOTE': '1', 18:08:45 INFO - 'NO_EM_RESTART': '1', 18:08:45 INFO - 'PAGER': '/bin/cat', 18:08:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:08:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:08:45 INFO - 'PWD': '/builds/slave/test', 18:08:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:08:45 INFO - 'SHELL': '/bin/bash', 18:08:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4xn40rzCup/Listeners', 18:08:45 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 18:08:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:08:45 INFO - 'USER': 'cltbld', 18:08:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:08:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:08:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:08:45 INFO - 'XPC_FLAGS': '0x0', 18:08:45 INFO - 'XPC_SERVICE_NAME': '0', 18:08:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:08:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:08:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:08:45 INFO - Cleaning up... 18:08:45 INFO - Return code: 0 18:08:45 INFO - Running post-action listener: _resource_record_post_action 18:08:45 INFO - Running post-action listener: _start_resource_monitoring 18:08:45 INFO - Starting resource monitoring. 18:08:45 INFO - [mozharness: 2016-08-30 01:08:45.522353Z] Finished create-virtualenv step (success) 18:08:45 INFO - [mozharness: 2016-08-30 01:08:45.523313Z] Running install step. 18:08:45 INFO - Running pre-action listener: _resource_record_pre_action 18:08:45 INFO - Running main action method: install 18:08:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:08:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:08:45 INFO - Reading from file tmpfile_stdout 18:08:45 INFO - Detecting whether we're running mozinstall >=1.0... 18:08:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:08:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:08:45 INFO - Reading from file tmpfile_stdout 18:08:45 INFO - Output received: 18:08:45 INFO - Usage: mozinstall [options] installer 18:08:45 INFO - Options: 18:08:45 INFO - -h, --help show this help message and exit 18:08:45 INFO - -d DEST, --destination=DEST 18:08:45 INFO - Directory to install application into. [default: 18:08:45 INFO - "/builds/slave/test"] 18:08:45 INFO - --app=APP Application being installed. [default: firefox] 18:08:45 INFO - mkdir: /builds/slave/test/build/application 18:08:45 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'] 18:08:45 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 18:09:20 INFO - Reading from file tmpfile_stdout 18:09:20 INFO - Output received: 18:09:20 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:09:20 INFO - Running post-action listener: _resource_record_post_action 18:09:20 INFO - [mozharness: 2016-08-30 01:09:20.057051Z] Finished install step (success) 18:09:20 INFO - [mozharness: 2016-08-30 01:09:20.057185Z] Running run-tests step. 18:09:20 INFO - Running pre-action listener: _resource_record_pre_action 18:09:20 INFO - Running main action method: run_tests 18:09:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:09:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:09:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:09:20 INFO - 2016-08-29 18:09:20.070 screenresolution[1635:7727] starting screenresolution argv=screenresolution get 18:09:20 INFO - 2016-08-29 18:09:20.073 screenresolution[1635:7727] Display 0: 1600x1200x32@60 18:09:20 INFO - 2016-08-29 18:09:20.079 screenresolution[1636:7729] starting screenresolution argv=screenresolution list 18:09:20 INFO - Available Modes on Display 0 18:09:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:09:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:09:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:09:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:09:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:09:20 INFO - Intel Iris: 18:09:20 INFO - Chipset Model: Intel Iris 18:09:20 INFO - Type: GPU 18:09:20 INFO - Bus: Built-In 18:09:20 INFO - VRAM (Dynamic, Max): 1536 MB 18:09:20 INFO - Vendor: Intel (0x8086) 18:09:20 INFO - Device ID: 0x0a2e 18:09:20 INFO - Revision ID: 0x0009 18:09:20 INFO - Displays: 18:09:20 INFO - Display: 18:09:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:09:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:09:20 INFO - Display Serial Number: 1600x1200 60 18:09:20 INFO - Main Display: Yes 18:09:20 INFO - Mirror: Off 18:09:20 INFO - Online: Yes 18:09:20 INFO - Rotation: Supported 18:09:20 INFO - Return code: 0 18:09:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:09:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:09:20 INFO - Python 2.7.3 18:09:20 INFO - Return code: 0 18:09:20 INFO - grabbing minidump binary from tooltool 18:09:20 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')]} 18:09:20 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 0x103b03d30>, '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 0x103868db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103afbb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:09:20 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 18:09:20 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 18:09:20 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 18:09:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:09:20 INFO - Return code: 0 18:09:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:09:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:09:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:09:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:09:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:09:20 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/Ysc2XOXESZ-vFMAoqKpTwA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0195', '--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 18:09:20 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/Ysc2XOXESZ-vFMAoqKpTwA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0195 --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 18:09:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XCu3OLqzb4/Render', 18:09:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:09:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:09:20 INFO - 'HOME': '/Users/cltbld', 18:09:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:09:20 INFO - 'LOGNAME': 'cltbld', 18:09:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:09:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:09:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:09:20 INFO - 'MOZ_NO_REMOTE': '1', 18:09:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:09:20 INFO - 'NO_EM_RESTART': '1', 18:09:20 INFO - 'PAGER': '/bin/cat', 18:09:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:09:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:09:20 INFO - 'PWD': '/builds/slave/test', 18:09:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:09:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:09:20 INFO - 'SHELL': '/bin/bash', 18:09:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4xn40rzCup/Listeners', 18:09:20 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 18:09:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:09:20 INFO - 'USER': 'cltbld', 18:09:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:09:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:09:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:09:20 INFO - 'XPC_FLAGS': '0x0', 18:09:20 INFO - 'XPC_SERVICE_NAME': '0', 18:09:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:09:20 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/Ysc2XOXESZ-vFMAoqKpTwA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0195', '--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 18:09:20 INFO - mozversion application_buildid: 20160829145731 18:09:20 INFO - mozversion application_changeset: e980aee95cb2ab7503daa5575dac1e649fbac4d0 18:09:20 INFO - mozversion application_display_name: Nightly 18:09:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:09:20 INFO - mozversion application_name: Firefox 18:09:20 INFO - mozversion application_remotingname: firefox 18:09:20 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 18:09:20 INFO - mozversion application_vendor: Mozilla 18:09:20 INFO - mozversion application_version: 51.0a1 18:09:20 INFO - mozversion platform_buildid: 20160829145731 18:09:20 INFO - mozversion platform_changeset: e980aee95cb2ab7503daa5575dac1e649fbac4d0 18:09:20 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 18:09:20 INFO - mozversion platform_version: 51.0a1 18:09:20 INFO - using testdate: 1472519360 18:09:20 INFO - actual date: 1472519360 18:09:20 INFO - starting webserver on 'localhost:49263' 18:09:20 INFO - SUITE-START | Running 2 tests 18:09:20 INFO - TEST-START | dromaeo_css 18:09:20 INFO - operating with platform_type : mac_ 18:09:20 INFO - Initialising browser for dromaeo_css test... 18:09:20 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/tmpah_SWA/profile http://localhost:49263/getInfo.html) 18:09:23 INFO - PROCESS | 1646 | __metrics Screen width/height:1600/1200 18:09:23 INFO - PROCESS | 1646 | colorDepth:24 18:09:23 INFO - PROCESS | 1646 | Browser inner width/height: 1024/689 18:09:23 INFO - PROCESS | 1646 | __metrics 18:09:23 INFO - PROCESS | 1646 | 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" 18:09:23 INFO - PROCESS | 1646 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:09:23 INFO - TEST-INFO | 1646: exit 0 18:09:23 INFO - Browser initialized. 18:09:23 INFO - Running cycle 1/1 for dromaeo_css test... 18:09:23 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/tmpah_SWA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:09:35 INFO - PROCESS | 1648 | RSS: Main: 244531200 18:09:35 INFO - PROCESS | 1648 | 18:10:27 INFO - PROCESS | 1648 | 1472519427569 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] 18:12:38 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49263/tests/dromaeo/cssquery-ext.html) 18:12:39 INFO - PROCESS | 1648 | RSS: Main: 353042432 18:12:39 INFO - PROCESS | 1648 | 18:15:42 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-ext.html (next: http://localhost:49263/tests/dromaeo/cssquery-jquery.html) 18:15:43 INFO - PROCESS | 1648 | RSS: Main: 379899904 18:15:43 INFO - PROCESS | 1648 | 18:18:45 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49263/tests/dromaeo/cssquery-mootools.html) 18:18:46 INFO - PROCESS | 1648 | RSS: Main: 343429120 18:18:46 INFO - PROCESS | 1648 | 18:21:48 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49263/tests/dromaeo/cssquery-prototype.html) 18:21:49 INFO - PROCESS | 1648 | RSS: Main: 334585856 18:21:49 INFO - PROCESS | 1648 | 18:24:52 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49263/tests/dromaeo/cssquery-yui.html) 18:24:53 INFO - PROCESS | 1648 | RSS: Main: 328126464 18:24:53 INFO - PROCESS | 1648 | 18:27:56 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-yui.html (next: http://localhost:49263/tests/dromaeo/cssquery-dojo.html) 18:27:57 INFO - PROCESS | 1648 | RSS: Main: 448733184 18:27:57 INFO - PROCESS | 1648 | 18:27:57 INFO - PROCESS | 1648 | __start_tp_report 18:27:57 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 18:27:57 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 18:27:57 INFO - PROCESS | 1648 | |i|pagename|runs| 18:27:57 INFO - PROCESS | 1648 | |0;dojo.html;3320.754716981133;3341.415252330842;3359.1602099475117;3384.255463797964;3417.0944699654597;5507.366652834925;5545.805896793613;5588.441155884409;5680.630759000667;5699.344575373833;5613.9614171378225;5620.269364982547;5706.971465142678;5707.058335374658;5734.025215713328;4664.4402671679445;4697.201475749131;5067.391912970449;5182.144946083891;5272.446393128724;4156.501219853607;5165.121929272014;5240.213967904826;5263.447338029509;5298.178782288752;5107.1828507439;5162.819301324435;5198.064348417283;5204.505571970657;5212.921806172911;5096.439391666913;5155.149400348938;5186.507281808223;5197.688138711689;5204.557612602934;5083.38999320084;5111.616628752859;5115.923261151049;5185.351831021122;5212.53087222148;5587.776488940438;5590.077637189859;5593.8321850344555;5650.802221922213;5660.122680984413;5988.101784732282;6038.486728628028;6200.852842224203;6201.410865967726;6201.813945581594;6095.861538843025;6107.4197951194565;6161.19904412428;6184.226971628546;6195.81412557624;5705.515031222392;5715.371309156031;5722.4277572242445;5740.167675687044;5774.422557744192;6059.311721788214;6180.721867515973;6226.62640241587;6236.282827474898;6238.469730072995;6042.389594827441;6126.303932855801;6133.509319254449;6142.140100385949;6143.880361275353;6214.285357183981;6222.066689996446;6222.160008398808;6230.190075290274;6235.75056997715;5983.730732117066;6024.909626355608;6164.537659675452;6182.412670796267;6200.224971878515;4990.226514890198;6098.847528811815;6116.761878222105;6132.569672417391;6172.506199504029;4940.90118197634;4942.184539551005;4947.851564453073;4954.628402869799;4962.032403681247;4396.230659634615;4420.80106395213;4425.380446737459;4435.423394958635;4489;5434.782608695687;5452.59105567084;5468.043092458804;5502.147167189092;5520.254765606672;6105.15650299495;6113.871604717271;6114.084479019511;6125.47964319526;6129.572745043327;4684.391029166187;5474.808381706621;5482.698451585201;5516.3380394352935;5591.6365436246515;5324.840254792362;5336.519713225751;5340.839774806762;5344.5724342052545;5353.705546194997;5234.345706786652;6350.7777227796805;6353.3329000455215;6354.332795056453;6374.362563743774;5951.464368206882;6080.148779170831;6106.450419462092;6109.84725381869;6139.062858969336;6271.278802937538;6337.144485494584;6337.2395312562785;6354.364563543609;6378.425941665272;6106.786262480792;6112.022076467922;6117.051856962178;6126.019836826086;6128.724207410766;5954.910676339771;6014.127951447103;6020.397960203944;6058.0610005449225;6078.756849725962;5922.615030023035;5930.051191809463;5932.228810254812;5933.614315069507;5948.434898684791;5909.054551271776;5914.763409463745;5922.763089476372;5935.2877654681715;5942.049272116614;5607.130894711327;5712.314522257356;5749.1663708762835;5770.221020162225;5782.10377391505;5929.407059294036;5935.792247271324;5942.64344139353;5952.910706339495;5975.760969561169;5740.167675686918;5776.682282474504;5780.364159942487;5785.074388097884;5785.103308987113 18:27:57 INFO - PROCESS | 1648 | |1;ext.html;475.85724282715177;489.8750818541272;522.3053339059054;523.5994464234861;551.2337850387959;29752.85123574382;30788.23029424263;31755.36489270213;31846.36307273859;31953.840230798873;32217;41516;41913.37129943053;41926.58073419273;43368.34947475782;9532.618695252182;9676.129148376645;9677.177439917616;9683.5958785976;9685.273604479657;9614.903850961471;9705.272104592184;9733.221342292634;9733.367331123489;9733.951330243339;8794.780130496725;8897.68858089967;9967.850482242773;11094.611688590901;11898.405079746022;11761.588344407948;11822.467988940432;11830;11832.82250766235;11873.881261187364;11622.88377116235;11719.824202636968;11720.648380548597;11725.824112638316;11732.120090993125;31193.84403077993;40244.597554024374;40608.39087413662;40646;40822.79588602068;3309.8676052957853;3344.8996530104137;3603.9099022524383;3917.255721412951;4075.6943129615265;26616.60075098875;30047.849760751276;30473.542896856565;30521.23696907573;30593.694063059305;3102.317490152163;3330.8667653293837;3380.6281309055985;3383.1034775784437;3392.2706618076986;504680.95319047075;507122;509218;510327;511022;57083.14375284374;57514;57585.71207144021;57684.134737978966;57732.71133644305;60037.09944350751;60218;60248.69875650681;60287.39712602906;60422.69788651029;15881.52355429339;16132.919335403249;16153.869229153846;16175.43385981485;16199.919000405158;12578.622641320775;12626.989840812714;12630.936845315715;12640.494380224687;12644.936775316248;12293.323867187211;13000.349982501026;13951.162930224218;15456.072635641898;15586.844131558766;5230.424653288135;5990.371011043778;6017.308009578955;6078.756849725962;6115.816525504242;767.5087943716027;819.2135549872189;852.5992783391796;950.7765675066354;983.1643103362085;26696;27098.86450567774;27237;27328.453430931666;27544.449111017668;923.1645360948219;933.7198840347869;950.6150009246267;971.145392054987;1018.8064267789097;13866.514671986431;14089.71820563583;14137.327793570992;14236.932230082784;14250.216238106796;610440;613832.6858948392;616004;616091;616170;49043;49053.26420103701;54894.451055488935;55831.441685583435;56137.157942630896;1316.4207748590588;1334.5662659635657;1347.2522749873915;1351.513455156143;1356.6608347913022;22562.774372256066;27415;27533.86233068822;28469.00358487443;28522.42955140927;47946.280805787246;48427;48444.51555484541;48515.5148448511;48631.27053094277;574335;575955;577070.1146494409;583840;807995;24928.62607060932;25168;25188.244352669448;25270.494590107726;25293.98824047091;45075.32387014263;45933.540664592925;45991.080178396915;46927.53072469232;48406.03187936292;38615.80692096634;38664.80667596644;38692;39679.20641587094;40574.39138412867;37917.62082379141;38055;39214.41178382384;39773.80113099416;40480.1903961925;36707.63292367042;37970.620293797816;38017;38298;38369.23261534698;30794;34947.30105397929;35077.824610877804;35378.292434151685;35657;24699.506009879344;24841.627375589742;24915.626265605668;25079.749202508472;25375.36561585975 18:27:57 INFO - PROCESS | 1648 | |2;jquery.html;311.4207573912522;341.1267156080433;359.46439804691;369.9297133544627;397.4217513517832;15392.61518462039;15403.306851191695;15823.367065317374;21122;21298.935053247355;20045.699314510257;20570.89714551429;21117.577648447023;21389.0374933128;22003.669944950838;5732.624170199143;5952.58331916766;6215.347388524185;6226.034964580497;6234.220722409699;6111.908321375183;6175.197224360849;6228.532860035492;6233.314335423122;6244.250689917217;5467.824417750183;5674.205611214434;6054.5459090568165;6187.907181392283;6194.5354098442795;6035.818925432222;6084.239470066242;6092.08618707193;6106.908396374058;6110.236220472441;5059.924101138486;5256;5279.815206467793;5288.233206185082;5292.417834038252;26827.463450731073;26983.46033079347;27000;27159;27282.863585681946;2321.872297023663;2378.8334816562847;2412.722536908243;2917.5769513420437;3109.54911537828;15270;15328.23358832054;15665.843341566553;16291.755623665655;16362.75455868151;1838.5016211787351;1840.3006857394105;1873.5222518257892;1900.7338972543855;1910.1786231920269;115719;133684;133747.33126334305;136057;137234.31382843122;9804.215662746963;10839.187060970458;10983.176261780398;11040.779184416428;11457.59898403552;14203.218822964836;14211.78682319766;14219.36012879423;14225;14228.359723812453;8176.424426305694;8423.199796019491;8440.915590844135;8552.358573107042;8579.313654907592;8426.325893928471;8460.280876125598;8466.619002144918;8639.704044393391;8652.264557512683;4343.457067866517;4430.822767089345;4434.977825110854;4473.910521789611;4478.932816007762;1496.944654018914;1731.2946747453748;1796.5329014456336;1822.2437688359182;1863.0963982468493;792.3067316098413;850.9446885952393;894.083564346537;907.4373888189365;944.9858252126223;18287.8451285305;20239.392818215478;21799.78200218009;21837.781622183575;21866.562668746537;822.2650208060986;835.7618078847621;841.8737189421636;878.5431575580662;914.7255823253131;10542.841857372145;10635.574577016832;11381.430928453545;11412.31526108436;11418.486168122288;540555;577122;591510;614938;615949;96038;131862;136172;137383.31308343593;141012;841.9452735572169;921.6635927886273;951.6954574536082;994.1599348550463;995.9950200249098;15019;18870.24519019279;20370.79629203748;20406.69389959122;20522.794772052686;52563.737181313845;53435.46564534305;53766.46233537615;53960;54924.72537637446;71994.64002679953;81813.59093204496;81906.59046704728;82027.5898620527;82779.58610206911;7700.075990881131;7700.499467534592;7944.285014348737;8016.198380161937;8186.836263274582;10887.510062047386;13069.673258168621;13497.122687025312;13510.864891351353;13657.863421365659;11169.944150279196;11172.888271117185;11219.270747401393;11628.476718547516;11719.941400292944;11140.554377824796;11223.989840914357;11253.649562052278;11658.300501969909;11766.764664706487;10531.368117912949;11479.540818367506;11768.529258829893;11860.22908510944;12007.979321757399;8042.9597852010365;8058.234467725557;8078.7172448964;8124.796880078045;8186.263236308522;8207.220314070153;9315.208207302456;10427.113695335678;10481.685549433529;10706 18:27:57 INFO - PROCESS | 1648 | |3;mootools.html;1510.1316742872848;1532.0577844625554;1723.129819441182;1824.9726254106183;1892.9148188331524;8466.872996905044;8972.8654070189;8976.461412315257;8977.640894364225;8994.100589941003;7791.376689864811;8018.238267364591;8537.871931921027;8756.912430875705;8801.515916624583;2342.508073304604;2377.072941552796;2383.2254517281867;2387.4986252886915;2388.0209114263157;2005.2881227164366;2346.5541547105995;2365.3849999000213;2388.7252965908883;2396.4128788446856;2346.9061237550563;2363.125643511903;2374.5013547155118;2375.4655202579456;2382.833201675883;2330.941726456852;2345.4605440748637;2351.1535847095033;2352.564775516535;2369.1944738788893;2337.8246631502707;2338.871362075085;2344.812415006795;2345.906163753465;2345.976540234593;8705.738827835175;8901.73294801157;8902.910970890338;8909.509976951265;8910.688125915627;5160.793568257265;5215.58275337972;5306.655067420644;5313.07021271276;5317.973410132963;3452;3573.26748016656;3658.5975542690285;3661.121330880596;3665.4318580620047;1045.6810672744887;1073.983890241647;1079.838024296354;1100.4607742206342;1104.1553211792984;11558.653240402802;11667.183297169202;11670.76658466826;11685.591004314892;11688.883111168865;3710.3321402147335;4543.45478542577;4726.834560790356;4734.242521196592;4738.360321356573;9907.455089969977;9931.900680993242;9932;9940.552675129637;9945.254105942084;4481.820727170942;5306.761195746123;5312.468753124734;5322;5322.813701520428;4134.565870583606;4206.85276015338;4349.760763157993;4857;4909.607231421478;3789.7157713171623;3833.3483307837732;3905.628965248297;3933.2330195612153;3935.6064393560414;1254.5609036837034;1265.4432049898016;1269.1750362264602;1279.5137847617846;1289.5422125145437;801.8849245634219;819.3854609043218;860.6643409070467;887.9422837515541;898.2544488074882;4642.154817948868;4986.376702912136;5679.943200568024;5951.1073338999495;6095.35998720128;433.1358938916881;433.5274550739709;437.71548493478855;437.87739432958824;444.282483788679;3907.1404291055924;3924.077841707254;4011.6590089842107;4035.959640403617;4042.15114825884;10361.740956475998;10853.186011049203;10860.958931499194;10877.401742904058;10886.836697449544;10783.299085559414;10797.676069717922;10803.081738052197;10824.78350432987;10826.242163048511;6981.860362792716;7712.691492340356;8195.483835490453;8258.298044666151;8261.463004904663;5906;6057.242844644419;6112.083187521907;6151.72317245716;6181.938180618136;9556.85664715016;9871.802563948824;10034.49827508607;10086.243531735003;10141.29010969225;10134.290599657954;10213.080822725693;10356.896431035593;10396.116330111723;10399.168066554506;5830.591858570029;6875.449964002968;7109.146902371748;7293.270672932665;7302.853942921218;8201.384896132695;8357.456765310235;8359.582020899052;8362.874556881774;8384.951881014873;7352.96323518379;7393.593352365671;7428.071491063617;7479.177290497932;7609.201033891583;7236.167840698387;7257.346838784535;7271.4182865369585;7392.519486233377;7484.625768711651;6750.662466876538;6889.2421833599265;6893.20728116253;6898.344657257552;6912.861742765016;4789.544993225638;4938.037082768826;4985.975070124626;5034.647574669738;5096.235564665181;4087.4890638670167;4738.194506933898;5025.39695236574;5048.621353398436;5054.974725126498 18:27:57 INFO - PROCESS | 1648 | |4;prototype.html;296.57441571345106;298.0848794201801;307.31928777757264;309.673294674119;311.3819069147741;4793.616510679141;5451.400345961946;5452.4274951130155;5490.725463726818;5506.339239291281;4545.613622842063;5063.189889617662;5064.822731204408;5261.447548007462;5396.217548455472;4104.404861295126;4123.587641235882;4144.399062135988;4170.687198460112;4210.242156411845;3591.94612080819;4096.750491100228;4154.875353739392;4163.68772342074;4172.873324202464;3540.8937731868086;3875.2443273561667;4025.899352516196;4109.547949725527;4122.361034039729;3916.4321173429835;4047.2310261050307;4054.432379466877;4056.979715101435;4062.2484840304637;3500.3699334119847;3500.494787241485;3504.211552400715;3518.4194607889667;3520.1727594015388;4336.1978034063795;4995.375578052744;5312.307945977547;5321.461228283187;5340.599455040849;4584.449866016066;4636.374089497908;4661.673682842203;4662.065242924183;4676.438827340707;5058.974705126488;5698.466950490722;5716.514096301819;5749.798757043525;5772.3938986406665;3288.917777055569;3886.2706095787385;3893.8902412812495;3930.3121953657997;3932.9213415731524;4786.928196077061;5286.894262114774;5952.880942381128;6034.948117640023;6084.604500707528;3408.7102596279096;3918.2555314490155;3919.3141200289833;3924.156306394139;3928.273269445162;3361.378145043126;3960.405939109099;3983.203359328146;4000.8199631016673;4034.8184331705143;4268.701190916669;4509.977450112729;4574.130915126116;4636.4436267647425;4676.742779147179;5263.6578622389425;5554.833354999357;5741.598088133874;5775.075987841925;5790.392008839095;1283.1851774123313;1335.786274196124;1350.1907444025053;1356.5048757203747;1361.162884825825;720.9279072092854;775.4571799740204;779.3881802784787;831.4221615976959;872.9781755456038;3771.755320744203;3945.5204298388103;4309.48286205651;4328.307470804656;4390.780460976938;4303.859477238534;4653.813847446064;4788.88722330488;4838.491958344323;4887.682300650517;3503.5795704515112;3807.4579795182995;4020.4170395292517;4271.914561708749;4306.289462238696;2124.6388114020656;2195.6706494026025;2473.9674330840617;2569.460413313188;2579.877753177374;4309.525952145262;4452.8218871245435;4998.975005125024;5020.949790502122;5040.49595040493;4073.9592604073578;4376.380739126544;4509.977450112729;4927.236278376858;4985.825496107619;4146;4780.091782561302;4909.239067944474;4986.75331167201;5021.238318924577;4306.526282109028;4471.508134105311;4839.79789195908;4846.636502262274;4853.708777473222;4332.6317263033;4364.212663744721;4605.263157894721;4855.344528488608;4855.902881942412;5114.693118412907;5993.490553302844;6138.662373569497;6180.876382472416;6190.969045154926;3878.650921417086;3899.4540764292456;3937.4684417604403;3951.9407208891316;3995.5604883462215;5268.050821196029;5465.672059676433;5861;5990;5991.64050156992;4758.928616070692;5722.513586345207;5725;5748.82149159413;5754.971225144015;4861.635377346784;4948.8762780930765;5595.692236927008;5726.370099288992;5792.681402522901;5241.50205730455;5572.4706152915405;5616.466435688603;5617.9719101404235;5654.745536450787;5441;5554.250176226158;5587.541481748018;5669.858253543694;5705.686187259741;4887.34020907173;5046.889684269455;5320.414754376938;5546.861328466821;5577.247071645276 18:27:57 INFO - PROCESS | 1648 | |5;yui.html;730.2879692300008;746.7386414754836;755.8374949385883;779.7816611348821;809.9190080991905;15563.844361556381;16149.354025838982;16164.919175404137;16201.946873453217;16225;14285.857141428583;14406.783898241534;14429.422823087063;14456.638584035405;14919.254037298146;686.1834417043727;706.1702499563019;838.3879767769532;839.7774589733725;866.8309679612447;860.0797147052656;867.995660021699;868.4008034456205;869.9951555952853;872.0494660819705;918.5040078357712;918.6279556779515;918.7840857398539;920.2913756407562;925.5973651461602;911.3666002128523;911.9498427586481;913.9360244782868;916.4547094478775;921.7280902133887;531.7288183026645;653.7385045981646;679.0425500044962;679.7315060551055;682.0791930893255;14457.638559036108;14511.984161108727;14518.128912265298;14529.636759081002;14553.12681239129;13356.86643133566;13745.24401157936;13861.376238069312;13926.860731392657;14140.222287774168;12059.397030148457;12519.311437870914;12556.74886502274;12562.30907300098;13007.154534955198;4479.865604031885;4572.216835434183;4576.656750743708;4619.23782575878;4688;113608;114989;120827.79172208253;121585.39207303995;121750.17374739386;8418.831623367498;10753.946230268799;10935.343879367263;10942.17933654979;11144.108471322275;20191.596168076558;20895.791042089688;21203.89398053;21445.78554214469;21448.927553622256;8065;8098.7165449209;8156.429049966446;8187.795305117301;8329.625166867505;3905.4542549579664;4427.734335939854;4450.149337222443;4588.334691469785;4733.242681170997;3476.774009689362;3619.8190090495896;3624.257027309395;3625.510556074896;3625.836837342273;553.32715219967;566.8384510414512;648.4812150279757;655.8786624474487;672.8519725660346;582.4001278682963;588.1002066837746;588.5409380682988;590.3034419385167;613.6931534232883;8768.657644349709;9523.380980236263;9539.14147726708;9648.372855764354;9657.957743310835;595.5444085274768;619.3837132053635;680.5814424129125;687.5290426058166;690.2579726793717;4334.328179132176;4441.178381999341;4635.837745678885;4659.673822832437;4742.027884283713;103425;121384.78615213734;121419;157456;170870;79222;83982;89317.10682893088;122356;127158.36420817836;83715.5814220925;90938;104829;106422;107220;9309.767255818388;9558.61765529371;9561.139497445263;9679.274054445805;9682.305596520722;38935.6106438932;45625.54374456346;53073;60609.39390606037;61278.38721612727;71503.28496714967;72146.27853721396;77463;77855;92941;12900.870991289967;12965.546205882749;13045.869541304466;13207.867921320663;13364.131331463424;12144.939275303566;14783.186924718813;14842.40630374773;15099.924500377429;15246.466373676869;10018.198544116307;13946.023778335824;14135.43458261658;14147.43410263578;14757.92621036888;12386.75226495447;12994.54519091865;13737.793933090812;13814.723705526034;14425.567232983027;13394.062415630813;13539.255340956342;13627.931860340635;13654.112482688593;13704.177749335071;10939.140211837483;11177.16171287173;11210.103191744804;11447.771044579229;11762.70593235176;10380.844287335847;10577.9471102644;10586.04725574702;10908.94545527299;11270.436478176221 18:27:57 INFO - PROCESS | 1648 | __end_tp_report 18:27:57 INFO - PROCESS | 1648 | __start_cc_report 18:27:57 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,1119 18:27:57 INFO - PROCESS | 1648 | __end_cc_report 18:27:57 INFO - PROCESS | 1648 | __startTimestamp1472520477075__endTimestamp 18:27:57 INFO - PROCESS | 1648 | 18:27:57 INFO - PROCESS | 1648 | ------- Summary: start ------- 18:27:57 INFO - PROCESS | 1648 | Number of tests: 6 18:27:57 INFO - PROCESS | 1648 | 18:27:57 INFO - PROCESS | 1648 | [#0] dojo.html Cycles:165 Average:5635.16 Median:5776.68 stddev:632.57 (11.0%) stddev-sans-first:607.89 18:27:57 INFO - PROCESS | 1648 | Values: 3320.8 3341.4 3359.2 3384.3 3417.1 5507.4 5545.8 5588.4 5680.6 5699.3 5614.0 5620.3 5707.0 5707.1 5734.0 4664.4 4697.2 5067.4 5182.1 5272.4 4156.5 5165.1 5240.2 5263.4 5298.2 5107.2 5162.8 5198.1 5204.5 5212.9 5096.4 5155.1 5186.5 5197.7 5204.6 5083.4 5111.6 5115.9 5185.4 5212.5 5587.8 5590.1 5593.8 5650.8 5660.1 5988.1 6038.5 6200.9 6201.4 6201.8 6095.9 6107.4 6161.2 6184.2 6195.8 5705.5 5715.4 5722.4 5740.2 5774.4 6059.3 6180.7 6226.6 6236.3 6238.5 6042.4 6126.3 6133.5 6142.1 6143.9 6214.3 6222.1 6222.2 6230.2 6235.8 5983.7 6024.9 6164.5 6182.4 6200.2 4990.2 6098.8 6116.8 6132.6 6172.5 4940.9 4942.2 4947.9 4954.6 4962.0 4396.2 4420.8 4425.4 4435.4 4489.0 5434.8 5452.6 5468.0 5502.1 5520.3 6105.2 6113.9 6114.1 6125.5 6129.6 4684.4 5474.8 5482.7 5516.3 5591.6 5324.8 5336.5 5340.8 5344.6 5353.7 5234.3 6350.8 6353.3 6354.3 6374.4 5951.5 6080.1 6106.5 6109.8 6139.1 6271.3 6337.1 6337.2 6354.4 6378.4 6106.8 6112.0 6117.1 6126.0 6128.7 5954.9 6014.1 6020.4 6058.1 6078.8 5922.6 5930.1 5932.2 5933.6 5948.4 5909.1 5914.8 5922.8 5935.3 5942.0 5607.1 5712.3 5749.2 5770.2 5782.1 5929.4 5935.8 5942.6 5952.9 5975.8 5740.2 5776.7 5780.4 5785.1 5785.1 18:27:57 INFO - PROCESS | 1648 | 18:27:57 INFO - PROCESS | 1648 | [#1] ext.html Cycles:180 Average:70374.18 Median:38640.31 stddev:157198.82 (406.8%) stddev-sans-first:157552.20 18:27:57 INFO - PROCESS | 1648 | Values: 475.9 489.9 522.3 523.6 551.2 29752.9 30788.2 31755.4 31846.4 31953.8 32217.0 41516.0 41913.4 41926.6 43368.3 9532.6 9676.1 9677.2 9683.6 9685.3 9614.9 9705.3 9733.2 9733.4 9734.0 8794.8 8897.7 9967.9 11094.6 11898.4 11761.6 11822.5 11830.0 11832.8 11873.9 11622.9 11719.8 11720.6 11725.8 11732.1 31193.8 40244.6 40608.4 40646.0 40822.8 3309.9 3344.9 3603.9 3917.3 4075.7 26616.6 30047.8 30473.5 30521.2 30593.7 3102.3 3330.9 3380.6 3383.1 3392.3 504681.0 507122.0 509218.0 510327.0 511022.0 57083.1 57514.0 57585.7 57684.1 57732.7 60037.1 60218.0 60248.7 60287.4 60422.7 15881.5 16132.9 16153.9 16175.4 16199.9 12578.6 12627.0 12630.9 12640.5 12644.9 12293.3 13000.3 13951.2 15456.1 15586.8 5230.4 5990.4 6017.3 6078.8 6115.8 767.5 819.2 852.6 950.8 983.2 26696.0 27098.9 27237.0 27328.5 27544.4 923.2 933.7 950.6 971.1 1018.8 13866.5 14089.7 14137.3 14236.9 14250.2 610440.0 613832.7 616004.0 616091.0 616170.0 49043.0 49053.3 54894.5 55831.4 56137.2 1316.4 1334.6 1347.3 1351.5 1356.7 22562.8 27415.0 27533.9 28469.0 28522.4 47946.3 48427.0 48444.5 48515.5 48631.3 574335.0 575955.0 577070.1 583840.0 807995.0 24928.6 25168.0 25188.2 25270.5 25294.0 45075.3 45933.5 45991.1 46927.5 48406.0 38615.8 38664.8 38692.0 39679.2 40574.4 37917.6 38055.0 39214.4 39773.8 40480.2 36707.6 37970.6 38017.0 38298.0 38369.2 30794.0 34947.3 35077.8 35378.3 35657.0 24699.5 24841.6 24915.6 25079.7 25375.4 18:27:57 INFO - PROCESS | 1648 | 18:27:57 INFO - PROCESS | 1648 | [#2] jquery.html Cycles:180 Average:35347.88 Median:1710.48 stddev:98748.38 (5773.1%) stddev-sans-first:98990.35 18:27:57 INFO - PROCESS | 1648 | Values: 311.4 341.1 359.5 369.9 397.4 15392.6 15403.3 15823.4 21122.0 21298.9 20045.7 20570.9 21117.6 21389.0 22003.7 5732.6 5952.6 6215.3 6226.0 6234.2 6111.9 6175.2 6228.5 6233.3 6244.3 5467.8 5674.2 6054.5 6187.9 6194.5 6035.8 6084.2 6092.1 6106.9 6110.2 5059.9 5256.0 5279.8 5288.2 5292.4 26827.5 26983.5 27000.0 27159.0 27282.9 2321.9 2378.8 2412.7 2917.6 3109.5 15270.0 15328.2 15665.8 16291.8 16362.8 1838.5 1840.3 1873.5 1900.7 1910.2 115719.0 133684.0 133747.3 136057.0 137234.3 9804.2 10839.2 10983.2 11040.8 11457.6 14203.2 14211.8 14219.4 14225.0 14228.4 8176.4 8423.2 8440.9 8552.4 8579.3 8426.3 8460.3 8466.6 8639.7 8652.3 4343.5 4430.8 4435.0 4473.9 4478.9 1496.9 1731.3 1796.5 1822.2 1863.1 792.3 850.9 894.1 907.4 945.0 18287.8 20239.4 21799.8 21837.8 21866.6 822.3 835.8 841.9 878.5 914.7 10542.8 10635.6 11381.4 11412.3 11418.5 540555.0 577122.0 591510.0 614938.0 615949.0 96038.0 131862.0 136172.0 137383.3 141012.0 841.9 921.7 951.7 994.2 996.0 15019.0 18870.2 20370.8 20406.7 20522.8 52563.7 53435.5 53766.5 53960.0 54924.7 71994.6 81813.6 81906.6 82027.6 82779.6 7700.1 7700.5 7944.3 8016.2 8186.8 10887.5 13069.7 13497.1 13510.9 13657.9 11169.9 11172.9 11219.3 11628.5 11719.9 11140.6 11224.0 11253.6 11658.3 11766.8 10531.4 11479.5 11768.5 11860.2 12008.0 8043.0 8058.2 8078.7 8124.8 8186.3 8207.2 9315.2 10427.1 10481.7 10706.0 18:27:57 INFO - PROCESS | 1648 | 18:27:57 INFO - PROCESS | 1648 | [#3] mootools.html Cycles:180 Average:5653.36 Median:4684.49 stddev:3229.81 (68.9%) stddev-sans-first:3223.87 18:27:57 INFO - PROCESS | 1648 | Values: 1510.1 1532.1 1723.1 1825.0 1892.9 8466.9 8972.9 8976.5 8977.6 8994.1 7791.4 8018.2 8537.9 8756.9 8801.5 2342.5 2377.1 2383.2 2387.5 2388.0 2005.3 2346.6 2365.4 2388.7 2396.4 2346.9 2363.1 2374.5 2375.5 2382.8 2330.9 2345.5 2351.2 2352.6 2369.2 2337.8 2338.9 2344.8 2345.9 2346.0 8705.7 8901.7 8902.9 8909.5 8910.7 5160.8 5215.6 5306.7 5313.1 5318.0 3452.0 3573.3 3658.6 3661.1 3665.4 1045.7 1074.0 1079.8 1100.5 1104.2 11558.7 11667.2 11670.8 11685.6 11688.9 3710.3 4543.5 4726.8 4734.2 4738.4 9907.5 9931.9 9932.0 9940.6 9945.3 4481.8 5306.8 5312.5 5322.0 5322.8 4134.6 4206.9 4349.8 4857.0 4909.6 3789.7 3833.3 3905.6 3933.2 3935.6 1254.6 1265.4 1269.2 1279.5 1289.5 801.9 819.4 860.7 887.9 898.3 4642.2 4986.4 5679.9 5951.1 6095.4 433.1 433.5 437.7 437.9 444.3 3907.1 3924.1 4011.7 4036.0 4042.2 10361.7 10853.2 10861.0 10877.4 10886.8 10783.3 10797.7 10803.1 10824.8 10826.2 6981.9 7712.7 8195.5 8258.3 8261.5 5906.0 6057.2 6112.1 6151.7 6181.9 9556.9 9871.8 10034.5 10086.2 10141.3 10134.3 10213.1 10356.9 10396.1 10399.2 5830.6 6875.4 7109.1 7293.3 7302.9 8201.4 8357.5 8359.6 8362.9 8385.0 7353.0 7393.6 7428.1 7479.2 7609.2 7236.2 7257.3 7271.4 7392.5 7484.6 6750.7 6889.2 6893.2 6898.3 6912.9 4789.5 4938.0 4986.0 5034.6 5096.2 4087.5 4738.2 5025.4 5048.6 5055.0 18:27:57 INFO - PROCESS | 1648 | 18:27:57 INFO - PROCESS | 1648 | [#4] prototype.html Cycles:180 Average:4348.96 Median:4657.74 stddev:1345.35 (28.9%) stddev-sans-first:1314.30 18:27:57 INFO - PROCESS | 1648 | Values: 296.6 298.1 307.3 309.7 311.4 4793.6 5451.4 5452.4 5490.7 5506.3 4545.6 5063.2 5064.8 5261.4 5396.2 4104.4 4123.6 4144.4 4170.7 4210.2 3591.9 4096.8 4154.9 4163.7 4172.9 3540.9 3875.2 4025.9 4109.5 4122.4 3916.4 4047.2 4054.4 4057.0 4062.2 3500.4 3500.5 3504.2 3518.4 3520.2 4336.2 4995.4 5312.3 5321.5 5340.6 4584.4 4636.4 4661.7 4662.1 4676.4 5059.0 5698.5 5716.5 5749.8 5772.4 3288.9 3886.3 3893.9 3930.3 3932.9 4786.9 5286.9 5952.9 6034.9 6084.6 3408.7 3918.3 3919.3 3924.2 3928.3 3361.4 3960.4 3983.2 4000.8 4034.8 4268.7 4510.0 4574.1 4636.4 4676.7 5263.7 5554.8 5741.6 5775.1 5790.4 1283.2 1335.8 1350.2 1356.5 1361.2 720.9 775.5 779.4 831.4 873.0 3771.8 3945.5 4309.5 4328.3 4390.8 4303.9 4653.8 4788.9 4838.5 4887.7 3503.6 3807.5 4020.4 4271.9 4306.3 2124.6 2195.7 2474.0 2569.5 2579.9 4309.5 4452.8 4999.0 5020.9 5040.5 4074.0 4376.4 4510.0 4927.2 4985.8 4146.0 4780.1 4909.2 4986.8 5021.2 4306.5 4471.5 4839.8 4846.6 4853.7 4332.6 4364.2 4605.3 4855.3 4855.9 5114.7 5993.5 6138.7 6180.9 6191.0 3878.7 3899.5 3937.5 3951.9 3995.6 5268.1 5465.7 5861.0 5990.0 5991.6 4758.9 5722.5 5725.0 5748.8 5755.0 4861.6 4948.9 5595.7 5726.4 5792.7 5241.5 5572.5 5616.5 5618.0 5654.7 5441.0 5554.3 5587.5 5669.9 5705.7 4887.3 5046.9 5320.4 5546.9 5577.2 18:27:57 INFO - PROCESS | 1648 | 18:27:57 INFO - PROCESS | 1648 | [#5] yui.html Cycles:180 Average:22966.50 Median:4119.89 stddev:35950.85 (872.6%) stddev-sans-first:36012.93 18:27:57 INFO - PROCESS | 1648 | Values: 730.3 746.7 755.8 779.8 809.9 15563.8 16149.4 16164.9 16201.9 16225.0 14285.9 14406.8 14429.4 14456.6 14919.3 686.2 706.2 838.4 839.8 866.8 860.1 868.0 868.4 870.0 872.0 918.5 918.6 918.8 920.3 925.6 911.4 911.9 913.9 916.5 921.7 531.7 653.7 679.0 679.7 682.1 14457.6 14512.0 14518.1 14529.6 14553.1 13356.9 13745.2 13861.4 13926.9 14140.2 12059.4 12519.3 12556.7 12562.3 13007.2 4479.9 4572.2 4576.7 4619.2 4688.0 113608.0 114989.0 120827.8 121585.4 121750.2 8418.8 10753.9 10935.3 10942.2 11144.1 20191.6 20895.8 21203.9 21445.8 21448.9 8065.0 8098.7 8156.4 8187.8 8329.6 3905.5 4427.7 4450.1 4588.3 4733.2 3476.8 3619.8 3624.3 3625.5 3625.8 553.3 566.8 648.5 655.9 672.9 582.4 588.1 588.5 590.3 613.7 8768.7 9523.4 9539.1 9648.4 9658.0 595.5 619.4 680.6 687.5 690.3 4334.3 4441.2 4635.8 4659.7 4742.0 103425.0 121384.8 121419.0 157456.0 170870.0 79222.0 83982.0 89317.1 122356.0 127158.4 83715.6 90938.0 104829.0 106422.0 107220.0 9309.8 9558.6 9561.1 9679.3 9682.3 38935.6 45625.5 53073.0 60609.4 61278.4 71503.3 72146.3 77463.0 77855.0 92941.0 12900.9 12965.5 13045.9 13207.9 13364.1 12144.9 14783.2 14842.4 15099.9 15246.5 10018.2 13946.0 14135.4 14147.4 14757.9 12386.8 12994.5 13737.8 13814.7 14425.6 13394.1 13539.3 13627.9 13654.1 13704.2 10939.1 11177.2 11210.1 11447.8 11762.7 10380.8 10577.9 10586.0 10908.9 11270.4 18:27:57 INFO - PROCESS | 1648 | -------- Summary: end -------- 18:27:57 INFO - PROCESS | 1648 | 18:27:57 INFO - TEST-INFO | 1648: exit 0 18:27:57 INFO - TEST-OK | dromaeo_css | took 1117031ms 18:27:57 INFO - TEST-START | kraken 18:27:57 INFO - operating with platform_type : mac_ 18:27:57 INFO - Initialising browser for kraken test... 18:27:57 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/tmpNT58nf/profile http://localhost:49263/getInfo.html) 18:28:00 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 18:28:00 INFO - PROCESS | 1654 | colorDepth:24 18:28:00 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 18:28:00 INFO - PROCESS | 1654 | __metrics 18:28:00 INFO - PROCESS | 1654 | 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" 18:28:00 INFO - PROCESS | 1654 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:28:00 INFO - TEST-INFO | 1654: exit 0 18:28:00 INFO - Browser initialized. 18:28:00 INFO - Running cycle 1/1 for kraken test... 18:28:00 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/tmpNT58nf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:28:12 INFO - PROCESS | 1655 | RSS: Main: 244445184 18:28:12 INFO - PROCESS | 1655 | 18:28:15 INFO - PROCESS | 1655 | 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) 18:28:16 INFO - PROCESS | 1655 | RSS: Main: 294772736 18:28:16 INFO - PROCESS | 1655 | 18:28:18 INFO - PROCESS | 1655 | 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) 18:28:19 INFO - PROCESS | 1655 | RSS: Main: 290656256 18:28:19 INFO - PROCESS | 1655 | 18:28:23 INFO - PROCESS | 1655 | 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) 18:28:23 INFO - PROCESS | 1655 | RSS: Main: 382337024 18:28:23 INFO - PROCESS | 1655 | 18:28:25 INFO - PROCESS | 1655 | 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) 18:28:26 INFO - PROCESS | 1655 | RSS: Main: 282578944 18:28:26 INFO - PROCESS | 1655 | 18:28:28 INFO - PROCESS | 1655 | 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) 18:28:29 INFO - PROCESS | 1655 | RSS: Main: 285749248 18:28:29 INFO - PROCESS | 1655 | 18:28:31 INFO - PROCESS | 1655 | 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) 18:28:32 INFO - PROCESS | 1655 | RSS: Main: 324710400 18:28:32 INFO - PROCESS | 1655 | 18:28:35 INFO - PROCESS | 1655 | 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) 18:28:35 INFO - PROCESS | 1655 | RSS: Main: 325484544 18:28:35 INFO - PROCESS | 1655 | 18:28:38 INFO - PROCESS | 1655 | 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) 18:28:39 INFO - PROCESS | 1655 | RSS: Main: 324091904 18:28:39 INFO - PROCESS | 1655 | 18:28:40 INFO - PROCESS | 1655 | 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) 18:28:41 INFO - PROCESS | 1655 | RSS: Main: 299491328 18:28:41 INFO - PROCESS | 1655 | 18:28:43 INFO - PROCESS | 1655 | 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) 18:28:44 INFO - PROCESS | 1655 | RSS: Main: 305926144 18:28:44 INFO - PROCESS | 1655 | 18:28:46 INFO - PROCESS | 1655 | 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) 18:28:47 INFO - PROCESS | 1655 | RSS: Main: 305008640 18:28:47 INFO - PROCESS | 1655 | 18:28:49 INFO - PROCESS | 1655 | 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) 18:28:50 INFO - PROCESS | 1655 | RSS: Main: 323141632 18:28:50 INFO - PROCESS | 1655 | 18:28:53 INFO - PROCESS | 1655 | 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) 18:28:53 INFO - PROCESS | 1655 | RSS: Main: 319442944 18:28:53 INFO - PROCESS | 1655 | 18:28:55 INFO - PROCESS | 1655 | 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) 18:28:55 INFO - PROCESS | 1655 | RSS: Main: 362483712 18:28:55 INFO - PROCESS | 1655 | 18:28:55 INFO - PROCESS | 1655 | __start_tp_report 18:28:55 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 18:28:55 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 18:28:55 INFO - PROCESS | 1655 | |i|pagename|runs| 18:28:55 INFO - PROCESS | 1655 | |0;ai-astar;113;112;113;113;113;111;125;112;111;112 18:28:55 INFO - PROCESS | 1655 | |1;audio-beat-detection;140;108;126;159;109;129;131;101;173;127 18:28:55 INFO - PROCESS | 1655 | |2;audio-dft;185;186;184;182;184;184;180;246;184;184 18:28:55 INFO - PROCESS | 1655 | |3;audio-fft;76;72;76;153;58;56;57;68;136;57 18:28:55 INFO - PROCESS | 1655 | |4;audio-oscillator;82;85;134;70;70;75;117;68;68;115 18:28:55 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;87;85;87;84;87;84;88;85;98;129 18:28:55 INFO - PROCESS | 1655 | |6;imaging-darkroom;94;92;93;94;93;96;93;93;94;92 18:28:55 INFO - PROCESS | 1655 | |7;imaging-desaturate;85;85;86;86;82;84;86;85;83;84 18:28:55 INFO - PROCESS | 1655 | |8;json-parse-financial;53;55;55;54;54;53;53;53;54;53 18:28:55 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;66;67;76;76;71;71;69;72;105;98 18:28:55 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;85;86;85;88;101;104;106;83;88;87 18:28:55 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;139;138;136;136;137;135;138;155;179;136 18:28:55 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;167;168;168;160;165;166;167;165;164;164 18:28:55 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;65;66;67;66;67;67;67;66;66;66 18:28:55 INFO - PROCESS | 1655 | __end_tp_report 18:28:55 INFO - PROCESS | 1655 | __start_cc_report 18:28:55 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,2034 18:28:55 INFO - PROCESS | 1655 | __end_cc_report 18:28:55 INFO - PROCESS | 1655 | __startTimestamp1472520535920__endTimestamp 18:28:55 INFO - PROCESS | 1655 | 18:28:55 INFO - PROCESS | 1655 | ------- Summary: start ------- 18:28:55 INFO - PROCESS | 1655 | Number of tests: 14 18:28:55 INFO - PROCESS | 1655 | 18:28:55 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:113.50 Median:113.00 stddev:4.12 (3.6%) stddev-sans-first:4.36 18:28:55 INFO - PROCESS | 1655 | Values: 113.0 112.0 113.0 113.0 113.0 111.0 125.0 112.0 111.0 112.0 18:28:55 INFO - PROCESS | 1655 | 18:28:55 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:130.30 Median:130.00 stddev:22.56 (17.4%) stddev-sans-first:23.66 18:28:55 INFO - PROCESS | 1655 | Values: 140.0 108.0 126.0 159.0 109.0 129.0 131.0 101.0 173.0 127.0 18:28:55 INFO - PROCESS | 1655 | 18:28:55 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:189.90 Median:184.00 stddev:19.78 (10.7%) stddev-sans-first:20.90 18:28:55 INFO - PROCESS | 1655 | Values: 185.0 186.0 184.0 182.0 184.0 184.0 180.0 246.0 184.0 184.0 18:28:55 INFO - PROCESS | 1655 | 18:28:55 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:80.90 Median:63.00 stddev:34.66 (55.0%) stddev-sans-first:36.72 18:28:55 INFO - PROCESS | 1655 | Values: 76.0 72.0 76.0 153.0 58.0 56.0 57.0 68.0 136.0 57.0 18:28:55 INFO - PROCESS | 1655 | 18:28:55 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:88.40 Median:70.00 stddev:24.37 (34.8%) stddev-sans-first:25.74 18:28:55 INFO - PROCESS | 1655 | Values: 82.0 85.0 134.0 70.0 70.0 75.0 117.0 68.0 68.0 115.0 18:28:55 INFO - PROCESS | 1655 | 18:28:55 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:91.40 Median:87.00 stddev:13.82 (15.9%) stddev-sans-first:14.56 18:28:55 INFO - PROCESS | 1655 | Values: 87.0 85.0 87.0 84.0 87.0 84.0 88.0 85.0 98.0 129.0 18:28:55 INFO - PROCESS | 1655 | 18:28:55 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:93.40 Median:93.50 stddev:1.17 (1.3%) stddev-sans-first:1.22 18:28:55 INFO - PROCESS | 1655 | Values: 94.0 92.0 93.0 94.0 93.0 96.0 93.0 93.0 94.0 92.0 18:28:55 INFO - PROCESS | 1655 | 18:28:55 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:84.60 Median:85.00 stddev:1.35 (1.6%) stddev-sans-first:1.42 18:28:55 INFO - PROCESS | 1655 | Values: 85.0 85.0 86.0 86.0 82.0 84.0 86.0 85.0 83.0 84.0 18:28:55 INFO - PROCESS | 1655 | 18:28:55 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:53.70 Median:54.00 stddev:0.82 (1.5%) stddev-sans-first:0.83 18:28:55 INFO - PROCESS | 1655 | Values: 53.0 55.0 55.0 54.0 54.0 53.0 53.0 53.0 54.0 53.0 18:28:55 INFO - PROCESS | 1655 | 18:28:55 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:77.10 Median:71.50 stddev:13.37 (18.7%) stddev-sans-first:13.56 18:28:55 INFO - PROCESS | 1655 | Values: 66.0 67.0 76.0 76.0 71.0 71.0 69.0 72.0 105.0 98.0 18:28:55 INFO - PROCESS | 1655 | 18:28:55 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:91.30 Median:85.50 stddev:8.74 (10.2%) stddev-sans-first:8.97 18:28:55 INFO - PROCESS | 1655 | Values: 85.0 86.0 85.0 88.0 101.0 104.0 106.0 83.0 88.0 87.0 18:28:55 INFO - PROCESS | 1655 | 18:28:55 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:142.90 Median:138.00 stddev:13.96 (10.1%) stddev-sans-first:14.73 18:28:55 INFO - PROCESS | 1655 | Values: 139.0 138.0 136.0 136.0 137.0 135.0 138.0 155.0 179.0 136.0 18:28:55 INFO - PROCESS | 1655 | 18:28:55 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.40 Median:166.50 stddev:2.41 (1.4%) stddev-sans-first:2.49 18:28:55 INFO - PROCESS | 1655 | Values: 167.0 168.0 168.0 160.0 165.0 166.0 167.0 165.0 164.0 164.0 18:28:55 INFO - PROCESS | 1655 | 18:28:55 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:66.50 stddev:0.67 (1.0%) stddev-sans-first:0.53 18:28:55 INFO - PROCESS | 1655 | Values: 65.0 66.0 67.0 66.0 67.0 67.0 67.0 66.0 66.0 66.0 18:28:55 INFO - PROCESS | 1655 | -------- Summary: end -------- 18:28:55 INFO - PROCESS | 1655 | 18:28:56 INFO - TEST-INFO | 1655: exit 0 18:28:56 INFO - TEST-OK | kraken | took 59141ms 18:28:56 INFO - SUITE-END | took 1176s 18:28:57 INFO - Completed test suite (00:19:36) 18:28:57 INFO - javascript benchmark 18:28:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3320.754716981133, 3341.415252330842, 3359.1602099475117, 3384.255463797964, 3417.0944699654597, 5507.366652834925, 5545.805896793613, 5588.441155884409, 5680.630759000667, 5699.344575373833, 5613.9614171378225, 5620.269364982547, 5706.971465142678, 5707.058335374658, 5734.025215713328, 4664.4402671679445, 4697.201475749131, 5067.391912970449, 5182.144946083891, 5272.446393128724, 4156.501219853607, 5165.121929272014, 5240.213967904826, 5263.447338029509, 5298.178782288752, 5107.1828507439, 5162.819301324435, 5198.064348417283, 5204.505571970657, 5212.921806172911, 5096.439391666913, 5155.149400348938, 5186.507281808223, 5197.688138711689, 5204.557612602934, 5083.38999320084, 5111.616628752859, 5115.923261151049, 5185.351831021122, 5212.53087222148, 5587.776488940438, 5590.077637189859, 5593.8321850344555, 5650.802221922213, 5660.122680984413, 5988.101784732282, 6038.486728628028, 6200.852842224203, 6201.410865967726, 6201.813945581594, 6095.861538843025, 6107.4197951194565, 6161.19904412428, 6184.226971628546, 6195.81412557624, 5705.515031222392, 5715.371309156031, 5722.4277572242445, 5740.167675687044, 5774.422557744192, 6059.311721788214, 6180.721867515973, 6226.62640241587, 6236.282827474898, 6238.469730072995, 6042.389594827441, 6126.303932855801, 6133.509319254449, 6142.140100385949, 6143.880361275353, 6214.285357183981, 6222.066689996446, 6222.160008398808, 6230.190075290274, 6235.75056997715, 5983.730732117066, 6024.909626355608, 6164.537659675452, 6182.412670796267, 6200.224971878515, 4990.226514890198, 6098.847528811815, 6116.761878222105, 6132.569672417391, 6172.506199504029, 4940.90118197634, 4942.184539551005, 4947.851564453073, 4954.628402869799, 4962.032403681247, 4396.230659634615, 4420.80106395213, 4425.380446737459, 4435.423394958635, 4489.0, 5434.782608695687, 5452.59105567084, 5468.043092458804, 5502.147167189092, 5520.254765606672, 6105.15650299495, 6113.871604717271, 6114.084479019511, 6125.47964319526, 6129.572745043327, 4684.391029166187, 5474.808381706621, 5482.698451585201, 5516.3380394352935, 5591.6365436246515, 5324.840254792362, 5336.519713225751, 5340.839774806762, 5344.5724342052545, 5353.705546194997, 5234.345706786652, 6350.7777227796805, 6353.3329000455215, 6354.332795056453, 6374.362563743774, 5951.464368206882, 6080.148779170831, 6106.450419462092, 6109.84725381869, 6139.062858969336, 6271.278802937538, 6337.144485494584, 6337.2395312562785, 6354.364563543609, 6378.425941665272, 6106.786262480792, 6112.022076467922, 6117.051856962178, 6126.019836826086, 6128.724207410766, 5954.910676339771, 6014.127951447103, 6020.397960203944, 6058.0610005449225, 6078.756849725962, 5922.615030023035, 5930.051191809463, 5932.228810254812, 5933.614315069507, 5948.434898684791, 5909.054551271776, 5914.763409463745, 5922.763089476372, 5935.2877654681715, 5942.049272116614, 5607.130894711327, 5712.314522257356, 5749.1663708762835, 5770.221020162225, 5782.10377391505, 5929.407059294036, 5935.792247271324, 5942.64344139353, 5952.910706339495, 5975.760969561169, 5740.167675686918, 5776.682282474504, 5780.364159942487, 5785.074388097884, 5785.103308987113], "value": 5596.302814524058, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [475.85724282715177, 489.8750818541272, 522.3053339059054, 523.5994464234861, 551.2337850387959, 29752.85123574382, 30788.23029424263, 31755.36489270213, 31846.36307273859, 31953.840230798873, 32217.0, 41516.0, 41913.37129943053, 41926.58073419273, 43368.34947475782, 9532.618695252182, 9676.129148376645, 9677.177439917616, 9683.5958785976, 9685.273604479657, 9614.903850961471, 9705.272104592184, 9733.221342292634, 9733.367331123489, 9733.951330243339, 8794.780130496725, 8897.68858089967, 9967.850482242773, 11094.611688590901, 11898.405079746022, 11761.588344407948, 11822.467988940432, 11830.0, 11832.82250766235, 11873.881261187364, 11622.88377116235, 11719.824202636968, 11720.648380548597, 11725.824112638316, 11732.120090993125, 31193.84403077993, 40244.597554024374, 40608.39087413662, 40646.0, 40822.79588602068, 3309.8676052957853, 3344.8996530104137, 3603.9099022524383, 3917.255721412951, 4075.6943129615265, 26616.60075098875, 30047.849760751276, 30473.542896856565, 30521.23696907573, 30593.694063059305, 3102.317490152163, 3330.8667653293837, 3380.6281309055985, 3383.1034775784437, 3392.2706618076986, 504680.95319047075, 507122.0, 509218.0, 510327.0, 511022.0, 57083.14375284374, 57514.0, 57585.71207144021, 57684.134737978966, 57732.71133644305, 60037.09944350751, 60218.0, 60248.69875650681, 60287.39712602906, 60422.69788651029, 15881.52355429339, 16132.919335403249, 16153.869229153846, 16175.43385981485, 16199.919000405158, 12578.622641320775, 12626.989840812714, 12630.936845315715, 12640.494380224687, 12644.936775316248, 12293.323867187211, 13000.349982501026, 13951.162930224218, 15456.072635641898, 15586.844131558766, 5230.424653288135, 5990.371011043778, 6017.308009578955, 6078.756849725962, 6115.816525504242, 767.5087943716027, 819.2135549872189, 852.5992783391796, 950.7765675066354, 983.1643103362085, 26696.0, 27098.86450567774, 27237.0, 27328.453430931666, 27544.449111017668, 923.1645360948219, 933.7198840347869, 950.6150009246267, 971.145392054987, 1018.8064267789097, 13866.514671986431, 14089.71820563583, 14137.327793570992, 14236.932230082784, 14250.216238106796, 610440.0, 613832.6858948392, 616004.0, 616091.0, 616170.0, 49043.0, 49053.26420103701, 54894.451055488935, 55831.441685583435, 56137.157942630896, 1316.4207748590588, 1334.5662659635657, 1347.2522749873915, 1351.513455156143, 1356.6608347913022, 22562.774372256066, 27415.0, 27533.86233068822, 28469.00358487443, 28522.42955140927, 47946.280805787246, 48427.0, 48444.51555484541, 48515.5148448511, 48631.27053094277, 574335.0, 575955.0, 577070.1146494409, 583840.0, 807995.0, 24928.62607060932, 25168.0, 25188.244352669448, 25270.494590107726, 25293.98824047091, 45075.32387014263, 45933.540664592925, 45991.080178396915, 46927.53072469232, 48406.03187936292, 38615.80692096634, 38664.80667596644, 38692.0, 39679.20641587094, 40574.39138412867, 37917.62082379141, 38055.0, 39214.41178382384, 39773.80113099416, 40480.1903961925, 36707.63292367042, 37970.620293797816, 38017.0, 38298.0, 38369.23261534698, 30794.0, 34947.30105397929, 35077.824610877804, 35378.292434151685, 35657.0, 24699.506009879344, 24841.627375589742, 24915.626265605668, 25079.749202508472, 25375.36561585975], "value": 19408.196113215574, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [311.4207573912522, 341.1267156080433, 359.46439804691, 369.9297133544627, 397.4217513517832, 15392.61518462039, 15403.306851191695, 15823.367065317374, 21122.0, 21298.935053247355, 20045.699314510257, 20570.89714551429, 21117.577648447023, 21389.0374933128, 22003.669944950838, 5732.624170199143, 5952.58331916766, 6215.347388524185, 6226.034964580497, 6234.220722409699, 6111.908321375183, 6175.197224360849, 6228.532860035492, 6233.314335423122, 6244.250689917217, 5467.824417750183, 5674.205611214434, 6054.5459090568165, 6187.907181392283, 6194.5354098442795, 6035.818925432222, 6084.239470066242, 6092.08618707193, 6106.908396374058, 6110.236220472441, 5059.924101138486, 5256.0, 5279.815206467793, 5288.233206185082, 5292.417834038252, 26827.463450731073, 26983.46033079347, 27000.0, 27159.0, 27282.863585681946, 2321.872297023663, 2378.8334816562847, 2412.722536908243, 2917.5769513420437, 3109.54911537828, 15270.0, 15328.23358832054, 15665.843341566553, 16291.755623665655, 16362.75455868151, 1838.5016211787351, 1840.3006857394105, 1873.5222518257892, 1900.7338972543855, 1910.1786231920269, 115719.0, 133684.0, 133747.33126334305, 136057.0, 137234.31382843122, 9804.215662746963, 10839.187060970458, 10983.176261780398, 11040.779184416428, 11457.59898403552, 14203.218822964836, 14211.78682319766, 14219.36012879423, 14225.0, 14228.359723812453, 8176.424426305694, 8423.199796019491, 8440.915590844135, 8552.358573107042, 8579.313654907592, 8426.325893928471, 8460.280876125598, 8466.619002144918, 8639.704044393391, 8652.264557512683, 4343.457067866517, 4430.822767089345, 4434.977825110854, 4473.910521789611, 4478.932816007762, 1496.944654018914, 1731.2946747453748, 1796.5329014456336, 1822.2437688359182, 1863.0963982468493, 792.3067316098413, 850.9446885952393, 894.083564346537, 907.4373888189365, 944.9858252126223, 18287.8451285305, 20239.392818215478, 21799.78200218009, 21837.781622183575, 21866.562668746537, 822.2650208060986, 835.7618078847621, 841.8737189421636, 878.5431575580662, 914.7255823253131, 10542.841857372145, 10635.574577016832, 11381.430928453545, 11412.31526108436, 11418.486168122288, 540555.0, 577122.0, 591510.0, 614938.0, 615949.0, 96038.0, 131862.0, 136172.0, 137383.31308343593, 141012.0, 841.9452735572169, 921.6635927886273, 951.6954574536082, 994.1599348550463, 995.9950200249098, 15019.0, 18870.24519019279, 20370.79629203748, 20406.69389959122, 20522.794772052686, 52563.737181313845, 53435.46564534305, 53766.46233537615, 53960.0, 54924.72537637446, 71994.64002679953, 81813.59093204496, 81906.59046704728, 82027.5898620527, 82779.58610206911, 7700.075990881131, 7700.499467534592, 7944.285014348737, 8016.198380161937, 8186.836263274582, 10887.510062047386, 13069.673258168621, 13497.122687025312, 13510.864891351353, 13657.863421365659, 11169.944150279196, 11172.888271117185, 11219.270747401393, 11628.476718547516, 11719.941400292944, 11140.554377824796, 11223.989840914357, 11253.649562052278, 11658.300501969909, 11766.764664706487, 10531.368117912949, 11479.540818367506, 11768.529258829893, 11860.22908510944, 12007.979321757399, 8042.9597852010365, 8058.234467725557, 8078.7172448964, 8124.796880078045, 8186.263236308522, 8207.220314070153, 9315.208207302456, 10427.113695335678, 10481.685549433529, 10706.0], "value": 9649.408535398425, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1510.1316742872848, 1532.0577844625554, 1723.129819441182, 1824.9726254106183, 1892.9148188331524, 8466.872996905044, 8972.8654070189, 8976.461412315257, 8977.640894364225, 8994.100589941003, 7791.376689864811, 8018.238267364591, 8537.871931921027, 8756.912430875705, 8801.515916624583, 2342.508073304604, 2377.072941552796, 2383.2254517281867, 2387.4986252886915, 2388.0209114263157, 2005.2881227164366, 2346.5541547105995, 2365.3849999000213, 2388.7252965908883, 2396.4128788446856, 2346.9061237550563, 2363.125643511903, 2374.5013547155118, 2375.4655202579456, 2382.833201675883, 2330.941726456852, 2345.4605440748637, 2351.1535847095033, 2352.564775516535, 2369.1944738788893, 2337.8246631502707, 2338.871362075085, 2344.812415006795, 2345.906163753465, 2345.976540234593, 8705.738827835175, 8901.73294801157, 8902.910970890338, 8909.509976951265, 8910.688125915627, 5160.793568257265, 5215.58275337972, 5306.655067420644, 5313.07021271276, 5317.973410132963, 3452.0, 3573.26748016656, 3658.5975542690285, 3661.121330880596, 3665.4318580620047, 1045.6810672744887, 1073.983890241647, 1079.838024296354, 1100.4607742206342, 1104.1553211792984, 11558.653240402802, 11667.183297169202, 11670.76658466826, 11685.591004314892, 11688.883111168865, 3710.3321402147335, 4543.45478542577, 4726.834560790356, 4734.242521196592, 4738.360321356573, 9907.455089969977, 9931.900680993242, 9932.0, 9940.552675129637, 9945.254105942084, 4481.820727170942, 5306.761195746123, 5312.468753124734, 5322.0, 5322.813701520428, 4134.565870583606, 4206.85276015338, 4349.760763157993, 4857.0, 4909.607231421478, 3789.7157713171623, 3833.3483307837732, 3905.628965248297, 3933.2330195612153, 3935.6064393560414, 1254.5609036837034, 1265.4432049898016, 1269.1750362264602, 1279.5137847617846, 1289.5422125145437, 801.8849245634219, 819.3854609043218, 860.6643409070467, 887.9422837515541, 898.2544488074882, 4642.154817948868, 4986.376702912136, 5679.943200568024, 5951.1073338999495, 6095.35998720128, 433.1358938916881, 433.5274550739709, 437.71548493478855, 437.87739432958824, 444.282483788679, 3907.1404291055924, 3924.077841707254, 4011.6590089842107, 4035.959640403617, 4042.15114825884, 10361.740956475998, 10853.186011049203, 10860.958931499194, 10877.401742904058, 10886.836697449544, 10783.299085559414, 10797.676069717922, 10803.081738052197, 10824.78350432987, 10826.242163048511, 6981.860362792716, 7712.691492340356, 8195.483835490453, 8258.298044666151, 8261.463004904663, 5906.0, 6057.242844644419, 6112.083187521907, 6151.72317245716, 6181.938180618136, 9556.85664715016, 9871.802563948824, 10034.49827508607, 10086.243531735003, 10141.29010969225, 10134.290599657954, 10213.080822725693, 10356.896431035593, 10396.116330111723, 10399.168066554506, 5830.591858570029, 6875.449964002968, 7109.146902371748, 7293.270672932665, 7302.853942921218, 8201.384896132695, 8357.456765310235, 8359.582020899052, 8362.874556881774, 8384.951881014873, 7352.96323518379, 7393.593352365671, 7428.071491063617, 7479.177290497932, 7609.201033891583, 7236.167840698387, 7257.346838784535, 7271.4182865369585, 7392.519486233377, 7484.625768711651, 6750.662466876538, 6889.2421833599265, 6893.20728116253, 6898.344657257552, 6912.861742765016, 4789.544993225638, 4938.037082768826, 4985.975070124626, 5034.647574669738, 5096.235564665181, 4087.4890638670167, 4738.194506933898, 5025.39695236574, 5048.621353398436, 5054.974725126498], "value": 4456.735309463898, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.57441571345106, 298.0848794201801, 307.31928777757264, 309.673294674119, 311.3819069147741, 4793.616510679141, 5451.400345961946, 5452.4274951130155, 5490.725463726818, 5506.339239291281, 4545.613622842063, 5063.189889617662, 5064.822731204408, 5261.447548007462, 5396.217548455472, 4104.404861295126, 4123.587641235882, 4144.399062135988, 4170.687198460112, 4210.242156411845, 3591.94612080819, 4096.750491100228, 4154.875353739392, 4163.68772342074, 4172.873324202464, 3540.8937731868086, 3875.2443273561667, 4025.899352516196, 4109.547949725527, 4122.361034039729, 3916.4321173429835, 4047.2310261050307, 4054.432379466877, 4056.979715101435, 4062.2484840304637, 3500.3699334119847, 3500.494787241485, 3504.211552400715, 3518.4194607889667, 3520.1727594015388, 4336.1978034063795, 4995.375578052744, 5312.307945977547, 5321.461228283187, 5340.599455040849, 4584.449866016066, 4636.374089497908, 4661.673682842203, 4662.065242924183, 4676.438827340707, 5058.974705126488, 5698.466950490722, 5716.514096301819, 5749.798757043525, 5772.3938986406665, 3288.917777055569, 3886.2706095787385, 3893.8902412812495, 3930.3121953657997, 3932.9213415731524, 4786.928196077061, 5286.894262114774, 5952.880942381128, 6034.948117640023, 6084.604500707528, 3408.7102596279096, 3918.2555314490155, 3919.3141200289833, 3924.156306394139, 3928.273269445162, 3361.378145043126, 3960.405939109099, 3983.203359328146, 4000.8199631016673, 4034.8184331705143, 4268.701190916669, 4509.977450112729, 4574.130915126116, 4636.4436267647425, 4676.742779147179, 5263.6578622389425, 5554.833354999357, 5741.598088133874, 5775.075987841925, 5790.392008839095, 1283.1851774123313, 1335.786274196124, 1350.1907444025053, 1356.5048757203747, 1361.162884825825, 720.9279072092854, 775.4571799740204, 779.3881802784787, 831.4221615976959, 872.9781755456038, 3771.755320744203, 3945.5204298388103, 4309.48286205651, 4328.307470804656, 4390.780460976938, 4303.859477238534, 4653.813847446064, 4788.88722330488, 4838.491958344323, 4887.682300650517, 3503.5795704515112, 3807.4579795182995, 4020.4170395292517, 4271.914561708749, 4306.289462238696, 2124.6388114020656, 2195.6706494026025, 2473.9674330840617, 2569.460413313188, 2579.877753177374, 4309.525952145262, 4452.8218871245435, 4998.975005125024, 5020.949790502122, 5040.49595040493, 4073.9592604073578, 4376.380739126544, 4509.977450112729, 4927.236278376858, 4985.825496107619, 4146.0, 4780.091782561302, 4909.239067944474, 4986.75331167201, 5021.238318924577, 4306.526282109028, 4471.508134105311, 4839.79789195908, 4846.636502262274, 4853.708777473222, 4332.6317263033, 4364.212663744721, 4605.263157894721, 4855.344528488608, 4855.902881942412, 5114.693118412907, 5993.490553302844, 6138.662373569497, 6180.876382472416, 6190.969045154926, 3878.650921417086, 3899.4540764292456, 3937.4684417604403, 3951.9407208891316, 3995.5604883462215, 5268.050821196029, 5465.672059676433, 5861.0, 5990.0, 5991.64050156992, 4758.928616070692, 5722.513586345207, 5725.0, 5748.82149159413, 5754.971225144015, 4861.635377346784, 4948.8762780930765, 5595.692236927008, 5726.370099288992, 5792.681402522901, 5241.50205730455, 5572.4706152915405, 5616.466435688603, 5617.9719101404235, 5654.745536450787, 5441.0, 5554.250176226158, 5587.541481748018, 5669.858253543694, 5705.686187259741, 4887.34020907173, 5046.889684269455, 5320.414754376938, 5546.861328466821, 5577.247071645276], "value": 3922.0960627897075, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [730.2879692300008, 746.7386414754836, 755.8374949385883, 779.7816611348821, 809.9190080991905, 15563.844361556381, 16149.354025838982, 16164.919175404137, 16201.946873453217, 16225.0, 14285.857141428583, 14406.783898241534, 14429.422823087063, 14456.638584035405, 14919.254037298146, 686.1834417043727, 706.1702499563019, 838.3879767769532, 839.7774589733725, 866.8309679612447, 860.0797147052656, 867.995660021699, 868.4008034456205, 869.9951555952853, 872.0494660819705, 918.5040078357712, 918.6279556779515, 918.7840857398539, 920.2913756407562, 925.5973651461602, 911.3666002128523, 911.9498427586481, 913.9360244782868, 916.4547094478775, 921.7280902133887, 531.7288183026645, 653.7385045981646, 679.0425500044962, 679.7315060551055, 682.0791930893255, 14457.638559036108, 14511.984161108727, 14518.128912265298, 14529.636759081002, 14553.12681239129, 13356.86643133566, 13745.24401157936, 13861.376238069312, 13926.860731392657, 14140.222287774168, 12059.397030148457, 12519.311437870914, 12556.74886502274, 12562.30907300098, 13007.154534955198, 4479.865604031885, 4572.216835434183, 4576.656750743708, 4619.23782575878, 4688.0, 113608.0, 114989.0, 120827.79172208253, 121585.39207303995, 121750.17374739386, 8418.831623367498, 10753.946230268799, 10935.343879367263, 10942.17933654979, 11144.108471322275, 20191.596168076558, 20895.791042089688, 21203.89398053, 21445.78554214469, 21448.927553622256, 8065.0, 8098.7165449209, 8156.429049966446, 8187.795305117301, 8329.625166867505, 3905.4542549579664, 4427.734335939854, 4450.149337222443, 4588.334691469785, 4733.242681170997, 3476.774009689362, 3619.8190090495896, 3624.257027309395, 3625.510556074896, 3625.836837342273, 553.32715219967, 566.8384510414512, 648.4812150279757, 655.8786624474487, 672.8519725660346, 582.4001278682963, 588.1002066837746, 588.5409380682988, 590.3034419385167, 613.6931534232883, 8768.657644349709, 9523.380980236263, 9539.14147726708, 9648.372855764354, 9657.957743310835, 595.5444085274768, 619.3837132053635, 680.5814424129125, 687.5290426058166, 690.2579726793717, 4334.328179132176, 4441.178381999341, 4635.837745678885, 4659.673822832437, 4742.027884283713, 103425.0, 121384.78615213734, 121419.0, 157456.0, 170870.0, 79222.0, 83982.0, 89317.10682893088, 122356.0, 127158.36420817836, 83715.5814220925, 90938.0, 104829.0, 106422.0, 107220.0, 9309.767255818388, 9558.61765529371, 9561.139497445263, 9679.274054445805, 9682.305596520722, 38935.6106438932, 45625.54374456346, 53073.0, 60609.39390606037, 61278.38721612727, 71503.28496714967, 72146.27853721396, 77463.0, 77855.0, 92941.0, 12900.870991289967, 12965.546205882749, 13045.869541304466, 13207.867921320663, 13364.131331463424, 12144.939275303566, 14783.186924718813, 14842.40630374773, 15099.924500377429, 15246.466373676869, 10018.198544116307, 13946.023778335824, 14135.43458261658, 14147.43410263578, 14757.92621036888, 12386.75226495447, 12994.54519091865, 13737.793933090812, 13814.723705526034, 14425.567232983027, 13394.062415630813, 13539.255340956342, 13627.931860340635, 13654.112482688593, 13704.177749335071, 10939.140211837483, 11177.16171287173, 11210.103191744804, 11447.771044579229, 11762.70593235176, 10380.844287335847, 10577.9471102644, 10586.04725574702, 10908.94545527299, 11270.436478176221], "value": 7691.079789392784, "unit": "score"}], "name": "dromaeo_css", "value": 7213.725993531864, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 113.0, 113.0, 113.0, 111.0, 125.0, 112.0, 111.0, 112.0], "value": 113.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 108.0, 126.0, 159.0, 109.0, 129.0, 131.0, 101.0, 173.0, 127.0], "value": 130.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 186.0, 184.0, 182.0, 184.0, 184.0, 180.0, 246.0, 184.0, 184.0], "value": 189.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 72.0, 76.0, 153.0, 58.0, 56.0, 57.0, 68.0, 136.0, 57.0], "value": 80.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 85.0, 134.0, 70.0, 70.0, 75.0, 117.0, 68.0, 68.0, 115.0], "value": 88.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 87.0, 84.0, 87.0, 84.0, 88.0, 85.0, 98.0, 129.0], "value": 91.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 92.0, 93.0, 94.0, 93.0, 96.0, 93.0, 93.0, 94.0, 92.0], "value": 93.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 86.0, 86.0, 82.0, 84.0, 86.0, 85.0, 83.0, 84.0], "value": 84.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 55.0, 55.0, 54.0, 54.0, 53.0, 53.0, 53.0, 54.0, 53.0], "value": 53.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 76.0, 76.0, 71.0, 71.0, 69.0, 72.0, 105.0, 98.0], "value": 77.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 85.0, 88.0, 101.0, 104.0, 106.0, 83.0, 88.0, 87.0], "value": 91.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 138.0, 136.0, 136.0, 137.0, 135.0, 138.0, 155.0, 179.0, 136.0], "value": 142.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 168.0, 168.0, 160.0, 165.0, 166.0, 167.0, 165.0, 164.0, 164.0], "value": 165.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 67.0, 66.0, 67.0, 67.0, 67.0, 66.0, 66.0, 66.0], "value": 66.3, "unit": "score"}], "name": "kraken", "value": 1469.1000000000001, "alertThreshold": 2.0}]} 18:28:57 INFO - Return code: 0 18:28:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:28:57 INFO - # TBPL SUCCESS # 18:28:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:28:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:28:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:28:57 INFO - 2016-08-29 18:28:57.265 screenresolution[1659:9909] starting screenresolution argv=screenresolution get 18:28:57 INFO - 2016-08-29 18:28:57.268 screenresolution[1659:9909] Display 0: 1600x1200x32@60 18:28:57 INFO - 2016-08-29 18:28:57.274 screenresolution[1660:9912] starting screenresolution argv=screenresolution list 18:28:57 INFO - Available Modes on Display 0 18:28:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:28:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:28:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:28:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:28:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:28:57 INFO - Intel Iris: 18:28:57 INFO - Chipset Model: Intel Iris 18:28:57 INFO - Type: GPU 18:28:57 INFO - Bus: Built-In 18:28:57 INFO - VRAM (Dynamic, Max): 1536 MB 18:28:57 INFO - Vendor: Intel (0x8086) 18:28:57 INFO - Device ID: 0x0a2e 18:28:57 INFO - Revision ID: 0x0009 18:28:57 INFO - Displays: 18:28:57 INFO - Display: 18:28:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:28:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:28:57 INFO - Display Serial Number: 1600x1200 60 18:28:57 INFO - Main Display: Yes 18:28:57 INFO - Mirror: Off 18:28:57 INFO - Online: Yes 18:28:57 INFO - Rotation: Supported 18:28:57 INFO - Return code: 0 18:28:57 INFO - Running post-action listener: _resource_record_post_action 18:28:57 INFO - [mozharness: 2016-08-30 01:28:57.507931Z] Finished run-tests step (success) 18:28:57 INFO - Running post-run listener: _resource_record_post_run 18:28:57 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 37891072; Write bytes: 707698688; Read time: 468; Write time: 2394 18:28:57 INFO - TinderboxPrint: CPU usage
53.6% 18:28:57 INFO - TinderboxPrint: I/O read bytes / time
37,891,072 / 468 18:28:57 INFO - TinderboxPrint: I/O write bytes / time
707,698,688 / 2,394 18:28:57 INFO - TinderboxPrint: CPU idle
2,248.2 (46.4%) 18:28:57 INFO - TinderboxPrint: CPU system
968.3 (20.0%) 18:28:57 INFO - TinderboxPrint: CPU user
1,629.1 (33.6%) 18:28:57 INFO - TinderboxPrint: Swap in / out
439,898,112 / 0 18:28:57 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 396710400; Write bytes: 392664576; Read time: 29045; Write time: 826 18:28:57 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 35904512; Write bytes: 296225280; Read time: 447; Write time: 1526 18:28:57 INFO - Running post-run listener: _upload_blobber_files 18:28:57 INFO - Blob upload gear active. 18:28:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:28: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 18:28: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'] 18:28: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 18:28:58 INFO - (blobuploader) - INFO - Open directory for files ... 18:28:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:28:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:28:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:28:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:28:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:28:59 INFO - (blobuploader) - INFO - Done attempting. 18:28:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:28:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:28:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:29:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:29:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:29:01 INFO - (blobuploader) - INFO - Done attempting. 18:29:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:29:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:29:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:29:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:29:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:29:03 INFO - (blobuploader) - INFO - Done attempting. 18:29:03 INFO - (blobuploader) - INFO - Iteration through files over. 18:29:03 INFO - Return code: 0 18:29:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:29:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:29:03 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/b886f016a35240bfaecdb0bed9a7df44fdff63cdb7265f1ad06ad7295aaf39eecfb3f3390c5e88356b7e72687d4352245f11be15914dabc7480ca54d076e6a68", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/05ea3df8798b642e3ab0ea83e085d7f2b57cf269f96dd0c3fc4aaea75d7c203d7766355ab61813eaf9c1dfe07434f77e2aeaaee6794f72873527bc4de571116e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/256f33405ab896e93afd8a1c441ce44919577538ca5b6de752d0cc30959b6792ba94ad36e91eac9c0a507dddb59796b22b4a930b0c4804d6e8ae8b277bdb268a"} 18:29:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:29:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:29:03 INFO - Contents: 18:29:03 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/b886f016a35240bfaecdb0bed9a7df44fdff63cdb7265f1ad06ad7295aaf39eecfb3f3390c5e88356b7e72687d4352245f11be15914dabc7480ca54d076e6a68", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/05ea3df8798b642e3ab0ea83e085d7f2b57cf269f96dd0c3fc4aaea75d7c203d7766355ab61813eaf9c1dfe07434f77e2aeaaee6794f72873527bc4de571116e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/256f33405ab896e93afd8a1c441ce44919577538ca5b6de752d0cc30959b6792ba94ad36e91eac9c0a507dddb59796b22b4a930b0c4804d6e8ae8b277bdb268a"} 18:29:03 INFO - Running post-run listener: copy_logs_to_upload_dir 18:29:03 INFO - Copying logs to upload dir... 18:29:03 INFO - mkdir: /builds/slave/test/build/upload/logs 18:29:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.830595 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-08-29 18:29:03.461305) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-29 18:29:03.464351) ========= 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.XCu3OLqzb4/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.4xn40rzCup/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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/b886f016a35240bfaecdb0bed9a7df44fdff63cdb7265f1ad06ad7295aaf39eecfb3f3390c5e88356b7e72687d4352245f11be15914dabc7480ca54d076e6a68", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/05ea3df8798b642e3ab0ea83e085d7f2b57cf269f96dd0c3fc4aaea75d7c203d7766355ab61813eaf9c1dfe07434f77e2aeaaee6794f72873527bc4de571116e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/256f33405ab896e93afd8a1c441ce44919577538ca5b6de752d0cc30959b6792ba94ad36e91eac9c0a507dddb59796b22b4a930b0c4804d6e8ae8b277bdb268a"} build_url:https://queue.taskcluster.net/v1/task/Ysc2XOXESZ-vFMAoqKpTwA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011241 build_url: 'https://queue.taskcluster.net/v1/task/Ysc2XOXESZ-vFMAoqKpTwA/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/b886f016a35240bfaecdb0bed9a7df44fdff63cdb7265f1ad06ad7295aaf39eecfb3f3390c5e88356b7e72687d4352245f11be15914dabc7480ca54d076e6a68", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/05ea3df8798b642e3ab0ea83e085d7f2b57cf269f96dd0c3fc4aaea75d7c203d7766355ab61813eaf9c1dfe07434f77e2aeaaee6794f72873527bc4de571116e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/256f33405ab896e93afd8a1c441ce44919577538ca5b6de752d0cc30959b6792ba94ad36e91eac9c0a507dddb59796b22b4a930b0c4804d6e8ae8b277bdb268a"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-29 18:29:03.501250) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 18:29:03.501569) ========= 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.XCu3OLqzb4/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.4xn40rzCup/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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.005086 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 18:29:03.549642) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-29 18:29:03.549945) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-29 18:29:03.550267) ========= ========= Total master_lag: 0.82 =========