builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0139 starttime: 1472054089.38 results: success (0) buildid: 20160824060514 builduid: bb7ec8241dba458dae0632dfdfacddf4 revision: 01748a2b1a463f24efd9cd8abad9ccfd76b037b8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-24 08:54:49.378333) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-24 08:54:49.378918) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-24 08:54:49.379482) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-24 08:54:49.408818) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-24 08:54:49.409499) ========= 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.6xFsE9E6vU/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.GrxTNfsils/Listeners TMPDIR=/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/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-24 08:54:49-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 196M=0s 2016-08-24 08:54:49 (196 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.088359 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-24 08:54:49.522745) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-24 08:54:49.523102) ========= 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.6xFsE9E6vU/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.GrxTNfsils/Listeners TMPDIR=/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/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.029402 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-24 08:54:49.573461) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-08-24 08:54:49.573857) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 01748a2b1a463f24efd9cd8abad9ccfd76b037b8 --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 01748a2b1a463f24efd9cd8abad9ccfd76b037b8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.6xFsE9E6vU/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.GrxTNfsils/Listeners TMPDIR=/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/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-24 08:54:49,648 truncating revision to first 12 chars 2016-08-24 08:54:49,648 Setting DEBUG logging. 2016-08-24 08:54:49,648 attempt 1/10 2016-08-24 08:54:49,648 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/01748a2b1a46?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-24 08:54:50,631 unpacking tar archive at: mozilla-central-01748a2b1a46/testing/mozharness/ program finished with exit code 0 elapsedTime=1.235170 ========= master_lag: 1.12 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-08-24 08:54:51.927213) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-24 08:54:51.927541) ========= script_repo_revision: 01748a2b1a463f24efd9cd8abad9ccfd76b037b8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-24 08:54:51.927939) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-24 08:54:51.928236) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-24 08:54:51.947722) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-24 08:54:51.948159) ========= /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.6xFsE9E6vU/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.GrxTNfsils/Listeners TMPDIR=/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/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 08:54:52 INFO - MultiFileLogger online at 20160824 08:54:52 in /builds/slave/test 08:54:52 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 08:54:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:54:52 INFO - {'append_to_log': False, 08:54:52 INFO - 'base_work_dir': '/builds/slave/test', 08:54:52 INFO - 'blob_upload_branch': 'Firefox', 08:54:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:54:52 INFO - 'branch': 'Firefox', 08:54:52 INFO - 'buildbot_json_path': 'buildprops.json', 08:54:52 INFO - 'config_files': ('talos/mac_config.py',), 08:54:52 INFO - 'default_actions': ('clobber', 08:54:52 INFO - 'read-buildbot-config', 08:54:52 INFO - 'download-and-extract', 08:54:52 INFO - 'populate-webroot', 08:54:52 INFO - 'create-virtualenv', 08:54:52 INFO - 'install', 08:54:52 INFO - 'run-tests'), 08:54:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:54:52 INFO - 'download_minidump_stackwalk': True, 08:54:52 INFO - 'download_symbols': 'ondemand', 08:54:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:54:52 INFO - 'tooltool.py': '/tools/tooltool.py', 08:54:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:54:52 INFO - '/tools/misc-python/virtualenv.py')}, 08:54:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:54:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:54:52 INFO - 'installer_path': 'installer.exe', 08:54:52 INFO - 'log_level': 'info', 08:54:52 INFO - 'log_name': 'talos', 08:54:52 INFO - 'log_to_console': True, 08:54:52 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:54:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:54:52 INFO - 'opt_config_files': (), 08:54:52 INFO - 'pip_index': False, 08:54:52 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:54:52 INFO - 'cmd': ('bash', 08:54:52 INFO - '-c', 08:54:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:54:52 INFO - 'enabled': True, 08:54:52 INFO - 'halt_on_failure': False, 08:54:52 INFO - 'name': 'check_screen_resolution'},), 08:54:52 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:54:52 INFO - 'cmd': ('bash', 08:54:52 INFO - '-c', 08:54:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:54:52 INFO - 'enabled': True, 08:54:52 INFO - 'halt_on_failure': False, 08:54:52 INFO - 'name': 'check_screen_resolution'},), 08:54:52 INFO - 'run_cmd_checks_enabled': True, 08:54:52 INFO - 'sps_profile': False, 08:54:52 INFO - 'sps_profile_interval': 0, 08:54:52 INFO - 'suite': 'dromaeojs', 08:54:52 INFO - 'system_bits': '32', 08:54:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:54:52 INFO - 'title': 't-yosemite-r7-0139', 08:54:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:54:52 INFO - 'use_talos_json': True, 08:54:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:54:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:54:52 INFO - 'work_dir': 'build'} 08:54:52 INFO - [mozharness: 2016-08-24 15:54:52.147010Z] Running clobber step. 08:54:52 INFO - Running pre-action listener: _resource_record_pre_action 08:54:52 INFO - Running main action method: clobber 08:54:52 INFO - rmtree: /builds/slave/test/build 08:54:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:54:54 INFO - Running post-action listener: _resource_record_post_action 08:54:54 INFO - [mozharness: 2016-08-24 15:54:54.252351Z] Finished clobber step (success) 08:54:54 INFO - [mozharness: 2016-08-24 15:54:54.252511Z] Running read-buildbot-config step. 08:54:54 INFO - Running pre-action listener: _resource_record_pre_action 08:54:54 INFO - Running main action method: read_buildbot_config 08:54:54 INFO - Using buildbot properties: 08:54:54 INFO - { 08:54:54 INFO - "project": "", 08:54:54 INFO - "product": "firefox", 08:54:54 INFO - "script_repo_revision": "production", 08:54:54 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 08:54:54 INFO - "repository": "", 08:54:54 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 08:54:54 INFO - "buildid": "20160824060514", 08:54:54 INFO - "pgo_build": "False", 08:54:54 INFO - "basedir": "/builds/slave/test", 08:54:54 INFO - "buildnumber": 76, 08:54:54 INFO - "slavename": "t-yosemite-r7-0139", 08:54:54 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 08:54:54 INFO - "platform": "yosemite_r7", 08:54:54 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:54:54 INFO - "branch": "mozilla-central", 08:54:54 INFO - "repo_path": "mozilla-central", 08:54:54 INFO - "revision": "01748a2b1a463f24efd9cd8abad9ccfd76b037b8", 08:54:54 INFO - "stage_platform": "macosx64", 08:54:54 INFO - "builduid": "bb7ec8241dba458dae0632dfdfacddf4", 08:54:54 INFO - "slavebuilddir": "test" 08:54:54 INFO - } 08:54:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/BSqo7oQ1TKS-3f8S-qyyTQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 08:54:54 INFO - Running post-action listener: _resource_record_post_action 08:54:54 INFO - [mozharness: 2016-08-24 15:54:54.255501Z] Finished read-buildbot-config step (success) 08:54:54 INFO - [mozharness: 2016-08-24 15:54:54.255613Z] Running download-and-extract step. 08:54:54 INFO - Running pre-action listener: _resource_record_pre_action 08:54:54 INFO - Running main action method: download_and_extract 08:54:54 INFO - mkdir: /builds/slave/test/build/tests 08:54:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:54 INFO - trying https://queue.taskcluster.net/v1/task/BSqo7oQ1TKS-3f8S-qyyTQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 08:54:54 INFO - Downloading https://queue.taskcluster.net/v1/task/BSqo7oQ1TKS-3f8S-qyyTQ/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 08:54:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BSqo7oQ1TKS-3f8S-qyyTQ/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 08:54:55 INFO - Downloaded 1199 bytes. 08:54:55 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 08:54:55 INFO - Using the following test package requirements: 08:54:55 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 08:54:55 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:54:55 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 08:54:55 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:54:55 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 08:54:55 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:54:55 INFO - u'jsshell-mac.zip'], 08:54:55 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:54:55 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 08:54:55 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 08:54:55 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:54:55 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 08:54:55 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:54:55 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 08:54:55 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:54:55 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 08:54:55 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:54:55 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 08:54:55 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:54:55 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')]} 08:54:55 INFO - trying https://queue.taskcluster.net/v1/task/BSqo7oQ1TKS-3f8S-qyyTQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 08:54:55 INFO - Downloading https://queue.taskcluster.net/v1/task/BSqo7oQ1TKS-3f8S-qyyTQ/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 08:54:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BSqo7oQ1TKS-3f8S-qyyTQ/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 08:54:56 INFO - Downloaded 16346293 bytes. 08:54:56 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 08:55:03 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 08:55:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:55:03 INFO - trying https://queue.taskcluster.net/v1/task/BSqo7oQ1TKS-3f8S-qyyTQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 08:55:03 INFO - Downloading https://queue.taskcluster.net/v1/task/BSqo7oQ1TKS-3f8S-qyyTQ/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 08:55:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BSqo7oQ1TKS-3f8S-qyyTQ/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 08:55:04 INFO - Downloaded 16346293 bytes. 08:55:04 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 08:55:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:55:10 INFO - trying https://queue.taskcluster.net/v1/task/BSqo7oQ1TKS-3f8S-qyyTQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 08:55:10 INFO - Downloading https://queue.taskcluster.net/v1/task/BSqo7oQ1TKS-3f8S-qyyTQ/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 08:55:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BSqo7oQ1TKS-3f8S-qyyTQ/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 08:55:11 INFO - Downloaded 14413981 bytes. 08:55:11 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 08:55:12 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')]} 08:55:12 INFO - trying https://queue.taskcluster.net/v1/task/BSqo7oQ1TKS-3f8S-qyyTQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 08:55:12 INFO - Downloading https://queue.taskcluster.net/v1/task/BSqo7oQ1TKS-3f8S-qyyTQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 08:55:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BSqo7oQ1TKS-3f8S-qyyTQ/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 08:55:15 INFO - Downloaded 111458774 bytes. 08:55:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BSqo7oQ1TKS-3f8S-qyyTQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 08:55:15 INFO - mkdir: /builds/slave/test/properties 08:55:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:55:15 INFO - Writing to file /builds/slave/test/properties/build_url 08:55:15 INFO - Contents: 08:55:15 INFO - build_url:https://queue.taskcluster.net/v1/task/BSqo7oQ1TKS-3f8S-qyyTQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 08:55:15 INFO - Running post-action listener: _resource_record_post_action 08:55:15 INFO - Running post-action listener: set_extra_try_arguments 08:55:15 INFO - [mozharness: 2016-08-24 15:55:15.757524Z] Finished download-and-extract step (success) 08:55:15 INFO - [mozharness: 2016-08-24 15:55:15.757674Z] Running populate-webroot step. 08:55:15 INFO - Running pre-action listener: _resource_record_pre_action 08:55:15 INFO - Running main action method: populate_webroot 08:55:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:55:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:55:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:55:15 INFO - '--tppagecycles', 08:55:15 INFO - '7'], 08:55:15 INFO - 'tests': ['tsvgm']}}, 08:55:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:55:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:55:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:55:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:55:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:55:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:55:15 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:55:15 INFO - 'talos_options': ['--disable-e10s'], 08:55:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:55:15 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:55:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:55:15 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:55:15 INFO - 'talos_options': ['--disable-e10s'], 08:55:15 INFO - 'tests': ['damp', 'tps']}, 08:55:15 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:55:15 INFO - 'tests': ['damp', 'tps']}, 08:55:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:55:15 INFO - 'tests': ['dromaeo_dom']}, 08:55:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:55:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:55:15 INFO - 'tests': ['basic_compositor_video']}, 08:55:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:55:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:55:15 INFO - 'tests': ['a11yr', 08:55:15 INFO - 'ts_paint', 08:55:15 INFO - 'tpaint', 08:55:15 INFO - 'sessionrestore', 08:55:15 INFO - 'sessionrestore_no_auto_restore', 08:55:15 INFO - 'tabpaint']}, 08:55:15 INFO - 'other-e10s': {'tests': ['a11yr', 08:55:15 INFO - 'ts_paint', 08:55:15 INFO - 'tpaint', 08:55:15 INFO - 'sessionrestore', 08:55:15 INFO - 'sessionrestore_no_auto_restore', 08:55:15 INFO - 'tabpaint']}, 08:55:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:55:15 INFO - 'ts_paint', 08:55:15 INFO - 'tpaint', 08:55:15 INFO - 'sessionrestore', 08:55:15 INFO - 'sessionrestore_no_auto_restore', 08:55:15 INFO - 'tabpaint']}, 08:55:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:55:15 INFO - 'ts_paint', 08:55:15 INFO - 'tpaint', 08:55:15 INFO - 'sessionrestore', 08:55:15 INFO - 'sessionrestore_no_auto_restore', 08:55:15 INFO - 'tabpaint']}, 08:55:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:55:15 INFO - 'tests': ['a11yr', 08:55:15 INFO - 'ts_paint', 08:55:15 INFO - 'tpaint', 08:55:15 INFO - 'sessionrestore', 08:55:15 INFO - 'sessionrestore_no_auto_restore', 08:55:15 INFO - 'tabpaint']}, 08:55:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:55:15 INFO - 'tests': ['a11yr', 08:55:15 INFO - 'ts_paint', 08:55:15 INFO - 'tpaint', 08:55:15 INFO - 'sessionrestore', 08:55:15 INFO - 'sessionrestore_no_auto_restore', 08:55:15 INFO - 'tabpaint']}, 08:55:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:55:15 INFO - 'tests': ['tsvgx', 08:55:15 INFO - 'tsvgr_opacity', 08:55:15 INFO - 'tart', 08:55:15 INFO - 'tscrollx', 08:55:15 INFO - 'cart']}, 08:55:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:55:15 INFO - 'tsvgr_opacity', 08:55:15 INFO - 'tart', 08:55:15 INFO - 'tscrollx', 08:55:15 INFO - 'cart']}, 08:55:15 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:55:15 INFO - 'talos_options': ['--disable-e10s'], 08:55:15 INFO - 'tests': ['tp5o']}, 08:55:15 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:55:15 INFO - 'tests': ['tp5o']}, 08:55:15 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:55:15 INFO - 'talos_options': ['--disable-e10s', 08:55:15 INFO - '--xperf_path', 08:55:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:55:15 INFO - 'tests': ['tp5n']}, 08:55:15 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:55:15 INFO - 'talos_options': ['--xperf_path', 08:55:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:55:15 INFO - 'tests': ['tp5n']}}, 08:55:15 INFO - 'talos.zip': {'path': '', 08:55:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:55:15 INFO - Running post-action listener: _resource_record_post_action 08:55:15 INFO - [mozharness: 2016-08-24 15:55:15.776572Z] Finished populate-webroot step (success) 08:55:15 INFO - [mozharness: 2016-08-24 15:55:15.776671Z] Running create-virtualenv step. 08:55:15 INFO - Running pre-action listener: _resource_record_pre_action 08:55:15 INFO - Running main action method: create_virtualenv 08:55:15 INFO - Creating virtualenv /builds/slave/test/build/venv 08:55:15 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 08:55:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:55:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:55:15 INFO - Using real prefix '/tools/python27' 08:55:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:55:16 INFO - Installing distribute.............................................................................................................................................................................................done. 08:55:19 INFO - Installing pip.................done. 08:55:19 INFO - Return code: 0 08:55:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:55:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:55:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:55:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:55:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:55:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100cb0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d45618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc1158a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c14d30>, '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 0x100979db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c0cb20>, '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.6xFsE9E6vU/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.GrxTNfsils/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/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 08:55:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:55:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:55:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6xFsE9E6vU/Render', 08:55:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:55:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:55:19 INFO - 'HOME': '/Users/cltbld', 08:55:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:55:19 INFO - 'LOGNAME': 'cltbld', 08:55:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:55:19 INFO - 'MOZ_NO_REMOTE': '1', 08:55:19 INFO - 'NO_EM_RESTART': '1', 08:55:19 INFO - 'PAGER': '/bin/cat', 08:55:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:55:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:55:19 INFO - 'PWD': '/builds/slave/test', 08:55:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:55:19 INFO - 'SHELL': '/bin/bash', 08:55:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GrxTNfsils/Listeners', 08:55:19 INFO - 'TMPDIR': '/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/', 08:55:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:55:19 INFO - 'USER': 'cltbld', 08:55:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:55:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:55:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:55:19 INFO - 'XPC_FLAGS': '0x0', 08:55:19 INFO - 'XPC_SERVICE_NAME': '0', 08:55:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:55:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:55:19 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 08:55:19 INFO - Cleaning up... 08:55:19 INFO - Return code: 0 08:55:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:55:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:55:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:55:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:55:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:55:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100cb0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d45618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc1158a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c14d30>, '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 0x100979db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c0cb20>, '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.6xFsE9E6vU/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.GrxTNfsils/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/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 08:55:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:55:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:55:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6xFsE9E6vU/Render', 08:55:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:55:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:55:19 INFO - 'HOME': '/Users/cltbld', 08:55:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:55:19 INFO - 'LOGNAME': 'cltbld', 08:55:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:55:19 INFO - 'MOZ_NO_REMOTE': '1', 08:55:19 INFO - 'NO_EM_RESTART': '1', 08:55:19 INFO - 'PAGER': '/bin/cat', 08:55:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:55:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:55:19 INFO - 'PWD': '/builds/slave/test', 08:55:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:55:19 INFO - 'SHELL': '/bin/bash', 08:55:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GrxTNfsils/Listeners', 08:55:19 INFO - 'TMPDIR': '/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/', 08:55:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:55:19 INFO - 'USER': 'cltbld', 08:55:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:55:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:55:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:55:19 INFO - 'XPC_FLAGS': '0x0', 08:55:19 INFO - 'XPC_SERVICE_NAME': '0', 08:55:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:55:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:55:20 INFO - Downloading/unpacking psutil>=3.1.1 08:55:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:55:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:55:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:55:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:55:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:55:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:55:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:55:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:55:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:55:23 INFO - Installing collected packages: psutil 08:55:23 INFO - Running setup.py install for psutil 08:55:23 INFO - building 'psutil._psutil_osx' extension 08:55:23 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 08:55:23 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 08:55:23 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 08:55:23 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 08:55:24 INFO - building 'psutil._psutil_posix' extension 08:55:24 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 08:55:24 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:55:24 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:55:24 INFO - ^ 08:55:24 INFO - 1 warning generated. 08:55:24 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 08:55:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:55:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:55:24 INFO - Successfully installed psutil 08:55:24 INFO - Cleaning up... 08:55:24 INFO - Return code: 0 08:55:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:55: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')]} 08:55:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:55:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55: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')]} 08:55:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:55:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:24 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 0x100cb0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d45618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc1158a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c14d30>, '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 0x100979db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c0cb20>, '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.6xFsE9E6vU/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.GrxTNfsils/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/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 08:55:24 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 08:55:24 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 08:55:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6xFsE9E6vU/Render', 08:55:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:55:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:55:24 INFO - 'HOME': '/Users/cltbld', 08:55:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:55:24 INFO - 'LOGNAME': 'cltbld', 08:55:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:55:24 INFO - 'MOZ_NO_REMOTE': '1', 08:55:24 INFO - 'NO_EM_RESTART': '1', 08:55:24 INFO - 'PAGER': '/bin/cat', 08:55:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:55:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:55:24 INFO - 'PWD': '/builds/slave/test', 08:55:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:55:24 INFO - 'SHELL': '/bin/bash', 08:55:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GrxTNfsils/Listeners', 08:55:24 INFO - 'TMPDIR': '/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/', 08:55:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:55:24 INFO - 'USER': 'cltbld', 08:55:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:55:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:55:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:55:24 INFO - 'XPC_FLAGS': '0x0', 08:55:24 INFO - 'XPC_SERVICE_NAME': '0', 08:55:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:55:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:55:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:55:24 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 08:55:24 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 08:55:24 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 08:55:24 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 08:55:24 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 08:55:24 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 08:55:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:55:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:55:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:55:27 INFO - Installing collected packages: mozsystemmonitor 08:55:27 INFO - Running setup.py install for mozsystemmonitor 08:55:27 INFO - Successfully installed mozsystemmonitor 08:55:27 INFO - Cleaning up... 08:55:27 INFO - Return code: 0 08:55:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:55:27 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')]} 08:55:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:55:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:27 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')]} 08:55:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:55:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:27 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 0x100cb0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d45618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc1158a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c14d30>, '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 0x100979db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c0cb20>, '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.6xFsE9E6vU/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.GrxTNfsils/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/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 08:55:27 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 08:55:27 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 08:55:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6xFsE9E6vU/Render', 08:55:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:55:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:55:27 INFO - 'HOME': '/Users/cltbld', 08:55:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:55:27 INFO - 'LOGNAME': 'cltbld', 08:55:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:55:27 INFO - 'MOZ_NO_REMOTE': '1', 08:55:27 INFO - 'NO_EM_RESTART': '1', 08:55:27 INFO - 'PAGER': '/bin/cat', 08:55:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:55:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:55:27 INFO - 'PWD': '/builds/slave/test', 08:55:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:55:27 INFO - 'SHELL': '/bin/bash', 08:55:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GrxTNfsils/Listeners', 08:55:27 INFO - 'TMPDIR': '/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/', 08:55:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:55:27 INFO - 'USER': 'cltbld', 08:55:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:55:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:55:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:55:27 INFO - 'XPC_FLAGS': '0x0', 08:55:27 INFO - 'XPC_SERVICE_NAME': '0', 08:55:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:55:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:55:27 INFO - Downloading/unpacking jsonschema==2.5.1 08:55:27 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 08:55:27 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 08:55:27 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 08:55:27 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 08:55:27 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 08:55:27 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 08:55:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:55:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:55:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:55:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:55:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:55:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:55:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:55:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:55:30 INFO - Downloading functools32-3.2.3-2.tar.gz 08:55:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:55:30 INFO - warning: no files found matching '*.txt' 08:55:30 INFO - no previously-included directories found matching 'build' 08:55:30 INFO - no previously-included directories found matching 'dist' 08:55:30 INFO - no previously-included directories found matching '.git*' 08:55:30 INFO - Installing collected packages: jsonschema, functools32 08:55:30 INFO - Running setup.py install for functools32 08:55:30 INFO - warning: no files found matching '*.txt' 08:55:30 INFO - no previously-included directories found matching 'build' 08:55:30 INFO - no previously-included directories found matching 'dist' 08:55:30 INFO - no previously-included directories found matching '.git*' 08:55:30 INFO - Successfully installed jsonschema functools32 08:55:30 INFO - Cleaning up... 08:55:30 INFO - Return code: 0 08:55:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:55:30 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')]} 08:55:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:55:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:30 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')]} 08:55:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:55:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:30 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 0x100cb0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d45618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc1158a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c14d30>, '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 0x100979db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c0cb20>, '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.6xFsE9E6vU/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.GrxTNfsils/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/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 08:55:30 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 08:55:30 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 08:55:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6xFsE9E6vU/Render', 08:55:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:55:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:55:30 INFO - 'HOME': '/Users/cltbld', 08:55:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:55:30 INFO - 'LOGNAME': 'cltbld', 08:55:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:55:30 INFO - 'MOZ_NO_REMOTE': '1', 08:55:30 INFO - 'NO_EM_RESTART': '1', 08:55:30 INFO - 'PAGER': '/bin/cat', 08:55:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:55:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:55:30 INFO - 'PWD': '/builds/slave/test', 08:55:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:55:30 INFO - 'SHELL': '/bin/bash', 08:55:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GrxTNfsils/Listeners', 08:55:30 INFO - 'TMPDIR': '/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/', 08:55:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:55:30 INFO - 'USER': 'cltbld', 08:55:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:55:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:55:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:55:30 INFO - 'XPC_FLAGS': '0x0', 08:55:30 INFO - 'XPC_SERVICE_NAME': '0', 08:55:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:55:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:55:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:55:31 INFO - Cleaning up... 08:55:31 INFO - Return code: 0 08:55:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:55:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:55:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:55:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:55:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:55:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:31 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 0x100cb0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d45618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc1158a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c14d30>, '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 0x100979db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c0cb20>, '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.6xFsE9E6vU/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.GrxTNfsils/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/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 08:55:31 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 08:55:31 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 08:55:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6xFsE9E6vU/Render', 08:55:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:55:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:55:31 INFO - 'HOME': '/Users/cltbld', 08:55:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:55:31 INFO - 'LOGNAME': 'cltbld', 08:55:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:55:31 INFO - 'MOZ_NO_REMOTE': '1', 08:55:31 INFO - 'NO_EM_RESTART': '1', 08:55:31 INFO - 'PAGER': '/bin/cat', 08:55:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:55:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:55:31 INFO - 'PWD': '/builds/slave/test', 08:55:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:55:31 INFO - 'SHELL': '/bin/bash', 08:55:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GrxTNfsils/Listeners', 08:55:31 INFO - 'TMPDIR': '/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/', 08:55:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:55:31 INFO - 'USER': 'cltbld', 08:55:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:55:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:55:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:55:31 INFO - 'XPC_FLAGS': '0x0', 08:55:31 INFO - 'XPC_SERVICE_NAME': '0', 08:55:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:55:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:55:31 INFO - Downloading/unpacking blobuploader==1.2.4 08:55:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:55:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:55:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:55:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:55:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:55:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:55:33 INFO - Downloading blobuploader-1.2.4.tar.gz 08:55:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:55:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:55: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 08:55: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 08:55: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 08:55: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 08:55: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 08:55: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 08:55:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:55:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:55:34 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 08:55:34 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 08:55:34 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 08:55:34 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 08:55:34 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 08:55:34 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 08:55:34 INFO - Downloading docopt-0.6.1.tar.gz 08:55:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:55:34 INFO - Installing collected packages: blobuploader, requests, docopt 08:55:34 INFO - Running setup.py install for blobuploader 08:55:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:55:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:55:35 INFO - Running setup.py install for requests 08:55:35 INFO - Running setup.py install for docopt 08:55:35 INFO - Successfully installed blobuploader requests docopt 08:55:35 INFO - Cleaning up... 08:55:35 INFO - Return code: 0 08:55:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:55:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:55:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:55:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:55:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:55:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:35 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 0x100cb0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d45618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc1158a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c14d30>, '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 0x100979db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c0cb20>, '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.6xFsE9E6vU/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.GrxTNfsils/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/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 08:55:36 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 08:55:36 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 08:55:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6xFsE9E6vU/Render', 08:55:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:55:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:55:36 INFO - 'HOME': '/Users/cltbld', 08:55:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:55:36 INFO - 'LOGNAME': 'cltbld', 08:55:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:55:36 INFO - 'MOZ_NO_REMOTE': '1', 08:55:36 INFO - 'NO_EM_RESTART': '1', 08:55:36 INFO - 'PAGER': '/bin/cat', 08:55:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:55:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:55:36 INFO - 'PWD': '/builds/slave/test', 08:55:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:55:36 INFO - 'SHELL': '/bin/bash', 08:55:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GrxTNfsils/Listeners', 08:55:36 INFO - 'TMPDIR': '/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/', 08:55:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:55:36 INFO - 'USER': 'cltbld', 08:55:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:55:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:55:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:55:36 INFO - 'XPC_FLAGS': '0x0', 08:55:36 INFO - 'XPC_SERVICE_NAME': '0', 08:55:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:55:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:55:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:55:36 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-Xpzt46-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:55:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:55:36 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-HN5zwz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:55:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:55:36 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-hjO3eM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:55:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:55:36 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-L_VI6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:55:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:55:36 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-mZFcdf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:55:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:55:36 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-n4UuKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:55:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:55:36 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-0pQQV6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:55:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:55:36 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-E0p500-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:55:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:55:37 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-4OuXDF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:55:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:55:37 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-Rp_OG9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:55:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:55:37 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-eCAKYG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:55:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:55:37 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-bdInhJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:55:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:55:37 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-pjp8IZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:55:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:55:37 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-FdFPud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:55:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:55:37 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-4vxdhN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:55:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:55:37 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-NZUvMO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:55:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:55:37 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-19IP91-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:55:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:55:38 INFO - Running setup.py install for manifestparser 08:55:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:55:38 INFO - Running setup.py install for mozcrash 08:55:38 INFO - Running setup.py install for mozdebug 08:55:38 INFO - Running setup.py install for mozdevice 08:55:38 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:55:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:55:38 INFO - Running setup.py install for mozfile 08:55:38 INFO - Running setup.py install for mozhttpd 08:55:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:55:38 INFO - Running setup.py install for mozinfo 08:55:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:55:39 INFO - Running setup.py install for mozInstall 08:55:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:55:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:55:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:55:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:55:39 INFO - Running setup.py install for mozleak 08:55:39 INFO - Running setup.py install for mozlog 08:55:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:55:39 INFO - Running setup.py install for moznetwork 08:55:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:55:39 INFO - Running setup.py install for mozprocess 08:55:39 INFO - Running setup.py install for mozprofile 08:55:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:55:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:55:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:55:39 INFO - Running setup.py install for mozrunner 08:55:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:55:40 INFO - Running setup.py install for mozscreenshot 08:55:40 INFO - Running setup.py install for moztest 08:55:40 INFO - Running setup.py install for mozversion 08:55:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:55:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:55:40 INFO - Cleaning up... 08:55:40 INFO - Return code: 0 08:55:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:55: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')]} 08:55:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:55:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55: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')]} 08:55:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:55:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:40 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 0x100cb0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d45618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc1158a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c14d30>, '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 0x100979db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c0cb20>, '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.6xFsE9E6vU/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.GrxTNfsils/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/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 08:55:40 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 08:55:40 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 08:55:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6xFsE9E6vU/Render', 08:55:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:55:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:55:40 INFO - 'HOME': '/Users/cltbld', 08:55:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:55:40 INFO - 'LOGNAME': 'cltbld', 08:55:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:55:40 INFO - 'MOZ_NO_REMOTE': '1', 08:55:40 INFO - 'NO_EM_RESTART': '1', 08:55:40 INFO - 'PAGER': '/bin/cat', 08:55:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:55:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:55:40 INFO - 'PWD': '/builds/slave/test', 08:55:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:55:40 INFO - 'SHELL': '/bin/bash', 08:55:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GrxTNfsils/Listeners', 08:55:40 INFO - 'TMPDIR': '/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/', 08:55:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:55:40 INFO - 'USER': 'cltbld', 08:55:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:55:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:55:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:55:40 INFO - 'XPC_FLAGS': '0x0', 08:55:40 INFO - 'XPC_SERVICE_NAME': '0', 08:55:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:55:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:55:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:55:41 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-kcJy_4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:55:41 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)) 08:55:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:55:41 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-smtVVd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:55:41 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)) 08:55:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:55:41 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-qfjXu7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:55:41 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)) 08:55:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:55:41 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-i9Nrke-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:55:41 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)) 08:55:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:55:41 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-Cb0Bku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:55:41 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)) 08:55:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:55:41 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-tIuQgS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:55:41 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)) 08:55:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:55:41 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-nUcJSZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:55:41 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)) 08:55:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:55:41 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-xFtce3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:55:42 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)) 08:55:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:55:42 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-fWwJVj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:55:42 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)) 08:55:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:55:42 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-15QWyk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:55:42 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)) 08:55:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:55:42 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-_5kzz_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:55:42 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)) 08:55:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:55:42 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-3Sy6YU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:55:42 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)) 08:55:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:55:42 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-3t51BT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:55:42 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)) 08:55:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:55:42 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-RSHgmQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:55:42 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)) 08:55:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:55:42 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-QIGfWi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:55:42 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)) 08:55:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:55:42 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-HJstiO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:55:42 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)) 08:55:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:55:42 INFO - Running setup.py (path:/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/pip-CP9Ppm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:55:42 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)) 08:55:42 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)) 08:55:42 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)) 08:55:43 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)) 08:55:43 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)) 08:55:43 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)) 08:55:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:55: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 08:55: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 08:55: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 08:55: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 08:55: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 08:55: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 08:55:45 INFO - Downloading blessings-1.6.tar.gz 08:55:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:55:45 INFO - Installing collected packages: blessings 08:55:45 INFO - Running setup.py install for blessings 08:55:45 INFO - Successfully installed blessings 08:55:45 INFO - Cleaning up... 08:55:46 INFO - Return code: 0 08:55:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:55:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:55:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:55:46 INFO - Reading from file tmpfile_stdout 08:55:46 INFO - Current package versions: 08:55:46 INFO - blessings == 1.6 08:55:46 INFO - blobuploader == 1.2.4 08:55:46 INFO - docopt == 0.6.1 08:55:46 INFO - functools32 == 3.2.3-2 08:55:46 INFO - jsonschema == 2.5.1 08:55:46 INFO - manifestparser == 1.1 08:55:46 INFO - mozInstall == 1.12 08:55:46 INFO - mozcrash == 0.17 08:55:46 INFO - mozdebug == 0.1 08:55:46 INFO - mozdevice == 0.48 08:55:46 INFO - mozfile == 1.2 08:55:46 INFO - mozhttpd == 0.7 08:55:46 INFO - mozinfo == 0.9 08:55:46 INFO - mozleak == 0.1 08:55:46 INFO - mozlog == 3.3 08:55:46 INFO - moznetwork == 0.27 08:55:46 INFO - mozprocess == 0.23 08:55:46 INFO - mozprofile == 0.28 08:55:46 INFO - mozrunner == 6.12 08:55:46 INFO - mozscreenshot == 0.1 08:55:46 INFO - mozsystemmonitor == 0.3 08:55:46 INFO - moztest == 0.7 08:55:46 INFO - mozversion == 1.4 08:55:46 INFO - psutil == 3.1.1 08:55:46 INFO - requests == 1.2.3 08:55:46 INFO - wsgiref == 0.1.2 08:55:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:55:46 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')]} 08:55:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:55:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:46 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')]} 08:55:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:55:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:46 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 0x100cb0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d45618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc1158a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c14d30>, '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 0x100979db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c0cb20>, '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.6xFsE9E6vU/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.GrxTNfsils/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/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 08:55:46 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 08:55:46 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 08:55:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6xFsE9E6vU/Render', 08:55:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:55:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:55:46 INFO - 'HOME': '/Users/cltbld', 08:55:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:55:46 INFO - 'LOGNAME': 'cltbld', 08:55:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:55:46 INFO - 'MOZ_NO_REMOTE': '1', 08:55:46 INFO - 'NO_EM_RESTART': '1', 08:55:46 INFO - 'PAGER': '/bin/cat', 08:55:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:55:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:55:46 INFO - 'PWD': '/builds/slave/test', 08:55:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:55:46 INFO - 'SHELL': '/bin/bash', 08:55:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GrxTNfsils/Listeners', 08:55:46 INFO - 'TMPDIR': '/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/', 08:55:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:55:46 INFO - 'USER': 'cltbld', 08:55:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:55:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:55:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:55:46 INFO - 'XPC_FLAGS': '0x0', 08:55:46 INFO - 'XPC_SERVICE_NAME': '0', 08:55:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:55:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:55:46 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)) 08:55:46 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)) 08:55:46 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)) 08:55:46 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)) 08:55:46 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)) 08:55:46 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)) 08:55:46 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)) 08:55:46 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)) 08:55:46 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)) 08:55:46 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)) 08:55:46 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)) 08:55:46 INFO - Cleaning up... 08:55:46 INFO - Return code: 0 08:55:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:55:46 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')]} 08:55:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:55:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:46 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')]} 08:55:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:55:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:55:46 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 0x100cb0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d45618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcc1158a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c14d30>, '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 0x100979db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c0cb20>, '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.6xFsE9E6vU/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.GrxTNfsils/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/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 08:55:46 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 08:55:46 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 08:55:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6xFsE9E6vU/Render', 08:55:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:55:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:55:46 INFO - 'HOME': '/Users/cltbld', 08:55:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:55:46 INFO - 'LOGNAME': 'cltbld', 08:55:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:55:46 INFO - 'MOZ_NO_REMOTE': '1', 08:55:46 INFO - 'NO_EM_RESTART': '1', 08:55:46 INFO - 'PAGER': '/bin/cat', 08:55:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:55:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:55:46 INFO - 'PWD': '/builds/slave/test', 08:55:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:55:46 INFO - 'SHELL': '/bin/bash', 08:55:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GrxTNfsils/Listeners', 08:55:46 INFO - 'TMPDIR': '/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/', 08:55:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:55:46 INFO - 'USER': 'cltbld', 08:55:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:55:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:55:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:55:46 INFO - 'XPC_FLAGS': '0x0', 08:55:46 INFO - 'XPC_SERVICE_NAME': '0', 08:55:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:55:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:55:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:55:47 INFO - Cleaning up... 08:55:47 INFO - Return code: 0 08:55:47 INFO - Running post-action listener: _resource_record_post_action 08:55:47 INFO - Running post-action listener: _start_resource_monitoring 08:55:47 INFO - Starting resource monitoring. 08:55:47 INFO - [mozharness: 2016-08-24 15:55:47.246840Z] Finished create-virtualenv step (success) 08:55:47 INFO - [mozharness: 2016-08-24 15:55:47.247639Z] Running install step. 08:55:47 INFO - Running pre-action listener: _resource_record_pre_action 08:55:47 INFO - Running main action method: install 08:55:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:55:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:55:47 INFO - Reading from file tmpfile_stdout 08:55:47 INFO - Detecting whether we're running mozinstall >=1.0... 08:55:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:55:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:55:47 INFO - Reading from file tmpfile_stdout 08:55:47 INFO - Output received: 08:55:47 INFO - Usage: mozinstall [options] installer 08:55:47 INFO - Options: 08:55:47 INFO - -h, --help show this help message and exit 08:55:47 INFO - -d DEST, --destination=DEST 08:55:47 INFO - Directory to install application into. [default: 08:55:47 INFO - "/builds/slave/test"] 08:55:47 INFO - --app=APP Application being installed. [default: firefox] 08:55:47 INFO - mkdir: /builds/slave/test/build/application 08:55:47 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'] 08:55:47 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 08:56:22 INFO - Reading from file tmpfile_stdout 08:56:22 INFO - Output received: 08:56:22 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:56:22 INFO - Running post-action listener: _resource_record_post_action 08:56:22 INFO - [mozharness: 2016-08-24 15:56:22.215019Z] Finished install step (success) 08:56:22 INFO - [mozharness: 2016-08-24 15:56:22.215152Z] Running run-tests step. 08:56:22 INFO - Running pre-action listener: _resource_record_pre_action 08:56:22 INFO - Running main action method: run_tests 08:56:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:56:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:56:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:56:22 INFO - 2016-08-24 08:56:22.229 screenresolution[1630:7555] starting screenresolution argv=screenresolution get 08:56:22 INFO - 2016-08-24 08:56:22.232 screenresolution[1630:7555] Display 0: 1600x1200x32@60 08:56:22 INFO - 2016-08-24 08:56:22.238 screenresolution[1631:7557] starting screenresolution argv=screenresolution list 08:56:22 INFO - Available Modes on Display 0 08:56:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:56:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:56:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:56:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:56:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:56:22 INFO - Intel Iris: 08:56:22 INFO - Chipset Model: Intel Iris 08:56:22 INFO - Type: GPU 08:56:22 INFO - Bus: Built-In 08:56:22 INFO - VRAM (Dynamic, Max): 1536 MB 08:56:22 INFO - Vendor: Intel (0x8086) 08:56:22 INFO - Device ID: 0x0a2e 08:56:22 INFO - Revision ID: 0x0009 08:56:22 INFO - Displays: 08:56:22 INFO - Display: 08:56:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:56:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:56:22 INFO - Display Serial Number: 1600x1200 60 08:56:22 INFO - Main Display: Yes 08:56:22 INFO - Mirror: Off 08:56:22 INFO - Online: Yes 08:56:22 INFO - Rotation: Supported 08:56:22 INFO - Return code: 0 08:56:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:56:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:56:22 INFO - Python 2.7.3 08:56:22 INFO - Return code: 0 08:56:22 INFO - grabbing minidump binary from tooltool 08:56:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:56:22 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 0x100c14d30>, '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 0x100979db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c0cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:56:22 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 08:56:22 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 08:56:22 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 08:56:22 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:56:22 INFO - Return code: 0 08:56:22 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:56:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:56:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:56:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:56:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:56:22 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/BSqo7oQ1TKS-3f8S-qyyTQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0139', '--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 08:56:22 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/BSqo7oQ1TKS-3f8S-qyyTQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0139 --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 08:56:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6xFsE9E6vU/Render', 08:56:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:56:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:56:22 INFO - 'HOME': '/Users/cltbld', 08:56:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:56:22 INFO - 'LOGNAME': 'cltbld', 08:56:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:56:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:56:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:56:22 INFO - 'MOZ_NO_REMOTE': '1', 08:56:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:56:22 INFO - 'NO_EM_RESTART': '1', 08:56:22 INFO - 'PAGER': '/bin/cat', 08:56:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:56:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:56:22 INFO - 'PWD': '/builds/slave/test', 08:56:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:56:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:56:22 INFO - 'SHELL': '/bin/bash', 08:56:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GrxTNfsils/Listeners', 08:56:22 INFO - 'TMPDIR': '/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/', 08:56:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:56:22 INFO - 'USER': 'cltbld', 08:56:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:56:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:56:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:56:22 INFO - 'XPC_FLAGS': '0x0', 08:56:22 INFO - 'XPC_SERVICE_NAME': '0', 08:56:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:56:22 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/BSqo7oQ1TKS-3f8S-qyyTQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0139', '--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 08:56:22 INFO - mozversion application_buildid: 20160824060514 08:56:22 INFO - mozversion application_changeset: 01748a2b1a463f24efd9cd8abad9ccfd76b037b8 08:56:22 INFO - mozversion application_display_name: Nightly 08:56:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:56:22 INFO - mozversion application_name: Firefox 08:56:22 INFO - mozversion application_remotingname: firefox 08:56:22 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:56:22 INFO - mozversion application_vendor: Mozilla 08:56:22 INFO - mozversion application_version: 51.0a1 08:56:22 INFO - mozversion platform_buildid: 20160824060514 08:56:22 INFO - mozversion platform_changeset: 01748a2b1a463f24efd9cd8abad9ccfd76b037b8 08:56:22 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:56:22 INFO - mozversion platform_version: 51.0a1 08:56:22 INFO - using testdate: 1472054182 08:56:22 INFO - actual date: 1472054182 08:56:22 INFO - starting webserver on 'localhost:49275' 08:56:22 INFO - SUITE-START | Running 2 tests 08:56:22 INFO - TEST-START | dromaeo_css 08:56:22 INFO - operating with platform_type : mac_ 08:56:22 INFO - Initialising browser for dromaeo_css test... 08:56:22 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/tmp944Lfy/profile http://localhost:49275/getInfo.html) 08:56:25 INFO - PROCESS | 1641 | __metrics Screen width/height:1600/1200 08:56:25 INFO - PROCESS | 1641 | colorDepth:24 08:56:25 INFO - PROCESS | 1641 | Browser inner width/height: 1024/689 08:56:25 INFO - PROCESS | 1641 | __metrics 08:56:25 INFO - PROCESS | 1641 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:56:25 INFO - PROCESS | 1641 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:56:26 INFO - TEST-INFO | 1641: exit 0 08:56:26 INFO - Browser initialized. 08:56:26 INFO - Running cycle 1/1 for dromaeo_css test... 08:56:26 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/tmp944Lfy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:56:38 INFO - PROCESS | 1644 | RSS: Main: 233963520 08:56:38 INFO - PROCESS | 1644 | 08:57:29 INFO - PROCESS | 1644 | 1472054249467 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] 08:59:41 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 08:59:42 INFO - PROCESS | 1644 | RSS: Main: 399208448 08:59:42 INFO - PROCESS | 1644 | 09:02:46 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 09:02:46 INFO - PROCESS | 1644 | RSS: Main: 371331072 09:02:46 INFO - PROCESS | 1644 | 09:05:49 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 09:05:49 INFO - PROCESS | 1644 | RSS: Main: 360706048 09:05:49 INFO - PROCESS | 1644 | 09:08:52 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 09:08:52 INFO - PROCESS | 1644 | RSS: Main: 322568192 09:08:52 INFO - PROCESS | 1644 | 09:11:56 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 09:11:56 INFO - PROCESS | 1644 | RSS: Main: 326516736 09:11:56 INFO - PROCESS | 1644 | 09:14:59 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 09:15:00 INFO - PROCESS | 1644 | RSS: Main: 501604352 09:15:00 INFO - PROCESS | 1644 | 09:15:00 INFO - PROCESS | 1644 | __start_tp_report 09:15:00 INFO - PROCESS | 1644 | _x_x_mozilla_page_load 09:15:00 INFO - PROCESS | 1644 | _x_x_mozilla_page_load_details 09:15:00 INFO - PROCESS | 1644 | |i|pagename|runs| 09:15:00 INFO - PROCESS | 1644 | |0;dojo.html;2902.521084021136;3365.9663403365976;3392.5759280089974;3425.783846734411;3464.6881780639737;5649.209110724501;5716.542676585874;5781.82654520365;5811.622244554101;5815.854603634912;5740.655560666352;5779.190913272145;5834.883302333951;5843.35723070463;5887.970560147204;5202.3497062867145;5261.368635763694;5288.576913846883;5292.073887069767;5411.729413529328;5386.8922621547545;5394.109971854639;5401.243825864362;5412.91880621791;5419.945800542003;5179.533841954205;5238.423773384925;5255.02781985334;5292.364916210041;5339.305890234284;5297.993381257549;5298.311219541435;5304.655197412156;5306.628536002481;5309.681419114865;5199.350081239845;5250.081235783722;5259.605529585258;5267.26258323835;5275.129603615399;5601.887962240773;5760.0783874579865;5768.077107662796;5781.306243250837;5782.681952492611;6188.535859810488;6223.315435302158;6265.342139075377;6332.1135041094285;6336.936630633727;6197.81406557804;6305.873882522324;6328.841778955563;6350.7777227796805;6365.586236894587;5691.288588926384;5889.705514724246;5896.38088000758;5898.469137777621;5902.734376953047;6237.532185086136;6283.497320214372;6305.5901366411035;6334.398232167937;6344.302126766052;6226.159468471789;6228.5017198624;6244.625322480666;6257.9687101564205;6272.529560282997;6153.323134455206;6330.905036424458;6349.587276826992;6355.523335749837;6371.171747672773;6179.969100154561;6257.374262573797;6268.717907694165;6308.7476500940365;6333.619982801047;6288.465480434214;6323.20959880015;6323.683815809283;6347.650879201688;6348.809535713936;4915.0415668944215;4959.752012399365;5017.272495488134;5036.748162591855;5080.796768129308;4456.844010459618;4474.843380481668;4499.482559505634;4508.661850361236;4557.293619488984;4774.594159496482;5714.200011998323;5724.570657200727;5739.454751798572;5760.798372056958;6164.876702466016;6347.809565713036;6349.873002539924;6356.237251529754;6357.841053973688;5601.719914004283;5625.803096891589;5651.915221271602;5660.179274005246;5696.11710184922;5151.9484805152215;5520.337559492887;5520.641158324696;5551.611387202857;5574.968630803314;6318.210223722035;6342.651154186564;6366.64983425916;6370.203724534434;6371.108044873814;6137.570370074052;6221.4089661482085;6337.9366206337345;6357.745690172342;6386.425221730066;6268.592541484789;6299.4645455135;6303.46420554258;6338;6361.618302901656;6271.937280627135;6285.402886725754;6295.5907865988565;6298.874022519432;6299.11812346281;5485.61600687948;6271.874562508816;6276.6547839869245;6293.559450838397;6360.109584658059;5713.914291285717;6094.954753935788;6117.9492029047315;6164.907526387197;6166.599171053867;6190.783322583688;6206.72069756853;6207.906881396692;6210.31686514474;6220.657863817352;6017.849553761016;6050.183225264533;6060.2424696912885;6074.787382441594;6078.635281883101;6065.787697430569;6145.907811382922;6183.103449999815;6183.134361189527;6248.156498872776;6094.108664719825;6111.865232675556;6115.270764408881;6130.551011451029;6134.4172303631085 09:15:00 INFO - PROCESS | 1644 | |1;ext.html;496.14415133894016;514.3570536828964;523.9240310155028;524.6248932013609;538.8329617818476;30823;31457.685423145762;32499.675003249962;40205.798971005104;41596;43218;43378.915527111756;43526.34710479337;43536;43699.90750231246;5459.826137380463;7707.96146019272;8986.326025548111;9082.54587270637;9085.727428177166;8024.43828931975;8120.553369564672;8845.115488451167;9034.457932524037;9039.27685785139;9061.818763624757;9165.908340916572;12003.819942700866;12270.81593776094;12304.81542776859;12279.938600306987;12357.25856448607;12358.196717213352;12369.381530923463;12416.56542021034;12210.26738395699;12294.692632684255;12295.877041229563;12296.200746951508;12302.876971230262;40802.59197408018;40958.79520602408;41032;41036.79481602603;41046.79476602598;3383.9323213535836;3421.349943510725;3536.6286539913194;3617.764845285048;3831.252905683393;31300.53049204264;31747.365052698817;32468.675313247037;32538.18654533632;32632.857849975375;3267.248532837437;3364.200915062652;3378.3918894598964;3406.7615266931325;3444.2422667013216;367483;371313;372356.13821930985;372844;373449.1327543372;58448;58787.118193227136;58834.70582647059;58910;58969.41030589639;61973;62036.68981655063;62119.37880621226;62126.378736212966;62180.689096555136;14758.335874885655;14980.925095374454;14997.775033374508;15002.774958375632;15021.02363346379;10823.621173258898;11544.076473882069;11587.88412115885;11594.188406811441;11600;14215.786763198561;14651.633709157357;16100.355985760672;16112.677746445006;16171.272292746855;5239.947600523946;6374.808755737336;6445.1943507061615;6481.04342109847;6521.347865213441;911.2891944283336;1013.5084484025242;1086.0668010940537;1099.444780385916;1113.6603335982604;29232;29495.55756663652;29656.555151672743;30517;30588;1071.8017166824163;1087.608460954056;1106.4080716816466;1111.4942701070993;1175.9176857619884;14488.927555362367;15260.771088433683;15436.459723909833;15614.642702748355;15664.1384723839;613138;617893;618764;618841;619569;50323;50846;57523.71238143783;58149.41850581524;58444.83110337769;1381.1851007905184;1393.2406838273166;1428.5357258890901;1431.1270125223398;1431.7279716853764;25177.87411062933;29825;30328.090157295315;32070.67929320677;32073.518897216094;49826;55377.89244215013;56420.71789641026;56572.717136415704;59706.70146649239;625494;630682;631688;784201.8957725109;818278;25473.23580292594;25739.613905791804;25806.483870322114;26058.60912086358;26107.216783496526;45939.77030114828;46103;46389.53610463987;46701.76649116733;46806;38118.809405952794;38508.61491385163;38681.41977870391;38879.028024299616;39146.804265978484;37366.813165934;37731.43402849014;38029.809850950565;38822.80588597152;39069.609303906596;37940.62059379371;38072.80963595164;38081;38554.6144538551;38561.614383855805;24242.151569686484;32269.773022697544;34032.31935361229;34221.486677700355;34344.48483272803;19679.40961771149;25114.748852511242;25331.366715831515;25739.485210295315;25785.96856125734 09:15:00 INFO - PROCESS | 1644 | |2;jquery.html;320.2889585121032;348.0428820742957;368.1973298209902;375.2682269574329;398.4939127308318;15537.533873983784;16048.679026419479;20951.790482095174;21089.15643374263;21727;20124.899375503137;20513.58972820543;21129.366119016417;21279.361619151452;21338.46653833655;5980.431858973401;6045.183900173486;6213.751449941997;6238.59449135807;6243.781467648633;4236.597523235288;4470.418845550074;5900.940990590104;6204.565680402374;6533.31400202979;6792.354726300993;6855.1088358513325;6858.417034552069;6862.421643022108;6863.828404289908;6503.024546318043;6705.798826035227;6721.697523611425;6750.1562304711915;6768.76309329176;5079.974600127012;5121.820736274212;5245.659032162897;5638.548916086703;5841.744025034638;13288.867111328957;13531.526396576171;13540.255285959269;13555.25446100464;22351.66472502914;2027.4728570571758;2127.585120901425;2130.1692339987417;2130.1905275995177;2193.923212687548;10561.63034293796;11822.349770762605;12929.03032272574;14654.340554674958;15938.043717376995;1573.920718826555;1858.7862395824518;1877.3804644467293;1912.1968773115325;1949.9707504387445;117855;118419.81580184175;123600.38199809034;130400;130780;9258.398204116846;10994.615188468366;11130.220884538005;11307.660770176908;11470.54117835294;14098.788518172236;14104.929475352557;14109.85890141106;14110.435582576787;14146.22195779242;7962.6416811243635;8246.793830154294;8251.669933202606;8376.078631350547;8380.706675266336;8022.438429309892;8025.719099831478;8026.558539280396;8202.195516986254;8228.053773816087;4387.100644367897;4449.243628583149;4454.599086082203;4459.531749166355;4534.569215924482;1542.8534289242505;1647.2998975435391;1717.2100833616425;1806.539332470238;1827.4060930197738;825.8389614025208;928.4707716601472;985.7929834734786;1035.9326643768131;1065.3288428290127;21216.25743099015;21790.564188716453;21896;21984.230551930927;21991.340259791912;910.5856835139996;948.9098535639104;980.6273616026007;1004.9196064314983;1008.5713571731984;10550.577976880992;11413.42861990838;11462.59880904181;11487.483063262172;11538.250013749079;578733;585676.0716196392;604056;625117.3069204915;631820;116905;135589;136166.3191684035;140067;141271;985.9605615775432;993.9602415903283;996.038822536256;1018.7453136715822;1018.8013337399284;18809.529761756065;19369.03154842224;19388.903055484632;19428.51428714237;19986.70152932373;51530.742346288025;54285.45714542804;54822.451775481735;55377.89244215174;55474.2262886846;82683;83007;84175.89889213334;85192.57403712942;85977;8099.109097999098;8145.226203510657;8185.345172386075;8321.292690121407;8354.958225208835;12192.75614487723;13051.28217948022;13093.41079651437;13552.93223533876;13620.250886201355;11257.662270131908;11412.201145919706;11446.02708769764;11505.827412588651;11655.941720291683;11184.049355804847;11216.383098929637;11259.549618015188;11567.76864462723;11645.59240426581;11216.775664486828;11496.367699776592;11623.825642615198;11765.941170294094;11860.110491712983;8051.275385215359;8136.796580085545;8170.918290817016;8236.435077335287;8254.54599996982;9536.475493847905;10891.400972946563;11296.322220666787;11680.357580333164;11711.941440292743 09:15:00 INFO - PROCESS | 1644 | |3;mootools.html;1498.8426215247398;1539.061172684662;1691.6532110917262;1820.517562845847;1858.9907050464747;8372.204640559145;8888.911110888888;8903.554822258893;8908.287337013047;8912.376133670638;7033.613151276678;7960.08459027212;8386.11945745697;8585.91414085859;8672.132786721324;2355.5995480768224;2381.9761802382013;2385.904563817454;2387.7492863249367;2391.210900402863;2048.4059622709397;2344.132670911765;2361.740208577055;2395.772401621843;2402.1352313167245;2371.905123795046;2378.369732021007;2389.032441861049;2405.747396523366;2408.8313818032743;2355.470019245656;2362.870042147681;2372.146027430124;2377.2868139558063;2385.3440303916386;2345.636426353918;2366.3610825077135;2368.7275963264274;2369;2369.8104151667826;8691.478511289342;8713.390062695615;8800.383973121883;8815.382923195379;8820.955895220482;5121.743912804345;5283.233931080011;5286.577073834084;5296.09966305729;5308.761105750251;3624.981875090634;3641.2353405784816;3701.68535674468;3702.5742039665515;3720.2931443025736;1102.0577406317643;1114.788190243851;1117.871444783844;1126.5155982927338;1141.4463984967285;11518.712032199179;11584.768304633944;11587.073034157247;11597.246178998423;11634.53461861533;4444.511103778582;4575.10785396851;4732.100900828831;4739.644526660514;4750.8337208197545;9810.31327807061;9817.803643927082;9822;9825.803483930424;9828.06633369843;5262.947370526245;5266.78932842682;5269.998700246941;5273.235380869753;5277.8416647500635;4272.145570885836;4440.511543730187;4697.412823397075;4809.543093406191;4821.9035619288115;3854.4603755474254;3873.922521549553;3948.842046318115;3971.205758848242;4006.6994975376965;1094;1276.8914642255327;1287.7488889666613;1298.8701129886936;1304.712963148106;919.5907821019582;929.8837645294338;930.2604429478581;936.8032713130183;980.9950950245203;5618.185363122325;5781.248437703071;5903.881922361615;5958.314793798682;6113.57204995646;433.44951911072764;435.1970614216765;444.4111552193357;452.35539356416973;457.75967617000674;3951.9604803952166;3964.5638979712207;4028.8589899353383;4037.656799172103;4110.527289361762;10054.245931555162;10538.315009524356;10542.209334299958;10545.261831671864;10547.789044219073;10488.213383996232;10502.579896804042;10509.159267258601;10521.158307335394;10523.315984461138;7026.648667566704;7865.882011769828;8010.319122874621;8059.073206581201;8184.386171037196;5213.6350455467755;5799.4780469757925;5828;5925.644461332334;5969.70151492415;8013.559254241072;9617.759556011155;9629.807403851744;9817.214622830013;9958.103770660386;9867.950660246654;10030.548625312025;10144.594216231268;10151.035651613085;10177.287589868638;6859.519833611599;6948.812560362587;7473.51422157558;7574.356179724785;7581.203973582695;8376.12050734664;8521.786955325919;8529.061803201766;8535.658573657232;8535.743927682179;7402.555846649218;7522.548647081193;7532.171461139161;7605.315521603082;7677.961610191914;6299.622022678654;7153.213146553979;7158.105236845395;7257.709691612276;7347.26527347261;6922.480813938874;6937.410320122846;7024.192217895007;7029.859402812018;7029.929700702927;4947.901041979212;4983.626228032985;5012.072766538202;5054.29239906406;5104.106781313181;4956.975215123901;4983.380401369497;5044.218146187347;5187.19598462239;5214.895702085862 09:15:00 INFO - PROCESS | 1644 | |4;prototype.html;292.75116151271413;301.5642396736715;302.22027169901656;314.92284390324374;315.67170143051226;4500.257457519509;5371.5165635092835;5424.728763561826;5425.782968681253;5469.398366179717;4682.438107427113;4712.952870471294;5374.865628359293;5381.408045115044;5453.509184173424;3671.9265614687756;3689.4650275710005;3702.2965636528975;3747.681447077002;4088.08018195905;3954.3277642800795;4077.9202606670515;4117.155983023473;4120.381942708603;4167.1040726243855;3689.8008147352084;3711.888643340704;4076.2255171517318;4083.9591604083876;4118.68202175304;3688.2070354873695;3745.4082015143454;4084.0402505411203;4143.81352839123;4143.875683729493;2965.4958657028355;3125.9062228133193;3563.7883119739413;3587.497750314958;3597.7661452005536;4737.005228901935;4773.923343345621;5046.84859454217;5252.185911183773;5317;4240.809163587646;4668.603168730662;4708.270218116198;4711.316859055452;4742.881427964294;4351.456067991501;4505.864824055251;4723.984343366158;4817.156997525419;5613.971930140323;3836.9808150959348;3844.904527032746;3848.461215429842;3852.499175107246;3859.7455826856103;5521.668699878021;5636.830895073154;5649.209110724501;5804.796832110856;5868.383819698955;3409.3181363727354;3797.8290976906105;3875.9806200968815;3876.6511014008875;3916;3947.6644485219076;3948.9732669506134;3955.4660120883887;3956.3472027115204;3976.8210430530694;3695.0947017980766;3900.8244628991765;4024.557298697141;4578.6794924355645;4615.976920115378;4782.95217047832;5184.5483264685945;5238.528532432099;5719.256496655408;5779.537636989031;1234.666640007193;1387.243952046117;1388.6389538720005;1413.8798202152727;1422.9754576704756;794.06697130871;810.9229623185788;863.6329559937127;874.5976850648773;881.4843316659698;4107.178564287095;4951.8266860659705;5164.638475306692;5241.213817927342;5414.3232095988005;5268.367795864521;5332.600054995891;5338.572914166857;5546.389897111315;5908.911366329508;4652.860414187579;4702.623790096784;4989.6777354001215;5052.646314758005;5375.596830237748;2638.941625532088;2692.905748298839;3071.5120752190796;3073.186819776343;3088.320569474712;5479.4520547944885;5559.9444005560235;5844.532437404998;6050.818475445743;6100.908486372708;5349.545288650508;5975.133605627159;5977.521798256129;6019.668918209541;6025.216721826135;5128.00003999224;5293.967676303084;5365.973170134124;5988.341282458839;6049.758009679652;5416.025115479251;5798.884022319446;5826.796062137805;5848.64908105515;5888.705564721832;4359.433273674402;4732.337472753886;4766.976165119152;5081.5426611605135;5783.971080144573;4985.601151907911;5784.037934015213;5889.499392551681;6063.211782468251;6079.3312735598165;3718.8512459501317;3872.7095467840586;3906.257811015898;3919.431682406092;3942.921141577095;4663.29789626777;5356.741390594409;5491.512889315393;5892.35184129754;5908.497777688945;5418.539424148991;5441.700706461183;5791.391903850035;5792.275965504312;5810.477057064715;5035.823746168867;5259.63182577216;5267.57859371257;5727.513161381329;5733.799317024051;4498.662600305056;5618.85952851182;5655.660660360391;5678.545716342765;5727.942720572908;5460.91808622863;5676.20533125371;5677.700531284836;5681.261436013292;5810.82567522975;5256.9737151314;5298.523132918056;5629.564461062403;5685.779060593106;5697.572682048946 09:15:00 INFO - PROCESS | 1644 | |5;yui.html;721.9711211551538;727.428968259916;776.3401109057302;815.2866242038217;822.6709316273492;15497;16455.09496977666;16461.34154633816;16472.50582482526;16499.42252021178;13071.607851764462;14088.591140885906;14778.778318325207;14863.554093377188;14887.627809304773;651.9445847103002;670.1823774994523;670.6210990790212;671.8320419895026;813.5769399912057;663.7975417497661;666.1473314157886;825.6738588257634;849.3163003781953;857.2327766648846;879.5778026547262;886.9955650221772;889.7063968890283;893.6559424621535;896.6144557840126;890.4879694175875;891.7859713668738;892.683097500391;893.477315770272;894.4856707393275;511.9334486516766;657.5627207906737;658.0031252652236;658.0852614865294;660.6894759463045;14175.78736318956;14742.631434214123;14766.261686915612;14767.040142390817;14774.482893098797;13621.25083120428;14889.851101488955;14900.478483253035;14948.850511494855;14975.251237438193;11844.881551184464;11992.760144797143;12021.098417618714;12123.393830308449;12403.069769767304;3820.789856557888;3840.7695538267512;3857.305684976703;3875.4380614810693;3941.5270167580074;119033;120293.98530073548;120488.39755801165;124213.37893310566;124330.37834810858;10804.729881753019;11178.44107794607;11260.549578016788;11596;11807.527698891949;22092;22378.664320035212;22461.88769056144;22588.322350329516;22608.547829043328;8250.298724608356;8297.502149871027;8300;8379.413441059067;8494.787630309293;4513.684042117086;4549.431321084864;4608.124456353282;4681;4794.568488836021;3380.7464440167087;3625.836837342326;3643.690286325639;3791.014336272589;3793.108619474421;685.7531288736051;685.8422562810581;687.3092541995362;704.6652839901109;708.1891234536427;574.6982834012177;582.3594046548762;634.6890023888261;652.3215855510311;665.4077870695086;8758.2993360531;9508.336041192764;9597.520123993912;9622.807543849083;9741.025897410344;566.2751677852356;676.736072931557;677.7797215904851;685.1914740606034;689.4071098855081;3818.961810381916;4097.651699605507;4718.457377401574;4737.573618374363;4745.359376484131;163310;165544;169413;170088.24338174687;170662;119136;144140.63085899383;146795;151735;151786.24106879396;77513.61243193748;78200.60899695464;127338;129479;130466;7771.650275737492;7841;8951.41815781972;9177.449353038839;9191.724248272563;67113.66443167948;67347.32652673607;67812;69808.95286570807;71429.92855107281;62359;63635.681821590595;68952.6552367235;104609.47695261474;104629.47685261525;9799.363041402286;11645;11835.171537992257;11978.58074967372;12390.3185324808;13120.606381808178;13362.866371336162;13865.722685546434;13879;13968.58094257174;12497;12519.090065180515;12890;12897.871021289668;13062.608121756362;12268.877311226774;12435.689107772016;12700.237985720887;12945.223286602833;12954.28751418681;12075.57735479254;12502.68743281389;12552.811707824572;12774.297413642338;12776.872231277568;10121.949390253001;10202.438865862448;10245.692629221136;10397.84403233937;10728.839067414152;8366.790830229293;8698.47809131454;9290.6283748652;9592.95203523978;10175.48945613762 09:15:00 INFO - PROCESS | 1644 | __end_tp_report 09:15:00 INFO - PROCESS | 1644 | __start_cc_report 09:15:00 INFO - PROCESS | 1644 | _x_x_mozilla_cycle_collect,1614 09:15:00 INFO - PROCESS | 1644 | __end_cc_report 09:15:00 INFO - PROCESS | 1644 | __startTimestamp1472055300093__endTimestamp 09:15:00 INFO - PROCESS | 1644 | 09:15:00 INFO - PROCESS | 1644 | ------- Summary: start ------- 09:15:00 INFO - PROCESS | 1644 | Number of tests: 6 09:15:00 INFO - PROCESS | 1644 | 09:15:00 INFO - PROCESS | 1644 | [#0] dojo.html Cycles:165 Average:5802.19 Median:6078.64 stddev:654.84 (10.8%) stddev-sans-first:616.08 09:15:00 INFO - PROCESS | 1644 | Values: 2902.5 3366.0 3392.6 3425.8 3464.7 5649.2 5716.5 5781.8 5811.6 5815.9 5740.7 5779.2 5834.9 5843.4 5888.0 5202.3 5261.4 5288.6 5292.1 5411.7 5386.9 5394.1 5401.2 5412.9 5419.9 5179.5 5238.4 5255.0 5292.4 5339.3 5298.0 5298.3 5304.7 5306.6 5309.7 5199.4 5250.1 5259.6 5267.3 5275.1 5601.9 5760.1 5768.1 5781.3 5782.7 6188.5 6223.3 6265.3 6332.1 6336.9 6197.8 6305.9 6328.8 6350.8 6365.6 5691.3 5889.7 5896.4 5898.5 5902.7 6237.5 6283.5 6305.6 6334.4 6344.3 6226.2 6228.5 6244.6 6258.0 6272.5 6153.3 6330.9 6349.6 6355.5 6371.2 6180.0 6257.4 6268.7 6308.7 6333.6 6288.5 6323.2 6323.7 6347.7 6348.8 4915.0 4959.8 5017.3 5036.7 5080.8 4456.8 4474.8 4499.5 4508.7 4557.3 4774.6 5714.2 5724.6 5739.5 5760.8 6164.9 6347.8 6349.9 6356.2 6357.8 5601.7 5625.8 5651.9 5660.2 5696.1 5151.9 5520.3 5520.6 5551.6 5575.0 6318.2 6342.7 6366.6 6370.2 6371.1 6137.6 6221.4 6337.9 6357.7 6386.4 6268.6 6299.5 6303.5 6338.0 6361.6 6271.9 6285.4 6295.6 6298.9 6299.1 5485.6 6271.9 6276.7 6293.6 6360.1 5713.9 6095.0 6117.9 6164.9 6166.6 6190.8 6206.7 6207.9 6210.3 6220.7 6017.8 6050.2 6060.2 6074.8 6078.6 6065.8 6145.9 6183.1 6183.1 6248.2 6094.1 6111.9 6115.3 6130.6 6134.4 09:15:00 INFO - PROCESS | 1644 | 09:15:00 INFO - PROCESS | 1644 | [#1] ext.html Cycles:180 Average:69459.17 Median:371834.57 stddev:156048.58 (42.0%) stddev-sans-first:156400.43 09:15:00 INFO - PROCESS | 1644 | Values: 496.1 514.4 523.9 524.6 538.8 30823.0 31457.7 32499.7 40205.8 41596.0 43218.0 43378.9 43526.3 43536.0 43699.9 5459.8 7708.0 8986.3 9082.5 9085.7 8024.4 8120.6 8845.1 9034.5 9039.3 9061.8 9165.9 12003.8 12270.8 12304.8 12279.9 12357.3 12358.2 12369.4 12416.6 12210.3 12294.7 12295.9 12296.2 12302.9 40802.6 40958.8 41032.0 41036.8 41046.8 3383.9 3421.3 3536.6 3617.8 3831.3 31300.5 31747.4 32468.7 32538.2 32632.9 3267.2 3364.2 3378.4 3406.8 3444.2 367483.0 371313.0 372356.1 372844.0 373449.1 58448.0 58787.1 58834.7 58910.0 58969.4 61973.0 62036.7 62119.4 62126.4 62180.7 14758.3 14980.9 14997.8 15002.8 15021.0 10823.6 11544.1 11587.9 11594.2 11600.0 14215.8 14651.6 16100.4 16112.7 16171.3 5239.9 6374.8 6445.2 6481.0 6521.3 911.3 1013.5 1086.1 1099.4 1113.7 29232.0 29495.6 29656.6 30517.0 30588.0 1071.8 1087.6 1106.4 1111.5 1175.9 14488.9 15260.8 15436.5 15614.6 15664.1 613138.0 617893.0 618764.0 618841.0 619569.0 50323.0 50846.0 57523.7 58149.4 58444.8 1381.2 1393.2 1428.5 1431.1 1431.7 25177.9 29825.0 30328.1 32070.7 32073.5 49826.0 55377.9 56420.7 56572.7 59706.7 625494.0 630682.0 631688.0 784201.9 818278.0 25473.2 25739.6 25806.5 26058.6 26107.2 45939.8 46103.0 46389.5 46701.8 46806.0 38118.8 38508.6 38681.4 38879.0 39146.8 37366.8 37731.4 38029.8 38822.8 39069.6 37940.6 38072.8 38081.0 38554.6 38561.6 24242.2 32269.8 34032.3 34221.5 34344.5 19679.4 25114.7 25331.4 25739.5 25786.0 09:15:00 INFO - PROCESS | 1644 | 09:15:00 INFO - PROCESS | 1644 | [#2] jquery.html Cycles:180 Average:35634.24 Median:11734.33 stddev:101399.24 (864.1%) stddev-sans-first:101649.02 09:15:00 INFO - PROCESS | 1644 | Values: 320.3 348.0 368.2 375.3 398.5 15537.5 16048.7 20951.8 21089.2 21727.0 20124.9 20513.6 21129.4 21279.4 21338.5 5980.4 6045.2 6213.8 6238.6 6243.8 4236.6 4470.4 5900.9 6204.6 6533.3 6792.4 6855.1 6858.4 6862.4 6863.8 6503.0 6705.8 6721.7 6750.2 6768.8 5080.0 5121.8 5245.7 5638.5 5841.7 13288.9 13531.5 13540.3 13555.3 22351.7 2027.5 2127.6 2130.2 2130.2 2193.9 10561.6 11822.3 12929.0 14654.3 15938.0 1573.9 1858.8 1877.4 1912.2 1950.0 117855.0 118419.8 123600.4 130400.0 130780.0 9258.4 10994.6 11130.2 11307.7 11470.5 14098.8 14104.9 14109.9 14110.4 14146.2 7962.6 8246.8 8251.7 8376.1 8380.7 8022.4 8025.7 8026.6 8202.2 8228.1 4387.1 4449.2 4454.6 4459.5 4534.6 1542.9 1647.3 1717.2 1806.5 1827.4 825.8 928.5 985.8 1035.9 1065.3 21216.3 21790.6 21896.0 21984.2 21991.3 910.6 948.9 980.6 1004.9 1008.6 10550.6 11413.4 11462.6 11487.5 11538.3 578733.0 585676.1 604056.0 625117.3 631820.0 116905.0 135589.0 136166.3 140067.0 141271.0 986.0 994.0 996.0 1018.7 1018.8 18809.5 19369.0 19388.9 19428.5 19986.7 51530.7 54285.5 54822.5 55377.9 55474.2 82683.0 83007.0 84175.9 85192.6 85977.0 8099.1 8145.2 8185.3 8321.3 8355.0 12192.8 13051.3 13093.4 13552.9 13620.3 11257.7 11412.2 11446.0 11505.8 11655.9 11184.0 11216.4 11259.5 11567.8 11645.6 11216.8 11496.4 11623.8 11765.9 11860.1 8051.3 8136.8 8170.9 8236.4 8254.5 9536.5 10891.4 11296.3 11680.4 11711.9 09:15:00 INFO - PROCESS | 1644 | 09:15:00 INFO - PROCESS | 1644 | [#3] mootools.html Cycles:180 Average:5642.22 Median:4970.18 stddev:3147.98 (63.3%) stddev-sans-first:3141.41 09:15:00 INFO - PROCESS | 1644 | Values: 1498.8 1539.1 1691.7 1820.5 1859.0 8372.2 8888.9 8903.6 8908.3 8912.4 7033.6 7960.1 8386.1 8585.9 8672.1 2355.6 2382.0 2385.9 2387.7 2391.2 2048.4 2344.1 2361.7 2395.8 2402.1 2371.9 2378.4 2389.0 2405.7 2408.8 2355.5 2362.9 2372.1 2377.3 2385.3 2345.6 2366.4 2368.7 2369.0 2369.8 8691.5 8713.4 8800.4 8815.4 8821.0 5121.7 5283.2 5286.6 5296.1 5308.8 3625.0 3641.2 3701.7 3702.6 3720.3 1102.1 1114.8 1117.9 1126.5 1141.4 11518.7 11584.8 11587.1 11597.2 11634.5 4444.5 4575.1 4732.1 4739.6 4750.8 9810.3 9817.8 9822.0 9825.8 9828.1 5262.9 5266.8 5270.0 5273.2 5277.8 4272.1 4440.5 4697.4 4809.5 4821.9 3854.5 3873.9 3948.8 3971.2 4006.7 1094.0 1276.9 1287.7 1298.9 1304.7 919.6 929.9 930.3 936.8 981.0 5618.2 5781.2 5903.9 5958.3 6113.6 433.4 435.2 444.4 452.4 457.8 3952.0 3964.6 4028.9 4037.7 4110.5 10054.2 10538.3 10542.2 10545.3 10547.8 10488.2 10502.6 10509.2 10521.2 10523.3 7026.6 7865.9 8010.3 8059.1 8184.4 5213.6 5799.5 5828.0 5925.6 5969.7 8013.6 9617.8 9629.8 9817.2 9958.1 9868.0 10030.5 10144.6 10151.0 10177.3 6859.5 6948.8 7473.5 7574.4 7581.2 8376.1 8521.8 8529.1 8535.7 8535.7 7402.6 7522.5 7532.2 7605.3 7678.0 6299.6 7153.2 7158.1 7257.7 7347.3 6922.5 6937.4 7024.2 7029.9 7029.9 4947.9 4983.6 5012.1 5054.3 5104.1 4957.0 4983.4 5044.2 5187.2 5214.9 09:15:00 INFO - PROCESS | 1644 | 09:15:00 INFO - PROCESS | 1644 | [#4] prototype.html Cycles:180 Average:4507.26 Median:5012.75 stddev:1393.94 (27.8%) stddev-sans-first:1361.49 09:15:00 INFO - PROCESS | 1644 | Values: 292.8 301.6 302.2 314.9 315.7 4500.3 5371.5 5424.7 5425.8 5469.4 4682.4 4713.0 5374.9 5381.4 5453.5 3671.9 3689.5 3702.3 3747.7 4088.1 3954.3 4077.9 4117.2 4120.4 4167.1 3689.8 3711.9 4076.2 4084.0 4118.7 3688.2 3745.4 4084.0 4143.8 4143.9 2965.5 3125.9 3563.8 3587.5 3597.8 4737.0 4773.9 5046.8 5252.2 5317.0 4240.8 4668.6 4708.3 4711.3 4742.9 4351.5 4505.9 4724.0 4817.2 5614.0 3837.0 3844.9 3848.5 3852.5 3859.7 5521.7 5636.8 5649.2 5804.8 5868.4 3409.3 3797.8 3876.0 3876.7 3916.0 3947.7 3949.0 3955.5 3956.3 3976.8 3695.1 3900.8 4024.6 4578.7 4616.0 4783.0 5184.5 5238.5 5719.3 5779.5 1234.7 1387.2 1388.6 1413.9 1423.0 794.1 810.9 863.6 874.6 881.5 4107.2 4951.8 5164.6 5241.2 5414.3 5268.4 5332.6 5338.6 5546.4 5908.9 4652.9 4702.6 4989.7 5052.6 5375.6 2638.9 2692.9 3071.5 3073.2 3088.3 5479.5 5559.9 5844.5 6050.8 6100.9 5349.5 5975.1 5977.5 6019.7 6025.2 5128.0 5294.0 5366.0 5988.3 6049.8 5416.0 5798.9 5826.8 5848.6 5888.7 4359.4 4732.3 4767.0 5081.5 5784.0 4985.6 5784.0 5889.5 6063.2 6079.3 3718.9 3872.7 3906.3 3919.4 3942.9 4663.3 5356.7 5491.5 5892.4 5908.5 5418.5 5441.7 5791.4 5792.3 5810.5 5035.8 5259.6 5267.6 5727.5 5733.8 4498.7 5618.9 5655.7 5678.5 5727.9 5460.9 5676.2 5677.7 5681.3 5810.8 5257.0 5298.5 5629.6 5685.8 5697.6 09:15:00 INFO - PROCESS | 1644 | 09:15:00 INFO - PROCESS | 1644 | [#5] yui.html Cycles:180 Average:25787.99 Median:3819.88 stddev:43129.68 (1129.1%) stddev-sans-first:43209.60 09:15:00 INFO - PROCESS | 1644 | Values: 722.0 727.4 776.3 815.3 822.7 15497.0 16455.1 16461.3 16472.5 16499.4 13071.6 14088.6 14778.8 14863.6 14887.6 651.9 670.2 670.6 671.8 813.6 663.8 666.1 825.7 849.3 857.2 879.6 887.0 889.7 893.7 896.6 890.5 891.8 892.7 893.5 894.5 511.9 657.6 658.0 658.1 660.7 14175.8 14742.6 14766.3 14767.0 14774.5 13621.3 14889.9 14900.5 14948.9 14975.3 11844.9 11992.8 12021.1 12123.4 12403.1 3820.8 3840.8 3857.3 3875.4 3941.5 119033.0 120294.0 120488.4 124213.4 124330.4 10804.7 11178.4 11260.5 11596.0 11807.5 22092.0 22378.7 22461.9 22588.3 22608.5 8250.3 8297.5 8300.0 8379.4 8494.8 4513.7 4549.4 4608.1 4681.0 4794.6 3380.7 3625.8 3643.7 3791.0 3793.1 685.8 685.8 687.3 704.7 708.2 574.7 582.4 634.7 652.3 665.4 8758.3 9508.3 9597.5 9622.8 9741.0 566.3 676.7 677.8 685.2 689.4 3819.0 4097.7 4718.5 4737.6 4745.4 163310.0 165544.0 169413.0 170088.2 170662.0 119136.0 144140.6 146795.0 151735.0 151786.2 77513.6 78200.6 127338.0 129479.0 130466.0 7771.7 7841.0 8951.4 9177.4 9191.7 67113.7 67347.3 67812.0 69809.0 71429.9 62359.0 63635.7 68952.7 104609.5 104629.5 9799.4 11645.0 11835.2 11978.6 12390.3 13120.6 13362.9 13865.7 13879.0 13968.6 12497.0 12519.1 12890.0 12897.9 13062.6 12268.9 12435.7 12700.2 12945.2 12954.3 12075.6 12502.7 12552.8 12774.3 12776.9 10121.9 10202.4 10245.7 10397.8 10728.8 8366.8 8698.5 9290.6 9593.0 10175.5 09:15:00 INFO - PROCESS | 1644 | -------- Summary: end -------- 09:15:00 INFO - PROCESS | 1644 | 09:15:00 INFO - TEST-INFO | 1644: exit 0 09:15:00 INFO - TEST-OK | dromaeo_css | took 1117918ms 09:15:00 INFO - TEST-START | kraken 09:15:00 INFO - operating with platform_type : mac_ 09:15:00 INFO - Initialising browser for kraken test... 09:15:00 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/tmpoRE8_9/profile http://localhost:49275/getInfo.html) 09:15:03 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 09:15:03 INFO - PROCESS | 1654 | colorDepth:24 09:15:03 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 09:15:03 INFO - PROCESS | 1654 | __metrics 09:15:03 INFO - PROCESS | 1654 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:15:03 INFO - PROCESS | 1654 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:15:03 INFO - TEST-INFO | 1654: exit 0 09:15:03 INFO - Browser initialized. 09:15:03 INFO - Running cycle 1/1 for kraken test... 09:15:03 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/T/tmpoRE8_9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:15:15 INFO - PROCESS | 1655 | RSS: Main: 233820160 09:15:15 INFO - PROCESS | 1655 | 09:15:18 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 09:15:19 INFO - PROCESS | 1655 | RSS: Main: 293392384 09:15:19 INFO - PROCESS | 1655 | 09:15:22 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 09:15:22 INFO - PROCESS | 1655 | RSS: Main: 290050048 09:15:22 INFO - PROCESS | 1655 | 09:15:26 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 09:15:26 INFO - PROCESS | 1655 | RSS: Main: 379072512 09:15:26 INFO - PROCESS | 1655 | 09:15:29 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 09:15:29 INFO - PROCESS | 1655 | RSS: Main: 281006080 09:15:29 INFO - PROCESS | 1655 | 09:15:31 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:15:32 INFO - PROCESS | 1655 | RSS: Main: 285057024 09:15:32 INFO - PROCESS | 1655 | 09:15:35 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 09:15:35 INFO - PROCESS | 1655 | RSS: Main: 322121728 09:15:35 INFO - PROCESS | 1655 | 09:15:38 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 09:15:39 INFO - PROCESS | 1655 | RSS: Main: 325554176 09:15:39 INFO - PROCESS | 1655 | 09:15:42 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 09:15:42 INFO - PROCESS | 1655 | RSS: Main: 323493888 09:15:42 INFO - PROCESS | 1655 | 09:15:44 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:15:45 INFO - PROCESS | 1655 | RSS: Main: 298172416 09:15:45 INFO - PROCESS | 1655 | 09:15:46 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:15:47 INFO - PROCESS | 1655 | RSS: Main: 306225152 09:15:47 INFO - PROCESS | 1655 | 09:15:49 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:15:50 INFO - PROCESS | 1655 | RSS: Main: 299196416 09:15:50 INFO - PROCESS | 1655 | 09:15:53 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:15:54 INFO - PROCESS | 1655 | RSS: Main: 309968896 09:15:54 INFO - PROCESS | 1655 | 09:15:56 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:15:57 INFO - PROCESS | 1655 | RSS: Main: 311791616 09:15:57 INFO - PROCESS | 1655 | 09:15:59 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 09:15:59 INFO - PROCESS | 1655 | RSS: Main: 355303424 09:15:59 INFO - PROCESS | 1655 | 09:15:59 INFO - PROCESS | 1655 | __start_tp_report 09:15:59 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 09:15:59 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 09:15:59 INFO - PROCESS | 1655 | |i|pagename|runs| 09:15:59 INFO - PROCESS | 1655 | |0;ai-astar;112;113;126;113;114;112;113;113;112;113 09:15:59 INFO - PROCESS | 1655 | |1;audio-beat-detection;146;109;133;162;108;128;130;105;161;107 09:15:59 INFO - PROCESS | 1655 | |2;audio-dft;252;252;185;184;185;183;182;180;185;184 09:15:59 INFO - PROCESS | 1655 | |3;audio-fft;75;76;75;155;62;78;81;93;140;58 09:15:59 INFO - PROCESS | 1655 | |4;audio-oscillator;82;85;133;70;71;88;119;69;71;119 09:15:59 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;88;86;90;87;88;89;135;87;89;87 09:15:59 INFO - PROCESS | 1655 | |6;imaging-darkroom;95;95;95;95;101;94;95;95;97;94 09:15:59 INFO - PROCESS | 1655 | |7;imaging-desaturate;86;99;143;84;85;85;84;85;86;85 09:15:59 INFO - PROCESS | 1655 | |8;json-parse-financial;55;52;55;55;54;56;55;54;53;55 09:15:59 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;65;69;67;72;73;77;76;71;70;71 09:15:59 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;90;85;83;86;85;86;86;87;87;97 09:15:59 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;142;150;139;138;140;135;141;141;176;150 09:15:59 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;168;168;168;168;168;168;167;165;165;171 09:15:59 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;65;65;63;67;68;69;66;67;66;66 09:15:59 INFO - PROCESS | 1655 | __end_tp_report 09:15:59 INFO - PROCESS | 1655 | __start_cc_report 09:15:59 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,2156 09:15:59 INFO - PROCESS | 1655 | __end_cc_report 09:15:59 INFO - PROCESS | 1655 | __startTimestamp1472055359531__endTimestamp 09:15:59 INFO - PROCESS | 1655 | 09:15:59 INFO - PROCESS | 1655 | ------- Summary: start ------- 09:15:59 INFO - PROCESS | 1655 | Number of tests: 14 09:15:59 INFO - PROCESS | 1655 | 09:15:59 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:114.10 Median:113.00 stddev:4.23 (3.7%) stddev-sans-first:4.42 09:15:59 INFO - PROCESS | 1655 | Values: 112.0 113.0 126.0 113.0 114.0 112.0 113.0 113.0 112.0 113.0 09:15:59 INFO - PROCESS | 1655 | 09:15:59 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:128.90 Median:131.50 stddev:21.91 (16.7%) stddev-sans-first:22.35 09:15:59 INFO - PROCESS | 1655 | Values: 146.0 109.0 133.0 162.0 108.0 128.0 130.0 105.0 161.0 107.0 09:15:59 INFO - PROCESS | 1655 | 09:15:59 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:197.20 Median:185.00 stddev:28.92 (15.6%) stddev-sans-first:22.89 09:15:59 INFO - PROCESS | 1655 | Values: 252.0 252.0 185.0 184.0 185.0 183.0 182.0 180.0 185.0 184.0 09:15:59 INFO - PROCESS | 1655 | 09:15:59 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:89.30 Median:75.50 stddev:32.33 (42.8%) stddev-sans-first:33.88 09:15:59 INFO - PROCESS | 1655 | Values: 75.0 76.0 75.0 155.0 62.0 78.0 81.0 93.0 140.0 58.0 09:15:59 INFO - PROCESS | 1655 | 09:15:59 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:90.70 Median:71.00 stddev:24.00 (33.8%) stddev-sans-first:25.24 09:15:59 INFO - PROCESS | 1655 | Values: 82.0 85.0 133.0 70.0 71.0 88.0 119.0 69.0 71.0 119.0 09:15:59 INFO - PROCESS | 1655 | 09:15:59 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:92.60 Median:88.00 stddev:14.95 (17.0%) stddev-sans-first:15.76 09:15:59 INFO - PROCESS | 1655 | Values: 88.0 86.0 90.0 87.0 88.0 89.0 135.0 87.0 89.0 87.0 09:15:59 INFO - PROCESS | 1655 | 09:15:59 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:95.60 Median:95.00 stddev:2.07 (2.2%) stddev-sans-first:2.18 09:15:59 INFO - PROCESS | 1655 | Values: 95.0 95.0 95.0 95.0 101.0 94.0 95.0 95.0 97.0 94.0 09:15:59 INFO - PROCESS | 1655 | 09:15:59 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:92.20 Median:85.00 stddev:18.40 (21.6%) stddev-sans-first:19.37 09:15:59 INFO - PROCESS | 1655 | Values: 86.0 99.0 143.0 84.0 85.0 85.0 84.0 85.0 86.0 85.0 09:15:59 INFO - PROCESS | 1655 | 09:15:59 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:55.00 stddev:1.17 (2.1%) stddev-sans-first:1.22 09:15:59 INFO - PROCESS | 1655 | Values: 55.0 52.0 55.0 55.0 54.0 56.0 55.0 54.0 53.0 55.0 09:15:59 INFO - PROCESS | 1655 | 09:15:59 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:71.10 Median:71.50 stddev:3.70 (5.2%) stddev-sans-first:3.19 09:15:59 INFO - PROCESS | 1655 | Values: 65.0 69.0 67.0 72.0 73.0 77.0 76.0 71.0 70.0 71.0 09:15:59 INFO - PROCESS | 1655 | 09:15:59 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:87.20 Median:86.50 stddev:3.88 (4.5%) stddev-sans-first:3.98 09:15:59 INFO - PROCESS | 1655 | Values: 90.0 85.0 83.0 86.0 85.0 86.0 86.0 87.0 87.0 97.0 09:15:59 INFO - PROCESS | 1655 | 09:15:59 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:145.20 Median:141.50 stddev:11.84 (8.4%) stddev-sans-first:12.50 09:15:59 INFO - PROCESS | 1655 | Values: 142.0 150.0 139.0 138.0 140.0 135.0 141.0 141.0 176.0 150.0 09:15:59 INFO - PROCESS | 1655 | 09:15:59 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.60 Median:168.00 stddev:1.71 (1.0%) stddev-sans-first:1.81 09:15:59 INFO - PROCESS | 1655 | Values: 168.0 168.0 168.0 168.0 168.0 168.0 167.0 165.0 165.0 171.0 09:15:59 INFO - PROCESS | 1655 | 09:15:59 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:66.50 stddev:1.69 (2.5%) stddev-sans-first:1.73 09:15:59 INFO - PROCESS | 1655 | Values: 65.0 65.0 63.0 67.0 68.0 69.0 66.0 67.0 66.0 66.0 09:15:59 INFO - PROCESS | 1655 | -------- Summary: end -------- 09:15:59 INFO - PROCESS | 1655 | 09:16:00 INFO - TEST-INFO | 1655: exit 0 09:16:00 INFO - TEST-OK | kraken | took 59653ms 09:16:00 INFO - SUITE-END | took 1177s 09:16:00 INFO - Completed test suite (00:19:37) 09:16:00 INFO - javascript benchmark 09:16:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2902.521084021136, 3365.9663403365976, 3392.5759280089974, 3425.783846734411, 3464.6881780639737, 5649.209110724501, 5716.542676585874, 5781.82654520365, 5811.622244554101, 5815.854603634912, 5740.655560666352, 5779.190913272145, 5834.883302333951, 5843.35723070463, 5887.970560147204, 5202.3497062867145, 5261.368635763694, 5288.576913846883, 5292.073887069767, 5411.729413529328, 5386.8922621547545, 5394.109971854639, 5401.243825864362, 5412.91880621791, 5419.945800542003, 5179.533841954205, 5238.423773384925, 5255.02781985334, 5292.364916210041, 5339.305890234284, 5297.993381257549, 5298.311219541435, 5304.655197412156, 5306.628536002481, 5309.681419114865, 5199.350081239845, 5250.081235783722, 5259.605529585258, 5267.26258323835, 5275.129603615399, 5601.887962240773, 5760.0783874579865, 5768.077107662796, 5781.306243250837, 5782.681952492611, 6188.535859810488, 6223.315435302158, 6265.342139075377, 6332.1135041094285, 6336.936630633727, 6197.81406557804, 6305.873882522324, 6328.841778955563, 6350.7777227796805, 6365.586236894587, 5691.288588926384, 5889.705514724246, 5896.38088000758, 5898.469137777621, 5902.734376953047, 6237.532185086136, 6283.497320214372, 6305.5901366411035, 6334.398232167937, 6344.302126766052, 6226.159468471789, 6228.5017198624, 6244.625322480666, 6257.9687101564205, 6272.529560282997, 6153.323134455206, 6330.905036424458, 6349.587276826992, 6355.523335749837, 6371.171747672773, 6179.969100154561, 6257.374262573797, 6268.717907694165, 6308.7476500940365, 6333.619982801047, 6288.465480434214, 6323.20959880015, 6323.683815809283, 6347.650879201688, 6348.809535713936, 4915.0415668944215, 4959.752012399365, 5017.272495488134, 5036.748162591855, 5080.796768129308, 4456.844010459618, 4474.843380481668, 4499.482559505634, 4508.661850361236, 4557.293619488984, 4774.594159496482, 5714.200011998323, 5724.570657200727, 5739.454751798572, 5760.798372056958, 6164.876702466016, 6347.809565713036, 6349.873002539924, 6356.237251529754, 6357.841053973688, 5601.719914004283, 5625.803096891589, 5651.915221271602, 5660.179274005246, 5696.11710184922, 5151.9484805152215, 5520.337559492887, 5520.641158324696, 5551.611387202857, 5574.968630803314, 6318.210223722035, 6342.651154186564, 6366.64983425916, 6370.203724534434, 6371.108044873814, 6137.570370074052, 6221.4089661482085, 6337.9366206337345, 6357.745690172342, 6386.425221730066, 6268.592541484789, 6299.4645455135, 6303.46420554258, 6338.0, 6361.618302901656, 6271.937280627135, 6285.402886725754, 6295.5907865988565, 6298.874022519432, 6299.11812346281, 5485.61600687948, 6271.874562508816, 6276.6547839869245, 6293.559450838397, 6360.109584658059, 5713.914291285717, 6094.954753935788, 6117.9492029047315, 6164.907526387197, 6166.599171053867, 6190.783322583688, 6206.72069756853, 6207.906881396692, 6210.31686514474, 6220.657863817352, 6017.849553761016, 6050.183225264533, 6060.2424696912885, 6074.787382441594, 6078.635281883101, 6065.787697430569, 6145.907811382922, 6183.103449999815, 6183.134361189527, 6248.156498872776, 6094.108664719825, 6111.865232675556, 6115.270764408881, 6130.551011451029, 6134.4172303631085], "value": 5759.075350673695, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [496.14415133894016, 514.3570536828964, 523.9240310155028, 524.6248932013609, 538.8329617818476, 30823.0, 31457.685423145762, 32499.675003249962, 40205.798971005104, 41596.0, 43218.0, 43378.915527111756, 43526.34710479337, 43536.0, 43699.90750231246, 5459.826137380463, 7707.96146019272, 8986.326025548111, 9082.54587270637, 9085.727428177166, 8024.43828931975, 8120.553369564672, 8845.115488451167, 9034.457932524037, 9039.27685785139, 9061.818763624757, 9165.908340916572, 12003.819942700866, 12270.81593776094, 12304.81542776859, 12279.938600306987, 12357.25856448607, 12358.196717213352, 12369.381530923463, 12416.56542021034, 12210.26738395699, 12294.692632684255, 12295.877041229563, 12296.200746951508, 12302.876971230262, 40802.59197408018, 40958.79520602408, 41032.0, 41036.79481602603, 41046.79476602598, 3383.9323213535836, 3421.349943510725, 3536.6286539913194, 3617.764845285048, 3831.252905683393, 31300.53049204264, 31747.365052698817, 32468.675313247037, 32538.18654533632, 32632.857849975375, 3267.248532837437, 3364.200915062652, 3378.3918894598964, 3406.7615266931325, 3444.2422667013216, 367483.0, 371313.0, 372356.13821930985, 372844.0, 373449.1327543372, 58448.0, 58787.118193227136, 58834.70582647059, 58910.0, 58969.41030589639, 61973.0, 62036.68981655063, 62119.37880621226, 62126.378736212966, 62180.689096555136, 14758.335874885655, 14980.925095374454, 14997.775033374508, 15002.774958375632, 15021.02363346379, 10823.621173258898, 11544.076473882069, 11587.88412115885, 11594.188406811441, 11600.0, 14215.786763198561, 14651.633709157357, 16100.355985760672, 16112.677746445006, 16171.272292746855, 5239.947600523946, 6374.808755737336, 6445.1943507061615, 6481.04342109847, 6521.347865213441, 911.2891944283336, 1013.5084484025242, 1086.0668010940537, 1099.444780385916, 1113.6603335982604, 29232.0, 29495.55756663652, 29656.555151672743, 30517.0, 30588.0, 1071.8017166824163, 1087.608460954056, 1106.4080716816466, 1111.4942701070993, 1175.9176857619884, 14488.927555362367, 15260.771088433683, 15436.459723909833, 15614.642702748355, 15664.1384723839, 613138.0, 617893.0, 618764.0, 618841.0, 619569.0, 50323.0, 50846.0, 57523.71238143783, 58149.41850581524, 58444.83110337769, 1381.1851007905184, 1393.2406838273166, 1428.5357258890901, 1431.1270125223398, 1431.7279716853764, 25177.87411062933, 29825.0, 30328.090157295315, 32070.67929320677, 32073.518897216094, 49826.0, 55377.89244215013, 56420.71789641026, 56572.717136415704, 59706.70146649239, 625494.0, 630682.0, 631688.0, 784201.8957725109, 818278.0, 25473.23580292594, 25739.613905791804, 25806.483870322114, 26058.60912086358, 26107.216783496526, 45939.77030114828, 46103.0, 46389.53610463987, 46701.76649116733, 46806.0, 38118.809405952794, 38508.61491385163, 38681.41977870391, 38879.028024299616, 39146.804265978484, 37366.813165934, 37731.43402849014, 38029.809850950565, 38822.80588597152, 39069.609303906596, 37940.62059379371, 38072.80963595164, 38081.0, 38554.6144538551, 38561.614383855805, 24242.151569686484, 32269.773022697544, 34032.31935361229, 34221.486677700355, 34344.48483272803, 19679.40961771149, 25114.748852511242, 25331.366715831515, 25739.485210295315, 25785.96856125734], "value": 19828.985349893996, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [320.2889585121032, 348.0428820742957, 368.1973298209902, 375.2682269574329, 398.4939127308318, 15537.533873983784, 16048.679026419479, 20951.790482095174, 21089.15643374263, 21727.0, 20124.899375503137, 20513.58972820543, 21129.366119016417, 21279.361619151452, 21338.46653833655, 5980.431858973401, 6045.183900173486, 6213.751449941997, 6238.59449135807, 6243.781467648633, 4236.597523235288, 4470.418845550074, 5900.940990590104, 6204.565680402374, 6533.31400202979, 6792.354726300993, 6855.1088358513325, 6858.417034552069, 6862.421643022108, 6863.828404289908, 6503.024546318043, 6705.798826035227, 6721.697523611425, 6750.1562304711915, 6768.76309329176, 5079.974600127012, 5121.820736274212, 5245.659032162897, 5638.548916086703, 5841.744025034638, 13288.867111328957, 13531.526396576171, 13540.255285959269, 13555.25446100464, 22351.66472502914, 2027.4728570571758, 2127.585120901425, 2130.1692339987417, 2130.1905275995177, 2193.923212687548, 10561.63034293796, 11822.349770762605, 12929.03032272574, 14654.340554674958, 15938.043717376995, 1573.920718826555, 1858.7862395824518, 1877.3804644467293, 1912.1968773115325, 1949.9707504387445, 117855.0, 118419.81580184175, 123600.38199809034, 130400.0, 130780.0, 9258.398204116846, 10994.615188468366, 11130.220884538005, 11307.660770176908, 11470.54117835294, 14098.788518172236, 14104.929475352557, 14109.85890141106, 14110.435582576787, 14146.22195779242, 7962.6416811243635, 8246.793830154294, 8251.669933202606, 8376.078631350547, 8380.706675266336, 8022.438429309892, 8025.719099831478, 8026.558539280396, 8202.195516986254, 8228.053773816087, 4387.100644367897, 4449.243628583149, 4454.599086082203, 4459.531749166355, 4534.569215924482, 1542.8534289242505, 1647.2998975435391, 1717.2100833616425, 1806.539332470238, 1827.4060930197738, 825.8389614025208, 928.4707716601472, 985.7929834734786, 1035.9326643768131, 1065.3288428290127, 21216.25743099015, 21790.564188716453, 21896.0, 21984.230551930927, 21991.340259791912, 910.5856835139996, 948.9098535639104, 980.6273616026007, 1004.9196064314983, 1008.5713571731984, 10550.577976880992, 11413.42861990838, 11462.59880904181, 11487.483063262172, 11538.250013749079, 578733.0, 585676.0716196392, 604056.0, 625117.3069204915, 631820.0, 116905.0, 135589.0, 136166.3191684035, 140067.0, 141271.0, 985.9605615775432, 993.9602415903283, 996.038822536256, 1018.7453136715822, 1018.8013337399284, 18809.529761756065, 19369.03154842224, 19388.903055484632, 19428.51428714237, 19986.70152932373, 51530.742346288025, 54285.45714542804, 54822.451775481735, 55377.89244215174, 55474.2262886846, 82683.0, 83007.0, 84175.89889213334, 85192.57403712942, 85977.0, 8099.109097999098, 8145.226203510657, 8185.345172386075, 8321.292690121407, 8354.958225208835, 12192.75614487723, 13051.28217948022, 13093.41079651437, 13552.93223533876, 13620.250886201355, 11257.662270131908, 11412.201145919706, 11446.02708769764, 11505.827412588651, 11655.941720291683, 11184.049355804847, 11216.383098929637, 11259.549618015188, 11567.76864462723, 11645.59240426581, 11216.775664486828, 11496.367699776592, 11623.825642615198, 11765.941170294094, 11860.110491712983, 8051.275385215359, 8136.796580085545, 8170.918290817016, 8236.435077335287, 8254.54599996982, 9536.475493847905, 10891.400972946563, 11296.322220666787, 11680.357580333164, 11711.941440292743], "value": 9590.355456708283, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1498.8426215247398, 1539.061172684662, 1691.6532110917262, 1820.517562845847, 1858.9907050464747, 8372.204640559145, 8888.911110888888, 8903.554822258893, 8908.287337013047, 8912.376133670638, 7033.613151276678, 7960.08459027212, 8386.11945745697, 8585.91414085859, 8672.132786721324, 2355.5995480768224, 2381.9761802382013, 2385.904563817454, 2387.7492863249367, 2391.210900402863, 2048.4059622709397, 2344.132670911765, 2361.740208577055, 2395.772401621843, 2402.1352313167245, 2371.905123795046, 2378.369732021007, 2389.032441861049, 2405.747396523366, 2408.8313818032743, 2355.470019245656, 2362.870042147681, 2372.146027430124, 2377.2868139558063, 2385.3440303916386, 2345.636426353918, 2366.3610825077135, 2368.7275963264274, 2369.0, 2369.8104151667826, 8691.478511289342, 8713.390062695615, 8800.383973121883, 8815.382923195379, 8820.955895220482, 5121.743912804345, 5283.233931080011, 5286.577073834084, 5296.09966305729, 5308.761105750251, 3624.981875090634, 3641.2353405784816, 3701.68535674468, 3702.5742039665515, 3720.2931443025736, 1102.0577406317643, 1114.788190243851, 1117.871444783844, 1126.5155982927338, 1141.4463984967285, 11518.712032199179, 11584.768304633944, 11587.073034157247, 11597.246178998423, 11634.53461861533, 4444.511103778582, 4575.10785396851, 4732.100900828831, 4739.644526660514, 4750.8337208197545, 9810.31327807061, 9817.803643927082, 9822.0, 9825.803483930424, 9828.06633369843, 5262.947370526245, 5266.78932842682, 5269.998700246941, 5273.235380869753, 5277.8416647500635, 4272.145570885836, 4440.511543730187, 4697.412823397075, 4809.543093406191, 4821.9035619288115, 3854.4603755474254, 3873.922521549553, 3948.842046318115, 3971.205758848242, 4006.6994975376965, 1094.0, 1276.8914642255327, 1287.7488889666613, 1298.8701129886936, 1304.712963148106, 919.5907821019582, 929.8837645294338, 930.2604429478581, 936.8032713130183, 980.9950950245203, 5618.185363122325, 5781.248437703071, 5903.881922361615, 5958.314793798682, 6113.57204995646, 433.44951911072764, 435.1970614216765, 444.4111552193357, 452.35539356416973, 457.75967617000674, 3951.9604803952166, 3964.5638979712207, 4028.8589899353383, 4037.656799172103, 4110.527289361762, 10054.245931555162, 10538.315009524356, 10542.209334299958, 10545.261831671864, 10547.789044219073, 10488.213383996232, 10502.579896804042, 10509.159267258601, 10521.158307335394, 10523.315984461138, 7026.648667566704, 7865.882011769828, 8010.319122874621, 8059.073206581201, 8184.386171037196, 5213.6350455467755, 5799.4780469757925, 5828.0, 5925.644461332334, 5969.70151492415, 8013.559254241072, 9617.759556011155, 9629.807403851744, 9817.214622830013, 9958.103770660386, 9867.950660246654, 10030.548625312025, 10144.594216231268, 10151.035651613085, 10177.287589868638, 6859.519833611599, 6948.812560362587, 7473.51422157558, 7574.356179724785, 7581.203973582695, 8376.12050734664, 8521.786955325919, 8529.061803201766, 8535.658573657232, 8535.743927682179, 7402.555846649218, 7522.548647081193, 7532.171461139161, 7605.315521603082, 7677.961610191914, 6299.622022678654, 7153.213146553979, 7158.105236845395, 7257.709691612276, 7347.26527347261, 6922.480813938874, 6937.410320122846, 7024.192217895007, 7029.859402812018, 7029.929700702927, 4947.901041979212, 4983.626228032985, 5012.072766538202, 5054.29239906406, 5104.106781313181, 4956.975215123901, 4983.380401369497, 5044.218146187347, 5187.19598462239, 5214.895702085862], "value": 4484.489399239928, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [292.75116151271413, 301.5642396736715, 302.22027169901656, 314.92284390324374, 315.67170143051226, 4500.257457519509, 5371.5165635092835, 5424.728763561826, 5425.782968681253, 5469.398366179717, 4682.438107427113, 4712.952870471294, 5374.865628359293, 5381.408045115044, 5453.509184173424, 3671.9265614687756, 3689.4650275710005, 3702.2965636528975, 3747.681447077002, 4088.08018195905, 3954.3277642800795, 4077.9202606670515, 4117.155983023473, 4120.381942708603, 4167.1040726243855, 3689.8008147352084, 3711.888643340704, 4076.2255171517318, 4083.9591604083876, 4118.68202175304, 3688.2070354873695, 3745.4082015143454, 4084.0402505411203, 4143.81352839123, 4143.875683729493, 2965.4958657028355, 3125.9062228133193, 3563.7883119739413, 3587.497750314958, 3597.7661452005536, 4737.005228901935, 4773.923343345621, 5046.84859454217, 5252.185911183773, 5317.0, 4240.809163587646, 4668.603168730662, 4708.270218116198, 4711.316859055452, 4742.881427964294, 4351.456067991501, 4505.864824055251, 4723.984343366158, 4817.156997525419, 5613.971930140323, 3836.9808150959348, 3844.904527032746, 3848.461215429842, 3852.499175107246, 3859.7455826856103, 5521.668699878021, 5636.830895073154, 5649.209110724501, 5804.796832110856, 5868.383819698955, 3409.3181363727354, 3797.8290976906105, 3875.9806200968815, 3876.6511014008875, 3916.0, 3947.6644485219076, 3948.9732669506134, 3955.4660120883887, 3956.3472027115204, 3976.8210430530694, 3695.0947017980766, 3900.8244628991765, 4024.557298697141, 4578.6794924355645, 4615.976920115378, 4782.95217047832, 5184.5483264685945, 5238.528532432099, 5719.256496655408, 5779.537636989031, 1234.666640007193, 1387.243952046117, 1388.6389538720005, 1413.8798202152727, 1422.9754576704756, 794.06697130871, 810.9229623185788, 863.6329559937127, 874.5976850648773, 881.4843316659698, 4107.178564287095, 4951.8266860659705, 5164.638475306692, 5241.213817927342, 5414.3232095988005, 5268.367795864521, 5332.600054995891, 5338.572914166857, 5546.389897111315, 5908.911366329508, 4652.860414187579, 4702.623790096784, 4989.6777354001215, 5052.646314758005, 5375.596830237748, 2638.941625532088, 2692.905748298839, 3071.5120752190796, 3073.186819776343, 3088.320569474712, 5479.4520547944885, 5559.9444005560235, 5844.532437404998, 6050.818475445743, 6100.908486372708, 5349.545288650508, 5975.133605627159, 5977.521798256129, 6019.668918209541, 6025.216721826135, 5128.00003999224, 5293.967676303084, 5365.973170134124, 5988.341282458839, 6049.758009679652, 5416.025115479251, 5798.884022319446, 5826.796062137805, 5848.64908105515, 5888.705564721832, 4359.433273674402, 4732.337472753886, 4766.976165119152, 5081.5426611605135, 5783.971080144573, 4985.601151907911, 5784.037934015213, 5889.499392551681, 6063.211782468251, 6079.3312735598165, 3718.8512459501317, 3872.7095467840586, 3906.257811015898, 3919.431682406092, 3942.921141577095, 4663.29789626777, 5356.741390594409, 5491.512889315393, 5892.35184129754, 5908.497777688945, 5418.539424148991, 5441.700706461183, 5791.391903850035, 5792.275965504312, 5810.477057064715, 5035.823746168867, 5259.63182577216, 5267.57859371257, 5727.513161381329, 5733.799317024051, 4498.662600305056, 5618.85952851182, 5655.660660360391, 5678.545716342765, 5727.942720572908, 5460.91808622863, 5676.20533125371, 5677.700531284836, 5681.261436013292, 5810.82567522975, 5256.9737151314, 5298.523132918056, 5629.564461062403, 5685.779060593106, 5697.572682048946], "value": 4064.4295555714652, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [721.9711211551538, 727.428968259916, 776.3401109057302, 815.2866242038217, 822.6709316273492, 15497.0, 16455.09496977666, 16461.34154633816, 16472.50582482526, 16499.42252021178, 13071.607851764462, 14088.591140885906, 14778.778318325207, 14863.554093377188, 14887.627809304773, 651.9445847103002, 670.1823774994523, 670.6210990790212, 671.8320419895026, 813.5769399912057, 663.7975417497661, 666.1473314157886, 825.6738588257634, 849.3163003781953, 857.2327766648846, 879.5778026547262, 886.9955650221772, 889.7063968890283, 893.6559424621535, 896.6144557840126, 890.4879694175875, 891.7859713668738, 892.683097500391, 893.477315770272, 894.4856707393275, 511.9334486516766, 657.5627207906737, 658.0031252652236, 658.0852614865294, 660.6894759463045, 14175.78736318956, 14742.631434214123, 14766.261686915612, 14767.040142390817, 14774.482893098797, 13621.25083120428, 14889.851101488955, 14900.478483253035, 14948.850511494855, 14975.251237438193, 11844.881551184464, 11992.760144797143, 12021.098417618714, 12123.393830308449, 12403.069769767304, 3820.789856557888, 3840.7695538267512, 3857.305684976703, 3875.4380614810693, 3941.5270167580074, 119033.0, 120293.98530073548, 120488.39755801165, 124213.37893310566, 124330.37834810858, 10804.729881753019, 11178.44107794607, 11260.549578016788, 11596.0, 11807.527698891949, 22092.0, 22378.664320035212, 22461.88769056144, 22588.322350329516, 22608.547829043328, 8250.298724608356, 8297.502149871027, 8300.0, 8379.413441059067, 8494.787630309293, 4513.684042117086, 4549.431321084864, 4608.124456353282, 4681.0, 4794.568488836021, 3380.7464440167087, 3625.836837342326, 3643.690286325639, 3791.014336272589, 3793.108619474421, 685.7531288736051, 685.8422562810581, 687.3092541995362, 704.6652839901109, 708.1891234536427, 574.6982834012177, 582.3594046548762, 634.6890023888261, 652.3215855510311, 665.4077870695086, 8758.2993360531, 9508.336041192764, 9597.520123993912, 9622.807543849083, 9741.025897410344, 566.2751677852356, 676.736072931557, 677.7797215904851, 685.1914740606034, 689.4071098855081, 3818.961810381916, 4097.651699605507, 4718.457377401574, 4737.573618374363, 4745.359376484131, 163310.0, 165544.0, 169413.0, 170088.24338174687, 170662.0, 119136.0, 144140.63085899383, 146795.0, 151735.0, 151786.24106879396, 77513.61243193748, 78200.60899695464, 127338.0, 129479.0, 130466.0, 7771.650275737492, 7841.0, 8951.41815781972, 9177.449353038839, 9191.724248272563, 67113.66443167948, 67347.32652673607, 67812.0, 69808.95286570807, 71429.92855107281, 62359.0, 63635.681821590595, 68952.6552367235, 104609.47695261474, 104629.47685261525, 9799.363041402286, 11645.0, 11835.171537992257, 11978.58074967372, 12390.3185324808, 13120.606381808178, 13362.866371336162, 13865.722685546434, 13879.0, 13968.58094257174, 12497.0, 12519.090065180515, 12890.0, 12897.871021289668, 13062.608121756362, 12268.877311226774, 12435.689107772016, 12700.237985720887, 12945.223286602833, 12954.28751418681, 12075.57735479254, 12502.68743281389, 12552.811707824572, 12774.297413642338, 12776.872231277568, 10121.949390253001, 10202.438865862448, 10245.692629221136, 10397.84403233937, 10728.839067414152, 8366.790830229293, 8698.47809131454, 9290.6283748652, 9592.95203523978, 10175.48945613762], "value": 7733.455811781173, "unit": "score"}], "name": "dromaeo_css", "value": 7324.3677188261545, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 126.0, 113.0, 114.0, 112.0, 113.0, 113.0, 112.0, 113.0], "value": 114.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 109.0, 133.0, 162.0, 108.0, 128.0, 130.0, 105.0, 161.0, 107.0], "value": 128.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [252.0, 252.0, 185.0, 184.0, 185.0, 183.0, 182.0, 180.0, 185.0, 184.0], "value": 197.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 155.0, 62.0, 78.0, 81.0, 93.0, 140.0, 58.0], "value": 89.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 85.0, 133.0, 70.0, 71.0, 88.0, 119.0, 69.0, 71.0, 119.0], "value": 90.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 90.0, 87.0, 88.0, 89.0, 135.0, 87.0, 89.0, 87.0], "value": 92.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 101.0, 94.0, 95.0, 95.0, 97.0, 94.0], "value": 95.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 99.0, 143.0, 84.0, 85.0, 85.0, 84.0, 85.0, 86.0, 85.0], "value": 92.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 52.0, 55.0, 55.0, 54.0, 56.0, 55.0, 54.0, 53.0, 55.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 69.0, 67.0, 72.0, 73.0, 77.0, 76.0, 71.0, 70.0, 71.0], "value": 71.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 85.0, 83.0, 86.0, 85.0, 86.0, 86.0, 87.0, 87.0, 97.0], "value": 87.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 150.0, 139.0, 138.0, 140.0, 135.0, 141.0, 141.0, 176.0, 150.0], "value": 145.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 168.0, 168.0, 168.0, 168.0, 168.0, 167.0, 165.0, 165.0, 171.0], "value": 167.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 63.0, 67.0, 68.0, 69.0, 66.0, 67.0, 66.0, 66.0], "value": 66.2, "unit": "score"}], "name": "kraken", "value": 1492.3000000000002, "alertThreshold": 2.0}]} 09:16:00 INFO - Return code: 0 09:16:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:16:00 INFO - # TBPL SUCCESS # 09:16:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:16:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:16:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:16:00 INFO - 2016-08-24 09:16:00.842 screenresolution[1659:9942] starting screenresolution argv=screenresolution get 09:16:00 INFO - 2016-08-24 09:16:00.844 screenresolution[1659:9942] Display 0: 1600x1200x32@60 09:16:00 INFO - 2016-08-24 09:16:00.850 screenresolution[1660:9946] starting screenresolution argv=screenresolution list 09:16:00 INFO - Available Modes on Display 0 09:16:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:16:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:16:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:16:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:16:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:16:01 INFO - Intel Iris: 09:16:01 INFO - Chipset Model: Intel Iris 09:16:01 INFO - Type: GPU 09:16:01 INFO - Bus: Built-In 09:16:01 INFO - VRAM (Dynamic, Max): 1536 MB 09:16:01 INFO - Vendor: Intel (0x8086) 09:16:01 INFO - Device ID: 0x0a2e 09:16:01 INFO - Revision ID: 0x0009 09:16:01 INFO - Displays: 09:16:01 INFO - Display: 09:16:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:16:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:16:01 INFO - Display Serial Number: 1600x1200 60 09:16:01 INFO - Main Display: Yes 09:16:01 INFO - Mirror: Off 09:16:01 INFO - Online: Yes 09:16:01 INFO - Rotation: Supported 09:16:01 INFO - Return code: 0 09:16:01 INFO - Running post-action listener: _resource_record_post_action 09:16:01 INFO - [mozharness: 2016-08-24 16:16:01.088638Z] Finished run-tests step (success) 09:16:01 INFO - Running post-run listener: _resource_record_post_run 09:16:01 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 44545536; Write bytes: 704626688; Read time: 442; Write time: 1946 09:16:01 INFO - TinderboxPrint: CPU usage
53.4% 09:16:01 INFO - TinderboxPrint: I/O read bytes / time
44,545,536 / 442 09:16:01 INFO - TinderboxPrint: I/O write bytes / time
704,626,688 / 1,946 09:16:01 INFO - TinderboxPrint: CPU idle
2,263.7 (46.6%) 09:16:01 INFO - TinderboxPrint: CPU system
966.1 (19.9%) 09:16:01 INFO - TinderboxPrint: CPU user
1,623.8 (33.5%) 09:16:01 INFO - TinderboxPrint: Swap in / out
457,785,344 / 0 09:16:01 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 384430592; Write bytes: 378258944; Read time: 29222; Write time: 735 09:16:01 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 43587072; Write bytes: 295782912; Read time: 428; Write time: 1092 09:16:01 INFO - Running post-run listener: _upload_blobber_files 09:16:01 INFO - Blob upload gear active. 09:16:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:16:01 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 09:16:01 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'] 09:16:01 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 09:16:02 INFO - (blobuploader) - INFO - Open directory for files ... 09:16:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:16:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:16:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:16:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:16:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:16:02 INFO - (blobuploader) - INFO - Done attempting. 09:16:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:16:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:16:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:16:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:16:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:16:03 INFO - (blobuploader) - INFO - Done attempting. 09:16:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:16:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:16:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:16:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:16:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:16:06 INFO - (blobuploader) - INFO - Done attempting. 09:16:06 INFO - (blobuploader) - INFO - Iteration through files over. 09:16:06 INFO - Return code: 0 09:16:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:16:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:16:06 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f766a22629669c25a615075923c5d3473f7983ef78e6cd24bdea7b6f38f67358a151ab7d4e62dff0f6e34a7de2d3b1838728d8199243ffa3f6fd43c0d9ce6351", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e1df7b1fc881d67a9d01978eaecb856ff1bf79ec74a06b8c0735850bb475abf5bef048e3420ab48bfda900a587909281e8dfcaf0732f0ef6b1031801e1f12f38", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/128c6c9121fbb828b59e9b50155593ebedf753a6605f4e8f59c98cf06f89ad756d975508a4447a65945f53d6c94a5f33f245197f82e5bca67dcbf60c6a702fd4"} 09:16:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:16:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:16:06 INFO - Contents: 09:16:06 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f766a22629669c25a615075923c5d3473f7983ef78e6cd24bdea7b6f38f67358a151ab7d4e62dff0f6e34a7de2d3b1838728d8199243ffa3f6fd43c0d9ce6351", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e1df7b1fc881d67a9d01978eaecb856ff1bf79ec74a06b8c0735850bb475abf5bef048e3420ab48bfda900a587909281e8dfcaf0732f0ef6b1031801e1f12f38", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/128c6c9121fbb828b59e9b50155593ebedf753a6605f4e8f59c98cf06f89ad756d975508a4447a65945f53d6c94a5f33f245197f82e5bca67dcbf60c6a702fd4"} 09:16:06 INFO - Running post-run listener: copy_logs_to_upload_dir 09:16:06 INFO - Copying logs to upload dir... 09:16:06 INFO - mkdir: /builds/slave/test/build/upload/logs 09:16:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.129026 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-24 09:16:06.115590) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-24 09:16:06.119467) ========= 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.6xFsE9E6vU/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.GrxTNfsils/Listeners TMPDIR=/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/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/f766a22629669c25a615075923c5d3473f7983ef78e6cd24bdea7b6f38f67358a151ab7d4e62dff0f6e34a7de2d3b1838728d8199243ffa3f6fd43c0d9ce6351", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e1df7b1fc881d67a9d01978eaecb856ff1bf79ec74a06b8c0735850bb475abf5bef048e3420ab48bfda900a587909281e8dfcaf0732f0ef6b1031801e1f12f38", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/128c6c9121fbb828b59e9b50155593ebedf753a6605f4e8f59c98cf06f89ad756d975508a4447a65945f53d6c94a5f33f245197f82e5bca67dcbf60c6a702fd4"} build_url:https://queue.taskcluster.net/v1/task/BSqo7oQ1TKS-3f8S-qyyTQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011220 build_url: 'https://queue.taskcluster.net/v1/task/BSqo7oQ1TKS-3f8S-qyyTQ/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/f766a22629669c25a615075923c5d3473f7983ef78e6cd24bdea7b6f38f67358a151ab7d4e62dff0f6e34a7de2d3b1838728d8199243ffa3f6fd43c0d9ce6351", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e1df7b1fc881d67a9d01978eaecb856ff1bf79ec74a06b8c0735850bb475abf5bef048e3420ab48bfda900a587909281e8dfcaf0732f0ef6b1031801e1f12f38", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/128c6c9121fbb828b59e9b50155593ebedf753a6605f4e8f59c98cf06f89ad756d975508a4447a65945f53d6c94a5f33f245197f82e5bca67dcbf60c6a702fd4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-24 09:16:06.153225) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-24 09:16:06.153564) ========= 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.6xFsE9E6vU/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.GrxTNfsils/Listeners TMPDIR=/var/folders/9j/83h02rbx6bn60ztt0ypr7lsm00000w/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.005299 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-24 09:16:06.203461) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-24 09:16:06.203817) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-24 09:16:06.204216) ========= ========= Total master_lag: 1.27 =========