builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0255 starttime: 1471899543.59 results: success (0) buildid: 20160822113014 builduid: 2253cea16ceb4551973b1bfa5c1211a7 revision: 3345f0e8ed3bd33d8af6e2b9bf1fd8bf397a45bc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-22 13:59:03.592953) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-22 13:59:03.593428) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-22 13:59:03.593787) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-22 13:59:03.618362) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 13:59:03.618684) ========= 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.D2aEo7gWtX/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.7k6vc6yIrI/Listeners TMPDIR=/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-08-22 13:59:03-- 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% 7.21M=0.002s 2016-08-22 13:59:03 (7.21 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.080047 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 13:59:03.721751) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 13:59:03.722108) ========= 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.D2aEo7gWtX/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.7k6vc6yIrI/Listeners TMPDIR=/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/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.021502 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 13:59:03.772748) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-08-22 13:59:03.773261) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3345f0e8ed3bd33d8af6e2b9bf1fd8bf397a45bc --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 3345f0e8ed3bd33d8af6e2b9bf1fd8bf397a45bc --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.D2aEo7gWtX/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.7k6vc6yIrI/Listeners TMPDIR=/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-08-22 13:59:03,854 truncating revision to first 12 chars 2016-08-22 13:59:03,854 Setting DEBUG logging. 2016-08-22 13:59:03,854 attempt 1/10 2016-08-22 13:59:03,854 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3345f0e8ed3b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-22 13:59:05,641 unpacking tar archive at: mozilla-central-3345f0e8ed3b/testing/mozharness/ program finished with exit code 0 elapsedTime=2.048173 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-08-22 13:59:05.845405) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-22 13:59:05.845794) ========= script_repo_revision: 3345f0e8ed3bd33d8af6e2b9bf1fd8bf397a45bc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-22 13:59:05.846330) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-22 13:59:05.846692) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-22 13:59:05.865565) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-08-22 13:59:05.865905) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.D2aEo7gWtX/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.7k6vc6yIrI/Listeners TMPDIR=/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/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 13:59:06 INFO - MultiFileLogger online at 20160822 13:59:06 in /builds/slave/test 13:59:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 13:59:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:59:06 INFO - {'append_to_log': False, 13:59:06 INFO - 'base_work_dir': '/builds/slave/test', 13:59:06 INFO - 'blob_upload_branch': 'Firefox', 13:59:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:59:06 INFO - 'branch': 'Firefox', 13:59:06 INFO - 'buildbot_json_path': 'buildprops.json', 13:59:06 INFO - 'config_files': ('talos/mac_config.py',), 13:59:06 INFO - 'default_actions': ('clobber', 13:59:06 INFO - 'read-buildbot-config', 13:59:06 INFO - 'download-and-extract', 13:59:06 INFO - 'populate-webroot', 13:59:06 INFO - 'create-virtualenv', 13:59:06 INFO - 'install', 13:59:06 INFO - 'run-tests'), 13:59:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:59:06 INFO - 'download_minidump_stackwalk': True, 13:59:06 INFO - 'download_symbols': 'ondemand', 13:59:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:59:06 INFO - 'tooltool.py': '/tools/tooltool.py', 13:59:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:59:06 INFO - '/tools/misc-python/virtualenv.py')}, 13:59:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:59:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:59:06 INFO - 'installer_path': 'installer.exe', 13:59:06 INFO - 'log_level': 'info', 13:59:06 INFO - 'log_name': 'talos', 13:59:06 INFO - 'log_to_console': True, 13:59:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:59:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:59:06 INFO - 'opt_config_files': (), 13:59:06 INFO - 'pip_index': False, 13:59:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:59:06 INFO - 'cmd': ('bash', 13:59:06 INFO - '-c', 13:59:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:59:06 INFO - 'enabled': True, 13:59:06 INFO - 'halt_on_failure': False, 13:59:06 INFO - 'name': 'check_screen_resolution'},), 13:59:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:59:06 INFO - 'cmd': ('bash', 13:59:06 INFO - '-c', 13:59:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:59:06 INFO - 'enabled': True, 13:59:06 INFO - 'halt_on_failure': False, 13:59:06 INFO - 'name': 'check_screen_resolution'},), 13:59:06 INFO - 'run_cmd_checks_enabled': True, 13:59:06 INFO - 'sps_profile': False, 13:59:06 INFO - 'sps_profile_interval': 0, 13:59:06 INFO - 'suite': 'dromaeojs', 13:59:06 INFO - 'system_bits': '32', 13:59:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:59:06 INFO - 'title': 't-yosemite-r7-0255', 13:59:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:59:06 INFO - 'use_talos_json': True, 13:59:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:59:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:59:06 INFO - 'work_dir': 'build'} 13:59:06 INFO - [mozharness: 2016-08-22 20:59:06.055334Z] Running clobber step. 13:59:06 INFO - Running pre-action listener: _resource_record_pre_action 13:59:06 INFO - Running main action method: clobber 13:59:06 INFO - rmtree: /builds/slave/test/build 13:59:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:59:07 INFO - Running post-action listener: _resource_record_post_action 13:59:07 INFO - [mozharness: 2016-08-22 20:59:07.935184Z] Finished clobber step (success) 13:59:07 INFO - [mozharness: 2016-08-22 20:59:07.935319Z] Running read-buildbot-config step. 13:59:07 INFO - Running pre-action listener: _resource_record_pre_action 13:59:07 INFO - Running main action method: read_buildbot_config 13:59:07 INFO - Using buildbot properties: 13:59:07 INFO - { 13:59:07 INFO - "project": "", 13:59:07 INFO - "product": "firefox", 13:59:07 INFO - "script_repo_revision": "production", 13:59:07 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 13:59:07 INFO - "repository": "", 13:59:07 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 13:59:07 INFO - "buildid": "20160822113014", 13:59:07 INFO - "pgo_build": "False", 13:59:07 INFO - "basedir": "/builds/slave/test", 13:59:07 INFO - "buildnumber": 39, 13:59:07 INFO - "slavename": "t-yosemite-r7-0255", 13:59:07 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 13:59:07 INFO - "platform": "yosemite_r7", 13:59:07 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 13:59:07 INFO - "branch": "mozilla-central", 13:59:07 INFO - "repo_path": "mozilla-central", 13:59:07 INFO - "revision": "3345f0e8ed3bd33d8af6e2b9bf1fd8bf397a45bc", 13:59:07 INFO - "stage_platform": "macosx64", 13:59:07 INFO - "builduid": "2253cea16ceb4551973b1bfa5c1211a7", 13:59:07 INFO - "slavebuilddir": "test" 13:59:07 INFO - } 13:59:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/D7KAnUDmTUKS1fGkWXefLg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 13:59:07 INFO - Running post-action listener: _resource_record_post_action 13:59:07 INFO - [mozharness: 2016-08-22 20:59:07.938627Z] Finished read-buildbot-config step (success) 13:59:07 INFO - [mozharness: 2016-08-22 20:59:07.938728Z] Running download-and-extract step. 13:59:07 INFO - Running pre-action listener: _resource_record_pre_action 13:59:07 INFO - Running main action method: download_and_extract 13:59:07 INFO - mkdir: /builds/slave/test/build/tests 13:59:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:59:07 INFO - trying https://queue.taskcluster.net/v1/task/D7KAnUDmTUKS1fGkWXefLg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 13:59:07 INFO - Downloading https://queue.taskcluster.net/v1/task/D7KAnUDmTUKS1fGkWXefLg/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 13:59:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/D7KAnUDmTUKS1fGkWXefLg/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 13:59:08 INFO - Downloaded 1199 bytes. 13:59:08 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 13:59:08 INFO - Using the following test package requirements: 13:59:08 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 13:59:08 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:59:08 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 13:59:08 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:59:08 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 13:59:08 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:59:08 INFO - u'jsshell-mac.zip'], 13:59:08 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:59:08 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 13:59:08 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 13:59:08 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:59:08 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 13:59:08 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:59:08 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 13:59:08 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:59:08 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 13:59:08 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:59:08 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 13:59:08 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 13:59: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')]} 13:59:08 INFO - trying https://queue.taskcluster.net/v1/task/D7KAnUDmTUKS1fGkWXefLg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 13:59:08 INFO - Downloading https://queue.taskcluster.net/v1/task/D7KAnUDmTUKS1fGkWXefLg/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 13:59:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/D7KAnUDmTUKS1fGkWXefLg/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 13:59:09 INFO - Downloaded 16352467 bytes. 13:59:09 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 13:59:16 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 13:59:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:59:16 INFO - trying https://queue.taskcluster.net/v1/task/D7KAnUDmTUKS1fGkWXefLg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 13:59:16 INFO - Downloading https://queue.taskcluster.net/v1/task/D7KAnUDmTUKS1fGkWXefLg/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 13:59:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/D7KAnUDmTUKS1fGkWXefLg/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 13:59:17 INFO - Downloaded 16352467 bytes. 13:59:17 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 13:59:24 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')]} 13:59:24 INFO - trying https://queue.taskcluster.net/v1/task/D7KAnUDmTUKS1fGkWXefLg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 13:59:24 INFO - Downloading https://queue.taskcluster.net/v1/task/D7KAnUDmTUKS1fGkWXefLg/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 13:59:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/D7KAnUDmTUKS1fGkWXefLg/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 13:59:25 INFO - Downloaded 14413981 bytes. 13:59:25 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 13:59: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')]} 13:59:25 INFO - trying https://queue.taskcluster.net/v1/task/D7KAnUDmTUKS1fGkWXefLg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 13:59:25 INFO - Downloading https://queue.taskcluster.net/v1/task/D7KAnUDmTUKS1fGkWXefLg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 13:59:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/D7KAnUDmTUKS1fGkWXefLg/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 13:59:28 INFO - Downloaded 111489018 bytes. 13:59:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/D7KAnUDmTUKS1fGkWXefLg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 13:59:28 INFO - mkdir: /builds/slave/test/properties 13:59:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:59:28 INFO - Writing to file /builds/slave/test/properties/build_url 13:59:28 INFO - Contents: 13:59:28 INFO - build_url:https://queue.taskcluster.net/v1/task/D7KAnUDmTUKS1fGkWXefLg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 13:59:29 INFO - Running post-action listener: _resource_record_post_action 13:59:29 INFO - Running post-action listener: set_extra_try_arguments 13:59:29 INFO - [mozharness: 2016-08-22 20:59:29.070395Z] Finished download-and-extract step (success) 13:59:29 INFO - [mozharness: 2016-08-22 20:59:29.070519Z] Running populate-webroot step. 13:59:29 INFO - Running pre-action listener: _resource_record_pre_action 13:59:29 INFO - Running main action method: populate_webroot 13:59:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:59:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:59:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:59:29 INFO - '--tppagecycles', 13:59:29 INFO - '7'], 13:59:29 INFO - 'tests': ['tsvgm']}}, 13:59:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:59:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:59:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:59:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:59:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:59:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:59:29 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:59:29 INFO - 'talos_options': ['--disable-e10s'], 13:59:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:59:29 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:59:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:59:29 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:59:29 INFO - 'talos_options': ['--disable-e10s'], 13:59:29 INFO - 'tests': ['damp', 'tps']}, 13:59:29 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:59:29 INFO - 'tests': ['damp', 'tps']}, 13:59:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:59:29 INFO - 'tests': ['dromaeo_dom']}, 13:59:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:59:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:59:29 INFO - 'tests': ['basic_compositor_video']}, 13:59:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:59:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:59:29 INFO - 'tests': ['a11yr', 13:59:29 INFO - 'ts_paint', 13:59:29 INFO - 'tpaint', 13:59:29 INFO - 'sessionrestore', 13:59:29 INFO - 'sessionrestore_no_auto_restore', 13:59:29 INFO - 'tabpaint']}, 13:59:29 INFO - 'other-e10s': {'tests': ['a11yr', 13:59:29 INFO - 'ts_paint', 13:59:29 INFO - 'tpaint', 13:59:29 INFO - 'sessionrestore', 13:59:29 INFO - 'sessionrestore_no_auto_restore', 13:59:29 INFO - 'tabpaint']}, 13:59:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:59:29 INFO - 'ts_paint', 13:59:29 INFO - 'tpaint', 13:59:29 INFO - 'sessionrestore', 13:59:29 INFO - 'sessionrestore_no_auto_restore', 13:59:29 INFO - 'tabpaint']}, 13:59:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:59:29 INFO - 'ts_paint', 13:59:29 INFO - 'tpaint', 13:59:29 INFO - 'sessionrestore', 13:59:29 INFO - 'sessionrestore_no_auto_restore', 13:59:29 INFO - 'tabpaint']}, 13:59:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:59:29 INFO - 'tests': ['a11yr', 13:59:29 INFO - 'ts_paint', 13:59:29 INFO - 'tpaint', 13:59:29 INFO - 'sessionrestore', 13:59:29 INFO - 'sessionrestore_no_auto_restore', 13:59:29 INFO - 'tabpaint']}, 13:59:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:59:29 INFO - 'tests': ['a11yr', 13:59:29 INFO - 'ts_paint', 13:59:29 INFO - 'tpaint', 13:59:29 INFO - 'sessionrestore', 13:59:29 INFO - 'sessionrestore_no_auto_restore', 13:59:29 INFO - 'tabpaint']}, 13:59:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:59:29 INFO - 'tests': ['tsvgx', 13:59:29 INFO - 'tsvgr_opacity', 13:59:29 INFO - 'tart', 13:59:29 INFO - 'tscrollx', 13:59:29 INFO - 'cart']}, 13:59:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:59:29 INFO - 'tsvgr_opacity', 13:59:29 INFO - 'tart', 13:59:29 INFO - 'tscrollx', 13:59:29 INFO - 'cart']}, 13:59:29 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:59:29 INFO - 'talos_options': ['--disable-e10s'], 13:59:29 INFO - 'tests': ['tp5o']}, 13:59:29 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:59:29 INFO - 'tests': ['tp5o']}, 13:59:29 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:59:29 INFO - 'talos_options': ['--disable-e10s', 13:59:29 INFO - '--xperf_path', 13:59:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:59:29 INFO - 'tests': ['tp5n']}, 13:59:29 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:59:29 INFO - 'talos_options': ['--xperf_path', 13:59:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:59:29 INFO - 'tests': ['tp5n']}}, 13:59:29 INFO - 'talos.zip': {'path': '', 13:59:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:59:29 INFO - Running post-action listener: _resource_record_post_action 13:59:29 INFO - [mozharness: 2016-08-22 20:59:29.089419Z] Finished populate-webroot step (success) 13:59:29 INFO - [mozharness: 2016-08-22 20:59:29.089549Z] Running create-virtualenv step. 13:59:29 INFO - Running pre-action listener: _resource_record_pre_action 13:59:29 INFO - Running main action method: create_virtualenv 13:59:29 INFO - Creating virtualenv /builds/slave/test/build/venv 13:59:29 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 13:59:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:59:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:59:29 INFO - Using real prefix '/tools/python27' 13:59:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:59:29 INFO - Installing distribute.............................................................................................................................................................................................done. 13:59:32 INFO - Installing pip.................done. 13:59:32 INFO - Return code: 0 13:59:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:59:32 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')]} 13:59:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:59:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:32 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')]} 13:59:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:59:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:32 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 0x10f4caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f55f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e735b7960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f42ed30>, '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 0x10f193db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f426b20>, '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.D2aEo7gWtX/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.7k6vc6yIrI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/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 13:59:32 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 13:59:32 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 13:59:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D2aEo7gWtX/Render', 13:59:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:59:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:59:32 INFO - 'HOME': '/Users/cltbld', 13:59:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:59:32 INFO - 'LOGNAME': 'cltbld', 13:59:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:59:32 INFO - 'MOZ_NO_REMOTE': '1', 13:59:32 INFO - 'NO_EM_RESTART': '1', 13:59:32 INFO - 'PAGER': '/bin/cat', 13:59:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:59:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:59:32 INFO - 'PWD': '/builds/slave/test', 13:59:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:59:32 INFO - 'SHELL': '/bin/bash', 13:59:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7k6vc6yIrI/Listeners', 13:59:32 INFO - 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/', 13:59:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:59:32 INFO - 'USER': 'cltbld', 13:59:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:59:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:59:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:59:32 INFO - 'XPC_FLAGS': '0x0', 13:59:32 INFO - 'XPC_SERVICE_NAME': '0', 13:59:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:59:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:59:33 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 13:59:33 INFO - Cleaning up... 13:59:33 INFO - Return code: 0 13:59:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:59: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')]} 13:59:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:59:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59: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')]} 13:59:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:59:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:33 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 0x10f4caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f55f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e735b7960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f42ed30>, '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 0x10f193db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f426b20>, '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.D2aEo7gWtX/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.7k6vc6yIrI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/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 13:59:33 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 13:59:33 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 13:59:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D2aEo7gWtX/Render', 13:59:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:59:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:59:33 INFO - 'HOME': '/Users/cltbld', 13:59:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:59:33 INFO - 'LOGNAME': 'cltbld', 13:59:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:59:33 INFO - 'MOZ_NO_REMOTE': '1', 13:59:33 INFO - 'NO_EM_RESTART': '1', 13:59:33 INFO - 'PAGER': '/bin/cat', 13:59:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:59:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:59:33 INFO - 'PWD': '/builds/slave/test', 13:59:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:59:33 INFO - 'SHELL': '/bin/bash', 13:59:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7k6vc6yIrI/Listeners', 13:59:33 INFO - 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/', 13:59:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:59:33 INFO - 'USER': 'cltbld', 13:59:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:59:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:59:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:59:33 INFO - 'XPC_FLAGS': '0x0', 13:59:33 INFO - 'XPC_SERVICE_NAME': '0', 13:59:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:59:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:59:33 INFO - Downloading/unpacking psutil>=3.1.1 13:59:33 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 13:59:33 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 13:59:33 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 13:59:33 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 13:59:33 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 13:59:33 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 13:59:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:59:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:59:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:59:36 INFO - Installing collected packages: psutil 13:59:36 INFO - Running setup.py install for psutil 13:59:36 INFO - building 'psutil._psutil_osx' extension 13:59:36 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 13:59:37 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 13:59:37 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 13:59:37 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 13:59:37 INFO - building 'psutil._psutil_posix' extension 13:59:37 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 13:59:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:59:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:59:37 INFO - ^ 13:59:37 INFO - 1 warning generated. 13:59:37 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 13:59:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:59:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:59:37 INFO - Successfully installed psutil 13:59:37 INFO - Cleaning up... 13:59:37 INFO - Return code: 0 13:59:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:59:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:59:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:59:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:59:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:59:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f4caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f55f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e735b7960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f42ed30>, '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 0x10f193db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f426b20>, '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.D2aEo7gWtX/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.7k6vc6yIrI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/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 13:59:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 13:59:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 13:59:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D2aEo7gWtX/Render', 13:59:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:59:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:59:37 INFO - 'HOME': '/Users/cltbld', 13:59:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:59:37 INFO - 'LOGNAME': 'cltbld', 13:59:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:59:37 INFO - 'MOZ_NO_REMOTE': '1', 13:59:37 INFO - 'NO_EM_RESTART': '1', 13:59:37 INFO - 'PAGER': '/bin/cat', 13:59:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:59:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:59:37 INFO - 'PWD': '/builds/slave/test', 13:59:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:59:37 INFO - 'SHELL': '/bin/bash', 13:59:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7k6vc6yIrI/Listeners', 13:59:37 INFO - 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/', 13:59:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:59:37 INFO - 'USER': 'cltbld', 13:59:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:59:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:59:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:59:37 INFO - 'XPC_FLAGS': '0x0', 13:59:37 INFO - 'XPC_SERVICE_NAME': '0', 13:59:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:59:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:59:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:59:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:59:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:59:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:59:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:59:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:59:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:59:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:59:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:59:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:59:40 INFO - Installing collected packages: mozsystemmonitor 13:59:40 INFO - Running setup.py install for mozsystemmonitor 13:59:40 INFO - Successfully installed mozsystemmonitor 13:59:40 INFO - Cleaning up... 13:59:40 INFO - Return code: 0 13:59:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:59:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:59:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:59:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:59:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:59:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:40 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 0x10f4caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f55f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e735b7960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f42ed30>, '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 0x10f193db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f426b20>, '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.D2aEo7gWtX/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.7k6vc6yIrI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/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 13:59:40 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 13:59:40 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 13:59:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D2aEo7gWtX/Render', 13:59:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:59:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:59:40 INFO - 'HOME': '/Users/cltbld', 13:59:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:59:40 INFO - 'LOGNAME': 'cltbld', 13:59:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:59:40 INFO - 'MOZ_NO_REMOTE': '1', 13:59:40 INFO - 'NO_EM_RESTART': '1', 13:59:40 INFO - 'PAGER': '/bin/cat', 13:59:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:59:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:59:40 INFO - 'PWD': '/builds/slave/test', 13:59:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:59:40 INFO - 'SHELL': '/bin/bash', 13:59:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7k6vc6yIrI/Listeners', 13:59:40 INFO - 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/', 13:59:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:59:40 INFO - 'USER': 'cltbld', 13:59:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:59:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:59:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:59:40 INFO - 'XPC_FLAGS': '0x0', 13:59:40 INFO - 'XPC_SERVICE_NAME': '0', 13:59:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:59:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:59:40 INFO - Downloading/unpacking jsonschema==2.5.1 13:59:40 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 13:59:40 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 13:59:40 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 13:59:40 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 13:59:40 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 13:59:40 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 13:59:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:59:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:59:43 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 13:59:43 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 13:59:43 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 13:59:43 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 13:59:43 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 13:59:43 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 13:59:43 INFO - Downloading functools32-3.2.3-2.tar.gz 13:59:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:59:43 INFO - warning: no files found matching '*.txt' 13:59:43 INFO - no previously-included directories found matching 'build' 13:59:43 INFO - no previously-included directories found matching 'dist' 13:59:43 INFO - no previously-included directories found matching '.git*' 13:59:43 INFO - Installing collected packages: jsonschema, functools32 13:59:43 INFO - Running setup.py install for functools32 13:59:43 INFO - warning: no files found matching '*.txt' 13:59:43 INFO - no previously-included directories found matching 'build' 13:59:43 INFO - no previously-included directories found matching 'dist' 13:59:43 INFO - no previously-included directories found matching '.git*' 13:59:43 INFO - Successfully installed jsonschema functools32 13:59:43 INFO - Cleaning up... 13:59:43 INFO - Return code: 0 13:59:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:59:43 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')]} 13:59:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:59:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:43 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')]} 13:59:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:59:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:44 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 0x10f4caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f55f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e735b7960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f42ed30>, '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 0x10f193db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f426b20>, '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.D2aEo7gWtX/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.7k6vc6yIrI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/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 13:59:44 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 13:59:44 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 13:59:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D2aEo7gWtX/Render', 13:59:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:59:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:59:44 INFO - 'HOME': '/Users/cltbld', 13:59:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:59:44 INFO - 'LOGNAME': 'cltbld', 13:59:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:59:44 INFO - 'MOZ_NO_REMOTE': '1', 13:59:44 INFO - 'NO_EM_RESTART': '1', 13:59:44 INFO - 'PAGER': '/bin/cat', 13:59:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:59:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:59:44 INFO - 'PWD': '/builds/slave/test', 13:59:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:59:44 INFO - 'SHELL': '/bin/bash', 13:59:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7k6vc6yIrI/Listeners', 13:59:44 INFO - 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/', 13:59:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:59:44 INFO - 'USER': 'cltbld', 13:59:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:59:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:59:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:59:44 INFO - 'XPC_FLAGS': '0x0', 13:59:44 INFO - 'XPC_SERVICE_NAME': '0', 13:59:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:59:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:59:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:59:44 INFO - Cleaning up... 13:59:44 INFO - Return code: 0 13:59:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:59: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')]} 13:59:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:59:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59: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')]} 13:59:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:59:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:44 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 0x10f4caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f55f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e735b7960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f42ed30>, '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 0x10f193db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f426b20>, '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.D2aEo7gWtX/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.7k6vc6yIrI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/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 13:59:44 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 13:59:44 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 13:59:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D2aEo7gWtX/Render', 13:59:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:59:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:59:44 INFO - 'HOME': '/Users/cltbld', 13:59:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:59:44 INFO - 'LOGNAME': 'cltbld', 13:59:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:59:44 INFO - 'MOZ_NO_REMOTE': '1', 13:59:44 INFO - 'NO_EM_RESTART': '1', 13:59:44 INFO - 'PAGER': '/bin/cat', 13:59:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:59:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:59:44 INFO - 'PWD': '/builds/slave/test', 13:59:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:59:44 INFO - 'SHELL': '/bin/bash', 13:59:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7k6vc6yIrI/Listeners', 13:59:44 INFO - 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/', 13:59:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:59:44 INFO - 'USER': 'cltbld', 13:59:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:59:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:59:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:59:44 INFO - 'XPC_FLAGS': '0x0', 13:59:44 INFO - 'XPC_SERVICE_NAME': '0', 13:59:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:59:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:59:44 INFO - Downloading/unpacking blobuploader==1.2.4 13:59:44 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 13:59:44 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 13:59:44 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 13:59:44 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 13:59:44 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 13:59:44 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 13:59:46 INFO - Downloading blobuploader-1.2.4.tar.gz 13:59:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:59:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:59:47 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 13:59:47 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 13:59:47 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 13:59:47 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 13:59:47 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 13:59:47 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 13:59:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:59:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:59:47 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 13:59:47 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 13:59:47 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 13:59:47 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 13:59:47 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 13:59:47 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 13:59:48 INFO - Downloading docopt-0.6.1.tar.gz 13:59:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:59:48 INFO - Installing collected packages: blobuploader, requests, docopt 13:59:48 INFO - Running setup.py install for blobuploader 13:59:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:59:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:59:48 INFO - Running setup.py install for requests 13:59:48 INFO - Running setup.py install for docopt 13:59:48 INFO - Successfully installed blobuploader requests docopt 13:59:48 INFO - Cleaning up... 13:59:49 INFO - Return code: 0 13:59:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:59:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:59:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:59:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:59:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:59:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:49 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 0x10f4caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f55f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e735b7960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f42ed30>, '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 0x10f193db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f426b20>, '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.D2aEo7gWtX/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.7k6vc6yIrI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/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 13:59:49 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 13:59:49 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 13:59:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D2aEo7gWtX/Render', 13:59:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:59:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:59:49 INFO - 'HOME': '/Users/cltbld', 13:59:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:59:49 INFO - 'LOGNAME': 'cltbld', 13:59:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:59:49 INFO - 'MOZ_NO_REMOTE': '1', 13:59:49 INFO - 'NO_EM_RESTART': '1', 13:59:49 INFO - 'PAGER': '/bin/cat', 13:59:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:59:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:59:49 INFO - 'PWD': '/builds/slave/test', 13:59:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:59:49 INFO - 'SHELL': '/bin/bash', 13:59:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7k6vc6yIrI/Listeners', 13:59:49 INFO - 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/', 13:59:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:59:49 INFO - 'USER': 'cltbld', 13:59:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:59:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:59:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:59:49 INFO - 'XPC_FLAGS': '0x0', 13:59:49 INFO - 'XPC_SERVICE_NAME': '0', 13:59:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:59:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:59:49 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-PbPvv_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:59:49 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-PWejNF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:59:49 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-7AzUfP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:59:49 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-LsDv_d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:59:49 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-raBRfh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:59:49 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-md3RgB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:59:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:59:49 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-piZZmJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:59:50 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-KItXY1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:59:50 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-ZNyeTO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:59:50 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-Le4cI8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:59:50 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-e9l5tY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:59:50 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-Wg5wpU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:59:50 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-Tvtcnx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:59:50 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-8YJLA9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:59:50 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-1MlS_4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:59:50 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-qau2pT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:59:50 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-o9mVB6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:59:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:59:51 INFO - Running setup.py install for manifestparser 13:59:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:59:51 INFO - Running setup.py install for mozcrash 13:59:51 INFO - Running setup.py install for mozdebug 13:59:51 INFO - Running setup.py install for mozdevice 13:59:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:59:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:59:51 INFO - Running setup.py install for mozfile 13:59:51 INFO - Running setup.py install for mozhttpd 13:59:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:59:51 INFO - Running setup.py install for mozinfo 13:59:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:59:52 INFO - Running setup.py install for mozInstall 13:59:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:59:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:59:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:59:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:59:52 INFO - Running setup.py install for mozleak 13:59:52 INFO - Running setup.py install for mozlog 13:59:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:59:52 INFO - Running setup.py install for moznetwork 13:59:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:59:52 INFO - Running setup.py install for mozprocess 13:59:52 INFO - Running setup.py install for mozprofile 13:59:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:59:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:59:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:59:53 INFO - Running setup.py install for mozrunner 13:59:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:59:53 INFO - Running setup.py install for mozscreenshot 13:59:53 INFO - Running setup.py install for moztest 13:59:53 INFO - Running setup.py install for mozversion 13:59:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:59:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:59:53 INFO - Cleaning up... 13:59:53 INFO - Return code: 0 13:59:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:59: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')]} 13:59:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:59:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59: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')]} 13:59:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:59:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:53 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 0x10f4caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f55f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e735b7960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f42ed30>, '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 0x10f193db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f426b20>, '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.D2aEo7gWtX/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.7k6vc6yIrI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/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 13:59:53 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 13:59:53 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 13:59:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D2aEo7gWtX/Render', 13:59:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:59:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:59:53 INFO - 'HOME': '/Users/cltbld', 13:59:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:59:53 INFO - 'LOGNAME': 'cltbld', 13:59:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:59:53 INFO - 'MOZ_NO_REMOTE': '1', 13:59:53 INFO - 'NO_EM_RESTART': '1', 13:59:53 INFO - 'PAGER': '/bin/cat', 13:59:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:59:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:59:53 INFO - 'PWD': '/builds/slave/test', 13:59:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:59:53 INFO - 'SHELL': '/bin/bash', 13:59:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7k6vc6yIrI/Listeners', 13:59:53 INFO - 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/', 13:59:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:59:53 INFO - 'USER': 'cltbld', 13:59:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:59:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:59:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:59:53 INFO - 'XPC_FLAGS': '0x0', 13:59:53 INFO - 'XPC_SERVICE_NAME': '0', 13:59:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:59:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:59:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:59:53 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-JsG7wK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:59:54 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)) 13:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:59:54 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-3_vgsf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:59:54 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)) 13:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:59:54 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-eTf2fS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:59:54 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)) 13:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:59:54 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-ZfDV0q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:59:54 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)) 13:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:59:54 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-eXUW4e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:59:54 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)) 13:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:59:54 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-EK2NYK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:59:54 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)) 13:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:59:54 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-3LtPHo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:59:54 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)) 13:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:59:54 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-oSBdLL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:59:54 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)) 13:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:59:54 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-5MDLd5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:59:54 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)) 13:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:59:54 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-vz9YYQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:59:54 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)) 13:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:59:54 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-Zx9C8N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:59:55 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)) 13:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:59:55 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-yMf1ag-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:59:55 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)) 13:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:59:55 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-eEAHtD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:59:55 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)) 13:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:59:55 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-GdVD1c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:59:55 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)) 13:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:59:55 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-N8jIwW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:59:55 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)) 13:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:59:55 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-emIGZv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:59:55 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)) 13:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:59:55 INFO - Running setup.py (path:/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/pip-TsGdlQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:59:55 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)) 13:59:55 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)) 13:59:55 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)) 13:59:55 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)) 13:59:55 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)) 13:59:55 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)) 13:59:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:59:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:59:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:59:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:59:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:59:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:59:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:59:58 INFO - Downloading blessings-1.6.tar.gz 13:59:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:59:58 INFO - Installing collected packages: blessings 13:59:58 INFO - Running setup.py install for blessings 13:59:58 INFO - Successfully installed blessings 13:59:58 INFO - Cleaning up... 13:59:58 INFO - Return code: 0 13:59:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:59:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:59:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:59:58 INFO - Reading from file tmpfile_stdout 13:59:58 INFO - Current package versions: 13:59:58 INFO - blessings == 1.6 13:59:58 INFO - blobuploader == 1.2.4 13:59:58 INFO - docopt == 0.6.1 13:59:58 INFO - functools32 == 3.2.3-2 13:59:58 INFO - jsonschema == 2.5.1 13:59:58 INFO - manifestparser == 1.1 13:59:58 INFO - mozInstall == 1.12 13:59:58 INFO - mozcrash == 0.17 13:59:58 INFO - mozdebug == 0.1 13:59:58 INFO - mozdevice == 0.48 13:59:58 INFO - mozfile == 1.2 13:59:58 INFO - mozhttpd == 0.7 13:59:58 INFO - mozinfo == 0.9 13:59:58 INFO - mozleak == 0.1 13:59:58 INFO - mozlog == 3.3 13:59:58 INFO - moznetwork == 0.27 13:59:58 INFO - mozprocess == 0.23 13:59:58 INFO - mozprofile == 0.28 13:59:58 INFO - mozrunner == 6.12 13:59:58 INFO - mozscreenshot == 0.1 13:59:58 INFO - mozsystemmonitor == 0.3 13:59:58 INFO - moztest == 0.7 13:59:58 INFO - mozversion == 1.4 13:59:58 INFO - psutil == 3.1.1 13:59:58 INFO - requests == 1.2.3 13:59:58 INFO - wsgiref == 0.1.2 13:59:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:59:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:59:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:59:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:59 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')]} 13:59:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:59:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:59 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 0x10f4caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f55f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e735b7960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f42ed30>, '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 0x10f193db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f426b20>, '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.D2aEo7gWtX/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.7k6vc6yIrI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/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 13:59:59 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 13:59:59 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 13:59:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D2aEo7gWtX/Render', 13:59:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:59:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:59:59 INFO - 'HOME': '/Users/cltbld', 13:59:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:59:59 INFO - 'LOGNAME': 'cltbld', 13:59:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:59:59 INFO - 'MOZ_NO_REMOTE': '1', 13:59:59 INFO - 'NO_EM_RESTART': '1', 13:59:59 INFO - 'PAGER': '/bin/cat', 13:59:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:59:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:59:59 INFO - 'PWD': '/builds/slave/test', 13:59:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:59:59 INFO - 'SHELL': '/bin/bash', 13:59:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7k6vc6yIrI/Listeners', 13:59:59 INFO - 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/', 13:59:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:59:59 INFO - 'USER': 'cltbld', 13:59:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:59:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:59:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:59:59 INFO - 'XPC_FLAGS': '0x0', 13:59:59 INFO - 'XPC_SERVICE_NAME': '0', 13:59:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:59:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:59:59 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)) 13:59:59 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)) 13:59:59 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)) 13:59:59 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)) 13:59:59 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)) 13:59:59 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)) 13:59:59 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)) 13:59:59 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)) 13:59:59 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)) 13:59:59 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)) 13:59:59 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)) 13:59:59 INFO - Cleaning up... 13:59:59 INFO - Return code: 0 13:59:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:59:59 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')]} 13:59:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:59:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:59 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')]} 13:59:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:59:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:59:59 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 0x10f4caa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f55f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e735b7960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f42ed30>, '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 0x10f193db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f426b20>, '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.D2aEo7gWtX/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.7k6vc6yIrI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/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 13:59:59 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 13:59:59 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 13:59:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D2aEo7gWtX/Render', 13:59:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:59:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:59:59 INFO - 'HOME': '/Users/cltbld', 13:59:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:59:59 INFO - 'LOGNAME': 'cltbld', 13:59:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:59:59 INFO - 'MOZ_NO_REMOTE': '1', 13:59:59 INFO - 'NO_EM_RESTART': '1', 13:59:59 INFO - 'PAGER': '/bin/cat', 13:59:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:59:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:59:59 INFO - 'PWD': '/builds/slave/test', 13:59:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:59:59 INFO - 'SHELL': '/bin/bash', 13:59:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7k6vc6yIrI/Listeners', 13:59:59 INFO - 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/', 13:59:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:59:59 INFO - 'USER': 'cltbld', 13:59:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:59:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:59:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:59:59 INFO - 'XPC_FLAGS': '0x0', 13:59:59 INFO - 'XPC_SERVICE_NAME': '0', 13:59:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:59:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:59:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:59:59 INFO - Cleaning up... 13:59:59 INFO - Return code: 0 13:59:59 INFO - Running post-action listener: _resource_record_post_action 13:59:59 INFO - Running post-action listener: _start_resource_monitoring 13:59:59 INFO - Starting resource monitoring. 13:59:59 INFO - [mozharness: 2016-08-22 20:59:59.898272Z] Finished create-virtualenv step (success) 13:59:59 INFO - [mozharness: 2016-08-22 20:59:59.899244Z] Running install step. 13:59:59 INFO - Running pre-action listener: _resource_record_pre_action 13:59:59 INFO - Running main action method: install 13:59:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:59:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:00:00 INFO - Reading from file tmpfile_stdout 14:00:00 INFO - Detecting whether we're running mozinstall >=1.0... 14:00:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:00:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:00:00 INFO - Reading from file tmpfile_stdout 14:00:00 INFO - Output received: 14:00:00 INFO - Usage: mozinstall [options] installer 14:00:00 INFO - Options: 14:00:00 INFO - -h, --help show this help message and exit 14:00:00 INFO - -d DEST, --destination=DEST 14:00:00 INFO - Directory to install application into. [default: 14:00:00 INFO - "/builds/slave/test"] 14:00:00 INFO - --app=APP Application being installed. [default: firefox] 14:00:00 INFO - mkdir: /builds/slave/test/build/application 14:00:00 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'] 14:00:00 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 14:00:34 INFO - Reading from file tmpfile_stdout 14:00:34 INFO - Output received: 14:00:34 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:00:34 INFO - Running post-action listener: _resource_record_post_action 14:00:34 INFO - [mozharness: 2016-08-22 21:00:34.247459Z] Finished install step (success) 14:00:34 INFO - [mozharness: 2016-08-22 21:00:34.247617Z] Running run-tests step. 14:00:34 INFO - Running pre-action listener: _resource_record_pre_action 14:00:34 INFO - Running main action method: run_tests 14:00:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:00:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:00:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:00:34 INFO - 2016-08-22 14:00:34.262 screenresolution[1982:11035] starting screenresolution argv=screenresolution get 14:00:34 INFO - 2016-08-22 14:00:34.264 screenresolution[1982:11035] Display 0: 1600x1200x32@60 14:00:34 INFO - 2016-08-22 14:00:34.271 screenresolution[1983:11037] starting screenresolution argv=screenresolution list 14:00:34 INFO - Available Modes on Display 0 14:00:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:00:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:00:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:00:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:00:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:00:34 INFO - Intel Iris: 14:00:34 INFO - Chipset Model: Intel Iris 14:00:34 INFO - Type: GPU 14:00:34 INFO - Bus: Built-In 14:00:34 INFO - VRAM (Dynamic, Max): 1536 MB 14:00:34 INFO - Vendor: Intel (0x8086) 14:00:34 INFO - Device ID: 0x0a2e 14:00:34 INFO - Revision ID: 0x0009 14:00:34 INFO - Displays: 14:00:34 INFO - Display: 14:00:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:00:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:00:34 INFO - Display Serial Number: 1600x1200 60 14:00:34 INFO - Main Display: Yes 14:00:34 INFO - Mirror: Off 14:00:34 INFO - Online: Yes 14:00:34 INFO - Rotation: Supported 14:00:34 INFO - Return code: 0 14:00:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:00:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:00:34 INFO - Python 2.7.3 14:00:34 INFO - Return code: 0 14:00:34 INFO - grabbing minidump binary from tooltool 14:00:34 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')]} 14:00:34 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 0x10f42ed30>, '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 0x10f193db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f426b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:00:34 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 14:00:34 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 14:00:34 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 14:00:34 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:00:34 INFO - Return code: 0 14:00:34 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:00:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:00:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:00:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:00:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:00:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/D7KAnUDmTUKS1fGkWXefLg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0255', '--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 14:00:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/D7KAnUDmTUKS1fGkWXefLg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0255 --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 14:00:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.D2aEo7gWtX/Render', 14:00:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:00:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:00:34 INFO - 'HOME': '/Users/cltbld', 14:00:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:00:34 INFO - 'LOGNAME': 'cltbld', 14:00:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:00:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:00:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:00:34 INFO - 'MOZ_NO_REMOTE': '1', 14:00:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:00:34 INFO - 'NO_EM_RESTART': '1', 14:00:34 INFO - 'PAGER': '/bin/cat', 14:00:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:00:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:00:34 INFO - 'PWD': '/builds/slave/test', 14:00:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:00:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:00:34 INFO - 'SHELL': '/bin/bash', 14:00:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7k6vc6yIrI/Listeners', 14:00:34 INFO - 'TMPDIR': '/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/', 14:00:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:00:34 INFO - 'USER': 'cltbld', 14:00:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:00:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:00:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:00:34 INFO - 'XPC_FLAGS': '0x0', 14:00:34 INFO - 'XPC_SERVICE_NAME': '0', 14:00:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:00:34 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/D7KAnUDmTUKS1fGkWXefLg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0255', '--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 14:00:34 INFO - mozversion application_buildid: 20160822113014 14:00:34 INFO - mozversion application_changeset: 3345f0e8ed3bd33d8af6e2b9bf1fd8bf397a45bc 14:00:34 INFO - mozversion application_display_name: Nightly 14:00:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:00:34 INFO - mozversion application_name: Firefox 14:00:34 INFO - mozversion application_remotingname: firefox 14:00:34 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:00:34 INFO - mozversion application_vendor: Mozilla 14:00:34 INFO - mozversion application_version: 51.0a1 14:00:34 INFO - mozversion platform_buildid: 20160822113014 14:00:34 INFO - mozversion platform_changeset: 3345f0e8ed3bd33d8af6e2b9bf1fd8bf397a45bc 14:00:34 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:00:34 INFO - mozversion platform_version: 51.0a1 14:00:34 INFO - using testdate: 1471899634 14:00:34 INFO - actual date: 1471899634 14:00:34 INFO - starting webserver on 'localhost:49382' 14:00:34 INFO - SUITE-START | Running 2 tests 14:00:34 INFO - TEST-START | dromaeo_css 14:00:34 INFO - operating with platform_type : mac_ 14:00:34 INFO - Initialising browser for dromaeo_css test... 14:00:34 INFO - TEST-INFO | started process 1993 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/tmpcXik2h/profile http://localhost:49382/getInfo.html) 14:00:37 INFO - PROCESS | 1993 | __metrics Screen width/height:1600/1200 14:00:37 INFO - PROCESS | 1993 | colorDepth:24 14:00:37 INFO - PROCESS | 1993 | Browser inner width/height: 1024/689 14:00:37 INFO - PROCESS | 1993 | __metrics 14:00:37 INFO - PROCESS | 1993 | 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" 14:00:37 INFO - PROCESS | 1993 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:00:38 INFO - TEST-INFO | 1993: exit 0 14:00:38 INFO - Browser initialized. 14:00:38 INFO - Running cycle 1/1 for dromaeo_css test... 14:00:38 INFO - TEST-INFO | started process 1995 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/tmpcXik2h/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:00:49 INFO - PROCESS | 1995 | RSS: Main: 228933632 14:00:49 INFO - PROCESS | 1995 | 14:01:40 INFO - PROCESS | 1995 | 1471899700880 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] 14:03:53 INFO - PROCESS | 1995 | Cycle 1(1): loaded http://localhost:49382/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49382/tests/dromaeo/cssquery-ext.html) 14:03:54 INFO - PROCESS | 1995 | RSS: Main: 351887360 14:03:54 INFO - PROCESS | 1995 | 14:06:57 INFO - PROCESS | 1995 | Cycle 1(1): loaded http://localhost:49382/tests/dromaeo/cssquery-ext.html (next: http://localhost:49382/tests/dromaeo/cssquery-jquery.html) 14:06:57 INFO - PROCESS | 1995 | RSS: Main: 369967104 14:06:57 INFO - PROCESS | 1995 | 14:10:00 INFO - PROCESS | 1995 | Cycle 1(1): loaded http://localhost:49382/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49382/tests/dromaeo/cssquery-mootools.html) 14:10:00 INFO - PROCESS | 1995 | RSS: Main: 337420288 14:10:00 INFO - PROCESS | 1995 | 14:13:03 INFO - PROCESS | 1995 | Cycle 1(1): loaded http://localhost:49382/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49382/tests/dromaeo/cssquery-prototype.html) 14:13:03 INFO - PROCESS | 1995 | RSS: Main: 338313216 14:13:03 INFO - PROCESS | 1995 | 14:16:07 INFO - PROCESS | 1995 | Cycle 1(1): loaded http://localhost:49382/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49382/tests/dromaeo/cssquery-yui.html) 14:16:07 INFO - PROCESS | 1995 | RSS: Main: 334127104 14:16:07 INFO - PROCESS | 1995 | 14:19:10 INFO - PROCESS | 1995 | Cycle 1(1): loaded http://localhost:49382/tests/dromaeo/cssquery-yui.html (next: http://localhost:49382/tests/dromaeo/cssquery-dojo.html) 14:19:11 INFO - PROCESS | 1995 | RSS: Main: 475545600 14:19:11 INFO - PROCESS | 1995 | 14:19:11 INFO - PROCESS | 1995 | __start_tp_report 14:19:11 INFO - PROCESS | 1995 | _x_x_mozilla_page_load 14:19:11 INFO - PROCESS | 1995 | _x_x_mozilla_page_load_details 14:19:11 INFO - PROCESS | 1995 | |i|pagename|runs| 14:19:11 INFO - PROCESS | 1995 | |0;dojo.html;3102.6587075421703;3256.983715081424;3261.7227535659476;3270.9836450817743;3289.4736842105267;5439.4832490913395;5445.564354851612;5523.91714124288;5524.889502209963;5535.58483113766;5526.640768350055;5566.805161819337;5585.078462053756;5589.105743081108;5594.58040646951;5028.874278143039;5082.364704411949;5087.135187018216;5128.4102328232175;5167.9483205168035;5121.308623335839;5249.527542521172;5252.185911183773;5256.053910296145;5262.736863156846;4340.652747780186;5074.949250507503;5269.077911365515;5270.393904700951;5281.155015197569;5048.192289233705;5059.089363914494;5097.184450487941;5179.766910489037;5198.480151984809;4194.706370554062;5151.510606492415;5160.56135228506;5187.792488300463;5203.687778733288;5603.159526071081;5603.803866864681;5632.283771233396;5643.6049476536655;5687.175359572881;5979.850503737398;6009.188759517453;6239.126522286883;6239.65681887496;6273.027680709497;6156.6613836238985;6209.192804935375;6275.843103922439;6278.121063051177;6283.183186185813;5744.167095771173;5745.13822926556;5745.7127143643065;5746.913796293059;5748.252727145528;5659.6321239119325;6338.8415289617205;6359.427651511386;6371.617702937746;6396;5778.335491418541;5981.880362392728;6037.54718396122;6048.153258543807;6140.4473597376455;6176.81469555914;6225.782097626652;6229.252489701174;6229.2524897012645;6234.875302494015;6184.938150618526;6190.535709821691;6197.597156184834;6202.658853762996;6228.688565571703;5913.911291330547;6024.849378765566;6032.125341825498;6154.815355539341;6155.969220153871;4817.373741413664;4886.682365646222;4889.046635906036;4931.4821943696115;4936.6297527685565;4374.8906277342685;4376.693631445832;4388.736675799462;4396.648268138541;4401.933970990438;5509.393966663663;5573.888522229533;5693.772249110072;5714.485696287271;5723.170140329629;6165.383461653799;6243.094751261042;6294.181756371734;6306.180196574416;6308.589941653778;5668.064769313017;5691.203231547586;5691.857703557444;5699.202111704365;5702.942970570324;5239.345081864767;5266.368035835724;5342;5412.864678382994;5417.404085550586;6267.185265915584;6331.683415829098;6338.207724034496;6352.396522330371;6397.200349956349;6240.219972503437;6257.78097766576;6267.62394256346;6269.74921003173;6274.403931626488;6355.332690067661;6365.777197798055;6375.139356187041;6384.07430922523;6386.393292637192;5900.911486327623;5984.1323008164445;5986.760529578943;6050.546209034252;6079.756809727738;5010.423801262756;6241.157443745036;6245.375462453719;6252.718627661857;6261.592996455398;6029.547783916136;6035.456808887221;6041.939580604314;6042;6042.486388657014;5173.6548497390195;5303.098473259633;5917.763289468373;5926.703664816828;5967.582269241285;5869.148973398918;5904.3800400959;5912.940870591411;5921.467067963904;5922.407759224043;4771.976140119277;5887.146363777143;6058.757649693963;6079.939200607937;6094.08588711679;5742.454466825645;5790.363060063305;5803.303603567599;5881.088431293157;5913.704314784158 14:19:11 INFO - PROCESS | 1995 | |1;ext.html;487.90485855258214;494.47338584407584;495.7655437959481;508.5252766975769;521.8956208758249;30243.69756302436;39818;41004.284507281634;41060;43500.78249608755;40548;42586.14827703444;42692;43204.91987700301;43205;9683.176929960962;9864.753381165492;9873.456959867204;9881.407115573089;9886.703398898044;9757.463339516324;9862.161716254092;9873.308868379216;9873.851892221623;9875.55559999798;9828.508574571242;9886.159676427513;9887.950560247189;9895.554700038516;9896.377459973077;9810.166135878459;9858.80282394348;9866.999999999929;9875.95062024689;9888.95055524725;9696.17582505488;9761;9781.315307928448;9784.755381115529;9804.166645835112;40122.59877401218;40532.59467405318;40555;40607.593924060675;40703.7964810177;3461.809600471973;3467.9133021674406;3576.5886923003927;3663.065918190844;3679.3193259247123;30584.2353941151;30861.14235720535;31824;31949.881754138725;32234.838825805957;3257.6090869095624;3310.7185889199445;3314.96685033149;3337.9499307510405;3457.913552161191;496346;503915;505140.47429762984;506754;513096;32727.181820454203;57126.57183570444;57436.71281643565;57463.850722985306;57467.13799293014;33594;34764;34811.30377392438;34887.4766878497;34916.82541587276;14580.927095364668;14745.705085898222;14785.704285914222;14811.333489992976;14818.33317500715;12569.245845249314;12619.810702839464;12625.936870315772;12647.683807904692;12655.114142010156;7685.116211635734;7714.112877019103;7750.031246094239;7759.612019399007;7779.72770953014;3211.727003204707;3261.3477304538715;3265.559149514833;3266.591676040495;3304.240024794263;967.5452537307455;1000.9049140331657;1070.0904231403244;1076.8384742288563;1083.7290677330668;25558.92023705985;29297.41405171927;30326.090217293517;30689;30836.84581577078;885.7564169853213;1019.2915923433291;1028.2596530498029;1088.2545456362418;1099.6700989703056;14202.573922782332;14927.477538286324;15428.997115187476;15503.457378991907;15803.604909877115;685159.5742021258;699515;699659;700534;702254;58068.70965645145;58566;58579.82840343169;58712.41287587155;58823.70588147032;1257.5095712672064;1366.5968539281134;1397.77635578307;1414.4907833180048;1416.357025850009;24727.38181545476;26561.867190664696;30129.901251085874;30977.91577294784;31462.842685786425;40284.3957340646;48569.271460927404;49066.75466622644;49067.50932490629;49195.99999999857;539347;540071;542321;543569;543573;26455.470890581695;26934.057307994128;27348.589771153052;27519.724802752517;28255.29361765972;46952.53047469482;47432.28851567298;47833;47942;47974.520254797004;38687.8393648191;39072.02319942024;39265.80367098146;39323;39708.404373934995;38888.611113888495;38929;38965.61034389733;39489.40765888571;39964.40053399259;37405;38065;38493.615063849;38515.42226866543;38581.807090964365;32809.835950820096;33643.83178084094;34224.65775342215;34414.13964650803;34538.82730586331;25040.998360065394;25209.11768088108;25326;25410.237692869243;25860.094896678525 14:19:11 INFO - PROCESS | 1995 | |2;jquery.html;313.4827534567961;365.6581096674608;372.51572955158287;382.64222951540313;403.9192161567685;15288.388464461423;16855.325786968522;19993;20495.385138445832;20533;20316.18735250592;20610.690839637417;20856;21058.262960796375;21112.4721881953;5920.378360272174;5931.139984702205;6108.511319094462;6216.409441103088;6220.129181914535;4172.812223449937;6087.05650624154;6177.907331390033;6193.721282542297;6204.069389591575;6429.099926010339;6693.2302785179845;6752.426043786284;6795.218549866754;6833.829154271133;6657.700403481855;6672.432843208308;6731.730730770763;6752.358525940028;6765.966170169142;5688.8862222755315;5840.240768700041;5846.3861294563885;5853.385394533555;5868.178455016301;13709.245991470565;13930.651733706638;23020.964056617493;24456.144034958692;25552.744472555223;2164.296603603835;2199.7800219978035;2297.4026753044163;2316.2587971849016;2806.564982427727;9934.297240084643;10393.948030259877;10395.536514894919;10450.425226612533;13226.404811783494;1850.481865077767;1898.7911329753715;1920.3470819921154;1937.651222779899;1994.2521554417094;86098;105675;109634;123235;124296.37851810774;11372.943135284271;11373;11476.901236666221;11523.53905843757;11767.646970590897;14512.492062777752;14523.346449409803;14523.927380363031;14530.273486325641;14549.054311469721;8260.215279548433;8506.191911768372;8529.914700853036;8601.655933762579;8611.009733880559;8452.323814094858;8470.534120623302;8474.067852536216;8594.44136131162;8602.698905538276;4419.690621656518;4509.594136527729;4516.751578663139;4601.861944141681;4623.606993405599;1698.5159229619499;1763.3563749231694;1783.9464816055538;1911.8566107541712;1913.416407995547;739.3973911262304;879.2833840420037;938.9061093890693;952.8523078922778;976.2970661123979;19109.61780764405;20330.695039574417;20904.47738806542;20994.685079723815;21341.573168536543;955.5986485676033;957.3537861943299;961.9615215391306;976.858355538443;997.7555498902815;8441.620127094295;8916.197542221102;9783;10527.999680060764;11064.834027489595;571141;585164.0741796264;593553;594170;598832;73247.63376183191;99406;135150.99999999802;135482;137067.6293237075;930.283681565191;979.0258692600908;995.835687111633;1003.2024540490328;1004.8944860789512;13599.660008499866;17343.826561734724;17426.651466970845;17717.557061073578;17779.46661600154;55754.32737017896;55912;56010.439895600524;56142.43857561372;56813.715931420076;76909.61545192238;78184;79280.60359698164;81201.69184773162;83483.16516834754;7978.606418074671;8109.310708589836;8332.33341332704;8349.290310323691;8498;12932.03009774252;13373.866261337262;13463.663408414868;13867.930660346634;13904.721905562035;11132.499037543168;11243.775124497628;11679.182457227913;11887;11980.341081240615;10445.164386849186;10497.317674351143;11167.329960202414;11806.350650714294;11890.940545297219;11544.365059921785;11619.883801161881;11836.70408239801;11936.343501107174;12005;7201.819954501179;7342.449316301406;7490.288422599844;7784.92215077842;8528.530930798866;10188.64339748135;11230.775384492428;11304.773904522028;11619.825702614637;11794.882051179378 14:19:11 INFO - PROCESS | 1995 | |3;mootools.html;1501.6396064944413;1531.433369653228;1692.7122389193835;1814.3014939248385;1841.926322947082;8404.159584041601;8925.152110549503;8936.374453788238;8936.642534298628;8949.239314658262;7809.140994490602;8046.476978996361;8468.618912148953;8680.652773889036;8727.86908196376;2336.8481048731865;2368.5973384524586;2373.727021392545;2374.8693821839793;2376.227725989052;2316.6293393057113;2351.5532048910654;2358.6815779869758;2361.527694461106;2362.11420717231;2322.129201549419;2331.4287999440076;2340.8946597403074;2341.906323747048;2342.2387723989773;2331.370529956919;2349.2364981381033;2361.2798096580536;2361.3860396297086;2375.2874137758777;2346.6128088865316;2348.917787877433;2367.112332875172;2367.195153647751;2374.726906405768;7090.432765378758;8830.24942879856;8843.11568843117;8844.646214151426;8864.157904999014;5136.922946155811;5331.44019877915;5343.064963631387;5349.063913815066;5350.571954243651;3660.7803531788177;3673.6326367363317;3737.5141231639986;3744.120131769032;3744.251149770057;1096.31480324797;1106.551846502168;1108.1965574958172;1119.871214810291;1129.4860838318546;11634.30194188343;11706.063514918786;11747.941260293645;11771.646850594496;11792.23350482216;3826.3877779555696;4436.911261774811;4755.191617425046;4764.2615394613895;4765.69023013503;9873.259505537113;9874.851877221847;9877.753056173653;9901.75245618851;9906.405615663083;5317.989581979412;5322.308099946982;5323.787048518016;5327.840164795062;5339.519443250126;4068.9186216275507;4373.868783936487;4458.5318541553315;4512.729236245836;4897.14299997499;3125.6405513366253;3791.109089363997;3797.8290976906105;3801.6958643308467;3807.904802379963;1114.9318015081906;1292.6057552446593;1312.8424589049375;1313.1792629606496;1313.9080264381594;881.4623079921242;918.169057003413;919.4667093085994;935.6865450074301;946.4368700623119;5686.630369026;5749.511291540182;5892.086726557391;5936.762529498858;6066.3023752269055;437.89928316487385;442.287916454508;451.7876597998935;452.8686680862513;460.7926433105116;3928.862489812843;3973.0861901762755;4030.8387664493093;4044.656204222674;4063.146739184804;9765.997970416047;10696.037356637784;10847.566097356175;10905.18211134168;10911.672649820359;10775.245732798785;10800.081993030522;10807.567697292021;10816.67549973502;10822.080123189617;8215.58922053895;8258.132896045949;8269.21442462977;8275.255227029596;8279.219967706971;4314.590113939171;5419.2413062170535;5545.667259964415;5632.211490391266;5638.37977822431;9806.21550275962;9814.852777208205;9949.751256218653;10033.899661003588;10131.89868101339;9978.550965206437;10129.290949633749;10236.744081398025;10255.07704306616;10373.014563616445;7256.092988376453;7426.777196684108;7532;7544.585047822423;7553.32020118192;8271.503709777433;8404.20160084791;8462.407631465738;8482.406231563731;8487.533185674847;7424.331810136898;7484.962575187089;7489.13874904393;7491.625418729151;7685.385169186564;6036.426539478743;7341.265873412616;7356.117265927909;7360.668769905475;7420.703171873281;6926.8614627708175;6977.023216749761;7068.151821781419;7084.610346430995;7087.539309944837;4973.875653108701;5023.748812559431;5045.394552653705;5114.3862736471865;5138.743062846917;4930.382325757088;4995.350604421402;5128.897422051464;5235.397929238186;5240.502152295526 14:19:11 INFO - PROCESS | 1995 | |4;prototype.html;292.5786866911647;306.60448022051554;311.0714517166259;312.33628539353856;314.9889753858615;5359.303290572225;5373.9193912091305;5385.084535628943;5386.91919621205;5393.217983392406;5365.517103460687;5389.086874159666;5393.217983392406;5395.21769343445;5425.782968681257;3362.882299119531;3847.1533663841374;3853.2293541291783;3982.5818289079666;4001.359782434811;3962.1877515109477;4000.639942405183;4016.3774614934587;4017.799110044486;4061.309577371839;3939.5863434339412;3969.940450893239;4002.899927501807;4007.1184339445276;4024.315866302721;3295.96704032959;3298.7855789373616;3720.9441858372147;3914.9804250978846;3982.940255896135;3487.7907325560545;3487.9128021799406;3520.5951315598777;3523.9647603523895;3528.012156596157;4261.573842615714;5156.742162891878;5180.715060671661;5201.375834899798;5213.869653258661;3545.042838433634;3998.8600398986187;4458.910821783578;4603.355530225737;4635.976820115911;4588.2888152336445;4623.375844261049;4878.341423907763;5322.654027488201;5608.55131589476;3812.2566099610585;3833.29084119436;3840.6927445804267;3867.4972253607134;3889.747166434173;4592;5211.41387666286;5751.654900705971;5779.104238842944;5801.767929282823;3900.3759398496104;3914.887664306883;3928.8035598219976;3930.663574384723;3940.4680368149934;3540.327337805809;3771.735978481535;3989.58109398509;3999.8001598720907;4027.536833264212;4142;4581.198290299184;4644.2801365788355;4644.490540574259;4648.094281344633;4878.36581244443;5212.48837837023;5648.209250704905;5655.632383895034;5763.3084029915835;1100.510272928539;1183.065378351093;1322.6494978830617;1332.68015676237;1341.9194848309132;709.5033476566424;756.4440136499778;776.8718161503289;815.5677490929698;822.399648256764;3942.7240093193773;4226.260404429213;4387.407699960529;4609.354690343288;4850.587700045465;4966.453690094087;5033.835450756137;5337.839864804062;5797.826065218051;5858.882822343529;4145.771982540929;5229.686218826807;5260.947390526122;5263.078961181778;5285.788568457296;2866.4059026580976;2960.3422083633236;2970.7177818107243;2983.6727352664957;2992.7280905615025;5845.707714614252;5863.70681465925;5868.354481007086;5896.174535565025;5981.431763982415;5129.204973229155;5226.3205783248695;5315.574754019669;5856;5892.823215303548;6083.452489275898;6086.147939288503;6090;6098.72555734993;6104.908426373519;4585.633149347977;4813.374261346143;5793.118610531039;5874.706264686834;5880.588358814842;5480.917786233289;5660.575456840671;5687.232223649919;5758.510526605285;5784.1034639630925;4880.536349046835;5182.663126896739;5412.78348866041;5458.935507575985;5965.8963091828155;3864.9033774155873;3866.4007078902814;3871.4901188535932;3878.903027424337;3903.5706072331454;4675.76621168947;5455.1271796512365;5797.884042319214;5804.796832110856;5828.883422331445;5629.887402252014;5655.632383895034;5671.8865622688145;5674.829755107354;5685.545156387562;5310.8141215057285;5445.019896418683;5629.380768115586;5644.657467151251;5659.943400565941;5038.647294689336;5515.25544051564;5529.806456774155;5567.972160139173;5577.8047768327915;4235.894102647459;4824.252240902666;5371.758270877742;5582.27430434041;5683.687397193194;4529.524399937959;5216.791673120927;5461.579989202915;5508.476694713996;5599.692016938944 14:19:11 INFO - PROCESS | 1995 | |5;yui.html;657.6382989355853;694.1392673085369;801.6993627389727;815.3029160068141;840.1136800675285;15190.31643576039;16272;16325;16332.101734404618;17018.659626807457;16174.919125404385;16458.423955161572;16748.49754507364;16795.160241987884;16799.748003779954;841.4572600672562;862.7411776467035;863.6113748813028;865.4980111535294;866.57537806475;677.0757915445406;694.9826254343657;816.7019038051099;842.0737189091981;844.5608283692476;900.8063266397724;902.499112992289;902.7788191892995;904.3624244907325;905.4069584422181;896.4352457951449;898.1333013641802;898.5417437107058;899.4872922434182;902.6885724425063;525.3039722367839;548.5584104795638;645.6287634610071;665.8634979829123;673.8180691213345;15072.020318679251;15085.849141508554;15087.321070551852;15119.546413607499;15132.848671513255;13448.394822233024;13866.861331386657;13975.231362275072;14001.509947151902;14337.42650293987;11604.593839215588;11776.131605678416;12277.508899644003;12295.077869159848;12349.444275007647;3838.500994870649;3839.9232015359535;3860.806959652034;3981;4018.0356714388336;86472;113892;115793;116323.41838290755;116375.41812290969;10671.57313707443;10934.07060399859;11135.832962505569;11168.553257869757;11555.711107222387;22383.776162238493;22777.31668049961;22787.544249115257;22808.771912280667;22850;8037.156098609678;8197.79505512367;8271.296939760188;8301.750947471586;8332.541710205996;3941.940870886939;4293.162833247549;4557.08858228349;4585.1746685596245;4776.641751868624;3575.606683264839;3756.267527832101;3759.887203383903;3761.3041587306434;3766.773993560395;554.3431034224457;624.2353117431111;628.5254632752243;670.4401824476519;671.5668393886014;505.24718169926547;585.7978997245943;673.5075459626752;683.4771399879096;685.8971154326891;8720.541742712085;9121.863172052424;9153.084691530927;9427.104425079606;9705.320627556004;515.7627491353944;542.888707814897;573.6241673795813;670.8960111182774;680.6154522694661;3718.8698395556567;4567.520410356931;4591.943852913849;4687.531246875285;4807.254875494304;126785;131219;134299;137992;152810.23594881955;85287;120423;121598;122484;124293.75706243003;97808;100697;103310;106738;109713.45143274392;9445.669401570913;9652.600372945742;10013.948535403677;10099.293049486465;10188.541515631667;50883.49116508788;57633.253838500365;58202;60159;61590;62855;63515;63674;81328;87070.56464717636;13444.39500222512;13669.794953075512;13974;14036.719265614835;14250.216238106588;15018.92490537584;15496.92251538735;15895.205239738198;15964.281607327466;16109.597260068593;14050.718985620435;14142.858571414154;14168;14344.282785860456;14356.641083972567;13836;14083.577492675236;14223.359948802536;14238.786418203943;14657.340419681354;13857.099288546615;14129.505467308594;14230.928845355707;14414.711705766036;14418;11442.199046066695;11674.474648640662;11710.94144529272;11815.350155741517;12088.637340879788;7669.923300766921;9621.276383413833;10490.475476226005;10696.946515267375;11364.147688923198 14:19:11 INFO - PROCESS | 1995 | __end_tp_report 14:19:11 INFO - PROCESS | 1995 | __start_cc_report 14:19:11 INFO - PROCESS | 1995 | _x_x_mozilla_cycle_collect,1250 14:19:11 INFO - PROCESS | 1995 | __end_cc_report 14:19:11 INFO - PROCESS | 1995 | __startTimestamp1471900751242__endTimestamp 14:19:11 INFO - PROCESS | 1995 | 14:19:11 INFO - PROCESS | 1995 | ------- Summary: start ------- 14:19:11 INFO - PROCESS | 1995 | Number of tests: 6 14:19:11 INFO - PROCESS | 1995 | 14:19:11 INFO - PROCESS | 1995 | [#0] dojo.html Cycles:165 Average:5655.63 Median:5790.36 stddev:673.67 (11.6%) stddev-sans-first:645.28 14:19:11 INFO - PROCESS | 1995 | Values: 3102.7 3257.0 3261.7 3271.0 3289.5 5439.5 5445.6 5523.9 5524.9 5535.6 5526.6 5566.8 5585.1 5589.1 5594.6 5028.9 5082.4 5087.1 5128.4 5167.9 5121.3 5249.5 5252.2 5256.1 5262.7 4340.7 5074.9 5269.1 5270.4 5281.2 5048.2 5059.1 5097.2 5179.8 5198.5 4194.7 5151.5 5160.6 5187.8 5203.7 5603.2 5603.8 5632.3 5643.6 5687.2 5979.9 6009.2 6239.1 6239.7 6273.0 6156.7 6209.2 6275.8 6278.1 6283.2 5744.2 5745.1 5745.7 5746.9 5748.3 5659.6 6338.8 6359.4 6371.6 6396.0 5778.3 5981.9 6037.5 6048.2 6140.4 6176.8 6225.8 6229.3 6229.3 6234.9 6184.9 6190.5 6197.6 6202.7 6228.7 5913.9 6024.8 6032.1 6154.8 6156.0 4817.4 4886.7 4889.0 4931.5 4936.6 4374.9 4376.7 4388.7 4396.6 4401.9 5509.4 5573.9 5693.8 5714.5 5723.2 6165.4 6243.1 6294.2 6306.2 6308.6 5668.1 5691.2 5691.9 5699.2 5702.9 5239.3 5266.4 5342.0 5412.9 5417.4 6267.2 6331.7 6338.2 6352.4 6397.2 6240.2 6257.8 6267.6 6269.7 6274.4 6355.3 6365.8 6375.1 6384.1 6386.4 5900.9 5984.1 5986.8 6050.5 6079.8 5010.4 6241.2 6245.4 6252.7 6261.6 6029.5 6035.5 6041.9 6042.0 6042.5 5173.7 5303.1 5917.8 5926.7 5967.6 5869.1 5904.4 5912.9 5921.5 5922.4 4772.0 5887.1 6058.8 6079.9 6094.1 5742.5 5790.4 5803.3 5881.1 5913.7 14:19:11 INFO - PROCESS | 1995 | 14:19:11 INFO - PROCESS | 1995 | [#1] ext.html Cycles:180 Average:69811.99 Median:39294.40 stddev:157454.98 (400.7%) stddev-sans-first:157810.65 14:19:11 INFO - PROCESS | 1995 | Values: 487.9 494.5 495.8 508.5 521.9 30243.7 39818.0 41004.3 41060.0 43500.8 40548.0 42586.1 42692.0 43204.9 43205.0 9683.2 9864.8 9873.5 9881.4 9886.7 9757.5 9862.2 9873.3 9873.9 9875.6 9828.5 9886.2 9888.0 9895.6 9896.4 9810.2 9858.8 9867.0 9876.0 9889.0 9696.2 9761.0 9781.3 9784.8 9804.2 40122.6 40532.6 40555.0 40607.6 40703.8 3461.8 3467.9 3576.6 3663.1 3679.3 30584.2 30861.1 31824.0 31949.9 32234.8 3257.6 3310.7 3315.0 3337.9 3457.9 496346.0 503915.0 505140.5 506754.0 513096.0 32727.2 57126.6 57436.7 57463.9 57467.1 33594.0 34764.0 34811.3 34887.5 34916.8 14580.9 14745.7 14785.7 14811.3 14818.3 12569.2 12619.8 12625.9 12647.7 12655.1 7685.1 7714.1 7750.0 7759.6 7779.7 3211.7 3261.3 3265.6 3266.6 3304.2 967.5 1000.9 1070.1 1076.8 1083.7 25558.9 29297.4 30326.1 30689.0 30836.8 885.8 1019.3 1028.3 1088.3 1099.7 14202.6 14927.5 15429.0 15503.5 15803.6 685159.6 699515.0 699659.0 700534.0 702254.0 58068.7 58566.0 58579.8 58712.4 58823.7 1257.5 1366.6 1397.8 1414.5 1416.4 24727.4 26561.9 30129.9 30977.9 31462.8 40284.4 48569.3 49066.8 49067.5 49196.0 539347.0 540071.0 542321.0 543569.0 543573.0 26455.5 26934.1 27348.6 27519.7 28255.3 46952.5 47432.3 47833.0 47942.0 47974.5 38687.8 39072.0 39265.8 39323.0 39708.4 38888.6 38929.0 38965.6 39489.4 39964.4 37405.0 38065.0 38493.6 38515.4 38581.8 32809.8 33643.8 34224.7 34414.1 34538.8 25041.0 25209.1 25326.0 25410.2 25860.1 14:19:11 INFO - PROCESS | 1995 | 14:19:11 INFO - PROCESS | 1995 | [#2] jquery.html Cycles:180 Average:34195.39 Median:339.57 stddev:98027.03 (28868.0%) stddev-sans-first:98269.01 14:19:11 INFO - PROCESS | 1995 | Values: 313.5 365.7 372.5 382.6 403.9 15288.4 16855.3 19993.0 20495.4 20533.0 20316.2 20610.7 20856.0 21058.3 21112.5 5920.4 5931.1 6108.5 6216.4 6220.1 4172.8 6087.1 6177.9 6193.7 6204.1 6429.1 6693.2 6752.4 6795.2 6833.8 6657.7 6672.4 6731.7 6752.4 6766.0 5688.9 5840.2 5846.4 5853.4 5868.2 13709.2 13930.7 23021.0 24456.1 25552.7 2164.3 2199.8 2297.4 2316.3 2806.6 9934.3 10393.9 10395.5 10450.4 13226.4 1850.5 1898.8 1920.3 1937.7 1994.3 86098.0 105675.0 109634.0 123235.0 124296.4 11372.9 11373.0 11476.9 11523.5 11767.6 14512.5 14523.3 14523.9 14530.3 14549.1 8260.2 8506.2 8529.9 8601.7 8611.0 8452.3 8470.5 8474.1 8594.4 8602.7 4419.7 4509.6 4516.8 4601.9 4623.6 1698.5 1763.4 1783.9 1911.9 1913.4 739.4 879.3 938.9 952.9 976.3 19109.6 20330.7 20904.5 20994.7 21341.6 955.6 957.4 962.0 976.9 997.8 8441.6 8916.2 9783.0 10528.0 11064.8 571141.0 585164.1 593553.0 594170.0 598832.0 73247.6 99406.0 135151.0 135482.0 137067.6 930.3 979.0 995.8 1003.2 1004.9 13599.7 17343.8 17426.7 17717.6 17779.5 55754.3 55912.0 56010.4 56142.4 56813.7 76909.6 78184.0 79280.6 81201.7 83483.2 7978.6 8109.3 8332.3 8349.3 8498.0 12932.0 13373.9 13463.7 13867.9 13904.7 11132.5 11243.8 11679.2 11887.0 11980.3 10445.2 10497.3 11167.3 11806.4 11890.9 11544.4 11619.9 11836.7 11936.3 12005.0 7201.8 7342.4 7490.3 7784.9 8528.5 10188.6 11230.8 11304.8 11619.8 11794.9 14:19:11 INFO - PROCESS | 1995 | 14:19:11 INFO - PROCESS | 1995 | [#3] mootools.html Cycles:180 Average:5671.49 Median:4913.76 stddev:3220.77 (65.5%) stddev-sans-first:3214.56 14:19:11 INFO - PROCESS | 1995 | Values: 1501.6 1531.4 1692.7 1814.3 1841.9 8404.2 8925.2 8936.4 8936.6 8949.2 7809.1 8046.5 8468.6 8680.7 8727.9 2336.8 2368.6 2373.7 2374.9 2376.2 2316.6 2351.6 2358.7 2361.5 2362.1 2322.1 2331.4 2340.9 2341.9 2342.2 2331.4 2349.2 2361.3 2361.4 2375.3 2346.6 2348.9 2367.1 2367.2 2374.7 7090.4 8830.2 8843.1 8844.6 8864.2 5136.9 5331.4 5343.1 5349.1 5350.6 3660.8 3673.6 3737.5 3744.1 3744.3 1096.3 1106.6 1108.2 1119.9 1129.5 11634.3 11706.1 11747.9 11771.6 11792.2 3826.4 4436.9 4755.2 4764.3 4765.7 9873.3 9874.9 9877.8 9901.8 9906.4 5318.0 5322.3 5323.8 5327.8 5339.5 4068.9 4373.9 4458.5 4512.7 4897.1 3125.6 3791.1 3797.8 3801.7 3807.9 1114.9 1292.6 1312.8 1313.2 1313.9 881.5 918.2 919.5 935.7 946.4 5686.6 5749.5 5892.1 5936.8 6066.3 437.9 442.3 451.8 452.9 460.8 3928.9 3973.1 4030.8 4044.7 4063.1 9766.0 10696.0 10847.6 10905.2 10911.7 10775.2 10800.1 10807.6 10816.7 10822.1 8215.6 8258.1 8269.2 8275.3 8279.2 4314.6 5419.2 5545.7 5632.2 5638.4 9806.2 9814.9 9949.8 10033.9 10131.9 9978.6 10129.3 10236.7 10255.1 10373.0 7256.1 7426.8 7532.0 7544.6 7553.3 8271.5 8404.2 8462.4 8482.4 8487.5 7424.3 7485.0 7489.1 7491.6 7685.4 6036.4 7341.3 7356.1 7360.7 7420.7 6926.9 6977.0 7068.2 7084.6 7087.5 4973.9 5023.7 5045.4 5114.4 5138.7 4930.4 4995.4 5128.9 5235.4 5240.5 14:19:11 INFO - PROCESS | 1995 | 14:19:11 INFO - PROCESS | 1995 | [#4] prototype.html Cycles:180 Average:4486.25 Median:5036.24 stddev:1398.68 (27.8%) stddev-sans-first:1366.72 14:19:11 INFO - PROCESS | 1995 | Values: 292.6 306.6 311.1 312.3 315.0 5359.3 5373.9 5385.1 5386.9 5393.2 5365.5 5389.1 5393.2 5395.2 5425.8 3362.9 3847.2 3853.2 3982.6 4001.4 3962.2 4000.6 4016.4 4017.8 4061.3 3939.6 3969.9 4002.9 4007.1 4024.3 3296.0 3298.8 3720.9 3915.0 3982.9 3487.8 3487.9 3520.6 3524.0 3528.0 4261.6 5156.7 5180.7 5201.4 5213.9 3545.0 3998.9 4458.9 4603.4 4636.0 4588.3 4623.4 4878.3 5322.7 5608.6 3812.3 3833.3 3840.7 3867.5 3889.7 4592.0 5211.4 5751.7 5779.1 5801.8 3900.4 3914.9 3928.8 3930.7 3940.5 3540.3 3771.7 3989.6 3999.8 4027.5 4142.0 4581.2 4644.3 4644.5 4648.1 4878.4 5212.5 5648.2 5655.6 5763.3 1100.5 1183.1 1322.6 1332.7 1341.9 709.5 756.4 776.9 815.6 822.4 3942.7 4226.3 4387.4 4609.4 4850.6 4966.5 5033.8 5337.8 5797.8 5858.9 4145.8 5229.7 5260.9 5263.1 5285.8 2866.4 2960.3 2970.7 2983.7 2992.7 5845.7 5863.7 5868.4 5896.2 5981.4 5129.2 5226.3 5315.6 5856.0 5892.8 6083.5 6086.1 6090.0 6098.7 6104.9 4585.6 4813.4 5793.1 5874.7 5880.6 5480.9 5660.6 5687.2 5758.5 5784.1 4880.5 5182.7 5412.8 5458.9 5965.9 3864.9 3866.4 3871.5 3878.9 3903.6 4675.8 5455.1 5797.9 5804.8 5828.9 5629.9 5655.6 5671.9 5674.8 5685.5 5310.8 5445.0 5629.4 5644.7 5659.9 5038.6 5515.3 5529.8 5568.0 5577.8 4235.9 4824.3 5371.8 5582.3 5683.7 4529.5 5216.8 5461.6 5508.5 5599.7 14:19:11 INFO - PROCESS | 1995 | 14:19:11 INFO - PROCESS | 1995 | [#5] yui.html Cycles:180 Average:23511.68 Median:3758.08 stddev:36286.55 (965.6%) stddev-sans-first:36347.77 14:19:11 INFO - PROCESS | 1995 | Values: 657.6 694.1 801.7 815.3 840.1 15190.3 16272.0 16325.0 16332.1 17018.7 16174.9 16458.4 16748.5 16795.2 16799.7 841.5 862.7 863.6 865.5 866.6 677.1 695.0 816.7 842.1 844.6 900.8 902.5 902.8 904.4 905.4 896.4 898.1 898.5 899.5 902.7 525.3 548.6 645.6 665.9 673.8 15072.0 15085.8 15087.3 15119.5 15132.8 13448.4 13866.9 13975.2 14001.5 14337.4 11604.6 11776.1 12277.5 12295.1 12349.4 3838.5 3839.9 3860.8 3981.0 4018.0 86472.0 113892.0 115793.0 116323.4 116375.4 10671.6 10934.1 11135.8 11168.6 11555.7 22383.8 22777.3 22787.5 22808.8 22850.0 8037.2 8197.8 8271.3 8301.8 8332.5 3941.9 4293.2 4557.1 4585.2 4776.6 3575.6 3756.3 3759.9 3761.3 3766.8 554.3 624.2 628.5 670.4 671.6 505.2 585.8 673.5 683.5 685.9 8720.5 9121.9 9153.1 9427.1 9705.3 515.8 542.9 573.6 670.9 680.6 3718.9 4567.5 4591.9 4687.5 4807.3 126785.0 131219.0 134299.0 137992.0 152810.2 85287.0 120423.0 121598.0 122484.0 124293.8 97808.0 100697.0 103310.0 106738.0 109713.5 9445.7 9652.6 10013.9 10099.3 10188.5 50883.5 57633.3 58202.0 60159.0 61590.0 62855.0 63515.0 63674.0 81328.0 87070.6 13444.4 13669.8 13974.0 14036.7 14250.2 15018.9 15496.9 15895.2 15964.3 16109.6 14050.7 14142.9 14168.0 14344.3 14356.6 13836.0 14083.6 14223.4 14238.8 14657.3 13857.1 14129.5 14230.9 14414.7 14418.0 11442.2 11674.5 11710.9 11815.4 12088.6 7669.9 9621.3 10490.5 10696.9 11364.1 14:19:11 INFO - PROCESS | 1995 | -------- Summary: end -------- 14:19:11 INFO - PROCESS | 1995 | 14:19:11 INFO - TEST-INFO | 1995: exit 0 14:19:11 INFO - TEST-OK | dromaeo_css | took 1116954ms 14:19:11 INFO - TEST-START | kraken 14:19:11 INFO - operating with platform_type : mac_ 14:19:11 INFO - Initialising browser for kraken test... 14:19:11 INFO - TEST-INFO | started process 2001 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/tmpVQWTcQ/profile http://localhost:49382/getInfo.html) 14:19:13 INFO - PROCESS | 2001 | __metrics Screen width/height:1600/1200 14:19:13 INFO - PROCESS | 2001 | colorDepth:24 14:19:13 INFO - PROCESS | 2001 | Browser inner width/height: 1024/689 14:19:13 INFO - PROCESS | 2001 | __metrics 14:19:14 INFO - PROCESS | 2001 | 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" 14:19:14 INFO - PROCESS | 2001 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:19:14 INFO - TEST-INFO | 2001: exit 0 14:19:14 INFO - Browser initialized. 14:19:14 INFO - Running cycle 1/1 for kraken test... 14:19:14 INFO - TEST-INFO | started process 2002 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/T/tmpVQWTcQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:19:26 INFO - PROCESS | 2002 | RSS: Main: 228642816 14:19:26 INFO - PROCESS | 2002 | 14:19:29 INFO - PROCESS | 2002 | Cycle 1(1): loaded http://localhost:49382/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49382/tests/kraken/driver.html?testName=audio-beat-detection) 14:19:30 INFO - PROCESS | 2002 | RSS: Main: 294043648 14:19:30 INFO - PROCESS | 2002 | 14:19:32 INFO - PROCESS | 2002 | Cycle 1(1): loaded http://localhost:49382/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49382/tests/kraken/driver.html?testName=audio-dft) 14:19:33 INFO - PROCESS | 2002 | RSS: Main: 295055360 14:19:33 INFO - PROCESS | 2002 | 14:19:36 INFO - PROCESS | 2002 | Cycle 1(1): loaded http://localhost:49382/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49382/tests/kraken/driver.html?testName=audio-fft) 14:19:37 INFO - PROCESS | 2002 | RSS: Main: 381448192 14:19:37 INFO - PROCESS | 2002 | 14:19:39 INFO - PROCESS | 2002 | Cycle 1(1): loaded http://localhost:49382/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49382/tests/kraken/driver.html?testName=audio-oscillator) 14:19:40 INFO - PROCESS | 2002 | RSS: Main: 281702400 14:19:40 INFO - PROCESS | 2002 | 14:19:42 INFO - PROCESS | 2002 | Cycle 1(1): loaded http://localhost:49382/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49382/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:19:42 INFO - PROCESS | 2002 | RSS: Main: 285732864 14:19:42 INFO - PROCESS | 2002 | 14:19:45 INFO - PROCESS | 2002 | Cycle 1(1): loaded http://localhost:49382/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49382/tests/kraken/driver.html?testName=imaging-darkroom) 14:19:46 INFO - PROCESS | 2002 | RSS: Main: 321921024 14:19:46 INFO - PROCESS | 2002 | 14:19:49 INFO - PROCESS | 2002 | Cycle 1(1): loaded http://localhost:49382/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49382/tests/kraken/driver.html?testName=imaging-desaturate) 14:19:49 INFO - PROCESS | 2002 | RSS: Main: 325136384 14:19:49 INFO - PROCESS | 2002 | 14:19:52 INFO - PROCESS | 2002 | Cycle 1(1): loaded http://localhost:49382/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49382/tests/kraken/driver.html?testName=json-parse-financial) 14:19:53 INFO - PROCESS | 2002 | RSS: Main: 324263936 14:19:53 INFO - PROCESS | 2002 | 14:19:54 INFO - PROCESS | 2002 | Cycle 1(1): loaded http://localhost:49382/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49382/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:19:55 INFO - PROCESS | 2002 | RSS: Main: 301027328 14:19:55 INFO - PROCESS | 2002 | 14:19:57 INFO - PROCESS | 2002 | Cycle 1(1): loaded http://localhost:49382/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49382/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:19:58 INFO - PROCESS | 2002 | RSS: Main: 303329280 14:19:58 INFO - PROCESS | 2002 | 14:20:00 INFO - PROCESS | 2002 | Cycle 1(1): loaded http://localhost:49382/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49382/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:20:01 INFO - PROCESS | 2002 | RSS: Main: 303136768 14:20:01 INFO - PROCESS | 2002 | 14:20:03 INFO - PROCESS | 2002 | Cycle 1(1): loaded http://localhost:49382/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49382/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:20:04 INFO - PROCESS | 2002 | RSS: Main: 315031552 14:20:04 INFO - PROCESS | 2002 | 14:20:07 INFO - PROCESS | 2002 | Cycle 1(1): loaded http://localhost:49382/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49382/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:20:08 INFO - PROCESS | 2002 | RSS: Main: 317640704 14:20:08 INFO - PROCESS | 2002 | 14:20:09 INFO - PROCESS | 2002 | Cycle 1(1): loaded http://localhost:49382/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49382/tests/kraken/driver.html?testName=ai-astar) 14:20:09 INFO - PROCESS | 2002 | RSS: Main: 361414656 14:20:09 INFO - PROCESS | 2002 | 14:20:09 INFO - PROCESS | 2002 | __start_tp_report 14:20:09 INFO - PROCESS | 2002 | _x_x_mozilla_page_load 14:20:09 INFO - PROCESS | 2002 | _x_x_mozilla_page_load_details 14:20:09 INFO - PROCESS | 2002 | |i|pagename|runs| 14:20:09 INFO - PROCESS | 2002 | |0;ai-astar;97;113;107;112;112;113;116;113;112;112 14:20:09 INFO - PROCESS | 2002 | |1;audio-beat-detection;143;114;131;162;108;128;128;108;162;103 14:20:09 INFO - PROCESS | 2002 | |2;audio-dft;185;181;184;181;185;192;187;180;185;180 14:20:09 INFO - PROCESS | 2002 | |3;audio-fft;75;77;73;146;55;57;57;89;88;56 14:20:09 INFO - PROCESS | 2002 | |4;audio-oscillator;83;86;132;70;71;77;118;70;69;121 14:20:09 INFO - PROCESS | 2002 | |5;imaging-gaussian-blur;94;86;88;91;88;85;95;91;90;84 14:20:09 INFO - PROCESS | 2002 | |6;imaging-darkroom;92;93;93;94;109;149;124;94;94;93 14:20:09 INFO - PROCESS | 2002 | |7;imaging-desaturate;86;91;127;86;84;83;83;86;85;83 14:20:09 INFO - PROCESS | 2002 | |8;json-parse-financial;53;54;53;55;53;55;54;55;54;55 14:20:09 INFO - PROCESS | 2002 | |9;json-stringify-tinderbox;67;66;69;74;78;71;71;74;110;105 14:20:09 INFO - PROCESS | 2002 | |10;stanford-crypto-aes;89;85;85;84;87;84;82;87;77;88 14:20:09 INFO - PROCESS | 2002 | |11;stanford-crypto-ccm;140;140;139;135;140;138;134;156;220;140 14:20:09 INFO - PROCESS | 2002 | |12;stanford-crypto-pbkdf2;172;165;171;164;161;167;167;169;170;165 14:20:09 INFO - PROCESS | 2002 | |13;stanford-crypto-sha256-iterative;67;68;68;68;67;65;67;67;65;66 14:20:09 INFO - PROCESS | 2002 | __end_tp_report 14:20:09 INFO - PROCESS | 2002 | __start_cc_report 14:20:09 INFO - PROCESS | 2002 | _x_x_mozilla_cycle_collect,2115 14:20:09 INFO - PROCESS | 2002 | __end_cc_report 14:20:09 INFO - PROCESS | 2002 | __startTimestamp1471900809969__endTimestamp 14:20:09 INFO - PROCESS | 2002 | 14:20:09 INFO - PROCESS | 2002 | ------- Summary: start ------- 14:20:09 INFO - PROCESS | 2002 | Number of tests: 14 14:20:09 INFO - PROCESS | 2002 | 14:20:09 INFO - PROCESS | 2002 | [#0] ai-astar Cycles:10 Average:110.70 Median:113.00 stddev:5.29 (4.7%) stddev-sans-first:2.33 14:20:09 INFO - PROCESS | 2002 | Values: 97.0 113.0 107.0 112.0 112.0 113.0 116.0 113.0 112.0 112.0 14:20:09 INFO - PROCESS | 2002 | 14:20:09 INFO - PROCESS | 2002 | [#1] audio-beat-detection Cycles:10 Average:128.70 Median:129.50 stddev:21.50 (16.6%) stddev-sans-first:22.18 14:20:09 INFO - PROCESS | 2002 | Values: 143.0 114.0 131.0 162.0 108.0 128.0 128.0 108.0 162.0 103.0 14:20:09 INFO - PROCESS | 2002 | 14:20:09 INFO - PROCESS | 2002 | [#2] audio-dft Cycles:10 Average:184.00 Median:185.00 stddev:3.74 (2.0%) stddev-sans-first:3.95 14:20:09 INFO - PROCESS | 2002 | Values: 185.0 181.0 184.0 181.0 185.0 192.0 187.0 180.0 185.0 180.0 14:20:09 INFO - PROCESS | 2002 | 14:20:09 INFO - PROCESS | 2002 | [#3] audio-fft Cycles:10 Average:77.30 Median:74.00 stddev:27.43 (37.1%) stddev-sans-first:29.08 14:20:09 INFO - PROCESS | 2002 | Values: 75.0 77.0 73.0 146.0 55.0 57.0 57.0 89.0 88.0 56.0 14:20:09 INFO - PROCESS | 2002 | 14:20:09 INFO - PROCESS | 2002 | [#4] audio-oscillator Cycles:10 Average:89.70 Median:70.50 stddev:24.37 (34.6%) stddev-sans-first:25.73 14:20:09 INFO - PROCESS | 2002 | Values: 83.0 86.0 132.0 70.0 71.0 77.0 118.0 70.0 69.0 121.0 14:20:09 INFO - PROCESS | 2002 | 14:20:09 INFO - PROCESS | 2002 | [#5] imaging-gaussian-blur Cycles:10 Average:89.20 Median:90.50 stddev:3.68 (4.1%) stddev-sans-first:3.46 14:20:09 INFO - PROCESS | 2002 | Values: 94.0 86.0 88.0 91.0 88.0 85.0 95.0 91.0 90.0 84.0 14:20:09 INFO - PROCESS | 2002 | 14:20:09 INFO - PROCESS | 2002 | [#6] imaging-darkroom Cycles:10 Average:103.50 Median:93.00 stddev:19.02 (20.4%) stddev-sans-first:19.71 14:20:09 INFO - PROCESS | 2002 | Values: 92.0 93.0 93.0 94.0 109.0 149.0 124.0 94.0 94.0 93.0 14:20:09 INFO - PROCESS | 2002 | 14:20:09 INFO - PROCESS | 2002 | [#7] imaging-desaturate Cycles:10 Average:89.40 Median:85.50 stddev:13.43 (15.7%) stddev-sans-first:14.18 14:20:09 INFO - PROCESS | 2002 | Values: 86.0 91.0 127.0 86.0 84.0 83.0 83.0 86.0 85.0 83.0 14:20:09 INFO - PROCESS | 2002 | 14:20:09 INFO - PROCESS | 2002 | [#8] json-parse-financial Cycles:10 Average:54.10 Median:54.50 stddev:0.88 (1.6%) stddev-sans-first:0.83 14:20:09 INFO - PROCESS | 2002 | Values: 53.0 54.0 53.0 55.0 53.0 55.0 54.0 55.0 54.0 55.0 14:20:09 INFO - PROCESS | 2002 | 14:20:09 INFO - PROCESS | 2002 | [#9] json-stringify-tinderbox Cycles:10 Average:78.50 Median:71.00 stddev:15.73 (22.2%) stddev-sans-first:16.12 14:20:09 INFO - PROCESS | 2002 | Values: 67.0 66.0 69.0 74.0 78.0 71.0 71.0 74.0 110.0 105.0 14:20:09 INFO - PROCESS | 2002 | 14:20:09 INFO - PROCESS | 2002 | [#10] stanford-crypto-aes Cycles:10 Average:84.80 Median:86.00 stddev:3.46 (4.0%) stddev-sans-first:3.32 14:20:09 INFO - PROCESS | 2002 | Values: 89.0 85.0 85.0 84.0 87.0 84.0 82.0 87.0 77.0 88.0 14:20:09 INFO - PROCESS | 2002 | 14:20:09 INFO - PROCESS | 2002 | [#11] stanford-crypto-ccm Cycles:10 Average:148.20 Median:140.00 stddev:25.93 (18.5%) stddev-sans-first:27.33 14:20:09 INFO - PROCESS | 2002 | Values: 140.0 140.0 139.0 135.0 140.0 138.0 134.0 156.0 220.0 140.0 14:20:09 INFO - PROCESS | 2002 | 14:20:09 INFO - PROCESS | 2002 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.10 Median:168.00 stddev:3.45 (2.1%) stddev-sans-first:3.17 14:20:09 INFO - PROCESS | 2002 | Values: 172.0 165.0 171.0 164.0 161.0 167.0 167.0 169.0 170.0 165.0 14:20:09 INFO - PROCESS | 2002 | 14:20:09 INFO - PROCESS | 2002 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:67.00 stddev:1.14 (1.7%) stddev-sans-first:1.20 14:20:09 INFO - PROCESS | 2002 | Values: 67.0 68.0 68.0 68.0 67.0 65.0 67.0 67.0 65.0 66.0 14:20:09 INFO - PROCESS | 2002 | -------- Summary: end -------- 14:20:09 INFO - PROCESS | 2002 | 14:20:10 INFO - TEST-INFO | 2002: exit 0 14:20:10 INFO - TEST-OK | kraken | took 59111ms 14:20:10 INFO - SUITE-END | took 1176s 14:20:11 INFO - Completed test suite (00:19:36) 14:20:11 INFO - javascript benchmark 14:20:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3102.6587075421703, 3256.983715081424, 3261.7227535659476, 3270.9836450817743, 3289.4736842105267, 5439.4832490913395, 5445.564354851612, 5523.91714124288, 5524.889502209963, 5535.58483113766, 5526.640768350055, 5566.805161819337, 5585.078462053756, 5589.105743081108, 5594.58040646951, 5028.874278143039, 5082.364704411949, 5087.135187018216, 5128.4102328232175, 5167.9483205168035, 5121.308623335839, 5249.527542521172, 5252.185911183773, 5256.053910296145, 5262.736863156846, 4340.652747780186, 5074.949250507503, 5269.077911365515, 5270.393904700951, 5281.155015197569, 5048.192289233705, 5059.089363914494, 5097.184450487941, 5179.766910489037, 5198.480151984809, 4194.706370554062, 5151.510606492415, 5160.56135228506, 5187.792488300463, 5203.687778733288, 5603.159526071081, 5603.803866864681, 5632.283771233396, 5643.6049476536655, 5687.175359572881, 5979.850503737398, 6009.188759517453, 6239.126522286883, 6239.65681887496, 6273.027680709497, 6156.6613836238985, 6209.192804935375, 6275.843103922439, 6278.121063051177, 6283.183186185813, 5744.167095771173, 5745.13822926556, 5745.7127143643065, 5746.913796293059, 5748.252727145528, 5659.6321239119325, 6338.8415289617205, 6359.427651511386, 6371.617702937746, 6396.0, 5778.335491418541, 5981.880362392728, 6037.54718396122, 6048.153258543807, 6140.4473597376455, 6176.81469555914, 6225.782097626652, 6229.252489701174, 6229.2524897012645, 6234.875302494015, 6184.938150618526, 6190.535709821691, 6197.597156184834, 6202.658853762996, 6228.688565571703, 5913.911291330547, 6024.849378765566, 6032.125341825498, 6154.815355539341, 6155.969220153871, 4817.373741413664, 4886.682365646222, 4889.046635906036, 4931.4821943696115, 4936.6297527685565, 4374.8906277342685, 4376.693631445832, 4388.736675799462, 4396.648268138541, 4401.933970990438, 5509.393966663663, 5573.888522229533, 5693.772249110072, 5714.485696287271, 5723.170140329629, 6165.383461653799, 6243.094751261042, 6294.181756371734, 6306.180196574416, 6308.589941653778, 5668.064769313017, 5691.203231547586, 5691.857703557444, 5699.202111704365, 5702.942970570324, 5239.345081864767, 5266.368035835724, 5342.0, 5412.864678382994, 5417.404085550586, 6267.185265915584, 6331.683415829098, 6338.207724034496, 6352.396522330371, 6397.200349956349, 6240.219972503437, 6257.78097766576, 6267.62394256346, 6269.74921003173, 6274.403931626488, 6355.332690067661, 6365.777197798055, 6375.139356187041, 6384.07430922523, 6386.393292637192, 5900.911486327623, 5984.1323008164445, 5986.760529578943, 6050.546209034252, 6079.756809727738, 5010.423801262756, 6241.157443745036, 6245.375462453719, 6252.718627661857, 6261.592996455398, 6029.547783916136, 6035.456808887221, 6041.939580604314, 6042.0, 6042.486388657014, 5173.6548497390195, 5303.098473259633, 5917.763289468373, 5926.703664816828, 5967.582269241285, 5869.148973398918, 5904.3800400959, 5912.940870591411, 5921.467067963904, 5922.407759224043, 4771.976140119277, 5887.146363777143, 6058.757649693963, 6079.939200607937, 6094.08588711679, 5742.454466825645, 5790.363060063305, 5803.303603567599, 5881.088431293157, 5913.704314784158], "value": 5611.5315227664405, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [487.90485855258214, 494.47338584407584, 495.7655437959481, 508.5252766975769, 521.8956208758249, 30243.69756302436, 39818.0, 41004.284507281634, 41060.0, 43500.78249608755, 40548.0, 42586.14827703444, 42692.0, 43204.91987700301, 43205.0, 9683.176929960962, 9864.753381165492, 9873.456959867204, 9881.407115573089, 9886.703398898044, 9757.463339516324, 9862.161716254092, 9873.308868379216, 9873.851892221623, 9875.55559999798, 9828.508574571242, 9886.159676427513, 9887.950560247189, 9895.554700038516, 9896.377459973077, 9810.166135878459, 9858.80282394348, 9866.999999999929, 9875.95062024689, 9888.95055524725, 9696.17582505488, 9761.0, 9781.315307928448, 9784.755381115529, 9804.166645835112, 40122.59877401218, 40532.59467405318, 40555.0, 40607.593924060675, 40703.7964810177, 3461.809600471973, 3467.9133021674406, 3576.5886923003927, 3663.065918190844, 3679.3193259247123, 30584.2353941151, 30861.14235720535, 31824.0, 31949.881754138725, 32234.838825805957, 3257.6090869095624, 3310.7185889199445, 3314.96685033149, 3337.9499307510405, 3457.913552161191, 496346.0, 503915.0, 505140.47429762984, 506754.0, 513096.0, 32727.181820454203, 57126.57183570444, 57436.71281643565, 57463.850722985306, 57467.13799293014, 33594.0, 34764.0, 34811.30377392438, 34887.4766878497, 34916.82541587276, 14580.927095364668, 14745.705085898222, 14785.704285914222, 14811.333489992976, 14818.33317500715, 12569.245845249314, 12619.810702839464, 12625.936870315772, 12647.683807904692, 12655.114142010156, 7685.116211635734, 7714.112877019103, 7750.031246094239, 7759.612019399007, 7779.72770953014, 3211.727003204707, 3261.3477304538715, 3265.559149514833, 3266.591676040495, 3304.240024794263, 967.5452537307455, 1000.9049140331657, 1070.0904231403244, 1076.8384742288563, 1083.7290677330668, 25558.92023705985, 29297.41405171927, 30326.090217293517, 30689.0, 30836.84581577078, 885.7564169853213, 1019.2915923433291, 1028.2596530498029, 1088.2545456362418, 1099.6700989703056, 14202.573922782332, 14927.477538286324, 15428.997115187476, 15503.457378991907, 15803.604909877115, 685159.5742021258, 699515.0, 699659.0, 700534.0, 702254.0, 58068.70965645145, 58566.0, 58579.82840343169, 58712.41287587155, 58823.70588147032, 1257.5095712672064, 1366.5968539281134, 1397.77635578307, 1414.4907833180048, 1416.357025850009, 24727.38181545476, 26561.867190664696, 30129.901251085874, 30977.91577294784, 31462.842685786425, 40284.3957340646, 48569.271460927404, 49066.75466622644, 49067.50932490629, 49195.99999999857, 539347.0, 540071.0, 542321.0, 543569.0, 543573.0, 26455.470890581695, 26934.057307994128, 27348.589771153052, 27519.724802752517, 28255.29361765972, 46952.53047469482, 47432.28851567298, 47833.0, 47942.0, 47974.520254797004, 38687.8393648191, 39072.02319942024, 39265.80367098146, 39323.0, 39708.404373934995, 38888.611113888495, 38929.0, 38965.61034389733, 39489.40765888571, 39964.40053399259, 37405.0, 38065.0, 38493.615063849, 38515.42226866543, 38581.807090964365, 32809.835950820096, 33643.83178084094, 34224.65775342215, 34414.13964650803, 34538.82730586331, 25040.998360065394, 25209.11768088108, 25326.0, 25410.237692869243, 25860.094896678525], "value": 18731.109087693738, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [313.4827534567961, 365.6581096674608, 372.51572955158287, 382.64222951540313, 403.9192161567685, 15288.388464461423, 16855.325786968522, 19993.0, 20495.385138445832, 20533.0, 20316.18735250592, 20610.690839637417, 20856.0, 21058.262960796375, 21112.4721881953, 5920.378360272174, 5931.139984702205, 6108.511319094462, 6216.409441103088, 6220.129181914535, 4172.812223449937, 6087.05650624154, 6177.907331390033, 6193.721282542297, 6204.069389591575, 6429.099926010339, 6693.2302785179845, 6752.426043786284, 6795.218549866754, 6833.829154271133, 6657.700403481855, 6672.432843208308, 6731.730730770763, 6752.358525940028, 6765.966170169142, 5688.8862222755315, 5840.240768700041, 5846.3861294563885, 5853.385394533555, 5868.178455016301, 13709.245991470565, 13930.651733706638, 23020.964056617493, 24456.144034958692, 25552.744472555223, 2164.296603603835, 2199.7800219978035, 2297.4026753044163, 2316.2587971849016, 2806.564982427727, 9934.297240084643, 10393.948030259877, 10395.536514894919, 10450.425226612533, 13226.404811783494, 1850.481865077767, 1898.7911329753715, 1920.3470819921154, 1937.651222779899, 1994.2521554417094, 86098.0, 105675.0, 109634.0, 123235.0, 124296.37851810774, 11372.943135284271, 11373.0, 11476.901236666221, 11523.53905843757, 11767.646970590897, 14512.492062777752, 14523.346449409803, 14523.927380363031, 14530.273486325641, 14549.054311469721, 8260.215279548433, 8506.191911768372, 8529.914700853036, 8601.655933762579, 8611.009733880559, 8452.323814094858, 8470.534120623302, 8474.067852536216, 8594.44136131162, 8602.698905538276, 4419.690621656518, 4509.594136527729, 4516.751578663139, 4601.861944141681, 4623.606993405599, 1698.5159229619499, 1763.3563749231694, 1783.9464816055538, 1911.8566107541712, 1913.416407995547, 739.3973911262304, 879.2833840420037, 938.9061093890693, 952.8523078922778, 976.2970661123979, 19109.61780764405, 20330.695039574417, 20904.47738806542, 20994.685079723815, 21341.573168536543, 955.5986485676033, 957.3537861943299, 961.9615215391306, 976.858355538443, 997.7555498902815, 8441.620127094295, 8916.197542221102, 9783.0, 10527.999680060764, 11064.834027489595, 571141.0, 585164.0741796264, 593553.0, 594170.0, 598832.0, 73247.63376183191, 99406.0, 135150.99999999802, 135482.0, 137067.6293237075, 930.283681565191, 979.0258692600908, 995.835687111633, 1003.2024540490328, 1004.8944860789512, 13599.660008499866, 17343.826561734724, 17426.651466970845, 17717.557061073578, 17779.46661600154, 55754.32737017896, 55912.0, 56010.439895600524, 56142.43857561372, 56813.715931420076, 76909.61545192238, 78184.0, 79280.60359698164, 81201.69184773162, 83483.16516834754, 7978.606418074671, 8109.310708589836, 8332.33341332704, 8349.290310323691, 8498.0, 12932.03009774252, 13373.866261337262, 13463.663408414868, 13867.930660346634, 13904.721905562035, 11132.499037543168, 11243.775124497628, 11679.182457227913, 11887.0, 11980.341081240615, 10445.164386849186, 10497.317674351143, 11167.329960202414, 11806.350650714294, 11890.940545297219, 11544.365059921785, 11619.883801161881, 11836.70408239801, 11936.343501107174, 12005.0, 7201.819954501179, 7342.449316301406, 7490.288422599844, 7784.92215077842, 8528.530930798866, 10188.64339748135, 11230.775384492428, 11304.773904522028, 11619.825702614637, 11794.882051179378], "value": 9539.749182428672, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1501.6396064944413, 1531.433369653228, 1692.7122389193835, 1814.3014939248385, 1841.926322947082, 8404.159584041601, 8925.152110549503, 8936.374453788238, 8936.642534298628, 8949.239314658262, 7809.140994490602, 8046.476978996361, 8468.618912148953, 8680.652773889036, 8727.86908196376, 2336.8481048731865, 2368.5973384524586, 2373.727021392545, 2374.8693821839793, 2376.227725989052, 2316.6293393057113, 2351.5532048910654, 2358.6815779869758, 2361.527694461106, 2362.11420717231, 2322.129201549419, 2331.4287999440076, 2340.8946597403074, 2341.906323747048, 2342.2387723989773, 2331.370529956919, 2349.2364981381033, 2361.2798096580536, 2361.3860396297086, 2375.2874137758777, 2346.6128088865316, 2348.917787877433, 2367.112332875172, 2367.195153647751, 2374.726906405768, 7090.432765378758, 8830.24942879856, 8843.11568843117, 8844.646214151426, 8864.157904999014, 5136.922946155811, 5331.44019877915, 5343.064963631387, 5349.063913815066, 5350.571954243651, 3660.7803531788177, 3673.6326367363317, 3737.5141231639986, 3744.120131769032, 3744.251149770057, 1096.31480324797, 1106.551846502168, 1108.1965574958172, 1119.871214810291, 1129.4860838318546, 11634.30194188343, 11706.063514918786, 11747.941260293645, 11771.646850594496, 11792.23350482216, 3826.3877779555696, 4436.911261774811, 4755.191617425046, 4764.2615394613895, 4765.69023013503, 9873.259505537113, 9874.851877221847, 9877.753056173653, 9901.75245618851, 9906.405615663083, 5317.989581979412, 5322.308099946982, 5323.787048518016, 5327.840164795062, 5339.519443250126, 4068.9186216275507, 4373.868783936487, 4458.5318541553315, 4512.729236245836, 4897.14299997499, 3125.6405513366253, 3791.109089363997, 3797.8290976906105, 3801.6958643308467, 3807.904802379963, 1114.9318015081906, 1292.6057552446593, 1312.8424589049375, 1313.1792629606496, 1313.9080264381594, 881.4623079921242, 918.169057003413, 919.4667093085994, 935.6865450074301, 946.4368700623119, 5686.630369026, 5749.511291540182, 5892.086726557391, 5936.762529498858, 6066.3023752269055, 437.89928316487385, 442.287916454508, 451.7876597998935, 452.8686680862513, 460.7926433105116, 3928.862489812843, 3973.0861901762755, 4030.8387664493093, 4044.656204222674, 4063.146739184804, 9765.997970416047, 10696.037356637784, 10847.566097356175, 10905.18211134168, 10911.672649820359, 10775.245732798785, 10800.081993030522, 10807.567697292021, 10816.67549973502, 10822.080123189617, 8215.58922053895, 8258.132896045949, 8269.21442462977, 8275.255227029596, 8279.219967706971, 4314.590113939171, 5419.2413062170535, 5545.667259964415, 5632.211490391266, 5638.37977822431, 9806.21550275962, 9814.852777208205, 9949.751256218653, 10033.899661003588, 10131.89868101339, 9978.550965206437, 10129.290949633749, 10236.744081398025, 10255.07704306616, 10373.014563616445, 7256.092988376453, 7426.777196684108, 7532.0, 7544.585047822423, 7553.32020118192, 8271.503709777433, 8404.20160084791, 8462.407631465738, 8482.406231563731, 8487.533185674847, 7424.331810136898, 7484.962575187089, 7489.13874904393, 7491.625418729151, 7685.385169186564, 6036.426539478743, 7341.265873412616, 7356.117265927909, 7360.668769905475, 7420.703171873281, 6926.8614627708175, 6977.023216749761, 7068.151821781419, 7084.610346430995, 7087.539309944837, 4973.875653108701, 5023.748812559431, 5045.394552653705, 5114.3862736471865, 5138.743062846917, 4930.382325757088, 4995.350604421402, 5128.897422051464, 5235.397929238186, 5240.502152295526], "value": 4485.191146081713, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [292.5786866911647, 306.60448022051554, 311.0714517166259, 312.33628539353856, 314.9889753858615, 5359.303290572225, 5373.9193912091305, 5385.084535628943, 5386.91919621205, 5393.217983392406, 5365.517103460687, 5389.086874159666, 5393.217983392406, 5395.21769343445, 5425.782968681257, 3362.882299119531, 3847.1533663841374, 3853.2293541291783, 3982.5818289079666, 4001.359782434811, 3962.1877515109477, 4000.639942405183, 4016.3774614934587, 4017.799110044486, 4061.309577371839, 3939.5863434339412, 3969.940450893239, 4002.899927501807, 4007.1184339445276, 4024.315866302721, 3295.96704032959, 3298.7855789373616, 3720.9441858372147, 3914.9804250978846, 3982.940255896135, 3487.7907325560545, 3487.9128021799406, 3520.5951315598777, 3523.9647603523895, 3528.012156596157, 4261.573842615714, 5156.742162891878, 5180.715060671661, 5201.375834899798, 5213.869653258661, 3545.042838433634, 3998.8600398986187, 4458.910821783578, 4603.355530225737, 4635.976820115911, 4588.2888152336445, 4623.375844261049, 4878.341423907763, 5322.654027488201, 5608.55131589476, 3812.2566099610585, 3833.29084119436, 3840.6927445804267, 3867.4972253607134, 3889.747166434173, 4592.0, 5211.41387666286, 5751.654900705971, 5779.104238842944, 5801.767929282823, 3900.3759398496104, 3914.887664306883, 3928.8035598219976, 3930.663574384723, 3940.4680368149934, 3540.327337805809, 3771.735978481535, 3989.58109398509, 3999.8001598720907, 4027.536833264212, 4142.0, 4581.198290299184, 4644.2801365788355, 4644.490540574259, 4648.094281344633, 4878.36581244443, 5212.48837837023, 5648.209250704905, 5655.632383895034, 5763.3084029915835, 1100.510272928539, 1183.065378351093, 1322.6494978830617, 1332.68015676237, 1341.9194848309132, 709.5033476566424, 756.4440136499778, 776.8718161503289, 815.5677490929698, 822.399648256764, 3942.7240093193773, 4226.260404429213, 4387.407699960529, 4609.354690343288, 4850.587700045465, 4966.453690094087, 5033.835450756137, 5337.839864804062, 5797.826065218051, 5858.882822343529, 4145.771982540929, 5229.686218826807, 5260.947390526122, 5263.078961181778, 5285.788568457296, 2866.4059026580976, 2960.3422083633236, 2970.7177818107243, 2983.6727352664957, 2992.7280905615025, 5845.707714614252, 5863.70681465925, 5868.354481007086, 5896.174535565025, 5981.431763982415, 5129.204973229155, 5226.3205783248695, 5315.574754019669, 5856.0, 5892.823215303548, 6083.452489275898, 6086.147939288503, 6090.0, 6098.72555734993, 6104.908426373519, 4585.633149347977, 4813.374261346143, 5793.118610531039, 5874.706264686834, 5880.588358814842, 5480.917786233289, 5660.575456840671, 5687.232223649919, 5758.510526605285, 5784.1034639630925, 4880.536349046835, 5182.663126896739, 5412.78348866041, 5458.935507575985, 5965.8963091828155, 3864.9033774155873, 3866.4007078902814, 3871.4901188535932, 3878.903027424337, 3903.5706072331454, 4675.76621168947, 5455.1271796512365, 5797.884042319214, 5804.796832110856, 5828.883422331445, 5629.887402252014, 5655.632383895034, 5671.8865622688145, 5674.829755107354, 5685.545156387562, 5310.8141215057285, 5445.019896418683, 5629.380768115586, 5644.657467151251, 5659.943400565941, 5038.647294689336, 5515.25544051564, 5529.806456774155, 5567.972160139173, 5577.8047768327915, 4235.894102647459, 4824.252240902666, 5371.758270877742, 5582.27430434041, 5683.687397193194, 4529.524399937959, 5216.791673120927, 5461.579989202915, 5508.476694713996, 5599.692016938944], "value": 4033.657246930224, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [657.6382989355853, 694.1392673085369, 801.6993627389727, 815.3029160068141, 840.1136800675285, 15190.31643576039, 16272.0, 16325.0, 16332.101734404618, 17018.659626807457, 16174.919125404385, 16458.423955161572, 16748.49754507364, 16795.160241987884, 16799.748003779954, 841.4572600672562, 862.7411776467035, 863.6113748813028, 865.4980111535294, 866.57537806475, 677.0757915445406, 694.9826254343657, 816.7019038051099, 842.0737189091981, 844.5608283692476, 900.8063266397724, 902.499112992289, 902.7788191892995, 904.3624244907325, 905.4069584422181, 896.4352457951449, 898.1333013641802, 898.5417437107058, 899.4872922434182, 902.6885724425063, 525.3039722367839, 548.5584104795638, 645.6287634610071, 665.8634979829123, 673.8180691213345, 15072.020318679251, 15085.849141508554, 15087.321070551852, 15119.546413607499, 15132.848671513255, 13448.394822233024, 13866.861331386657, 13975.231362275072, 14001.509947151902, 14337.42650293987, 11604.593839215588, 11776.131605678416, 12277.508899644003, 12295.077869159848, 12349.444275007647, 3838.500994870649, 3839.9232015359535, 3860.806959652034, 3981.0, 4018.0356714388336, 86472.0, 113892.0, 115793.0, 116323.41838290755, 116375.41812290969, 10671.57313707443, 10934.07060399859, 11135.832962505569, 11168.553257869757, 11555.711107222387, 22383.776162238493, 22777.31668049961, 22787.544249115257, 22808.771912280667, 22850.0, 8037.156098609678, 8197.79505512367, 8271.296939760188, 8301.750947471586, 8332.541710205996, 3941.940870886939, 4293.162833247549, 4557.08858228349, 4585.1746685596245, 4776.641751868624, 3575.606683264839, 3756.267527832101, 3759.887203383903, 3761.3041587306434, 3766.773993560395, 554.3431034224457, 624.2353117431111, 628.5254632752243, 670.4401824476519, 671.5668393886014, 505.24718169926547, 585.7978997245943, 673.5075459626752, 683.4771399879096, 685.8971154326891, 8720.541742712085, 9121.863172052424, 9153.084691530927, 9427.104425079606, 9705.320627556004, 515.7627491353944, 542.888707814897, 573.6241673795813, 670.8960111182774, 680.6154522694661, 3718.8698395556567, 4567.520410356931, 4591.943852913849, 4687.531246875285, 4807.254875494304, 126785.0, 131219.0, 134299.0, 137992.0, 152810.23594881955, 85287.0, 120423.0, 121598.0, 122484.0, 124293.75706243003, 97808.0, 100697.0, 103310.0, 106738.0, 109713.45143274392, 9445.669401570913, 9652.600372945742, 10013.948535403677, 10099.293049486465, 10188.541515631667, 50883.49116508788, 57633.253838500365, 58202.0, 60159.0, 61590.0, 62855.0, 63515.0, 63674.0, 81328.0, 87070.56464717636, 13444.39500222512, 13669.794953075512, 13974.0, 14036.719265614835, 14250.216238106588, 15018.92490537584, 15496.92251538735, 15895.205239738198, 15964.281607327466, 16109.597260068593, 14050.718985620435, 14142.858571414154, 14168.0, 14344.282785860456, 14356.641083972567, 13836.0, 14083.577492675236, 14223.359948802536, 14238.786418203943, 14657.340419681354, 13857.099288546615, 14129.505467308594, 14230.928845355707, 14414.711705766036, 14418.0, 11442.199046066695, 11674.474648640662, 11710.94144529272, 11815.350155741517, 12088.637340879788, 7669.923300766921, 9621.276383413833, 10490.475476226005, 10696.946515267375, 11364.147688923198], "value": 7783.389442489106, "unit": "score"}], "name": "dromaeo_css", "value": 7216.267810439997, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 113.0, 107.0, 112.0, 112.0, 113.0, 116.0, 113.0, 112.0, 112.0], "value": 110.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 114.0, 131.0, 162.0, 108.0, 128.0, 128.0, 108.0, 162.0, 103.0], "value": 128.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 181.0, 184.0, 181.0, 185.0, 192.0, 187.0, 180.0, 185.0, 180.0], "value": 184.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 73.0, 146.0, 55.0, 57.0, 57.0, 89.0, 88.0, 56.0], "value": 77.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 132.0, 70.0, 71.0, 77.0, 118.0, 70.0, 69.0, 121.0], "value": 89.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 86.0, 88.0, 91.0, 88.0, 85.0, 95.0, 91.0, 90.0, 84.0], "value": 89.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 93.0, 94.0, 109.0, 149.0, 124.0, 94.0, 94.0, 93.0], "value": 103.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 91.0, 127.0, 86.0, 84.0, 83.0, 83.0, 86.0, 85.0, 83.0], "value": 89.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 53.0, 55.0, 53.0, 55.0, 54.0, 55.0, 54.0, 55.0], "value": 54.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 69.0, 74.0, 78.0, 71.0, 71.0, 74.0, 110.0, 105.0], "value": 78.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 85.0, 84.0, 87.0, 84.0, 82.0, 87.0, 77.0, 88.0], "value": 84.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 140.0, 139.0, 135.0, 140.0, 138.0, 134.0, 156.0, 220.0, 140.0], "value": 148.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 165.0, 171.0, 164.0, 161.0, 167.0, 167.0, 169.0, 170.0, 165.0], "value": 167.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 68.0, 68.0, 67.0, 65.0, 67.0, 67.0, 65.0, 66.0], "value": 66.8, "unit": "score"}], "name": "kraken", "value": 1472.0, "alertThreshold": 2.0}]} 14:20:11 INFO - Return code: 0 14:20:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:20:11 INFO - # TBPL SUCCESS # 14:20:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:20:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:20:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:20:11 INFO - 2016-08-22 14:20:11.273 screenresolution[2006:13076] starting screenresolution argv=screenresolution get 14:20:11 INFO - 2016-08-22 14:20:11.275 screenresolution[2006:13076] Display 0: 1600x1200x32@60 14:20:11 INFO - 2016-08-22 14:20:11.282 screenresolution[2007:13079] starting screenresolution argv=screenresolution list 14:20:11 INFO - Available Modes on Display 0 14:20:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:20:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:20:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:20:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:20:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:20:11 INFO - Intel Iris: 14:20:11 INFO - Chipset Model: Intel Iris 14:20:11 INFO - Type: GPU 14:20:11 INFO - Bus: Built-In 14:20:11 INFO - VRAM (Dynamic, Max): 1536 MB 14:20:11 INFO - Vendor: Intel (0x8086) 14:20:11 INFO - Device ID: 0x0a2e 14:20:11 INFO - Revision ID: 0x0009 14:20:11 INFO - Displays: 14:20:11 INFO - Display: 14:20:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:20:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:20:11 INFO - Display Serial Number: 1600x1200 60 14:20:11 INFO - Main Display: Yes 14:20:11 INFO - Mirror: Off 14:20:11 INFO - Online: Yes 14:20:11 INFO - Rotation: Supported 14:20:11 INFO - Return code: 0 14:20:11 INFO - Running post-action listener: _resource_record_post_action 14:20:11 INFO - [mozharness: 2016-08-22 21:20:11.521507Z] Finished run-tests step (success) 14:20:11 INFO - Running post-run listener: _resource_record_post_run 14:20:11 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 1069056; Write bytes: 707127808; Read time: 16; Write time: 1975 14:20:11 INFO - TinderboxPrint: CPU usage
53.4% 14:20:11 INFO - TinderboxPrint: I/O read bytes / time
1,069,056 / 16 14:20:11 INFO - TinderboxPrint: I/O write bytes / time
707,127,808 / 1,975 14:20:11 INFO - TinderboxPrint: CPU idle
2,259.0 (46.6%) 14:20:11 INFO - TinderboxPrint: CPU system
967.5 (20.0%) 14:20:11 INFO - TinderboxPrint: CPU user
1,619.1 (33.4%) 14:20:11 INFO - TinderboxPrint: Swap in / out
407,375,872 / 0 14:20:11 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 401725952; Write bytes: 395991040; Read time: 29761; Write time: 814 14:20:11 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 1015808; Write bytes: 297157120; Read time: 15; Write time: 1117 14:20:11 INFO - Running post-run listener: _upload_blobber_files 14:20:11 INFO - Blob upload gear active. 14:20:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:20:11 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 14:20:11 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:20:11 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:20:12 INFO - (blobuploader) - INFO - Open directory for files ... 14:20:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:20:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:20:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:20:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:20:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:20:13 INFO - (blobuploader) - INFO - Done attempting. 14:20:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:20:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:20:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:20:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:20:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:20:14 INFO - (blobuploader) - INFO - Done attempting. 14:20:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:20:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:20:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:20:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:20:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:20:16 INFO - (blobuploader) - INFO - Done attempting. 14:20:16 INFO - (blobuploader) - INFO - Iteration through files over. 14:20:16 INFO - Return code: 0 14:20:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:20:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:20:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3527f0fc7ad062b8c0f93aea4cd82c8c35fc6ba0940f6e91b16e9e7e2a9fe3a45aea9fa860c50d5ac1ab31748ba4b303640cf79aca8d4e5c60f174f8da55f0ec", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/27dac3d79e5c3c7ced556816c8b93585f58df452aaaa7e167f2c4be47be7ec33b27af3dbaead469fe43dbe4ca51aa4e053b19de3a6d0c2757db9388893d6064d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c50009edb0599e0b14c6e9ccf435783347a38203398873bde978a208d426c02379de1bd8a468bda82d5bb184561579bf308850d63ff2e586c72a3b1a22aebbe"} 14:20:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:20:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:20:16 INFO - Contents: 14:20:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3527f0fc7ad062b8c0f93aea4cd82c8c35fc6ba0940f6e91b16e9e7e2a9fe3a45aea9fa860c50d5ac1ab31748ba4b303640cf79aca8d4e5c60f174f8da55f0ec", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/27dac3d79e5c3c7ced556816c8b93585f58df452aaaa7e167f2c4be47be7ec33b27af3dbaead469fe43dbe4ca51aa4e053b19de3a6d0c2757db9388893d6064d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c50009edb0599e0b14c6e9ccf435783347a38203398873bde978a208d426c02379de1bd8a468bda82d5bb184561579bf308850d63ff2e586c72a3b1a22aebbe"} 14:20:16 INFO - Running post-run listener: copy_logs_to_upload_dir 14:20:16 INFO - Copying logs to upload dir... 14:20:16 INFO - mkdir: /builds/slave/test/build/upload/logs 14:20:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.954462 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-08-22 14:20:16.858799) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-22 14:20:16.862305) ========= 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.D2aEo7gWtX/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.7k6vc6yIrI/Listeners TMPDIR=/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/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/Firefox/sha512/3527f0fc7ad062b8c0f93aea4cd82c8c35fc6ba0940f6e91b16e9e7e2a9fe3a45aea9fa860c50d5ac1ab31748ba4b303640cf79aca8d4e5c60f174f8da55f0ec", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/27dac3d79e5c3c7ced556816c8b93585f58df452aaaa7e167f2c4be47be7ec33b27af3dbaead469fe43dbe4ca51aa4e053b19de3a6d0c2757db9388893d6064d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c50009edb0599e0b14c6e9ccf435783347a38203398873bde978a208d426c02379de1bd8a468bda82d5bb184561579bf308850d63ff2e586c72a3b1a22aebbe"} build_url:https://queue.taskcluster.net/v1/task/D7KAnUDmTUKS1fGkWXefLg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011141 build_url: 'https://queue.taskcluster.net/v1/task/D7KAnUDmTUKS1fGkWXefLg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3527f0fc7ad062b8c0f93aea4cd82c8c35fc6ba0940f6e91b16e9e7e2a9fe3a45aea9fa860c50d5ac1ab31748ba4b303640cf79aca8d4e5c60f174f8da55f0ec", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/27dac3d79e5c3c7ced556816c8b93585f58df452aaaa7e167f2c4be47be7ec33b27af3dbaead469fe43dbe4ca51aa4e053b19de3a6d0c2757db9388893d6064d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c50009edb0599e0b14c6e9ccf435783347a38203398873bde978a208d426c02379de1bd8a468bda82d5bb184561579bf308850d63ff2e586c72a3b1a22aebbe"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-22 14:20:16.895426) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 14:20:16.895808) ========= 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.D2aEo7gWtX/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.7k6vc6yIrI/Listeners TMPDIR=/var/folders/8q/hdbfxhcs15739c66r08gln1r00000w/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.005270 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 14:20:16.945035) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-22 14:20:16.945370) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-22 14:20:16.945764) ========= ========= Total master_lag: 0.18 =========