builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0315 starttime: 1471400763.47 results: success (0) buildid: 20160816163815 builduid: d4db7cea77864916975f0634444bd236 revision: 52be5024e34261bcd08d68fda65379ed0ffd363d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-16 19:26:03.469905) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-16 19:26:03.470425) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-16 19:26:03.470757) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-16 19:26:03.494174) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 19:26:03.494477) ========= 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.o0qYcHQjzC/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.o3MsZuxzVt/Listeners TMPDIR=/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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-16 19:26:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.32M=0.001s 2016-08-16 19:26:03 (8.32 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.084391 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 19:26:03.604519) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 19:26:03.605234) ========= 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.o0qYcHQjzC/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.o3MsZuxzVt/Listeners TMPDIR=/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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.020634 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 19:26:03.659547) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-16 19:26:03.660286) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 52be5024e34261bcd08d68fda65379ed0ffd363d --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 52be5024e34261bcd08d68fda65379ed0ffd363d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.o0qYcHQjzC/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.o3MsZuxzVt/Listeners TMPDIR=/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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-16 19:26:03,746 truncating revision to first 12 chars 2016-08-16 19:26:03,747 Setting DEBUG logging. 2016-08-16 19:26:03,747 attempt 1/10 2016-08-16 19:26:03,747 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/52be5024e342?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-16 19:26:04,931 unpacking tar archive at: mozilla-central-52be5024e342/testing/mozharness/ program finished with exit code 0 elapsedTime=1.442200 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-16 19:26:05.135437) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-16 19:26:05.135793) ========= script_repo_revision: 52be5024e34261bcd08d68fda65379ed0ffd363d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-16 19:26:05.136242) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-16 19:26:05.136543) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-16 19:26:05.229188) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-08-16 19:26:05.229541) ========= /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.o0qYcHQjzC/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.o3MsZuxzVt/Listeners TMPDIR=/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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 19:26:05 INFO - MultiFileLogger online at 20160816 19:26:05 in /builds/slave/test 19:26:05 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 19:26:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:26:05 INFO - {'append_to_log': False, 19:26:05 INFO - 'base_work_dir': '/builds/slave/test', 19:26:05 INFO - 'blob_upload_branch': 'Firefox', 19:26:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:26:05 INFO - 'branch': 'Firefox', 19:26:05 INFO - 'buildbot_json_path': 'buildprops.json', 19:26:05 INFO - 'config_files': ('talos/mac_config.py',), 19:26:05 INFO - 'default_actions': ('clobber', 19:26:05 INFO - 'read-buildbot-config', 19:26:05 INFO - 'download-and-extract', 19:26:05 INFO - 'populate-webroot', 19:26:05 INFO - 'create-virtualenv', 19:26:05 INFO - 'install', 19:26:05 INFO - 'run-tests'), 19:26:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:26:05 INFO - 'download_minidump_stackwalk': True, 19:26:05 INFO - 'download_symbols': 'ondemand', 19:26:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:26:05 INFO - 'tooltool.py': '/tools/tooltool.py', 19:26:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:26:05 INFO - '/tools/misc-python/virtualenv.py')}, 19:26:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:26:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:26:05 INFO - 'installer_path': 'installer.exe', 19:26:05 INFO - 'log_level': 'info', 19:26:05 INFO - 'log_name': 'talos', 19:26:05 INFO - 'log_to_console': True, 19:26:05 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:26:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:26:05 INFO - 'opt_config_files': (), 19:26:05 INFO - 'pip_index': False, 19:26:05 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:26:05 INFO - 'cmd': ('bash', 19:26:05 INFO - '-c', 19:26:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:26:05 INFO - 'enabled': True, 19:26:05 INFO - 'halt_on_failure': False, 19:26:05 INFO - 'name': 'check_screen_resolution'},), 19:26:05 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:26:05 INFO - 'cmd': ('bash', 19:26:05 INFO - '-c', 19:26:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:26:05 INFO - 'enabled': True, 19:26:05 INFO - 'halt_on_failure': False, 19:26:05 INFO - 'name': 'check_screen_resolution'},), 19:26:05 INFO - 'run_cmd_checks_enabled': True, 19:26:05 INFO - 'sps_profile': False, 19:26:05 INFO - 'sps_profile_interval': 0, 19:26:05 INFO - 'suite': 'dromaeojs', 19:26:05 INFO - 'system_bits': '32', 19:26:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:26:05 INFO - 'title': 't-yosemite-r7-0315', 19:26:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:26:05 INFO - 'use_talos_json': True, 19:26:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:26:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:26:05 INFO - 'work_dir': 'build'} 19:26:05 INFO - [mozharness: 2016-08-17 02:26:05.430939Z] Running clobber step. 19:26:05 INFO - Running pre-action listener: _resource_record_pre_action 19:26:05 INFO - Running main action method: clobber 19:26:05 INFO - rmtree: /builds/slave/test/build 19:26:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:26:06 INFO - Running post-action listener: _resource_record_post_action 19:26:06 INFO - [mozharness: 2016-08-17 02:26:06.313457Z] Finished clobber step (success) 19:26:06 INFO - [mozharness: 2016-08-17 02:26:06.313634Z] Running read-buildbot-config step. 19:26:06 INFO - Running pre-action listener: _resource_record_pre_action 19:26:06 INFO - Running main action method: read_buildbot_config 19:26:06 INFO - Using buildbot properties: 19:26:06 INFO - { 19:26:06 INFO - "project": "", 19:26:06 INFO - "product": "firefox", 19:26:06 INFO - "script_repo_revision": "production", 19:26:06 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 19:26:06 INFO - "repository": "", 19:26:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 19:26:06 INFO - "buildid": "20160816163815", 19:26:06 INFO - "pgo_build": "False", 19:26:06 INFO - "basedir": "/builds/slave/test", 19:26:06 INFO - "buildnumber": 24, 19:26:06 INFO - "slavename": "t-yosemite-r7-0315", 19:26:06 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 19:26:06 INFO - "platform": "yosemite_r7", 19:26:06 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 19:26:06 INFO - "branch": "mozilla-central", 19:26:06 INFO - "repo_path": "mozilla-central", 19:26:06 INFO - "revision": "52be5024e34261bcd08d68fda65379ed0ffd363d", 19:26:06 INFO - "stage_platform": "macosx64", 19:26:06 INFO - "builduid": "d4db7cea77864916975f0634444bd236", 19:26:06 INFO - "slavebuilddir": "test" 19:26:06 INFO - } 19:26:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/cxbgc7tOQMuVWBXcWkgn9g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 19:26:06 INFO - Running post-action listener: _resource_record_post_action 19:26:06 INFO - [mozharness: 2016-08-17 02:26:06.317113Z] Finished read-buildbot-config step (success) 19:26:06 INFO - [mozharness: 2016-08-17 02:26:06.317238Z] Running download-and-extract step. 19:26:06 INFO - Running pre-action listener: _resource_record_pre_action 19:26:06 INFO - Running main action method: download_and_extract 19:26:06 INFO - mkdir: /builds/slave/test/build/tests 19:26:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:06 INFO - trying https://queue.taskcluster.net/v1/task/cxbgc7tOQMuVWBXcWkgn9g/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 19:26:06 INFO - Downloading https://queue.taskcluster.net/v1/task/cxbgc7tOQMuVWBXcWkgn9g/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 19:26:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cxbgc7tOQMuVWBXcWkgn9g/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 19:26:07 INFO - Downloaded 1199 bytes. 19:26:07 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 19:26:07 INFO - Using the following test package requirements: 19:26:07 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 19:26:07 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:26:07 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 19:26:07 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:26:07 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 19:26:07 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:26:07 INFO - u'jsshell-mac.zip'], 19:26:07 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:26:07 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 19:26:07 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 19:26:07 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:26:07 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 19:26:07 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:26:07 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 19:26:07 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:26:07 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 19:26:07 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:26:07 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 19:26:07 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:26:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:07 INFO - trying https://queue.taskcluster.net/v1/task/cxbgc7tOQMuVWBXcWkgn9g/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 19:26:07 INFO - Downloading https://queue.taskcluster.net/v1/task/cxbgc7tOQMuVWBXcWkgn9g/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 19:26:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cxbgc7tOQMuVWBXcWkgn9g/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 19:26:08 INFO - Downloaded 16333000 bytes. 19:26:08 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 19:26:14 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 19:26:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:14 INFO - trying https://queue.taskcluster.net/v1/task/cxbgc7tOQMuVWBXcWkgn9g/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 19:26:14 INFO - Downloading https://queue.taskcluster.net/v1/task/cxbgc7tOQMuVWBXcWkgn9g/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 19:26:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cxbgc7tOQMuVWBXcWkgn9g/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 19:26:15 INFO - Downloaded 16333000 bytes. 19:26:15 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 19:26: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')]} 19:26:22 INFO - trying https://queue.taskcluster.net/v1/task/cxbgc7tOQMuVWBXcWkgn9g/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 19:26:22 INFO - Downloading https://queue.taskcluster.net/v1/task/cxbgc7tOQMuVWBXcWkgn9g/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 19:26:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cxbgc7tOQMuVWBXcWkgn9g/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 19:26:23 INFO - Downloaded 14413719 bytes. 19:26:23 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 19:26:23 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')]} 19:26:23 INFO - trying https://queue.taskcluster.net/v1/task/cxbgc7tOQMuVWBXcWkgn9g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:26:23 INFO - Downloading https://queue.taskcluster.net/v1/task/cxbgc7tOQMuVWBXcWkgn9g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 19:26:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cxbgc7tOQMuVWBXcWkgn9g/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 19:26:26 INFO - Downloaded 111015212 bytes. 19:26:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cxbgc7tOQMuVWBXcWkgn9g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:26:26 INFO - mkdir: /builds/slave/test/properties 19:26:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:26:26 INFO - Writing to file /builds/slave/test/properties/build_url 19:26:26 INFO - Contents: 19:26:26 INFO - build_url:https://queue.taskcluster.net/v1/task/cxbgc7tOQMuVWBXcWkgn9g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:26:27 INFO - Running post-action listener: _resource_record_post_action 19:26:27 INFO - Running post-action listener: set_extra_try_arguments 19:26:27 INFO - [mozharness: 2016-08-17 02:26:27.506758Z] Finished download-and-extract step (success) 19:26:27 INFO - [mozharness: 2016-08-17 02:26:27.506919Z] Running populate-webroot step. 19:26:27 INFO - Running pre-action listener: _resource_record_pre_action 19:26:27 INFO - Running main action method: populate_webroot 19:26:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:26:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:26:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:26:27 INFO - '--tppagecycles', 19:26:27 INFO - '7'], 19:26:27 INFO - 'tests': ['tsvgm']}}, 19:26:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:26:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:26:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:26:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:26:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:26:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:26:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:26:27 INFO - 'talos_options': ['--disable-e10s'], 19:26:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:26:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:26:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:26:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:26:27 INFO - 'talos_options': ['--disable-e10s'], 19:26:27 INFO - 'tests': ['damp', 'tps']}, 19:26:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:26:27 INFO - 'tests': ['damp', 'tps']}, 19:26:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:26:27 INFO - 'tests': ['dromaeo_dom']}, 19:26:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:26:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:26:27 INFO - 'tests': ['basic_compositor_video']}, 19:26:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:26:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:26:27 INFO - 'tests': ['a11yr', 19:26:27 INFO - 'ts_paint', 19:26:27 INFO - 'tpaint', 19:26:27 INFO - 'sessionrestore', 19:26:27 INFO - 'sessionrestore_no_auto_restore', 19:26:27 INFO - 'tabpaint']}, 19:26:27 INFO - 'other-e10s': {'tests': ['a11yr', 19:26:27 INFO - 'ts_paint', 19:26:27 INFO - 'tpaint', 19:26:27 INFO - 'sessionrestore', 19:26:27 INFO - 'sessionrestore_no_auto_restore', 19:26:27 INFO - 'tabpaint']}, 19:26:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:26:27 INFO - 'ts_paint', 19:26:27 INFO - 'tpaint', 19:26:27 INFO - 'sessionrestore', 19:26:27 INFO - 'sessionrestore_no_auto_restore', 19:26:27 INFO - 'tabpaint']}, 19:26:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:26:27 INFO - 'ts_paint', 19:26:27 INFO - 'tpaint', 19:26:27 INFO - 'sessionrestore', 19:26:27 INFO - 'sessionrestore_no_auto_restore', 19:26:27 INFO - 'tabpaint']}, 19:26:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:26:27 INFO - 'tests': ['a11yr', 19:26:27 INFO - 'ts_paint', 19:26:27 INFO - 'tpaint', 19:26:27 INFO - 'sessionrestore', 19:26:27 INFO - 'sessionrestore_no_auto_restore', 19:26:27 INFO - 'tabpaint']}, 19:26:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:26:27 INFO - 'tests': ['a11yr', 19:26:27 INFO - 'ts_paint', 19:26:27 INFO - 'tpaint', 19:26:27 INFO - 'sessionrestore', 19:26:27 INFO - 'sessionrestore_no_auto_restore', 19:26:27 INFO - 'tabpaint']}, 19:26:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:26:27 INFO - 'tests': ['tsvgx', 19:26:27 INFO - 'tsvgr_opacity', 19:26:27 INFO - 'tart', 19:26:27 INFO - 'tscrollx', 19:26:27 INFO - 'cart']}, 19:26:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:26:27 INFO - 'tsvgr_opacity', 19:26:27 INFO - 'tart', 19:26:27 INFO - 'tscrollx', 19:26:27 INFO - 'cart']}, 19:26:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:26:27 INFO - 'talos_options': ['--disable-e10s'], 19:26:27 INFO - 'tests': ['tp5o']}, 19:26:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:26:27 INFO - 'tests': ['tp5o']}, 19:26:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:26:27 INFO - 'talos_options': ['--disable-e10s', 19:26:27 INFO - '--xperf_path', 19:26:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:26:27 INFO - 'tests': ['tp5n']}, 19:26:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:26:27 INFO - 'talos_options': ['--xperf_path', 19:26:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:26:27 INFO - 'tests': ['tp5n']}}, 19:26:27 INFO - 'talos.zip': {'path': '', 19:26:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:26:27 INFO - Running post-action listener: _resource_record_post_action 19:26:27 INFO - [mozharness: 2016-08-17 02:26:27.525975Z] Finished populate-webroot step (success) 19:26:27 INFO - [mozharness: 2016-08-17 02:26:27.526085Z] Running create-virtualenv step. 19:26:27 INFO - Running pre-action listener: _resource_record_pre_action 19:26:27 INFO - Running main action method: create_virtualenv 19:26:27 INFO - Creating virtualenv /builds/slave/test/build/venv 19:26:27 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 19:26:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:26:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:26:27 INFO - Using real prefix '/tools/python27' 19:26:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:26:28 INFO - Installing distribute.............................................................................................................................................................................................done. 19:26:31 INFO - Installing pip.................done. 19:26:31 INFO - Return code: 0 19:26:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:26: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')]} 19:26:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26: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')]} 19:26:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ca3ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cad1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbad2da0580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9a0d30>, '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 0x10c705db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c998b20>, '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.o0qYcHQjzC/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.o3MsZuxzVt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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 19:26: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', 'pip>=1.5'] in /builds/slave/test/build 19:26: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 pip>=1.5 19:26:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o0qYcHQjzC/Render', 19:26:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:26:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:26:31 INFO - 'HOME': '/Users/cltbld', 19:26:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:26:31 INFO - 'LOGNAME': 'cltbld', 19:26:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:31 INFO - 'MOZ_NO_REMOTE': '1', 19:26:31 INFO - 'NO_EM_RESTART': '1', 19:26:31 INFO - 'PAGER': '/bin/cat', 19:26:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:26:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:31 INFO - 'PWD': '/builds/slave/test', 19:26:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:26:31 INFO - 'SHELL': '/bin/bash', 19:26:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o3MsZuxzVt/Listeners', 19:26:31 INFO - 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/', 19:26:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:26:31 INFO - 'USER': 'cltbld', 19:26:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:26:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:26:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:31 INFO - 'XPC_FLAGS': '0x0', 19:26:31 INFO - 'XPC_SERVICE_NAME': '0', 19:26:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:26:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:31 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 19:26:31 INFO - Cleaning up... 19:26:31 INFO - Return code: 0 19:26:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:26: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')]} 19:26:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26: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')]} 19:26:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26: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', '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 0x10ca3ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cad1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbad2da0580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9a0d30>, '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 0x10c705db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c998b20>, '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.o0qYcHQjzC/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.o3MsZuxzVt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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 19:26: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', 'psutil>=3.1.1'] in /builds/slave/test/build 19:26: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 psutil>=3.1.1 19:26:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o0qYcHQjzC/Render', 19:26:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:26:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:26:31 INFO - 'HOME': '/Users/cltbld', 19:26:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:26:31 INFO - 'LOGNAME': 'cltbld', 19:26:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:31 INFO - 'MOZ_NO_REMOTE': '1', 19:26:31 INFO - 'NO_EM_RESTART': '1', 19:26:31 INFO - 'PAGER': '/bin/cat', 19:26:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:26:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:31 INFO - 'PWD': '/builds/slave/test', 19:26:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:26:31 INFO - 'SHELL': '/bin/bash', 19:26:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o3MsZuxzVt/Listeners', 19:26:31 INFO - 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/', 19:26:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:26:31 INFO - 'USER': 'cltbld', 19:26:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:26:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:26:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:31 INFO - 'XPC_FLAGS': '0x0', 19:26:31 INFO - 'XPC_SERVICE_NAME': '0', 19:26:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:26:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:31 INFO - Downloading/unpacking psutil>=3.1.1 19:26: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 19:26: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 19:26: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 19:26: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 19:26: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 19:26: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 19:26:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:26:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:26:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:26:34 INFO - Installing collected packages: psutil 19:26:34 INFO - Running setup.py install for psutil 19:26:34 INFO - building 'psutil._psutil_osx' extension 19:26:34 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 19:26:34 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 19:26:34 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 19:26:34 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 19:26:34 INFO - building 'psutil._psutil_posix' extension 19:26:34 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 19:26:34 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:26:34 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:26:34 INFO - ^ 19:26:35 INFO - 1 warning generated. 19:26:35 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 19:26:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:26:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:26:35 INFO - Successfully installed psutil 19:26:35 INFO - Cleaning up... 19:26:35 INFO - Return code: 0 19:26:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:26: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')]} 19:26:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26: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')]} 19:26:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:35 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 0x10ca3ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cad1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbad2da0580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9a0d30>, '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 0x10c705db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c998b20>, '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.o0qYcHQjzC/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.o3MsZuxzVt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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 19:26:35 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 19:26:35 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 19:26:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o0qYcHQjzC/Render', 19:26:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:26:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:26:35 INFO - 'HOME': '/Users/cltbld', 19:26:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:26:35 INFO - 'LOGNAME': 'cltbld', 19:26:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:35 INFO - 'MOZ_NO_REMOTE': '1', 19:26:35 INFO - 'NO_EM_RESTART': '1', 19:26:35 INFO - 'PAGER': '/bin/cat', 19:26:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:26:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:35 INFO - 'PWD': '/builds/slave/test', 19:26:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:26:35 INFO - 'SHELL': '/bin/bash', 19:26:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o3MsZuxzVt/Listeners', 19:26:35 INFO - 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/', 19:26:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:26:35 INFO - 'USER': 'cltbld', 19:26:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:26:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:26:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:35 INFO - 'XPC_FLAGS': '0x0', 19:26:35 INFO - 'XPC_SERVICE_NAME': '0', 19:26:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:26:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:26:35 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 19:26:35 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 19:26:35 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 19:26:35 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 19:26:35 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 19:26:35 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 19:26:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:26:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:26:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:26:38 INFO - Installing collected packages: mozsystemmonitor 19:26:38 INFO - Running setup.py install for mozsystemmonitor 19:26:38 INFO - Successfully installed mozsystemmonitor 19:26:38 INFO - Cleaning up... 19:26:38 INFO - Return code: 0 19:26:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:26:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:38 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 0x10ca3ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cad1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbad2da0580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9a0d30>, '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 0x10c705db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c998b20>, '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.o0qYcHQjzC/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.o3MsZuxzVt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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 19:26:38 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 19:26:38 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 19:26:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o0qYcHQjzC/Render', 19:26:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:26:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:26:38 INFO - 'HOME': '/Users/cltbld', 19:26:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:26:38 INFO - 'LOGNAME': 'cltbld', 19:26:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:38 INFO - 'MOZ_NO_REMOTE': '1', 19:26:38 INFO - 'NO_EM_RESTART': '1', 19:26:38 INFO - 'PAGER': '/bin/cat', 19:26:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:26:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:38 INFO - 'PWD': '/builds/slave/test', 19:26:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:26:38 INFO - 'SHELL': '/bin/bash', 19:26:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o3MsZuxzVt/Listeners', 19:26:38 INFO - 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/', 19:26:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:26:38 INFO - 'USER': 'cltbld', 19:26:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:26:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:26:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:38 INFO - 'XPC_FLAGS': '0x0', 19:26:38 INFO - 'XPC_SERVICE_NAME': '0', 19:26:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:26:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:38 INFO - Downloading/unpacking jsonschema==2.5.1 19:26:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:26:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:26:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:26:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:26:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:26:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:26:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:42 INFO - Downloading functools32-3.2.3-2.tar.gz 19:26:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:26:42 INFO - warning: no files found matching '*.txt' 19:26:42 INFO - no previously-included directories found matching 'build' 19:26:42 INFO - no previously-included directories found matching 'dist' 19:26:42 INFO - no previously-included directories found matching '.git*' 19:26:42 INFO - Installing collected packages: jsonschema, functools32 19:26:42 INFO - Running setup.py install for functools32 19:26:42 INFO - warning: no files found matching '*.txt' 19:26:42 INFO - no previously-included directories found matching 'build' 19:26:42 INFO - no previously-included directories found matching 'dist' 19:26:42 INFO - no previously-included directories found matching '.git*' 19:26:42 INFO - Successfully installed jsonschema functools32 19:26:42 INFO - Cleaning up... 19:26:42 INFO - Return code: 0 19:26:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:26:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ca3ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cad1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbad2da0580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9a0d30>, '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 0x10c705db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c998b20>, '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.o0qYcHQjzC/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.o3MsZuxzVt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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 19:26:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:26:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 19:26:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o0qYcHQjzC/Render', 19:26:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:26:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:26:42 INFO - 'HOME': '/Users/cltbld', 19:26:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:26:42 INFO - 'LOGNAME': 'cltbld', 19:26:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:42 INFO - 'MOZ_NO_REMOTE': '1', 19:26:42 INFO - 'NO_EM_RESTART': '1', 19:26:42 INFO - 'PAGER': '/bin/cat', 19:26:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:26:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:42 INFO - 'PWD': '/builds/slave/test', 19:26:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:26:42 INFO - 'SHELL': '/bin/bash', 19:26:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o3MsZuxzVt/Listeners', 19:26:42 INFO - 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/', 19:26:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:26:42 INFO - 'USER': 'cltbld', 19:26:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:26:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:26:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:42 INFO - 'XPC_FLAGS': '0x0', 19:26:42 INFO - 'XPC_SERVICE_NAME': '0', 19:26:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:26:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:26:42 INFO - Cleaning up... 19:26:42 INFO - Return code: 0 19:26:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:26:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ca3ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cad1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbad2da0580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9a0d30>, '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 0x10c705db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c998b20>, '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.o0qYcHQjzC/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.o3MsZuxzVt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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 19:26:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:26:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:26:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o0qYcHQjzC/Render', 19:26:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:26:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:26:42 INFO - 'HOME': '/Users/cltbld', 19:26:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:26:42 INFO - 'LOGNAME': 'cltbld', 19:26:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:42 INFO - 'MOZ_NO_REMOTE': '1', 19:26:42 INFO - 'NO_EM_RESTART': '1', 19:26:42 INFO - 'PAGER': '/bin/cat', 19:26:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:26:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:42 INFO - 'PWD': '/builds/slave/test', 19:26:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:26:42 INFO - 'SHELL': '/bin/bash', 19:26:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o3MsZuxzVt/Listeners', 19:26:42 INFO - 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/', 19:26:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:26:42 INFO - 'USER': 'cltbld', 19:26:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:26:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:26:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:42 INFO - 'XPC_FLAGS': '0x0', 19:26:42 INFO - 'XPC_SERVICE_NAME': '0', 19:26:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:26:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:43 INFO - Downloading/unpacking blobuploader==1.2.4 19:26: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 19:26: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 19:26: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 19:26: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 19:26: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 19:26: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 19:26:45 INFO - Downloading blobuploader-1.2.4.tar.gz 19:26:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:26:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:26:45 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 19:26:45 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 19:26:45 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 19:26:45 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 19:26:45 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 19:26:45 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 19:26:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:26:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:26:46 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 19:26:46 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 19:26:46 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 19:26:46 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 19:26:46 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 19:26:46 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 19:26:46 INFO - Downloading docopt-0.6.1.tar.gz 19:26:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:26:46 INFO - Installing collected packages: blobuploader, requests, docopt 19:26:46 INFO - Running setup.py install for blobuploader 19:26:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:26:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:26:46 INFO - Running setup.py install for requests 19:26:47 INFO - Running setup.py install for docopt 19:26:47 INFO - Successfully installed blobuploader requests docopt 19:26:47 INFO - Cleaning up... 19:26:47 INFO - Return code: 0 19:26:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:26:47 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')]} 19:26:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:47 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')]} 19:26:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:47 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 0x10ca3ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cad1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbad2da0580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9a0d30>, '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 0x10c705db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c998b20>, '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.o0qYcHQjzC/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.o3MsZuxzVt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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 19:26:47 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 19:26:47 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 19:26:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o0qYcHQjzC/Render', 19:26:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:26:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:26:47 INFO - 'HOME': '/Users/cltbld', 19:26:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:26:47 INFO - 'LOGNAME': 'cltbld', 19:26:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:47 INFO - 'MOZ_NO_REMOTE': '1', 19:26:47 INFO - 'NO_EM_RESTART': '1', 19:26:47 INFO - 'PAGER': '/bin/cat', 19:26:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:26:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:47 INFO - 'PWD': '/builds/slave/test', 19:26:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:26:47 INFO - 'SHELL': '/bin/bash', 19:26:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o3MsZuxzVt/Listeners', 19:26:47 INFO - 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/', 19:26:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:26:47 INFO - 'USER': 'cltbld', 19:26:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:26:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:26:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:47 INFO - 'XPC_FLAGS': '0x0', 19:26:47 INFO - 'XPC_SERVICE_NAME': '0', 19:26:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:26:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:26:47 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-qx3Bkt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:26:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:26:48 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-oLR0DO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:26:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:26:48 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-zc9SEj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:26:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:26:48 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-OCx57c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:26:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:26:48 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-SUtg0S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:26:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:26:48 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-wPwjmK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:26:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:26:48 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-RJPvJh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:26:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:26:48 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-Waav6r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:26:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:26:48 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-E7aCrH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:26:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:26:48 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-12tvaq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:26:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:26:49 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-IouOvu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:26:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:26:49 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-PgfUFJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:26:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:26:49 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-6EOH6_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:26:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:26:49 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-qbIv5e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:26:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:26:49 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-kSfZJ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:26:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:26:49 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-UTvjZG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:26:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:26:49 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-6yM4kQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:26:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:26:49 INFO - Running setup.py install for manifestparser 19:26:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:26:49 INFO - Running setup.py install for mozcrash 19:26:50 INFO - Running setup.py install for mozdebug 19:26:50 INFO - Running setup.py install for mozdevice 19:26:50 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:26:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:26:50 INFO - Running setup.py install for mozfile 19:26:50 INFO - Running setup.py install for mozhttpd 19:26:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:26:50 INFO - Running setup.py install for mozinfo 19:26:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:26:50 INFO - Running setup.py install for mozInstall 19:26:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:26:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:26:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:26:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:26:50 INFO - Running setup.py install for mozleak 19:26:51 INFO - Running setup.py install for mozlog 19:26:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:26:51 INFO - Running setup.py install for moznetwork 19:26:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:26:51 INFO - Running setup.py install for mozprocess 19:26:51 INFO - Running setup.py install for mozprofile 19:26:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:26:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:26:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:26:51 INFO - Running setup.py install for mozrunner 19:26:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:26:51 INFO - Running setup.py install for mozscreenshot 19:26:52 INFO - Running setup.py install for moztest 19:26:52 INFO - Running setup.py install for mozversion 19:26:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:26:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:26:52 INFO - Cleaning up... 19:26:52 INFO - Return code: 0 19:26:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:26:52 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')]} 19:26:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:52 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')]} 19:26:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:52 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 0x10ca3ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cad1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbad2da0580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9a0d30>, '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 0x10c705db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c998b20>, '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.o0qYcHQjzC/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.o3MsZuxzVt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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 19:26:52 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 19:26:52 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 19:26:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o0qYcHQjzC/Render', 19:26:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:26:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:26:52 INFO - 'HOME': '/Users/cltbld', 19:26:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:26:52 INFO - 'LOGNAME': 'cltbld', 19:26:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:52 INFO - 'MOZ_NO_REMOTE': '1', 19:26:52 INFO - 'NO_EM_RESTART': '1', 19:26:52 INFO - 'PAGER': '/bin/cat', 19:26:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:26:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:52 INFO - 'PWD': '/builds/slave/test', 19:26:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:26:52 INFO - 'SHELL': '/bin/bash', 19:26:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o3MsZuxzVt/Listeners', 19:26:52 INFO - 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/', 19:26:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:26:52 INFO - 'USER': 'cltbld', 19:26:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:26:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:26:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:52 INFO - 'XPC_FLAGS': '0x0', 19:26:52 INFO - 'XPC_SERVICE_NAME': '0', 19:26:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:26:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:26:52 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-wTbTHT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:26:52 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)) 19:26:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:26:52 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-4BphKH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:26:52 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)) 19:26:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:26:52 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-ZTKQyL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:26:52 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)) 19:26:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:26:52 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-wrzYd5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:26:53 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)) 19:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:26:53 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-t2qHUB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:26:53 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)) 19:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:26:53 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-KKr8mf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:26:53 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)) 19:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:26:53 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-H95I8n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:26:53 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)) 19:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:26:53 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-120X68-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:26:53 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)) 19:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:26:53 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-9U58p0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:26:53 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)) 19:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:26:53 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-JybmJ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:26:53 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)) 19:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:26:53 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-G_v3tR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:26:53 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)) 19:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:26:53 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-w9aOKt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:26:53 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)) 19:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:26:53 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-FaibLi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:26:53 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)) 19:26:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:26:53 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-4fJA4O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:26:54 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)) 19:26:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:26:54 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-2Y31g9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:26:54 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)) 19:26:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:26:54 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-Ufc7ZD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:26:54 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)) 19:26:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:26:54 INFO - Running setup.py (path:/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/pip-U4wJOL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:26:54 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)) 19:26:54 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)) 19:26:54 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)) 19:26:54 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)) 19:26:54 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)) 19:26:54 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)) 19:26:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:26:54 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 19:26:54 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 19:26:54 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 19:26:54 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 19:26:54 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 19:26:54 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 19:26:56 INFO - Downloading blessings-1.6.tar.gz 19:26:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:26:56 INFO - Installing collected packages: blessings 19:26:56 INFO - Running setup.py install for blessings 19:26:57 INFO - Successfully installed blessings 19:26:57 INFO - Cleaning up... 19:26:57 INFO - Return code: 0 19:26:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:26:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:26:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:26:57 INFO - Reading from file tmpfile_stdout 19:26:57 INFO - Current package versions: 19:26:57 INFO - blessings == 1.6 19:26:57 INFO - blobuploader == 1.2.4 19:26:57 INFO - docopt == 0.6.1 19:26:57 INFO - functools32 == 3.2.3-2 19:26:57 INFO - jsonschema == 2.5.1 19:26:57 INFO - manifestparser == 1.1 19:26:57 INFO - mozInstall == 1.12 19:26:57 INFO - mozcrash == 0.17 19:26:57 INFO - mozdebug == 0.1 19:26:57 INFO - mozdevice == 0.48 19:26:57 INFO - mozfile == 1.2 19:26:57 INFO - mozhttpd == 0.7 19:26:57 INFO - mozinfo == 0.9 19:26:57 INFO - mozleak == 0.1 19:26:57 INFO - mozlog == 3.3 19:26:57 INFO - moznetwork == 0.27 19:26:57 INFO - mozprocess == 0.23 19:26:57 INFO - mozprofile == 0.28 19:26:57 INFO - mozrunner == 6.12 19:26:57 INFO - mozscreenshot == 0.1 19:26:57 INFO - mozsystemmonitor == 0.3 19:26:57 INFO - moztest == 0.7 19:26:57 INFO - mozversion == 1.4 19:26:57 INFO - psutil == 3.1.1 19:26:57 INFO - requests == 1.2.3 19:26:57 INFO - wsgiref == 0.1.2 19:26:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:26:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:57 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 0x10ca3ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cad1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbad2da0580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9a0d30>, '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 0x10c705db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c998b20>, '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.o0qYcHQjzC/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.o3MsZuxzVt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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 19:26:57 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 19:26:57 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 19:26:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o0qYcHQjzC/Render', 19:26:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:26:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:26:57 INFO - 'HOME': '/Users/cltbld', 19:26:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:26:57 INFO - 'LOGNAME': 'cltbld', 19:26:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:57 INFO - 'MOZ_NO_REMOTE': '1', 19:26:57 INFO - 'NO_EM_RESTART': '1', 19:26:57 INFO - 'PAGER': '/bin/cat', 19:26:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:26:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:57 INFO - 'PWD': '/builds/slave/test', 19:26:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:26:57 INFO - 'SHELL': '/bin/bash', 19:26:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o3MsZuxzVt/Listeners', 19:26:57 INFO - 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/', 19:26:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:26:57 INFO - 'USER': 'cltbld', 19:26:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:26:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:26:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:57 INFO - 'XPC_FLAGS': '0x0', 19:26:57 INFO - 'XPC_SERVICE_NAME': '0', 19:26:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:26:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:57 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)) 19:26:57 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)) 19:26:57 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)) 19:26:57 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)) 19:26:57 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)) 19:26:57 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)) 19:26:57 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)) 19:26:57 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)) 19:26:57 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)) 19:26:57 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)) 19:26:57 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)) 19:26:57 INFO - Cleaning up... 19:26:57 INFO - Return code: 0 19:26:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:26:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:26:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:57 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 0x10ca3ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cad1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbad2da0580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c9a0d30>, '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 0x10c705db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c998b20>, '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.o0qYcHQjzC/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.o3MsZuxzVt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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 19:26:57 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 19:26:57 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 19:26:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o0qYcHQjzC/Render', 19:26:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:26:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:26:57 INFO - 'HOME': '/Users/cltbld', 19:26:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:26:57 INFO - 'LOGNAME': 'cltbld', 19:26:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:57 INFO - 'MOZ_NO_REMOTE': '1', 19:26:57 INFO - 'NO_EM_RESTART': '1', 19:26:57 INFO - 'PAGER': '/bin/cat', 19:26:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:26:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:57 INFO - 'PWD': '/builds/slave/test', 19:26:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:26:57 INFO - 'SHELL': '/bin/bash', 19:26:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o3MsZuxzVt/Listeners', 19:26:57 INFO - 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/', 19:26:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:26:57 INFO - 'USER': 'cltbld', 19:26:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:26:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:26:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:57 INFO - 'XPC_FLAGS': '0x0', 19:26:57 INFO - 'XPC_SERVICE_NAME': '0', 19:26:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:26:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:26:57 INFO - Cleaning up... 19:26:57 INFO - Return code: 0 19:26:57 INFO - Running post-action listener: _resource_record_post_action 19:26:57 INFO - Running post-action listener: _start_resource_monitoring 19:26:57 INFO - Starting resource monitoring. 19:26:57 INFO - [mozharness: 2016-08-17 02:26:57.965694Z] Finished create-virtualenv step (success) 19:26:57 INFO - [mozharness: 2016-08-17 02:26:57.966688Z] Running install step. 19:26:57 INFO - Running pre-action listener: _resource_record_pre_action 19:26:57 INFO - Running main action method: install 19:26:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:26:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:26:58 INFO - Reading from file tmpfile_stdout 19:26:58 INFO - Detecting whether we're running mozinstall >=1.0... 19:26:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:26:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:26:58 INFO - Reading from file tmpfile_stdout 19:26:58 INFO - Output received: 19:26:58 INFO - Usage: mozinstall [options] installer 19:26:58 INFO - Options: 19:26:58 INFO - -h, --help show this help message and exit 19:26:58 INFO - -d DEST, --destination=DEST 19:26:58 INFO - Directory to install application into. [default: 19:26:58 INFO - "/builds/slave/test"] 19:26:58 INFO - --app=APP Application being installed. [default: firefox] 19:26:58 INFO - mkdir: /builds/slave/test/build/application 19:26:58 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'] 19:26:58 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 19:27:32 INFO - Reading from file tmpfile_stdout 19:27:32 INFO - Output received: 19:27:32 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:27:32 INFO - Running post-action listener: _resource_record_post_action 19:27:32 INFO - [mozharness: 2016-08-17 02:27:32.314197Z] Finished install step (success) 19:27:32 INFO - [mozharness: 2016-08-17 02:27:32.314392Z] Running run-tests step. 19:27:32 INFO - Running pre-action listener: _resource_record_pre_action 19:27:32 INFO - Running main action method: run_tests 19:27:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:27:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:27:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:27:32 INFO - 2016-08-16 19:27:32.329 screenresolution[1916:14384] starting screenresolution argv=screenresolution get 19:27:32 INFO - 2016-08-16 19:27:32.331 screenresolution[1916:14384] Display 0: 1600x1200x32@60 19:27:32 INFO - 2016-08-16 19:27:32.337 screenresolution[1917:14386] starting screenresolution argv=screenresolution list 19:27:32 INFO - Available Modes on Display 0 19:27:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:27:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:27:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:27:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:27:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:27:32 INFO - Intel Iris: 19:27:32 INFO - Chipset Model: Intel Iris 19:27:32 INFO - Type: GPU 19:27:32 INFO - Bus: Built-In 19:27:32 INFO - VRAM (Dynamic, Max): 1536 MB 19:27:32 INFO - Vendor: Intel (0x8086) 19:27:32 INFO - Device ID: 0x0a2e 19:27:32 INFO - Revision ID: 0x0009 19:27:32 INFO - Displays: 19:27:32 INFO - Display: 19:27:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:27:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:27:32 INFO - Display Serial Number: 1600x1200 60 19:27:32 INFO - Main Display: Yes 19:27:32 INFO - Mirror: Off 19:27:32 INFO - Online: Yes 19:27:32 INFO - Rotation: Supported 19:27:32 INFO - Return code: 0 19:27:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:27:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:27:32 INFO - Python 2.7.3 19:27:32 INFO - Return code: 0 19:27:32 INFO - grabbing minidump binary from tooltool 19:27:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:27:32 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 0x10c9a0d30>, '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 0x10c705db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c998b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:27:32 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 19:27:32 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 19:27:32 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 19:27:32 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:27:32 INFO - Return code: 0 19:27:32 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:27:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:27:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:27:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:27:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:27:32 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/cxbgc7tOQMuVWBXcWkgn9g/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0315', '--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 19:27:32 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/cxbgc7tOQMuVWBXcWkgn9g/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0315 --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 19:27:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.o0qYcHQjzC/Render', 19:27:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:27:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:27:32 INFO - 'HOME': '/Users/cltbld', 19:27:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:27:32 INFO - 'LOGNAME': 'cltbld', 19:27:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:27:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:27:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:27:32 INFO - 'MOZ_NO_REMOTE': '1', 19:27:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:27:32 INFO - 'NO_EM_RESTART': '1', 19:27:32 INFO - 'PAGER': '/bin/cat', 19:27:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:27:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:27:32 INFO - 'PWD': '/builds/slave/test', 19:27:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:27:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:27:32 INFO - 'SHELL': '/bin/bash', 19:27:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o3MsZuxzVt/Listeners', 19:27:32 INFO - 'TMPDIR': '/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/', 19:27:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:27:32 INFO - 'USER': 'cltbld', 19:27:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:27:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:27:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:27:32 INFO - 'XPC_FLAGS': '0x0', 19:27:32 INFO - 'XPC_SERVICE_NAME': '0', 19:27:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:27:32 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/cxbgc7tOQMuVWBXcWkgn9g/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0315', '--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 19:27:32 INFO - mozversion application_buildid: 20160816163815 19:27:32 INFO - mozversion application_changeset: 52be5024e34261bcd08d68fda65379ed0ffd363d 19:27:32 INFO - mozversion application_display_name: Nightly 19:27:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:27:32 INFO - mozversion application_name: Firefox 19:27:32 INFO - mozversion application_remotingname: firefox 19:27:32 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:27:32 INFO - mozversion application_vendor: Mozilla 19:27:32 INFO - mozversion application_version: 51.0a1 19:27:32 INFO - mozversion platform_buildid: 20160816163815 19:27:32 INFO - mozversion platform_changeset: 52be5024e34261bcd08d68fda65379ed0ffd363d 19:27:32 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:27:32 INFO - mozversion platform_version: 51.0a1 19:27:32 INFO - using testdate: 1471400852 19:27:32 INFO - actual date: 1471400852 19:27:32 INFO - starting webserver on 'localhost:49426' 19:27:32 INFO - SUITE-START | Running 2 tests 19:27:32 INFO - TEST-START | dromaeo_css 19:27:32 INFO - operating with platform_type : mac_ 19:27:32 INFO - Initialising browser for dromaeo_css test... 19:27:32 INFO - TEST-INFO | started process 1927 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/tmpXB9HlR/profile http://localhost:49426/getInfo.html) 19:27:35 INFO - PROCESS | 1927 | __metrics Screen width/height:1600/1200 19:27:35 INFO - PROCESS | 1927 | colorDepth:24 19:27:35 INFO - PROCESS | 1927 | Browser inner width/height: 1024/689 19:27:35 INFO - PROCESS | 1927 | __metrics 19:27:35 INFO - PROCESS | 1927 | 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" 19:27:35 INFO - PROCESS | 1927 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:27:36 INFO - TEST-INFO | 1927: exit 0 19:27:36 INFO - Browser initialized. 19:27:36 INFO - Running cycle 1/1 for dromaeo_css test... 19:27:36 INFO - TEST-INFO | started process 1929 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/tmpXB9HlR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:27:48 INFO - PROCESS | 1929 | RSS: Main: 228237312 19:27:48 INFO - PROCESS | 1929 | 19:28:39 INFO - PROCESS | 1929 | 1471400919164 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] 19:30:51 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49426/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49426/tests/dromaeo/cssquery-ext.html) 19:30:52 INFO - PROCESS | 1929 | RSS: Main: 337424384 19:30:52 INFO - PROCESS | 1929 | 19:33:55 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49426/tests/dromaeo/cssquery-ext.html (next: http://localhost:49426/tests/dromaeo/cssquery-jquery.html) 19:33:56 INFO - PROCESS | 1929 | RSS: Main: 368730112 19:33:56 INFO - PROCESS | 1929 | 19:36:58 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49426/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49426/tests/dromaeo/cssquery-mootools.html) 19:36:59 INFO - PROCESS | 1929 | RSS: Main: 331362304 19:36:59 INFO - PROCESS | 1929 | 19:40:01 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49426/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49426/tests/dromaeo/cssquery-prototype.html) 19:40:02 INFO - PROCESS | 1929 | RSS: Main: 343076864 19:40:02 INFO - PROCESS | 1929 | 19:43:05 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49426/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49426/tests/dromaeo/cssquery-yui.html) 19:43:06 INFO - PROCESS | 1929 | RSS: Main: 330698752 19:43:06 INFO - PROCESS | 1929 | 19:46:09 INFO - PROCESS | 1929 | Cycle 1(1): loaded http://localhost:49426/tests/dromaeo/cssquery-yui.html (next: http://localhost:49426/tests/dromaeo/cssquery-dojo.html) 19:46:09 INFO - PROCESS | 1929 | RSS: Main: 388431872 19:46:09 INFO - PROCESS | 1929 | 19:46:09 INFO - PROCESS | 1929 | __start_tp_report 19:46:09 INFO - PROCESS | 1929 | _x_x_mozilla_page_load 19:46:09 INFO - PROCESS | 1929 | _x_x_mozilla_page_load_details 19:46:09 INFO - PROCESS | 1929 | |i|pagename|runs| 19:46:09 INFO - PROCESS | 1929 | |0;dojo.html;3317.8838740644073;3338.248893998849;3356.0267522418508;3359.916002099949;3393.2025973896148;5515;5613.635113717605;5623.606347555673;5673.092305231164;5682.488576028157;5549.417311182328;5639.210510528529;5700.543956483481;5720.570957178207;5733.455321744438;5092.2107073403495;5105.693658380491;5172;5188.507091826271;5209.661372010826;5180;5192.299039629658;5229.529342359187;5232.973835130819;5244.501772331622;5012.874678133076;5076.796928122871;5083.415407228179;5121.692698438068;5149.999999999981;5082.822101226477;5116.641835071547;5184.351956005499;5200.349956255468;5255.973720131413;5072.746362681851;5091.287219789232;5137.48625137487;5148.819791307286;5199.818006369759;5629.268195134648;5640.2667653205235;5679.460451257165;5693.430656934315;5712.771489140429;5987.550933679991;6089.147519347251;6149.877002459971;6152.507799376039;6218.782342618032;6215.098810672427;6226.62640241587;6262.2172228471445;6266.592671476339;6290.339514350973;5582.748776305076;5776.740046697867;5777.566682498829;5787.508061814793;5793.623414478046;6231.937680623227;6252.155958945484;6291.9685401573615;6319.115323854664;6328.367163283635;6091.907563486476;6092.859244776726;6097.664628445389;6107.918501380683;6108.175396321529;6229.3770622937345;6234.812955611339;6234.875302494015;6237.09562113491;6252.280987686474;5895.145203945404;6044;6056.878862422815;6123.191113103742;6142.989934931851;6148.415900489446;6208.068789681584;6210.782622608187;6218.347073557211;6228.4705800007505;4854.101991131582;4864.391951006124;4872.853814385574;4876.634252431082;4906.58294045003;4318.5681431856565;4321.870343889688;4325.070109926381;4416.734995900256;4419.447569053868;5657.858553536194;5674.631148975386;5682.488576028178;5695.974724549621;5718.1136923776885;6276.246850377984;6276.497880169575;6278.748850046039;6288.465480434214;6297.149884765589;5513.393526712139;5549.250851135126;5584.246126772915;5651.152327150878;5667.008273552112;5334.11987022137;5352.759125839347;5354.41101478837;5360.276362691065;5397.568194544427;6247.562670613104;6268.968655156695;6275.6862156892885;6278.46633036197;6290.874182516415;6045.727942242697;6066.545009124245;6149.139120523041;6202.193714817045;6270.874582508233;6287.779927702509;6304.464120549621;6313.873722525615;6314.431701146918;6357.4596159326975;5994.580379373402;6025.216721826135;6028.125921741411;6031.487323577545;6069.393060693896;5481.232627432083;5781.756922261734;6061.424164704346;6138.447539721446;6146.293176284606;5942.524598032061;5994.820155395345;6023.36754640774;6040.426159514839;6124.203853499017;6005.549583781147;6015.518758499397;6025.337212906489;6037.63774173551;6043.667598281961;5687.829365119053;5798.159266906359;5879.176915231957;5897.085951677497;5929.822105336847;5993.101034844808;6060.545459090497;6088.086786981811;6113.419225173601;6114.816555503342;5838.678872662044;5865.472107510344;5882.411758824083;5886.470217680429;5901.085331773582 19:46:09 INFO - PROCESS | 1929 | |1;ext.html;466.42163716990933;530.8752443175854;534.6204195021535;535.8258565966062;555.289229785874;30870.382592348138;39783.80108099459;41048;41074.58925410741;42633.360499592454;41677;41959.79020104895;42008;42101.36847947283;42120.78939605306;9666.758331041745;9839.90160098397;9843.114119729224;9866.963968783282;9870.753231169207;8655.177758113;8863.689770858022;11874.16880818343;12118.575849845258;12141.514339426412;11975.982041526437;12054.15620906537;12072.336021518813;12083.033357331436;12144.939275303612;12051.698707532294;12154.87845121555;12164.939175304155;12166.330851803148;12185.87814121865;12102.878971210263;12153.574624888086;12165.20926139798;12173.208741431867;12174.452149653287;39558.60441395578;39733.40399894004;39759.20481590352;39828.402573961415;39863.60136398628;3347.815870127142;3389.830508474591;3593.389123848952;3659.5791483979415;3965.1871366369824;30943;30951.535726964114;31002.84498577515;31963.84018079918;32421.351572968408;3100.635720283068;3245.8863939761995;3259.168911927467;3275.96724032759;3375.2236985493228;525261;526880;528984.9999999962;530450;531136;60456.69771651114;61183.694081529306;61243;61248.69375653182;61288;34995;36156.45765313522;63564;63573.68213158905;63728.68135659292;13536.323183840768;15684.137372444626;16003.599910002344;16134.273957671932;16260.430884918971;11648.767024659459;11866.110041746904;12334.259944403186;12454.688632784071;12459.750804983849;16693.749593756103;16797.160141993096;16808.495745127664;18309.3591724289;19000.61998760017;7616.428767842434;7722.575258360844;7748.186440423787;7804.336631386395;7841.49072296871;834.6911642692121;886.7561420609255;938.4885237545554;1063.9202059845388;1075.4783929794044;25445.87277063603;29079.418411632072;30101.849490752407;30380.54429183564;30543.14624851979;1042.4370839746452;1106.1537923488538;1110.9111271098293;1151.6008050219025;1178.457909361686;14639.341229644691;15673.902826802014;15772.526824195293;15903.602409939613;15911.840881591268;643354;651142;651481;651910;652821;57179.14231286534;57608;57634.13548796772;57666;57690.711546442;1074.3500182389698;1305.6344223617402;1361.3873756809674;1394.1147371419222;1404.1154072933987;26060.8696956514;29510.704892950795;30821.845890770404;30945.226369340948;31200.21999450032;48116.518834811206;48675;48678.75660621674;48810.5118948806;48836.2674559889;460946;462364;465071;466413;466512;26452.603210951467;26839.731602684504;27347;27383.178504644893;28344.43311133807;46586.53413465822;46855.53144468512;47399.052018960116;47656;47709.284360733924;38518;38660.6133938657;38682.80658596689;40262;40363.798181008904;38381.040473987254;38447.0388240285;39136.608633913296;40307.79846100751;40368.798156009034;37953;38404.84785456441;38804.41793373158;38863.805680972546;38885.41671874981;32894.835525822215;33908.15229619279;34355.7242087733;34451.82774086113;34611.48082778811;23760.049598016576;24066.037358506164;24253.63619545673;24340;24486.87756561206 19:46:09 INFO - PROCESS | 1929 | |2;jquery.html;345.48848786558807;351.8012323037485;368.54852805313493;376.9170782427866;402.4044414266885;16275.75586366204;16716.582085447855;20665.586688266227;20993.950302484893;21105.894470527644;18691.158897849597;20499.180032798708;21012.579748405024;21014.57970840582;21031.36905892822;5257.447967963366;5380.704061276629;5415.323084614413;5518.889622207554;5524.364698059714;3889;5359.946400535984;5446.2375267462585;5452.536534394561;5454.91817622734;5857.3849745776715;6122.510199184077;6142.785002524913;6185.8144255672405;6222.191115155046;5870.26621672291;6085.847853803646;6166.537509686791;6174.289956654975;6181.227346581677;5945.672987985659;5950.256217972754;5951.940480595182;5952.880942381172;5967.1347654589845;13505.932470337586;16737;19258.422247332463;25408.745912541006;25723.74276257232;2150.387139665188;2217.9556408871895;2417.8791060446906;2495.513374891897;2790.14900455361;9880.851787223197;10422.739431514197;10513.684589462327;15545.067295962279;16048.919755401148;1675.2126500544725;1743.5466778637644;1871.953201169968;1917.7986311437237;1936.7966363531766;106682.46658766811;115728;116502.41748791287;117643;120658;11037.668869933916;11236.988671019648;11491;11539.365334906493;11743.354115523729;14578.781318280235;14581.781273280909;14591.124532528082;14597.489087881873;14622.341994610268;7750.108737495148;8194.30348420379;8438.36712246584;8469.576521173916;8615.009273933452;7648.847023059508;8338.708145214889;8340.29107525867;8519.062903080656;8545.914540854636;3720.3489389356755;4461.420015398043;4539.682222244412;4568.132054909556;4656.3713898623255;1615.2166199393284;1755.0698129991124;1859.2284202056267;1892.7539419875327;1897.7438045863632;786.3944762532818;907.5734404829719;946.6639343033124;952.538019060755;986.7681094942683;20855.1657933684;21411.143554257655;21456.05710184235;21586.35240942774;21821.345359639236;868.4281450574481;913.0686699566405;945.4091193004373;1004.231762701534;1021.5811517278029;9521.39050749542;10846.945765271123;11054.834177487344;11095.778084438267;11499.310041397543;492931;510961;511041;522203.38898305263;532740;124654.37672811578;125317.37341313418;126605;127410.36294818467;131177;960.3421656165689;970.9368891022061;993.4138858073627;1021.5045702834119;1021.9386836789816;18617.813821861608;19092.140853661895;19132.52168695738;19163.904180479007;19249.941253231205;52912.735436322575;53193.73403132959;54348.64128396664;54654.906901860944;55037;78354.6082269585;80132;81388;81483.18516814992;84351;8006.599670016592;8103.918960810316;8276.875846862422;8292.12932642064;8428.662853485792;12438.875611243771;12950.222986620833;13247.40386682621;13529.255890926093;13598.93200533991;10546.630867919586;11356.318620882774;11677.416129193676;11827.704307392383;11877.881221187678;11257.21199516026;11293.830592540955;11544.88455115438;11568.492535973604;11612.361320127136;11510.59712910044;11573.074154067486;11728.765424691288;11908.047356211655;11992.700182495508;7123.82190445243;7148.28517148281;7385.409167266714;7405.407567394704;8295.087540370434;9391.403461411439;11051.226414151253;11506.367149806842;11636.650900473;11774.52901883915 19:46:09 INFO - PROCESS | 1929 | |3;mootools.html;1441.7332793433216;1511.735446296898;1620.5138458462466;1817.2095138614711;1833.651606194823;8050.516968981865;8384.664613415463;8585.441946273488;8953.23897468715;8961.327900407463;7389.5196812207305;7420.777376678695;8485.575721213945;8658.696945606906;8679.609417576225;2354.7409784923643;2384.3443053160345;2387.55830171418;2393.7366889642126;2395.8682272475007;2352.12971200656;2371.727251366098;2392.6650268962358;2402.651615515749;2410.8794560272004;2382.4996750682376;2387.140629373424;2393.3418309964813;2399.7120345558556;2403.2069417092316;2369.3602727263715;2379.1910750344878;2380.5357955198742;2382.2614989353356;2386.809055275574;2021.2824447321284;2372.810175185981;2375.3111597636666;2377.239283429303;2380.1193558383334;8935.64257429702;8976.551172441352;9052.049534798816;9065.728028159167;9100.954495227481;5206.453322401131;5342.626016178869;5372.758225879845;5384.138537833927;5416.702081385522;2878.8992385266415;3540.716742660581;3651.269746050774;3665.8533658653623;3701.5373078365205;1092.554863207574;1094.7099018760034;1104.7017305327518;1125.5835340923911;1126.8591426071741;11604.709882253012;11681.123915706356;11816.940915295369;11836.467358968866;11998.160128791076;4596.931046034312;4702.741349225826;4794.089123066607;4851.781669824796;4875.9512404875495;9891.109800118023;10007.249456290661;10042.899571004342;10120.038596333483;10145.442000689885;5412.07994640912;5415;5430.402655707869;5448.101063324586;5453.4273901240595;4172.624463798273;4436.157130145262;4574;4947.233178857283;4964.553190212898;3953.486046813953;3953.7034722395933;4038.353863381845;4066.9186616267507;4077.796110194538;1299.8050292456207;1308.4439113376777;1309.4531136938563;1325.7282257137263;1328.8173525562258;893.8793262909554;924.7087167542203;932.9207017403596;933.2813733425224;949.8907625623142;5743.282089738783;5901.374292643485;5970.791022314198;6057.969710151509;6209.658468784261;416.98048271975335;436.4130244820711;440.7685964868406;447.1704987248672;454.9818007279672;3946.861859834892;3975.6223158799867;4037.7375470594907;4056.229316429869;4116.073883376216;10457.215708821868;10788.190885683449;10830.94584527088;10974.561017559368;11036.172287078502;8281;10679.038886500251;10805.243632945618;10871.83692244617;11063.446827658585;8046.436749427484;8228.547429891294;8304.211099945496;8331.54176520285;8369.372297077743;5702.629329093762;5795.420457954339;5873.207117039145;5906.320773111147;5951.672658003678;9880.308378413441;10048.598056077675;10152.54313555877;10157.898421015696;10235.130013948898;9344.813103738023;9569.665061722806;9939.950300248453;9965.850512242178;10355.326903751233;6780.254172040973;7294.343509083996;7619.809504762426;7644.235576442311;7687.577183254974;8366.372522060748;8453.78865528342;8497.405181637227;8506.532140732319;8622.655093796178;7554.2823431773895;7612.733554325572;7640.197779233101;7641.235876412315;7682;7431.219721929119;7437.851242975218;7459.328660420588;7496.58768767723;7512.399008079231;6999.755008574675;7003.3346832050875;7101.786946391617;7116.928830711834;7149.85700285981;4898.093852637274;4979.278004689228;5025.874353141201;5028.748562571784;5235.031519168966;4998.6750861193905;5084.440711521655;5090.490950905028;5105.923411148761;5220 19:46:09 INFO - PROCESS | 1929 | |4;prototype.html;297.47792623944974;303.8040463900784;313.65654608204017;316.0660248964311;316.68173485646935;4668.813247470097;5346.812861549846;5374.865628359288;5386.38760201302;5408.080626293529;4316.9568304316945;4556.977215113928;5284.867878303035;5298.1522956327;5311.468853114687;3336.4652259960326;3892.4624773214564;3923.6664883484937;4027.295223335912;4066.877993660195;3180.8568614412293;3372.780769249991;3844.5578758442857;4084.448599439082;4098.4671992640915;3873.8644147454843;4070.4911886014247;4079.4492743479695;4092.9590704093025;4103.4870641169855;3144.4340018796606;3754.5119134512474;3979.7015223858325;3988.282109220339;4046.696497762665;3012.9096127116222;3324.2188085799817;3402.1664692150334;3486.843092060838;3491.5810102787827;4244.72409293395;4679.9704065105625;5143.459936706629;5184.559312458446;5185.796036684432;3785.432185172219;4017.4174744662155;4330.783460826978;4584.908301833978;4607.815687372501;5571.13647384656;5604.982206405691;5614.6631202128;5627.6060675752715;5646.435356464362;3832.9041773955596;3840.327942610032;3864.95646175531;3866.2074274773604;3911.6870650347905;5738.68437235956;5933.673647949361;5951.702414879239;5965.642061476282;5967.015442451991;3340.248444100058;3405.506201600754;3604.4593311003036;3844.4233364994916;3932.9745181231688;3962.2471730371135;3971.543272523697;3972.801359931992;3982.4623675803973;4005.759654420686;4644.535546445396;4645.907081858344;4650.790714417859;4653.836540864784;4659.347691323217;5475.425080366584;5658.528782516575;5682.374938756733;5688.459596338341;5696.886062278814;1057.1014637558014;1303.9543615973585;1308.5420102963888;1335.052112999781;1336.4988129451456;769.9345555627834;874.7681864305964;906.8775715278485;911.64901512918;913.59345091435;5180.481951804864;5185.51095474167;5193.039967218725;5206.375234971828;5220.580519759487;4598.586127248564;5082.364704411949;5676.403977582295;5720.456556627114;5722.4277572242445;4095.6245377680875;4215.163232342996;4988.5260900214425;5038.874028149326;5212.322398088224;2063.473814177375;2193.890305484719;2557.8721063946728;2559.308986573652;2567.7945764338806;4685.508021657745;4722.929156062662;4850.563449289581;4980.800767969241;5012.448630650625;4389.473263208435;4425.955740442619;4520.81916723334;4603.859205469473;4736.939431347375;4257.5529569396;4282.5075116361395;4422.921227023358;4978.004399120118;5077.898442031139;4306.397104405449;4341.630961368319;4673.224174813549;4701;4830.6135509159885;3997.360422332529;4030.818613162356;4745.928811067768;4747.548982846624;4757.762111894461;4529.900440101713;4554.908901822011;4658.487566367629;4985.700857948535;4989.927165659401;3044.6965042927786;3093.8917137900066;3257.4625186843905;3262.5758651375168;3370.893191755153;4145.54399016102;4154.317501411933;4855.086947831273;4923.606111511142;5408.621396502207;4564.748938808398;5356.100335816594;5412.538614573964;5518.448155184449;5595.916061259003;4880.853574392774;5499.725013749377;5547.861303467445;5550.250716153268;5599.984005757842;5455.454454554513;5461.804844166208;5469.781208751605;5510.197963405238;5523.309586301712;4172.749635021908;4425.358323043077;5382.354117505768;5521.282233309644;5592.406164243172;4580.625812256377;5558.861028474159;5569.721513924369;5581.878886023285;5604.607677462538 19:46:09 INFO - PROCESS | 1929 | |5;yui.html;735.9300866417689;736.3262614707543;763.5304287862963;786.0999155966418;803.9508441483863;15704.214789260534;16452.42416515422;16487.928284661488;16504.83495165048;16534.421295254655;14856.628584285398;14957.252137393114;14991.625209369771;14998.550043498684;15006.174660393674;676.4351766275166;685.7428464325877;694.6353164588581;825.7192554531458;852.4033176776251;701.316216688727;828.0932379044937;834.9081601023883;837.0708513549955;838.1032295443878;894.1371576428745;894.6734441928681;895.2166854002747;896.3770179725094;899.5322432335182;888.2760550151605;890.1410139215656;890.470170248701;890.5903284489143;891.4740303221066;530.875244317585;647.8412788866758;665.3313420012904;665.933406659335;666.4468491152332;14895.553133406016;14897;14912.701745965129;14917.179555124572;14945.626359340995;13770.65573360664;14276.57170284893;14300.570982870426;14356.856431435657;14577.48978785748;12864.549740759123;12905.935470322682;12998.415071321813;13026.47894084245;13037.86962130376;4541.137183935041;4665.836695715668;4674.387336368947;4697.318888761145;4780.689255198401;69394;101645;107364;117901.82098178995;118259;11096.944515277371;11277.605283815026;11337.886621133683;11533.423328833525;11806.822897656542;22583.88708056449;22690.978905949272;22714;22716;22775.772242277366;8008.79978000543;8015.919840801517;8029.638666260033;8272.296854767292;8275.751727448187;4399;4457.487388950248;4471.932921006188;4591.334256532784;4626.97686511572;3538.7699799512948;3620.909477263037;3638.8544458221904;3681.1349332906743;3687.3547129252274;564.632988557441;665.8335416145964;666.1573110015834;673.0879658063352;678.4362045485204;516.3261943164132;598.86525531755;599.9280086389574;627.7018416252243;635.3650122357413;8742.475451472805;8899.199072083544;8917.077428120934;9292.983662769293;9293.488858112869;604.7278724573871;617.6232498176109;630.9652969086744;632.7816903168399;641.4868105515569;4245.639120674716;4481.708688935209;4490.281554951257;4492.37106805041;4529.501754806968;87538;129356.41287174204;133479;161348;163497;135088.95446669817;139370.3031484836;142619;145184.5481545171;146678.26660866628;98273;117185;118656;121046.78953210355;122503.3874830638;8567.100454452442;8613.956930215309;8631.91368086311;8783.912160878564;8992.190702836777;50208;56892.71553642205;57501.712491437276;58102;59666.70166649313;86474;92433;96324;97128.05743885224;100043.49978250063;11693.590724324607;11810.527578897092;11811.350375729417;11818.231814932346;12234.143609947218;11896.76206475883;12000.939995299968;12054.638360849189;12491.93754031224;12534.373281336078;11767.646970590897;11817.054635629302;11866.288022718665;11987.22083064598;12143.939280303543;11849.170558061198;11878.465469054086;12007.219530730474;12026.699332516408;12062.75874482488;11699.649010529698;11737.530498779954;11760.58837940668;11858.525658973544;11883.940580297043;8880.978687450768;8975.865362019706;9787.265955053543;9903.653372131941;9936.304458687822;7449.478536502393;8434.987801463863;9042.547872606476;9889.406635601887;10167 19:46:09 INFO - PROCESS | 1929 | __end_tp_report 19:46:09 INFO - PROCESS | 1929 | __start_cc_report 19:46:09 INFO - PROCESS | 1929 | _x_x_mozilla_cycle_collect,1244 19:46:09 INFO - PROCESS | 1929 | __end_cc_report 19:46:09 INFO - PROCESS | 1929 | __startTimestamp1471401969673__endTimestamp 19:46:09 INFO - PROCESS | 1929 | 19:46:09 INFO - PROCESS | 1929 | ------- Summary: start ------- 19:46:09 INFO - PROCESS | 1929 | Number of tests: 6 19:46:09 INFO - PROCESS | 1929 | 19:46:09 INFO - PROCESS | 1929 | [#0] dojo.html Cycles:165 Average:5689.25 Median:5882.41 stddev:634.97 (10.8%) stddev-sans-first:609.06 19:46:09 INFO - PROCESS | 1929 | Values: 3317.9 3338.2 3356.0 3359.9 3393.2 5515.0 5613.6 5623.6 5673.1 5682.5 5549.4 5639.2 5700.5 5720.6 5733.5 5092.2 5105.7 5172.0 5188.5 5209.7 5180.0 5192.3 5229.5 5233.0 5244.5 5012.9 5076.8 5083.4 5121.7 5150.0 5082.8 5116.6 5184.4 5200.3 5256.0 5072.7 5091.3 5137.5 5148.8 5199.8 5629.3 5640.3 5679.5 5693.4 5712.8 5987.6 6089.1 6149.9 6152.5 6218.8 6215.1 6226.6 6262.2 6266.6 6290.3 5582.7 5776.7 5777.6 5787.5 5793.6 6231.9 6252.2 6292.0 6319.1 6328.4 6091.9 6092.9 6097.7 6107.9 6108.2 6229.4 6234.8 6234.9 6237.1 6252.3 5895.1 6044.0 6056.9 6123.2 6143.0 6148.4 6208.1 6210.8 6218.3 6228.5 4854.1 4864.4 4872.9 4876.6 4906.6 4318.6 4321.9 4325.1 4416.7 4419.4 5657.9 5674.6 5682.5 5696.0 5718.1 6276.2 6276.5 6278.7 6288.5 6297.1 5513.4 5549.3 5584.2 5651.2 5667.0 5334.1 5352.8 5354.4 5360.3 5397.6 6247.6 6269.0 6275.7 6278.5 6290.9 6045.7 6066.5 6149.1 6202.2 6270.9 6287.8 6304.5 6313.9 6314.4 6357.5 5994.6 6025.2 6028.1 6031.5 6069.4 5481.2 5781.8 6061.4 6138.4 6146.3 5942.5 5994.8 6023.4 6040.4 6124.2 6005.5 6015.5 6025.3 6037.6 6043.7 5687.8 5798.2 5879.2 5897.1 5929.8 5993.1 6060.5 6088.1 6113.4 6114.8 5838.7 5865.5 5882.4 5886.5 5901.1 19:46:09 INFO - PROCESS | 1929 | 19:46:09 INFO - PROCESS | 1929 | [#1] ext.html Cycles:180 Average:68328.60 Median:19908.02 stddev:147650.77 (741.7%) stddev-sans-first:147977.06 19:46:09 INFO - PROCESS | 1929 | Values: 466.4 530.9 534.6 535.8 555.3 30870.4 39783.8 41048.0 41074.6 42633.4 41677.0 41959.8 42008.0 42101.4 42120.8 9666.8 9839.9 9843.1 9867.0 9870.8 8655.2 8863.7 11874.2 12118.6 12141.5 11976.0 12054.2 12072.3 12083.0 12144.9 12051.7 12154.9 12164.9 12166.3 12185.9 12102.9 12153.6 12165.2 12173.2 12174.5 39558.6 39733.4 39759.2 39828.4 39863.6 3347.8 3389.8 3593.4 3659.6 3965.2 30943.0 30951.5 31002.8 31963.8 32421.4 3100.6 3245.9 3259.2 3276.0 3375.2 525261.0 526880.0 528985.0 530450.0 531136.0 60456.7 61183.7 61243.0 61248.7 61288.0 34995.0 36156.5 63564.0 63573.7 63728.7 13536.3 15684.1 16003.6 16134.3 16260.4 11648.8 11866.1 12334.3 12454.7 12459.8 16693.7 16797.2 16808.5 18309.4 19000.6 7616.4 7722.6 7748.2 7804.3 7841.5 834.7 886.8 938.5 1063.9 1075.5 25445.9 29079.4 30101.8 30380.5 30543.1 1042.4 1106.2 1110.9 1151.6 1178.5 14639.3 15673.9 15772.5 15903.6 15911.8 643354.0 651142.0 651481.0 651910.0 652821.0 57179.1 57608.0 57634.1 57666.0 57690.7 1074.4 1305.6 1361.4 1394.1 1404.1 26060.9 29510.7 30821.8 30945.2 31200.2 48116.5 48675.0 48678.8 48810.5 48836.3 460946.0 462364.0 465071.0 466413.0 466512.0 26452.6 26839.7 27347.0 27383.2 28344.4 46586.5 46855.5 47399.1 47656.0 47709.3 38518.0 38660.6 38682.8 40262.0 40363.8 38381.0 38447.0 39136.6 40307.8 40368.8 37953.0 38404.8 38804.4 38863.8 38885.4 32894.8 33908.2 34355.7 34451.8 34611.5 23760.0 24066.0 24253.6 24340.0 24486.9 19:46:09 INFO - PROCESS | 1929 | 19:46:09 INFO - PROCESS | 1929 | [#2] jquery.html Cycles:180 Average:32628.44 Median:2317.92 stddev:86657.89 (3738.6%) stddev-sans-first:86867.08 19:46:09 INFO - PROCESS | 1929 | Values: 345.5 351.8 368.5 376.9 402.4 16275.8 16716.6 20665.6 20994.0 21105.9 18691.2 20499.2 21012.6 21014.6 21031.4 5257.4 5380.7 5415.3 5518.9 5524.4 3889.0 5359.9 5446.2 5452.5 5454.9 5857.4 6122.5 6142.8 6185.8 6222.2 5870.3 6085.8 6166.5 6174.3 6181.2 5945.7 5950.3 5951.9 5952.9 5967.1 13505.9 16737.0 19258.4 25408.7 25723.7 2150.4 2218.0 2417.9 2495.5 2790.1 9880.9 10422.7 10513.7 15545.1 16048.9 1675.2 1743.5 1872.0 1917.8 1936.8 106682.5 115728.0 116502.4 117643.0 120658.0 11037.7 11237.0 11491.0 11539.4 11743.4 14578.8 14581.8 14591.1 14597.5 14622.3 7750.1 8194.3 8438.4 8469.6 8615.0 7648.8 8338.7 8340.3 8519.1 8545.9 3720.3 4461.4 4539.7 4568.1 4656.4 1615.2 1755.1 1859.2 1892.8 1897.7 786.4 907.6 946.7 952.5 986.8 20855.2 21411.1 21456.1 21586.4 21821.3 868.4 913.1 945.4 1004.2 1021.6 9521.4 10846.9 11054.8 11095.8 11499.3 492931.0 510961.0 511041.0 522203.4 532740.0 124654.4 125317.4 126605.0 127410.4 131177.0 960.3 970.9 993.4 1021.5 1021.9 18617.8 19092.1 19132.5 19163.9 19249.9 52912.7 53193.7 54348.6 54654.9 55037.0 78354.6 80132.0 81388.0 81483.2 84351.0 8006.6 8103.9 8276.9 8292.1 8428.7 12438.9 12950.2 13247.4 13529.3 13598.9 10546.6 11356.3 11677.4 11827.7 11877.9 11257.2 11293.8 11544.9 11568.5 11612.4 11510.6 11573.1 11728.8 11908.0 11992.7 7123.8 7148.3 7385.4 7405.4 8295.1 9391.4 11051.2 11506.4 11636.7 11774.5 19:46:09 INFO - PROCESS | 1929 | 19:46:09 INFO - PROCESS | 1929 | [#3] mootools.html Cycles:180 Average:5719.57 Median:4887.02 stddev:3214.02 (65.8%) stddev-sans-first:3206.95 19:46:09 INFO - PROCESS | 1929 | Values: 1441.7 1511.7 1620.5 1817.2 1833.7 8050.5 8384.7 8585.4 8953.2 8961.3 7389.5 7420.8 8485.6 8658.7 8679.6 2354.7 2384.3 2387.6 2393.7 2395.9 2352.1 2371.7 2392.7 2402.7 2410.9 2382.5 2387.1 2393.3 2399.7 2403.2 2369.4 2379.2 2380.5 2382.3 2386.8 2021.3 2372.8 2375.3 2377.2 2380.1 8935.6 8976.6 9052.0 9065.7 9101.0 5206.5 5342.6 5372.8 5384.1 5416.7 2878.9 3540.7 3651.3 3665.9 3701.5 1092.6 1094.7 1104.7 1125.6 1126.9 11604.7 11681.1 11816.9 11836.5 11998.2 4596.9 4702.7 4794.1 4851.8 4876.0 9891.1 10007.2 10042.9 10120.0 10145.4 5412.1 5415.0 5430.4 5448.1 5453.4 4172.6 4436.2 4574.0 4947.2 4964.6 3953.5 3953.7 4038.4 4066.9 4077.8 1299.8 1308.4 1309.5 1325.7 1328.8 893.9 924.7 932.9 933.3 949.9 5743.3 5901.4 5970.8 6058.0 6209.7 417.0 436.4 440.8 447.2 455.0 3946.9 3975.6 4037.7 4056.2 4116.1 10457.2 10788.2 10830.9 10974.6 11036.2 8281.0 10679.0 10805.2 10871.8 11063.4 8046.4 8228.5 8304.2 8331.5 8369.4 5702.6 5795.4 5873.2 5906.3 5951.7 9880.3 10048.6 10152.5 10157.9 10235.1 9344.8 9569.7 9940.0 9965.9 10355.3 6780.3 7294.3 7619.8 7644.2 7687.6 8366.4 8453.8 8497.4 8506.5 8622.7 7554.3 7612.7 7640.2 7641.2 7682.0 7431.2 7437.9 7459.3 7496.6 7512.4 6999.8 7003.3 7101.8 7116.9 7149.9 4898.1 4979.3 5025.9 5028.7 5235.0 4998.7 5084.4 5090.5 5105.9 5220.0 19:46:09 INFO - PROCESS | 1929 | 19:46:09 INFO - PROCESS | 1929 | [#4] prototype.html Cycles:180 Average:4270.06 Median:4645.22 stddev:1328.34 (28.6%) stddev-sans-first:1298.17 19:46:09 INFO - PROCESS | 1929 | Values: 297.5 303.8 313.7 316.1 316.7 4668.8 5346.8 5374.9 5386.4 5408.1 4317.0 4557.0 5284.9 5298.2 5311.5 3336.5 3892.5 3923.7 4027.3 4066.9 3180.9 3372.8 3844.6 4084.4 4098.5 3873.9 4070.5 4079.4 4093.0 4103.5 3144.4 3754.5 3979.7 3988.3 4046.7 3012.9 3324.2 3402.2 3486.8 3491.6 4244.7 4680.0 5143.5 5184.6 5185.8 3785.4 4017.4 4330.8 4584.9 4607.8 5571.1 5605.0 5614.7 5627.6 5646.4 3832.9 3840.3 3865.0 3866.2 3911.7 5738.7 5933.7 5951.7 5965.6 5967.0 3340.2 3405.5 3604.5 3844.4 3933.0 3962.2 3971.5 3972.8 3982.5 4005.8 4644.5 4645.9 4650.8 4653.8 4659.3 5475.4 5658.5 5682.4 5688.5 5696.9 1057.1 1304.0 1308.5 1335.1 1336.5 769.9 874.8 906.9 911.6 913.6 5180.5 5185.5 5193.0 5206.4 5220.6 4598.6 5082.4 5676.4 5720.5 5722.4 4095.6 4215.2 4988.5 5038.9 5212.3 2063.5 2193.9 2557.9 2559.3 2567.8 4685.5 4722.9 4850.6 4980.8 5012.4 4389.5 4426.0 4520.8 4603.9 4736.9 4257.6 4282.5 4422.9 4978.0 5077.9 4306.4 4341.6 4673.2 4701.0 4830.6 3997.4 4030.8 4745.9 4747.5 4757.8 4529.9 4554.9 4658.5 4985.7 4989.9 3044.7 3093.9 3257.5 3262.6 3370.9 4145.5 4154.3 4855.1 4923.6 5408.6 4564.7 5356.1 5412.5 5518.4 5595.9 4880.9 5499.7 5547.9 5550.3 5600.0 5455.5 5461.8 5469.8 5510.2 5523.3 4172.7 4425.4 5382.4 5521.3 5592.4 4580.6 5558.9 5569.7 5581.9 5604.6 19:46:09 INFO - PROCESS | 1929 | 19:46:09 INFO - PROCESS | 1929 | [#5] yui.html Cycles:180 Average:24493.02 Median:4510.94 stddev:39876.34 (884.0%) stddev-sans-first:39948.30 19:46:09 INFO - PROCESS | 1929 | Values: 735.9 736.3 763.5 786.1 804.0 15704.2 16452.4 16487.9 16504.8 16534.4 14856.6 14957.3 14991.6 14998.6 15006.2 676.4 685.7 694.6 825.7 852.4 701.3 828.1 834.9 837.1 838.1 894.1 894.7 895.2 896.4 899.5 888.3 890.1 890.5 890.6 891.5 530.9 647.8 665.3 665.9 666.4 14895.6 14897.0 14912.7 14917.2 14945.6 13770.7 14276.6 14300.6 14356.9 14577.5 12864.5 12905.9 12998.4 13026.5 13037.9 4541.1 4665.8 4674.4 4697.3 4780.7 69394.0 101645.0 107364.0 117901.8 118259.0 11096.9 11277.6 11337.9 11533.4 11806.8 22583.9 22691.0 22714.0 22716.0 22775.8 8008.8 8015.9 8029.6 8272.3 8275.8 4399.0 4457.5 4471.9 4591.3 4627.0 3538.8 3620.9 3638.9 3681.1 3687.4 564.6 665.8 666.2 673.1 678.4 516.3 598.9 599.9 627.7 635.4 8742.5 8899.2 8917.1 9293.0 9293.5 604.7 617.6 631.0 632.8 641.5 4245.6 4481.7 4490.3 4492.4 4529.5 87538.0 129356.4 133479.0 161348.0 163497.0 135089.0 139370.3 142619.0 145184.5 146678.3 98273.0 117185.0 118656.0 121046.8 122503.4 8567.1 8614.0 8631.9 8783.9 8992.2 50208.0 56892.7 57501.7 58102.0 59666.7 86474.0 92433.0 96324.0 97128.1 100043.5 11693.6 11810.5 11811.4 11818.2 12234.1 11896.8 12000.9 12054.6 12491.9 12534.4 11767.6 11817.1 11866.3 11987.2 12143.9 11849.2 11878.5 12007.2 12026.7 12062.8 11699.6 11737.5 11760.6 11858.5 11883.9 8881.0 8975.9 9787.3 9903.7 9936.3 7449.5 8435.0 9042.5 9889.4 10167.0 19:46:09 INFO - PROCESS | 1929 | -------- Summary: end -------- 19:46:09 INFO - PROCESS | 1929 | 19:46:10 INFO - TEST-INFO | 1929: exit 0 19:46:10 INFO - TEST-OK | dromaeo_css | took 1117511ms 19:46:10 INFO - TEST-START | kraken 19:46:10 INFO - operating with platform_type : mac_ 19:46:10 INFO - Initialising browser for kraken test... 19:46:10 INFO - TEST-INFO | started process 1934 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/tmpVfCnuO/profile http://localhost:49426/getInfo.html) 19:46:12 INFO - PROCESS | 1934 | __metrics Screen width/height:1600/1200 19:46:12 INFO - PROCESS | 1934 | colorDepth:24 19:46:12 INFO - PROCESS | 1934 | Browser inner width/height: 1024/689 19:46:12 INFO - PROCESS | 1934 | __metrics 19:46:12 INFO - PROCESS | 1934 | 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" 19:46:12 INFO - PROCESS | 1934 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:46:13 INFO - TEST-INFO | 1934: exit 0 19:46:13 INFO - Browser initialized. 19:46:13 INFO - Running cycle 1/1 for kraken test... 19:46:13 INFO - TEST-INFO | started process 1935 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/T/tmpVfCnuO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:46:25 INFO - PROCESS | 1935 | RSS: Main: 228057088 19:46:25 INFO - PROCESS | 1935 | 19:46:28 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:49426/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49426/tests/kraken/driver.html?testName=audio-beat-detection) 19:46:29 INFO - PROCESS | 1935 | RSS: Main: 286048256 19:46:29 INFO - PROCESS | 1935 | 19:46:31 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:49426/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49426/tests/kraken/driver.html?testName=audio-dft) 19:46:32 INFO - PROCESS | 1935 | RSS: Main: 292802560 19:46:32 INFO - PROCESS | 1935 | 19:46:35 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:49426/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49426/tests/kraken/driver.html?testName=audio-fft) 19:46:36 INFO - PROCESS | 1935 | RSS: Main: 385212416 19:46:36 INFO - PROCESS | 1935 | 19:46:38 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:49426/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49426/tests/kraken/driver.html?testName=audio-oscillator) 19:46:39 INFO - PROCESS | 1935 | RSS: Main: 289312768 19:46:39 INFO - PROCESS | 1935 | 19:46:41 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:49426/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49426/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:46:41 INFO - PROCESS | 1935 | RSS: Main: 293597184 19:46:41 INFO - PROCESS | 1935 | 19:46:44 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:49426/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49426/tests/kraken/driver.html?testName=imaging-darkroom) 19:46:45 INFO - PROCESS | 1935 | RSS: Main: 329084928 19:46:45 INFO - PROCESS | 1935 | 19:46:47 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:49426/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49426/tests/kraken/driver.html?testName=imaging-desaturate) 19:46:48 INFO - PROCESS | 1935 | RSS: Main: 331132928 19:46:48 INFO - PROCESS | 1935 | 19:46:51 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:49426/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49426/tests/kraken/driver.html?testName=json-parse-financial) 19:46:52 INFO - PROCESS | 1935 | RSS: Main: 332267520 19:46:52 INFO - PROCESS | 1935 | 19:46:53 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:49426/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49426/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:46:54 INFO - PROCESS | 1935 | RSS: Main: 301629440 19:46:54 INFO - PROCESS | 1935 | 19:46:56 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:49426/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49426/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:46:56 INFO - PROCESS | 1935 | RSS: Main: 308584448 19:46:56 INFO - PROCESS | 1935 | 19:46:59 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:49426/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49426/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:46:59 INFO - PROCESS | 1935 | RSS: Main: 301121536 19:46:59 INFO - PROCESS | 1935 | 19:47:02 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:49426/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49426/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:47:03 INFO - PROCESS | 1935 | RSS: Main: 312688640 19:47:03 INFO - PROCESS | 1935 | 19:47:06 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:49426/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49426/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:47:06 INFO - PROCESS | 1935 | RSS: Main: 314138624 19:47:06 INFO - PROCESS | 1935 | 19:47:08 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:49426/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49426/tests/kraken/driver.html?testName=ai-astar) 19:47:08 INFO - PROCESS | 1935 | RSS: Main: 357867520 19:47:08 INFO - PROCESS | 1935 | 19:47:08 INFO - PROCESS | 1935 | __start_tp_report 19:47:08 INFO - PROCESS | 1935 | _x_x_mozilla_page_load 19:47:08 INFO - PROCESS | 1935 | _x_x_mozilla_page_load_details 19:47:08 INFO - PROCESS | 1935 | |i|pagename|runs| 19:47:08 INFO - PROCESS | 1935 | |0;ai-astar;103;114;113;114;109;115;111;113;112;115 19:47:08 INFO - PROCESS | 1935 | |1;audio-beat-detection;146;109;107;168;105;106;130;108;140;106 19:47:08 INFO - PROCESS | 1935 | |2;audio-dft;181;185;182;184;184;182;178;183;181;185 19:47:08 INFO - PROCESS | 1935 | |3;audio-fft;74;59;78;110;81;58;63;56;99;58 19:47:08 INFO - PROCESS | 1935 | |4;audio-oscillator;83;68;103;70;70;68;96;71;67;101 19:47:08 INFO - PROCESS | 1935 | |5;imaging-gaussian-blur;86;83;87;96;86;84;87;88;89;84 19:47:08 INFO - PROCESS | 1935 | |6;imaging-darkroom;93;93;93;94;93;93;93;93;101;129 19:47:08 INFO - PROCESS | 1935 | |7;imaging-desaturate;86;84;83;83;86;85;85;84;84;103 19:47:08 INFO - PROCESS | 1935 | |8;json-parse-financial;56;55;57;56;55;57;53;55;77;80 19:47:08 INFO - PROCESS | 1935 | |9;json-stringify-tinderbox;66;65;70;82;73;71;70;70;71;72 19:47:08 INFO - PROCESS | 1935 | |10;stanford-crypto-aes;84;86;85;85;83;83;103;91;85;80 19:47:08 INFO - PROCESS | 1935 | |11;stanford-crypto-ccm;142;138;129;144;138;135;137;138;160;143 19:47:08 INFO - PROCESS | 1935 | |12;stanford-crypto-pbkdf2;165;150;212;227;180;171;169;171;168;167 19:47:08 INFO - PROCESS | 1935 | |13;stanford-crypto-sha256-iterative;68;69;69;67;66;66;66;68;66;66 19:47:08 INFO - PROCESS | 1935 | __end_tp_report 19:47:08 INFO - PROCESS | 1935 | __start_cc_report 19:47:08 INFO - PROCESS | 1935 | _x_x_mozilla_cycle_collect,2145 19:47:08 INFO - PROCESS | 1935 | __end_cc_report 19:47:08 INFO - PROCESS | 1935 | __startTimestamp1471402028928__endTimestamp 19:47:08 INFO - PROCESS | 1935 | 19:47:08 INFO - PROCESS | 1935 | ------- Summary: start ------- 19:47:08 INFO - PROCESS | 1935 | Number of tests: 14 19:47:08 INFO - PROCESS | 1935 | 19:47:08 INFO - PROCESS | 1935 | [#0] ai-astar Cycles:10 Average:111.90 Median:113.50 stddev:3.63 (3.2%) stddev-sans-first:1.96 19:47:08 INFO - PROCESS | 1935 | Values: 103.0 114.0 113.0 114.0 109.0 115.0 111.0 113.0 112.0 115.0 19:47:08 INFO - PROCESS | 1935 | 19:47:08 INFO - PROCESS | 1935 | [#1] audio-beat-detection Cycles:10 Average:122.50 Median:119.50 stddev:22.28 (18.6%) stddev-sans-first:21.95 19:47:08 INFO - PROCESS | 1935 | Values: 146.0 109.0 107.0 168.0 105.0 106.0 130.0 108.0 140.0 106.0 19:47:08 INFO - PROCESS | 1935 | 19:47:08 INFO - PROCESS | 1935 | [#2] audio-dft Cycles:10 Average:182.50 Median:183.50 stddev:2.17 (1.2%) stddev-sans-first:2.24 19:47:08 INFO - PROCESS | 1935 | Values: 181.0 185.0 182.0 184.0 184.0 182.0 178.0 183.0 181.0 185.0 19:47:08 INFO - PROCESS | 1935 | 19:47:08 INFO - PROCESS | 1935 | [#3] audio-fft Cycles:10 Average:73.60 Median:68.50 stddev:18.76 (27.4%) stddev-sans-first:19.89 19:47:08 INFO - PROCESS | 1935 | Values: 74.0 59.0 78.0 110.0 81.0 58.0 63.0 56.0 99.0 58.0 19:47:08 INFO - PROCESS | 1935 | 19:47:08 INFO - PROCESS | 1935 | [#4] audio-oscillator Cycles:10 Average:79.70 Median:70.00 stddev:14.80 (21.1%) stddev-sans-first:15.65 19:47:08 INFO - PROCESS | 1935 | Values: 83.0 68.0 103.0 70.0 70.0 68.0 96.0 71.0 67.0 101.0 19:47:08 INFO - PROCESS | 1935 | 19:47:08 INFO - PROCESS | 1935 | [#5] imaging-gaussian-blur Cycles:10 Average:87.00 Median:87.00 stddev:3.68 (4.2%) stddev-sans-first:3.89 19:47:08 INFO - PROCESS | 1935 | Values: 86.0 83.0 87.0 96.0 86.0 84.0 87.0 88.0 89.0 84.0 19:47:08 INFO - PROCESS | 1935 | 19:47:08 INFO - PROCESS | 1935 | [#6] imaging-darkroom Cycles:10 Average:97.50 Median:93.00 stddev:11.35 (12.2%) stddev-sans-first:11.92 19:47:08 INFO - PROCESS | 1935 | Values: 93.0 93.0 93.0 94.0 93.0 93.0 93.0 93.0 101.0 129.0 19:47:08 INFO - PROCESS | 1935 | 19:47:08 INFO - PROCESS | 1935 | [#7] imaging-desaturate Cycles:10 Average:86.30 Median:84.50 stddev:5.96 (7.1%) stddev-sans-first:6.32 19:47:08 INFO - PROCESS | 1935 | Values: 86.0 84.0 83.0 83.0 86.0 85.0 85.0 84.0 84.0 103.0 19:47:08 INFO - PROCESS | 1935 | 19:47:08 INFO - PROCESS | 1935 | [#8] json-parse-financial Cycles:10 Average:60.10 Median:56.50 stddev:9.79 (17.3%) stddev-sans-first:10.27 19:47:08 INFO - PROCESS | 1935 | Values: 56.0 55.0 57.0 56.0 55.0 57.0 53.0 55.0 77.0 80.0 19:47:08 INFO - PROCESS | 1935 | 19:47:08 INFO - PROCESS | 1935 | [#9] json-stringify-tinderbox Cycles:10 Average:71.00 Median:71.00 stddev:4.59 (6.5%) stddev-sans-first:4.50 19:47:08 INFO - PROCESS | 1935 | Values: 66.0 65.0 70.0 82.0 73.0 71.0 70.0 70.0 71.0 72.0 19:47:08 INFO - PROCESS | 1935 | 19:47:08 INFO - PROCESS | 1935 | [#10] stanford-crypto-aes Cycles:10 Average:86.50 Median:85.00 stddev:6.43 (7.6%) stddev-sans-first:6.76 19:47:08 INFO - PROCESS | 1935 | Values: 84.0 86.0 85.0 85.0 83.0 83.0 103.0 91.0 85.0 80.0 19:47:08 INFO - PROCESS | 1935 | 19:47:08 INFO - PROCESS | 1935 | [#11] stanford-crypto-ccm Cycles:10 Average:140.40 Median:140.00 stddev:8.13 (5.8%) stddev-sans-first:8.60 19:47:08 INFO - PROCESS | 1935 | Values: 142.0 138.0 129.0 144.0 138.0 135.0 137.0 138.0 160.0 143.0 19:47:08 INFO - PROCESS | 1935 | 19:47:08 INFO - PROCESS | 1935 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.00 Median:171.00 stddev:23.37 (13.7%) stddev-sans-first:24.31 19:47:08 INFO - PROCESS | 1935 | Values: 165.0 150.0 212.0 227.0 180.0 171.0 169.0 171.0 168.0 167.0 19:47:08 INFO - PROCESS | 1935 | 19:47:08 INFO - PROCESS | 1935 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.50 stddev:1.29 (1.9%) stddev-sans-first:1.32 19:47:08 INFO - PROCESS | 1935 | Values: 68.0 69.0 69.0 67.0 66.0 66.0 66.0 68.0 66.0 66.0 19:47:08 INFO - PROCESS | 1935 | -------- Summary: end -------- 19:47:08 INFO - PROCESS | 1935 | 19:47:09 INFO - TEST-INFO | 1935: exit 0 19:47:09 INFO - TEST-OK | kraken | took 59409ms 19:47:09 INFO - SUITE-END | took 1176s 19:47:10 INFO - Completed test suite (00:19:37) 19:47:10 INFO - javascript benchmark 19:47:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3317.8838740644073, 3338.248893998849, 3356.0267522418508, 3359.916002099949, 3393.2025973896148, 5515.0, 5613.635113717605, 5623.606347555673, 5673.092305231164, 5682.488576028157, 5549.417311182328, 5639.210510528529, 5700.543956483481, 5720.570957178207, 5733.455321744438, 5092.2107073403495, 5105.693658380491, 5172.0, 5188.507091826271, 5209.661372010826, 5180.0, 5192.299039629658, 5229.529342359187, 5232.973835130819, 5244.501772331622, 5012.874678133076, 5076.796928122871, 5083.415407228179, 5121.692698438068, 5149.999999999981, 5082.822101226477, 5116.641835071547, 5184.351956005499, 5200.349956255468, 5255.973720131413, 5072.746362681851, 5091.287219789232, 5137.48625137487, 5148.819791307286, 5199.818006369759, 5629.268195134648, 5640.2667653205235, 5679.460451257165, 5693.430656934315, 5712.771489140429, 5987.550933679991, 6089.147519347251, 6149.877002459971, 6152.507799376039, 6218.782342618032, 6215.098810672427, 6226.62640241587, 6262.2172228471445, 6266.592671476339, 6290.339514350973, 5582.748776305076, 5776.740046697867, 5777.566682498829, 5787.508061814793, 5793.623414478046, 6231.937680623227, 6252.155958945484, 6291.9685401573615, 6319.115323854664, 6328.367163283635, 6091.907563486476, 6092.859244776726, 6097.664628445389, 6107.918501380683, 6108.175396321529, 6229.3770622937345, 6234.812955611339, 6234.875302494015, 6237.09562113491, 6252.280987686474, 5895.145203945404, 6044.0, 6056.878862422815, 6123.191113103742, 6142.989934931851, 6148.415900489446, 6208.068789681584, 6210.782622608187, 6218.347073557211, 6228.4705800007505, 4854.101991131582, 4864.391951006124, 4872.853814385574, 4876.634252431082, 4906.58294045003, 4318.5681431856565, 4321.870343889688, 4325.070109926381, 4416.734995900256, 4419.447569053868, 5657.858553536194, 5674.631148975386, 5682.488576028178, 5695.974724549621, 5718.1136923776885, 6276.246850377984, 6276.497880169575, 6278.748850046039, 6288.465480434214, 6297.149884765589, 5513.393526712139, 5549.250851135126, 5584.246126772915, 5651.152327150878, 5667.008273552112, 5334.11987022137, 5352.759125839347, 5354.41101478837, 5360.276362691065, 5397.568194544427, 6247.562670613104, 6268.968655156695, 6275.6862156892885, 6278.46633036197, 6290.874182516415, 6045.727942242697, 6066.545009124245, 6149.139120523041, 6202.193714817045, 6270.874582508233, 6287.779927702509, 6304.464120549621, 6313.873722525615, 6314.431701146918, 6357.4596159326975, 5994.580379373402, 6025.216721826135, 6028.125921741411, 6031.487323577545, 6069.393060693896, 5481.232627432083, 5781.756922261734, 6061.424164704346, 6138.447539721446, 6146.293176284606, 5942.524598032061, 5994.820155395345, 6023.36754640774, 6040.426159514839, 6124.203853499017, 6005.549583781147, 6015.518758499397, 6025.337212906489, 6037.63774173551, 6043.667598281961, 5687.829365119053, 5798.159266906359, 5879.176915231957, 5897.085951677497, 5929.822105336847, 5993.101034844808, 6060.545459090497, 6088.086786981811, 6113.419225173601, 6114.816555503342, 5838.678872662044, 5865.472107510344, 5882.411758824083, 5886.470217680429, 5901.085331773582], "value": 5647.910991032918, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [466.42163716990933, 530.8752443175854, 534.6204195021535, 535.8258565966062, 555.289229785874, 30870.382592348138, 39783.80108099459, 41048.0, 41074.58925410741, 42633.360499592454, 41677.0, 41959.79020104895, 42008.0, 42101.36847947283, 42120.78939605306, 9666.758331041745, 9839.90160098397, 9843.114119729224, 9866.963968783282, 9870.753231169207, 8655.177758113, 8863.689770858022, 11874.16880818343, 12118.575849845258, 12141.514339426412, 11975.982041526437, 12054.15620906537, 12072.336021518813, 12083.033357331436, 12144.939275303612, 12051.698707532294, 12154.87845121555, 12164.939175304155, 12166.330851803148, 12185.87814121865, 12102.878971210263, 12153.574624888086, 12165.20926139798, 12173.208741431867, 12174.452149653287, 39558.60441395578, 39733.40399894004, 39759.20481590352, 39828.402573961415, 39863.60136398628, 3347.815870127142, 3389.830508474591, 3593.389123848952, 3659.5791483979415, 3965.1871366369824, 30943.0, 30951.535726964114, 31002.84498577515, 31963.84018079918, 32421.351572968408, 3100.635720283068, 3245.8863939761995, 3259.168911927467, 3275.96724032759, 3375.2236985493228, 525261.0, 526880.0, 528984.9999999962, 530450.0, 531136.0, 60456.69771651114, 61183.694081529306, 61243.0, 61248.69375653182, 61288.0, 34995.0, 36156.45765313522, 63564.0, 63573.68213158905, 63728.68135659292, 13536.323183840768, 15684.137372444626, 16003.599910002344, 16134.273957671932, 16260.430884918971, 11648.767024659459, 11866.110041746904, 12334.259944403186, 12454.688632784071, 12459.750804983849, 16693.749593756103, 16797.160141993096, 16808.495745127664, 18309.3591724289, 19000.61998760017, 7616.428767842434, 7722.575258360844, 7748.186440423787, 7804.336631386395, 7841.49072296871, 834.6911642692121, 886.7561420609255, 938.4885237545554, 1063.9202059845388, 1075.4783929794044, 25445.87277063603, 29079.418411632072, 30101.849490752407, 30380.54429183564, 30543.14624851979, 1042.4370839746452, 1106.1537923488538, 1110.9111271098293, 1151.6008050219025, 1178.457909361686, 14639.341229644691, 15673.902826802014, 15772.526824195293, 15903.602409939613, 15911.840881591268, 643354.0, 651142.0, 651481.0, 651910.0, 652821.0, 57179.14231286534, 57608.0, 57634.13548796772, 57666.0, 57690.711546442, 1074.3500182389698, 1305.6344223617402, 1361.3873756809674, 1394.1147371419222, 1404.1154072933987, 26060.8696956514, 29510.704892950795, 30821.845890770404, 30945.226369340948, 31200.21999450032, 48116.518834811206, 48675.0, 48678.75660621674, 48810.5118948806, 48836.2674559889, 460946.0, 462364.0, 465071.0, 466413.0, 466512.0, 26452.603210951467, 26839.731602684504, 27347.0, 27383.178504644893, 28344.43311133807, 46586.53413465822, 46855.53144468512, 47399.052018960116, 47656.0, 47709.284360733924, 38518.0, 38660.6133938657, 38682.80658596689, 40262.0, 40363.798181008904, 38381.040473987254, 38447.0388240285, 39136.608633913296, 40307.79846100751, 40368.798156009034, 37953.0, 38404.84785456441, 38804.41793373158, 38863.805680972546, 38885.41671874981, 32894.835525822215, 33908.15229619279, 34355.7242087733, 34451.82774086113, 34611.48082778811, 23760.049598016576, 24066.037358506164, 24253.63619545673, 24340.0, 24486.87756561206], "value": 20229.707987127607, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [345.48848786558807, 351.8012323037485, 368.54852805313493, 376.9170782427866, 402.4044414266885, 16275.75586366204, 16716.582085447855, 20665.586688266227, 20993.950302484893, 21105.894470527644, 18691.158897849597, 20499.180032798708, 21012.579748405024, 21014.57970840582, 21031.36905892822, 5257.447967963366, 5380.704061276629, 5415.323084614413, 5518.889622207554, 5524.364698059714, 3889.0, 5359.946400535984, 5446.2375267462585, 5452.536534394561, 5454.91817622734, 5857.3849745776715, 6122.510199184077, 6142.785002524913, 6185.8144255672405, 6222.191115155046, 5870.26621672291, 6085.847853803646, 6166.537509686791, 6174.289956654975, 6181.227346581677, 5945.672987985659, 5950.256217972754, 5951.940480595182, 5952.880942381172, 5967.1347654589845, 13505.932470337586, 16737.0, 19258.422247332463, 25408.745912541006, 25723.74276257232, 2150.387139665188, 2217.9556408871895, 2417.8791060446906, 2495.513374891897, 2790.14900455361, 9880.851787223197, 10422.739431514197, 10513.684589462327, 15545.067295962279, 16048.919755401148, 1675.2126500544725, 1743.5466778637644, 1871.953201169968, 1917.7986311437237, 1936.7966363531766, 106682.46658766811, 115728.0, 116502.41748791287, 117643.0, 120658.0, 11037.668869933916, 11236.988671019648, 11491.0, 11539.365334906493, 11743.354115523729, 14578.781318280235, 14581.781273280909, 14591.124532528082, 14597.489087881873, 14622.341994610268, 7750.108737495148, 8194.30348420379, 8438.36712246584, 8469.576521173916, 8615.009273933452, 7648.847023059508, 8338.708145214889, 8340.29107525867, 8519.062903080656, 8545.914540854636, 3720.3489389356755, 4461.420015398043, 4539.682222244412, 4568.132054909556, 4656.3713898623255, 1615.2166199393284, 1755.0698129991124, 1859.2284202056267, 1892.7539419875327, 1897.7438045863632, 786.3944762532818, 907.5734404829719, 946.6639343033124, 952.538019060755, 986.7681094942683, 20855.1657933684, 21411.143554257655, 21456.05710184235, 21586.35240942774, 21821.345359639236, 868.4281450574481, 913.0686699566405, 945.4091193004373, 1004.231762701534, 1021.5811517278029, 9521.39050749542, 10846.945765271123, 11054.834177487344, 11095.778084438267, 11499.310041397543, 492931.0, 510961.0, 511041.0, 522203.38898305263, 532740.0, 124654.37672811578, 125317.37341313418, 126605.0, 127410.36294818467, 131177.0, 960.3421656165689, 970.9368891022061, 993.4138858073627, 1021.5045702834119, 1021.9386836789816, 18617.813821861608, 19092.140853661895, 19132.52168695738, 19163.904180479007, 19249.941253231205, 52912.735436322575, 53193.73403132959, 54348.64128396664, 54654.906901860944, 55037.0, 78354.6082269585, 80132.0, 81388.0, 81483.18516814992, 84351.0, 8006.599670016592, 8103.918960810316, 8276.875846862422, 8292.12932642064, 8428.662853485792, 12438.875611243771, 12950.222986620833, 13247.40386682621, 13529.255890926093, 13598.93200533991, 10546.630867919586, 11356.318620882774, 11677.416129193676, 11827.704307392383, 11877.881221187678, 11257.21199516026, 11293.830592540955, 11544.88455115438, 11568.492535973604, 11612.361320127136, 11510.59712910044, 11573.074154067486, 11728.765424691288, 11908.047356211655, 11992.700182495508, 7123.82190445243, 7148.28517148281, 7385.409167266714, 7405.407567394704, 8295.087540370434, 9391.403461411439, 11051.226414151253, 11506.367149806842, 11636.650900473, 11774.52901883915], "value": 9497.207830288013, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1441.7332793433216, 1511.735446296898, 1620.5138458462466, 1817.2095138614711, 1833.651606194823, 8050.516968981865, 8384.664613415463, 8585.441946273488, 8953.23897468715, 8961.327900407463, 7389.5196812207305, 7420.777376678695, 8485.575721213945, 8658.696945606906, 8679.609417576225, 2354.7409784923643, 2384.3443053160345, 2387.55830171418, 2393.7366889642126, 2395.8682272475007, 2352.12971200656, 2371.727251366098, 2392.6650268962358, 2402.651615515749, 2410.8794560272004, 2382.4996750682376, 2387.140629373424, 2393.3418309964813, 2399.7120345558556, 2403.2069417092316, 2369.3602727263715, 2379.1910750344878, 2380.5357955198742, 2382.2614989353356, 2386.809055275574, 2021.2824447321284, 2372.810175185981, 2375.3111597636666, 2377.239283429303, 2380.1193558383334, 8935.64257429702, 8976.551172441352, 9052.049534798816, 9065.728028159167, 9100.954495227481, 5206.453322401131, 5342.626016178869, 5372.758225879845, 5384.138537833927, 5416.702081385522, 2878.8992385266415, 3540.716742660581, 3651.269746050774, 3665.8533658653623, 3701.5373078365205, 1092.554863207574, 1094.7099018760034, 1104.7017305327518, 1125.5835340923911, 1126.8591426071741, 11604.709882253012, 11681.123915706356, 11816.940915295369, 11836.467358968866, 11998.160128791076, 4596.931046034312, 4702.741349225826, 4794.089123066607, 4851.781669824796, 4875.9512404875495, 9891.109800118023, 10007.249456290661, 10042.899571004342, 10120.038596333483, 10145.442000689885, 5412.07994640912, 5415.0, 5430.402655707869, 5448.101063324586, 5453.4273901240595, 4172.624463798273, 4436.157130145262, 4574.0, 4947.233178857283, 4964.553190212898, 3953.486046813953, 3953.7034722395933, 4038.353863381845, 4066.9186616267507, 4077.796110194538, 1299.8050292456207, 1308.4439113376777, 1309.4531136938563, 1325.7282257137263, 1328.8173525562258, 893.8793262909554, 924.7087167542203, 932.9207017403596, 933.2813733425224, 949.8907625623142, 5743.282089738783, 5901.374292643485, 5970.791022314198, 6057.969710151509, 6209.658468784261, 416.98048271975335, 436.4130244820711, 440.7685964868406, 447.1704987248672, 454.9818007279672, 3946.861859834892, 3975.6223158799867, 4037.7375470594907, 4056.229316429869, 4116.073883376216, 10457.215708821868, 10788.190885683449, 10830.94584527088, 10974.561017559368, 11036.172287078502, 8281.0, 10679.038886500251, 10805.243632945618, 10871.83692244617, 11063.446827658585, 8046.436749427484, 8228.547429891294, 8304.211099945496, 8331.54176520285, 8369.372297077743, 5702.629329093762, 5795.420457954339, 5873.207117039145, 5906.320773111147, 5951.672658003678, 9880.308378413441, 10048.598056077675, 10152.54313555877, 10157.898421015696, 10235.130013948898, 9344.813103738023, 9569.665061722806, 9939.950300248453, 9965.850512242178, 10355.326903751233, 6780.254172040973, 7294.343509083996, 7619.809504762426, 7644.235576442311, 7687.577183254974, 8366.372522060748, 8453.78865528342, 8497.405181637227, 8506.532140732319, 8622.655093796178, 7554.2823431773895, 7612.733554325572, 7640.197779233101, 7641.235876412315, 7682.0, 7431.219721929119, 7437.851242975218, 7459.328660420588, 7496.58768767723, 7512.399008079231, 6999.755008574675, 7003.3346832050875, 7101.786946391617, 7116.928830711834, 7149.85700285981, 4898.093852637274, 4979.278004689228, 5025.874353141201, 5028.748562571784, 5235.031519168966, 4998.6750861193905, 5084.440711521655, 5090.490950905028, 5105.923411148761, 5220.0], "value": 4527.884454022028, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.47792623944974, 303.8040463900784, 313.65654608204017, 316.0660248964311, 316.68173485646935, 4668.813247470097, 5346.812861549846, 5374.865628359288, 5386.38760201302, 5408.080626293529, 4316.9568304316945, 4556.977215113928, 5284.867878303035, 5298.1522956327, 5311.468853114687, 3336.4652259960326, 3892.4624773214564, 3923.6664883484937, 4027.295223335912, 4066.877993660195, 3180.8568614412293, 3372.780769249991, 3844.5578758442857, 4084.448599439082, 4098.4671992640915, 3873.8644147454843, 4070.4911886014247, 4079.4492743479695, 4092.9590704093025, 4103.4870641169855, 3144.4340018796606, 3754.5119134512474, 3979.7015223858325, 3988.282109220339, 4046.696497762665, 3012.9096127116222, 3324.2188085799817, 3402.1664692150334, 3486.843092060838, 3491.5810102787827, 4244.72409293395, 4679.9704065105625, 5143.459936706629, 5184.559312458446, 5185.796036684432, 3785.432185172219, 4017.4174744662155, 4330.783460826978, 4584.908301833978, 4607.815687372501, 5571.13647384656, 5604.982206405691, 5614.6631202128, 5627.6060675752715, 5646.435356464362, 3832.9041773955596, 3840.327942610032, 3864.95646175531, 3866.2074274773604, 3911.6870650347905, 5738.68437235956, 5933.673647949361, 5951.702414879239, 5965.642061476282, 5967.015442451991, 3340.248444100058, 3405.506201600754, 3604.4593311003036, 3844.4233364994916, 3932.9745181231688, 3962.2471730371135, 3971.543272523697, 3972.801359931992, 3982.4623675803973, 4005.759654420686, 4644.535546445396, 4645.907081858344, 4650.790714417859, 4653.836540864784, 4659.347691323217, 5475.425080366584, 5658.528782516575, 5682.374938756733, 5688.459596338341, 5696.886062278814, 1057.1014637558014, 1303.9543615973585, 1308.5420102963888, 1335.052112999781, 1336.4988129451456, 769.9345555627834, 874.7681864305964, 906.8775715278485, 911.64901512918, 913.59345091435, 5180.481951804864, 5185.51095474167, 5193.039967218725, 5206.375234971828, 5220.580519759487, 4598.586127248564, 5082.364704411949, 5676.403977582295, 5720.456556627114, 5722.4277572242445, 4095.6245377680875, 4215.163232342996, 4988.5260900214425, 5038.874028149326, 5212.322398088224, 2063.473814177375, 2193.890305484719, 2557.8721063946728, 2559.308986573652, 2567.7945764338806, 4685.508021657745, 4722.929156062662, 4850.563449289581, 4980.800767969241, 5012.448630650625, 4389.473263208435, 4425.955740442619, 4520.81916723334, 4603.859205469473, 4736.939431347375, 4257.5529569396, 4282.5075116361395, 4422.921227023358, 4978.004399120118, 5077.898442031139, 4306.397104405449, 4341.630961368319, 4673.224174813549, 4701.0, 4830.6135509159885, 3997.360422332529, 4030.818613162356, 4745.928811067768, 4747.548982846624, 4757.762111894461, 4529.900440101713, 4554.908901822011, 4658.487566367629, 4985.700857948535, 4989.927165659401, 3044.6965042927786, 3093.8917137900066, 3257.4625186843905, 3262.5758651375168, 3370.893191755153, 4145.54399016102, 4154.317501411933, 4855.086947831273, 4923.606111511142, 5408.621396502207, 4564.748938808398, 5356.100335816594, 5412.538614573964, 5518.448155184449, 5595.916061259003, 4880.853574392774, 5499.725013749377, 5547.861303467445, 5550.250716153268, 5599.984005757842, 5455.454454554513, 5461.804844166208, 5469.781208751605, 5510.197963405238, 5523.309586301712, 4172.749635021908, 4425.358323043077, 5382.354117505768, 5521.282233309644, 5592.406164243172, 4580.625812256377, 5558.861028474159, 5569.721513924369, 5581.878886023285, 5604.607677462538], "value": 3858.893014544007, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [735.9300866417689, 736.3262614707543, 763.5304287862963, 786.0999155966418, 803.9508441483863, 15704.214789260534, 16452.42416515422, 16487.928284661488, 16504.83495165048, 16534.421295254655, 14856.628584285398, 14957.252137393114, 14991.625209369771, 14998.550043498684, 15006.174660393674, 676.4351766275166, 685.7428464325877, 694.6353164588581, 825.7192554531458, 852.4033176776251, 701.316216688727, 828.0932379044937, 834.9081601023883, 837.0708513549955, 838.1032295443878, 894.1371576428745, 894.6734441928681, 895.2166854002747, 896.3770179725094, 899.5322432335182, 888.2760550151605, 890.1410139215656, 890.470170248701, 890.5903284489143, 891.4740303221066, 530.875244317585, 647.8412788866758, 665.3313420012904, 665.933406659335, 666.4468491152332, 14895.553133406016, 14897.0, 14912.701745965129, 14917.179555124572, 14945.626359340995, 13770.65573360664, 14276.57170284893, 14300.570982870426, 14356.856431435657, 14577.48978785748, 12864.549740759123, 12905.935470322682, 12998.415071321813, 13026.47894084245, 13037.86962130376, 4541.137183935041, 4665.836695715668, 4674.387336368947, 4697.318888761145, 4780.689255198401, 69394.0, 101645.0, 107364.0, 117901.82098178995, 118259.0, 11096.944515277371, 11277.605283815026, 11337.886621133683, 11533.423328833525, 11806.822897656542, 22583.88708056449, 22690.978905949272, 22714.0, 22716.0, 22775.772242277366, 8008.79978000543, 8015.919840801517, 8029.638666260033, 8272.296854767292, 8275.751727448187, 4399.0, 4457.487388950248, 4471.932921006188, 4591.334256532784, 4626.97686511572, 3538.7699799512948, 3620.909477263037, 3638.8544458221904, 3681.1349332906743, 3687.3547129252274, 564.632988557441, 665.8335416145964, 666.1573110015834, 673.0879658063352, 678.4362045485204, 516.3261943164132, 598.86525531755, 599.9280086389574, 627.7018416252243, 635.3650122357413, 8742.475451472805, 8899.199072083544, 8917.077428120934, 9292.983662769293, 9293.488858112869, 604.7278724573871, 617.6232498176109, 630.9652969086744, 632.7816903168399, 641.4868105515569, 4245.639120674716, 4481.708688935209, 4490.281554951257, 4492.37106805041, 4529.501754806968, 87538.0, 129356.41287174204, 133479.0, 161348.0, 163497.0, 135088.95446669817, 139370.3031484836, 142619.0, 145184.5481545171, 146678.26660866628, 98273.0, 117185.0, 118656.0, 121046.78953210355, 122503.3874830638, 8567.100454452442, 8613.956930215309, 8631.91368086311, 8783.912160878564, 8992.190702836777, 50208.0, 56892.71553642205, 57501.712491437276, 58102.0, 59666.70166649313, 86474.0, 92433.0, 96324.0, 97128.05743885224, 100043.49978250063, 11693.590724324607, 11810.527578897092, 11811.350375729417, 11818.231814932346, 12234.143609947218, 11896.76206475883, 12000.939995299968, 12054.638360849189, 12491.93754031224, 12534.373281336078, 11767.646970590897, 11817.054635629302, 11866.288022718665, 11987.22083064598, 12143.939280303543, 11849.170558061198, 11878.465469054086, 12007.219530730474, 12026.699332516408, 12062.75874482488, 11699.649010529698, 11737.530498779954, 11760.58837940668, 11858.525658973544, 11883.940580297043, 8880.978687450768, 8975.865362019706, 9787.265955053543, 9903.653372131941, 9936.304458687822, 7449.478536502393, 8434.987801463863, 9042.547872606476, 9889.406635601887, 10167.0], "value": 7614.6891462909, "unit": "score"}], "name": "dromaeo_css", "value": 7243.062684464564, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 114.0, 113.0, 114.0, 109.0, 115.0, 111.0, 113.0, 112.0, 115.0], "value": 111.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 109.0, 107.0, 168.0, 105.0, 106.0, 130.0, 108.0, 140.0, 106.0], "value": 122.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 185.0, 182.0, 184.0, 184.0, 182.0, 178.0, 183.0, 181.0, 185.0], "value": 182.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 59.0, 78.0, 110.0, 81.0, 58.0, 63.0, 56.0, 99.0, 58.0], "value": 73.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 68.0, 103.0, 70.0, 70.0, 68.0, 96.0, 71.0, 67.0, 101.0], "value": 79.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 87.0, 96.0, 86.0, 84.0, 87.0, 88.0, 89.0, 84.0], "value": 87.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 94.0, 93.0, 93.0, 93.0, 93.0, 101.0, 129.0], "value": 97.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 83.0, 83.0, 86.0, 85.0, 85.0, 84.0, 84.0, 103.0], "value": 86.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 57.0, 56.0, 55.0, 57.0, 53.0, 55.0, 77.0, 80.0], "value": 60.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 70.0, 82.0, 73.0, 71.0, 70.0, 70.0, 71.0, 72.0], "value": 71.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 85.0, 85.0, 83.0, 83.0, 103.0, 91.0, 85.0, 80.0], "value": 86.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 138.0, 129.0, 144.0, 138.0, 135.0, 137.0, 138.0, 160.0, 143.0], "value": 140.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 150.0, 212.0, 227.0, 180.0, 171.0, 169.0, 171.0, 168.0, 167.0], "value": 178.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 69.0, 67.0, 66.0, 66.0, 66.0, 68.0, 66.0, 66.0], "value": 67.1, "unit": "score"}], "name": "kraken", "value": 1444.1, "alertThreshold": 2.0}]} 19:47:10 INFO - Return code: 0 19:47:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:47:10 INFO - # TBPL SUCCESS # 19:47:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:47:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:47:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:47:10 INFO - 2016-08-16 19:47:10.275 screenresolution[1939:16472] starting screenresolution argv=screenresolution get 19:47:10 INFO - 2016-08-16 19:47:10.277 screenresolution[1939:16472] Display 0: 1600x1200x32@60 19:47:10 INFO - 2016-08-16 19:47:10.283 screenresolution[1940:16475] starting screenresolution argv=screenresolution list 19:47:10 INFO - Available Modes on Display 0 19:47:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:47:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:47:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:47:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:47:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:47:10 INFO - Intel Iris: 19:47:10 INFO - Chipset Model: Intel Iris 19:47:10 INFO - Type: GPU 19:47:10 INFO - Bus: Built-In 19:47:10 INFO - VRAM (Dynamic, Max): 1536 MB 19:47:10 INFO - Vendor: Intel (0x8086) 19:47:10 INFO - Device ID: 0x0a2e 19:47:10 INFO - Revision ID: 0x0009 19:47:10 INFO - Displays: 19:47:10 INFO - Display: 19:47:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:47:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:47:10 INFO - Display Serial Number: 1600x1200 60 19:47:10 INFO - Main Display: Yes 19:47:10 INFO - Mirror: Off 19:47:10 INFO - Online: Yes 19:47:10 INFO - Rotation: Supported 19:47:10 INFO - Return code: 0 19:47:10 INFO - Running post-action listener: _resource_record_post_action 19:47:10 INFO - [mozharness: 2016-08-17 02:47:10.516395Z] Finished run-tests step (success) 19:47:10 INFO - Running post-run listener: _resource_record_post_run 19:47:10 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 1105920; Write bytes: 705111552; Read time: 16; Write time: 1972 19:47:10 INFO - TinderboxPrint: CPU usage
53.5% 19:47:10 INFO - TinderboxPrint: I/O read bytes / time
1,105,920 / 16 19:47:10 INFO - TinderboxPrint: I/O write bytes / time
705,111,552 / 1,972 19:47:10 INFO - TinderboxPrint: CPU idle
2,253.8 (46.5%) 19:47:10 INFO - TinderboxPrint: CPU system
969.1 (20.0%) 19:47:10 INFO - TinderboxPrint: CPU user
1,626.8 (33.5%) 19:47:10 INFO - TinderboxPrint: Swap in / out
406,548,480 / 0 19:47:10 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 400288256; Write bytes: 396046848; Read time: 29561; Write time: 810 19:47:10 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 1089536; Write bytes: 295060480; Read time: 13; Write time: 1117 19:47:10 INFO - Running post-run listener: _upload_blobber_files 19:47:10 INFO - Blob upload gear active. 19:47:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:47:10 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 19:47:10 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'] 19:47:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:47:11 INFO - (blobuploader) - INFO - Open directory for files ... 19:47:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:47:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:47:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:47:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:47:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:47:12 INFO - (blobuploader) - INFO - Done attempting. 19:47:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:47:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:47:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:47:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:47:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:47:13 INFO - (blobuploader) - INFO - Done attempting. 19:47:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:47:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:47:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:47:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:47:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:47:15 INFO - (blobuploader) - INFO - Done attempting. 19:47:15 INFO - (blobuploader) - INFO - Iteration through files over. 19:47:15 INFO - Return code: 0 19:47:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:47:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:47:15 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/edca7d6eb77275bb3b553ddd1de48408372ea4542ad405bc9cd6384704d7339bc1c2856c10f87fdacb5d00e15677476b8e8914bebb0e6045ef75bb60bf6c6b55", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/83e46bfed3cdae36f90c7cfa7cfc90571663faa55494e7a4faccfbe8fca51d6e72a61229b042837baa8b51bc386a4576a7e90a98eb13b43a074350a9a0cd75c0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b3750f91e5eb55fe6dea56e3778f7c0960a9d3479b48fd79a4657efb0e1fc763985ca2f239f643fcabdb1d37c4d1e2b6946704fa9fc1d7d5577f2e5e02d1081"} 19:47:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:47:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:47:15 INFO - Contents: 19:47:15 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/edca7d6eb77275bb3b553ddd1de48408372ea4542ad405bc9cd6384704d7339bc1c2856c10f87fdacb5d00e15677476b8e8914bebb0e6045ef75bb60bf6c6b55", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/83e46bfed3cdae36f90c7cfa7cfc90571663faa55494e7a4faccfbe8fca51d6e72a61229b042837baa8b51bc386a4576a7e90a98eb13b43a074350a9a0cd75c0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b3750f91e5eb55fe6dea56e3778f7c0960a9d3479b48fd79a4657efb0e1fc763985ca2f239f643fcabdb1d37c4d1e2b6946704fa9fc1d7d5577f2e5e02d1081"} 19:47:15 INFO - Running post-run listener: copy_logs_to_upload_dir 19:47:15 INFO - Copying logs to upload dir... 19:47:15 INFO - mkdir: /builds/slave/test/build/upload/logs 19:47:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.345190 ========= master_lag: 3.39 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-08-16 19:47:18.963721) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-16 19:47:18.970959) ========= 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.o0qYcHQjzC/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.o3MsZuxzVt/Listeners TMPDIR=/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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/edca7d6eb77275bb3b553ddd1de48408372ea4542ad405bc9cd6384704d7339bc1c2856c10f87fdacb5d00e15677476b8e8914bebb0e6045ef75bb60bf6c6b55", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/83e46bfed3cdae36f90c7cfa7cfc90571663faa55494e7a4faccfbe8fca51d6e72a61229b042837baa8b51bc386a4576a7e90a98eb13b43a074350a9a0cd75c0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b3750f91e5eb55fe6dea56e3778f7c0960a9d3479b48fd79a4657efb0e1fc763985ca2f239f643fcabdb1d37c4d1e2b6946704fa9fc1d7d5577f2e5e02d1081"} build_url:https://queue.taskcluster.net/v1/task/cxbgc7tOQMuVWBXcWkgn9g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010699 build_url: 'https://queue.taskcluster.net/v1/task/cxbgc7tOQMuVWBXcWkgn9g/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/edca7d6eb77275bb3b553ddd1de48408372ea4542ad405bc9cd6384704d7339bc1c2856c10f87fdacb5d00e15677476b8e8914bebb0e6045ef75bb60bf6c6b55", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/83e46bfed3cdae36f90c7cfa7cfc90571663faa55494e7a4faccfbe8fca51d6e72a61229b042837baa8b51bc386a4576a7e90a98eb13b43a074350a9a0cd75c0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b3750f91e5eb55fe6dea56e3778f7c0960a9d3479b48fd79a4657efb0e1fc763985ca2f239f643fcabdb1d37c4d1e2b6946704fa9fc1d7d5577f2e5e02d1081"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-16 19:47:19.009406) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 19:47:19.009730) ========= 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.o0qYcHQjzC/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.o3MsZuxzVt/Listeners TMPDIR=/var/folders/hf/ps7y69tn3s12w25w6jngdq6000000w/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.005376 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 19:47:19.058129) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-16 19:47:19.058462) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-16 19:47:19.061823) ========= ========= Total master_lag: 3.55 =========