builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0173 starttime: 1479845562.91 results: success (0) buildid: 20161122095451 builduid: f2015b100320419f946e5a08ca7e91f0 revision: c684ebca8623a319e2ebd93a8140de680bf88456 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-22 12:12:42.906625) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-22 12:12:42.907095) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-22 12:12:42.907379) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-22 12:12:42.933855) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 12:12:42.934202) ========= 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.AnY54q9qpZ/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.S3YEp20sQV/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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-11-22 12:12:42-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 168M=0s 2016-11-22 12:12:43 (168 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.139372 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 12:12:43.098037) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 12:12:43.098354) ========= 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.AnY54q9qpZ/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.S3YEp20sQV/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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.028003 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 12:12:43.147863) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-22 12:12:43.148217) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c684ebca8623a319e2ebd93a8140de680bf88456 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c684ebca8623a319e2ebd93a8140de680bf88456 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.AnY54q9qpZ/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.S3YEp20sQV/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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-11-22 12:12:43,241 truncating revision to first 12 chars 2016-11-22 12:12:43,241 Setting DEBUG logging. 2016-11-22 12:12:43,241 attempt 1/10 2016-11-22 12:12:43,241 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/c684ebca8623?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-22 12:12:44,335 unpacking tar archive at: mozilla-aurora-c684ebca8623/testing/mozharness/ program finished with exit code 0 elapsedTime=1.364474 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-22 12:12:44.535750) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-22 12:12:44.536076) ========= script_repo_revision: c684ebca8623a319e2ebd93a8140de680bf88456 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-22 12:12:44.536495) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-22 12:12:44.536808) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-22 12:12:44.556785) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-22 12:12:44.557126) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.AnY54q9qpZ/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.S3YEp20sQV/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 12:12:44 INFO - MultiFileLogger online at 20161122 12:12:44 in /builds/slave/test 12:12:44 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 12:12:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:12:44 INFO - {'append_to_log': False, 12:12:44 INFO - 'base_work_dir': '/builds/slave/test', 12:12:44 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 12:12:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:12:44 INFO - 'branch': 'Mozilla-Aurora', 12:12:44 INFO - 'buildbot_json_path': 'buildprops.json', 12:12:44 INFO - 'config_files': ('talos/mac_config.py',), 12:12:44 INFO - 'default_actions': ('clobber', 12:12:44 INFO - 'read-buildbot-config', 12:12:44 INFO - 'download-and-extract', 12:12:44 INFO - 'populate-webroot', 12:12:44 INFO - 'create-virtualenv', 12:12:44 INFO - 'install', 12:12:44 INFO - 'run-tests'), 12:12:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:12:44 INFO - 'download_minidump_stackwalk': True, 12:12:44 INFO - 'download_symbols': 'ondemand', 12:12:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:12:44 INFO - 'tooltool.py': '/tools/tooltool.py', 12:12:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:12:44 INFO - '/tools/misc-python/virtualenv.py')}, 12:12:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:12:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:12:44 INFO - 'installer_path': 'installer.exe', 12:12:44 INFO - 'log_level': 'info', 12:12:44 INFO - 'log_name': 'talos', 12:12:44 INFO - 'log_to_console': True, 12:12:44 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:12:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:12:44 INFO - 'opt_config_files': (), 12:12:44 INFO - 'pip_index': False, 12:12:44 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:12:44 INFO - 'cmd': ('bash', 12:12:44 INFO - '-c', 12:12:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:12:44 INFO - 'enabled': True, 12:12:44 INFO - 'halt_on_failure': False, 12:12:44 INFO - 'name': 'check_screen_resolution'},), 12:12:44 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:12:44 INFO - 'cmd': ('bash', 12:12:44 INFO - '-c', 12:12:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:12:44 INFO - 'enabled': True, 12:12:44 INFO - 'halt_on_failure': False, 12:12:44 INFO - 'name': 'check_screen_resolution'},), 12:12:44 INFO - 'run_cmd_checks_enabled': True, 12:12:44 INFO - 'sps_profile': False, 12:12:44 INFO - 'sps_profile_interval': 0, 12:12:44 INFO - 'suite': 'dromaeojs', 12:12:44 INFO - 'system_bits': '32', 12:12:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:12:44 INFO - 'title': 't-yosemite-r7-0173', 12:12:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:12:44 INFO - 'use_talos_json': True, 12:12:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:12:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:12:44 INFO - 'work_dir': 'build'} 12:12:44 INFO - [mozharness: 2016-11-22 20:12:44.759312Z] Running clobber step. 12:12:44 INFO - Running pre-action listener: _resource_record_pre_action 12:12:44 INFO - Running main action method: clobber 12:12:44 INFO - rmtree: /builds/slave/test/build 12:12:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:12:47 INFO - Running post-action listener: _resource_record_post_action 12:12:47 INFO - [mozharness: 2016-11-22 20:12:47.287280Z] Finished clobber step (success) 12:12:47 INFO - [mozharness: 2016-11-22 20:12:47.287489Z] Running read-buildbot-config step. 12:12:47 INFO - Running pre-action listener: _resource_record_pre_action 12:12:47 INFO - Running main action method: read_buildbot_config 12:12:47 INFO - Using buildbot properties: 12:12:47 INFO - { 12:12:47 INFO - "project": "", 12:12:47 INFO - "product": "firefox", 12:12:47 INFO - "script_repo_revision": "production", 12:12:47 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 12:12:47 INFO - "repository": "", 12:12:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 12:12:47 INFO - "buildid": "20161122095451", 12:12:47 INFO - "pgo_build": "False", 12:12:47 INFO - "basedir": "/builds/slave/test", 12:12:47 INFO - "buildnumber": 46, 12:12:47 INFO - "slavename": "t-yosemite-r7-0173", 12:12:47 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 12:12:47 INFO - "platform": "yosemite_r7", 12:12:47 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 12:12:47 INFO - "branch": "mozilla-aurora", 12:12:47 INFO - "repo_path": "releases/mozilla-aurora", 12:12:47 INFO - "revision": "c684ebca8623a319e2ebd93a8140de680bf88456", 12:12:47 INFO - "stage_platform": "macosx64", 12:12:47 INFO - "builduid": "f2015b100320419f946e5a08ca7e91f0", 12:12:47 INFO - "slavebuilddir": "test" 12:12:47 INFO - } 12:12:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/HWZAwR0QTWiC28oo2Qr6oA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 12:12:47 INFO - Running post-action listener: _resource_record_post_action 12:12:47 INFO - [mozharness: 2016-11-22 20:12:47.292980Z] Finished read-buildbot-config step (success) 12:12:47 INFO - [mozharness: 2016-11-22 20:12:47.293203Z] Running download-and-extract step. 12:12:47 INFO - Running pre-action listener: _resource_record_pre_action 12:12:47 INFO - Running main action method: download_and_extract 12:12:47 INFO - mkdir: /builds/slave/test/build/tests 12:12: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')]} 12:12:47 INFO - trying https://queue.taskcluster.net/v1/task/HWZAwR0QTWiC28oo2Qr6oA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 12:12:47 INFO - Downloading https://queue.taskcluster.net/v1/task/HWZAwR0QTWiC28oo2Qr6oA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 12:12:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HWZAwR0QTWiC28oo2Qr6oA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 12:12:48 INFO - Downloaded 1199 bytes. 12:12:48 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 12:12:48 INFO - Using the following test package requirements: 12:12:48 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 12:12:48 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:12:48 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 12:12:48 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:12:48 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 12:12:48 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:12:48 INFO - u'jsshell-mac.zip'], 12:12:48 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:12:48 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 12:12:48 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 12:12:48 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:12:48 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 12:12:48 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:12:48 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 12:12:48 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:12:48 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 12:12:48 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:12:48 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 12:12:48 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 12:12:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HWZAwR0QTWiC28oo2Qr6oA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 12:12:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HWZAwR0QTWiC28oo2Qr6oA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 12:12:48 INFO - Fetch https://queue.taskcluster.net/v1/task/HWZAwR0QTWiC28oo2Qr6oA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 12:12:48 INFO - Http code: 200 12:12:48 INFO - via: 1.1 9baadab4e4d95afe7af7bcba342b29c6.cloudfront.net (CloudFront) 12:12:48 INFO - x-amz-cf-id: V_BMyWkaFJ0ckspXR7o-wGs7NO2jjk_oJenw5HR5nMG_8odde2cmLg== 12:12:48 INFO - x-amz-version-id: W3XY0AcjJvki5RL09nN99vwDb9aDtU7C 12:12:49 INFO - Expected file size: 16277503 12:12:49 INFO - Obtained file size: 16277503 12:12:55 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 12:12:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HWZAwR0QTWiC28oo2Qr6oA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 12:12:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HWZAwR0QTWiC28oo2Qr6oA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 12:12:55 INFO - Fetch https://queue.taskcluster.net/v1/task/HWZAwR0QTWiC28oo2Qr6oA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 12:12:56 INFO - Http code: 200 12:12:56 INFO - via: 1.1 5ed093c37d643652e278a198e0556a40.cloudfront.net (CloudFront) 12:12:56 INFO - x-amz-cf-id: juOfa161npiAu4r6HgI-6WePR010dJ3drgKZiT0ot82cmfgXyjwCdw== 12:12:56 INFO - x-amz-version-id: W3XY0AcjJvki5RL09nN99vwDb9aDtU7C 12:12:56 INFO - Expected file size: 16277503 12:12:56 INFO - Obtained file size: 16277503 12:13:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HWZAwR0QTWiC28oo2Qr6oA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 12:13:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HWZAwR0QTWiC28oo2Qr6oA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 12:13:02 INFO - Fetch https://queue.taskcluster.net/v1/task/HWZAwR0QTWiC28oo2Qr6oA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 12:13:03 INFO - Http code: 200 12:13:03 INFO - via: 1.1 d897b81191cbfd68a1b6fc44f98c52b0.cloudfront.net (CloudFront) 12:13:03 INFO - x-amz-cf-id: qhrkft-86OJnX5FDBdByOo32I8iP8-3Olvm3RBz_Tk1lk-NDgBzQ6Q== 12:13:03 INFO - x-amz-version-id: k8ps3cHbjocwNiBkiwy0GgCqwf8jwNCx 12:13:03 INFO - Expected file size: 14409038 12:13:03 INFO - Obtained file size: 14409038 12:13:04 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')]} 12:13:04 INFO - trying https://queue.taskcluster.net/v1/task/HWZAwR0QTWiC28oo2Qr6oA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 12:13:04 INFO - Downloading https://queue.taskcluster.net/v1/task/HWZAwR0QTWiC28oo2Qr6oA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 12:13:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HWZAwR0QTWiC28oo2Qr6oA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 12:13:06 INFO - Downloaded 109675445 bytes. 12:13:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HWZAwR0QTWiC28oo2Qr6oA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 12:13:06 INFO - mkdir: /builds/slave/test/properties 12:13:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:13:06 INFO - Writing to file /builds/slave/test/properties/build_url 12:13:06 INFO - Contents: 12:13:06 INFO - build_url:https://queue.taskcluster.net/v1/task/HWZAwR0QTWiC28oo2Qr6oA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 12:13:07 INFO - Running post-action listener: _resource_record_post_action 12:13:07 INFO - Running post-action listener: set_extra_try_arguments 12:13:07 INFO - [mozharness: 2016-11-22 20:13:07.621721Z] Finished download-and-extract step (success) 12:13:07 INFO - [mozharness: 2016-11-22 20:13:07.621851Z] Running populate-webroot step. 12:13:07 INFO - Running pre-action listener: _resource_record_pre_action 12:13:07 INFO - Running main action method: populate_webroot 12:13:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:13:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:13:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:13:07 INFO - '--tppagecycles', 12:13:07 INFO - '7'], 12:13:07 INFO - 'tests': ['tsvgm']}}, 12:13:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:13:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:13:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:13:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:13:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:13:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:13:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:13:07 INFO - 'talos_options': ['--disable-e10s'], 12:13:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:13:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:13:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:13:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:13:07 INFO - 'talos_options': ['--disable-e10s'], 12:13:07 INFO - 'tests': ['damp', 'tps']}, 12:13:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:13:07 INFO - 'tests': ['damp', 'tps']}, 12:13:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:13:07 INFO - 'tests': ['dromaeo_dom']}, 12:13:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:13:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:13:07 INFO - 'tests': ['basic_compositor_video']}, 12:13:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:13:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:13:07 INFO - 'tests': ['a11yr', 12:13:07 INFO - 'ts_paint', 12:13:07 INFO - 'tpaint', 12:13:07 INFO - 'sessionrestore', 12:13:07 INFO - 'sessionrestore_no_auto_restore', 12:13:07 INFO - 'tabpaint']}, 12:13:07 INFO - 'other-e10s': {'tests': ['a11yr', 12:13:07 INFO - 'ts_paint', 12:13:07 INFO - 'tpaint', 12:13:07 INFO - 'sessionrestore', 12:13:07 INFO - 'sessionrestore_no_auto_restore', 12:13:07 INFO - 'tabpaint']}, 12:13:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:13:07 INFO - 'ts_paint', 12:13:07 INFO - 'tpaint', 12:13:07 INFO - 'sessionrestore', 12:13:07 INFO - 'sessionrestore_no_auto_restore', 12:13:07 INFO - 'tabpaint']}, 12:13:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:13:07 INFO - 'ts_paint', 12:13:07 INFO - 'tpaint', 12:13:07 INFO - 'sessionrestore', 12:13:07 INFO - 'sessionrestore_no_auto_restore', 12:13:07 INFO - 'tabpaint']}, 12:13:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:13:07 INFO - 'tests': ['a11yr', 12:13:07 INFO - 'ts_paint', 12:13:07 INFO - 'tpaint', 12:13:07 INFO - 'sessionrestore', 12:13:07 INFO - 'sessionrestore_no_auto_restore', 12:13:07 INFO - 'tabpaint']}, 12:13:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:13:07 INFO - 'tests': ['a11yr', 12:13:07 INFO - 'ts_paint', 12:13:07 INFO - 'tpaint', 12:13:07 INFO - 'sessionrestore', 12:13:07 INFO - 'sessionrestore_no_auto_restore', 12:13:07 INFO - 'tabpaint']}, 12:13:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:13:07 INFO - 'tests': ['tsvgx', 12:13:07 INFO - 'tsvgr_opacity', 12:13:07 INFO - 'tart', 12:13:07 INFO - 'tscrollx', 12:13:07 INFO - 'cart']}, 12:13:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:13:07 INFO - 'tsvgr_opacity', 12:13:07 INFO - 'tart', 12:13:07 INFO - 'tscrollx', 12:13:07 INFO - 'cart']}, 12:13:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:13:07 INFO - 'talos_options': ['--disable-e10s'], 12:13:07 INFO - 'tests': ['tp5o']}, 12:13:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:13:07 INFO - 'tests': ['tp5o']}, 12:13:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:13:07 INFO - 'talos_options': ['--disable-e10s', 12:13:07 INFO - '--xperf_path', 12:13:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:13:07 INFO - 'tests': ['tp5n']}, 12:13:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:13:07 INFO - 'talos_options': ['--xperf_path', 12:13:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:13:07 INFO - 'tests': ['tp5n']}}, 12:13:07 INFO - 'talos.zip': {'path': '', 12:13:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:13:07 INFO - Running post-action listener: _resource_record_post_action 12:13:07 INFO - [mozharness: 2016-11-22 20:13:07.640811Z] Finished populate-webroot step (success) 12:13:07 INFO - [mozharness: 2016-11-22 20:13:07.640922Z] Running create-virtualenv step. 12:13:07 INFO - Running pre-action listener: _resource_record_pre_action 12:13:07 INFO - Running main action method: create_virtualenv 12:13:07 INFO - Creating virtualenv /builds/slave/test/build/venv 12:13:07 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 12:13:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:13:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:13:07 INFO - Using real prefix '/tools/python27' 12:13:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:13:08 INFO - Installing distribute.............................................................................................................................................................................................done. 12:13:11 INFO - Installing pip.................done. 12:13:11 INFO - Return code: 0 12:13:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:13:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:13:11 INFO - Reading from file tmpfile_stdout 12:13:11 INFO - Output received: 12:13:11 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 12:13:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:13:11 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')]} 12:13:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:11 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')]} 12:13:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:11 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 0x101584490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014fa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2da4ba540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101398d30>, '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 0x1011fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101390b20>, '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.AnY54q9qpZ/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.S3YEp20sQV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 12:13:11 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 12:13:11 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 12:13:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AnY54q9qpZ/Render', 12:13:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:11 INFO - 'HOME': '/Users/cltbld', 12:13:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:11 INFO - 'LOGNAME': 'cltbld', 12:13:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:11 INFO - 'MOZ_NO_REMOTE': '1', 12:13:11 INFO - 'NO_EM_RESTART': '1', 12:13:11 INFO - 'PAGER': '/bin/cat', 12:13:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:11 INFO - 'PWD': '/builds/slave/test', 12:13:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:11 INFO - 'SHELL': '/bin/bash', 12:13:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S3YEp20sQV/Listeners', 12:13:11 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 12:13:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:11 INFO - 'USER': 'cltbld', 12:13:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:11 INFO - 'XPC_FLAGS': '0x0', 12:13:11 INFO - 'XPC_SERVICE_NAME': '0', 12:13:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:11 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 12:13:11 INFO - Cleaning up... 12:13:11 INFO - Return code: 0 12:13:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:13:11 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')]} 12:13:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:11 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')]} 12:13:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:11 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 0x101584490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014fa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2da4ba540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101398d30>, '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 0x1011fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101390b20>, '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.AnY54q9qpZ/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.S3YEp20sQV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 12:13:11 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 12:13:11 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 12:13:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AnY54q9qpZ/Render', 12:13:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:11 INFO - 'HOME': '/Users/cltbld', 12:13:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:11 INFO - 'LOGNAME': 'cltbld', 12:13:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:11 INFO - 'MOZ_NO_REMOTE': '1', 12:13:11 INFO - 'NO_EM_RESTART': '1', 12:13:11 INFO - 'PAGER': '/bin/cat', 12:13:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:11 INFO - 'PWD': '/builds/slave/test', 12:13:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:11 INFO - 'SHELL': '/bin/bash', 12:13:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S3YEp20sQV/Listeners', 12:13:11 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 12:13:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:11 INFO - 'USER': 'cltbld', 12:13:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:11 INFO - 'XPC_FLAGS': '0x0', 12:13:11 INFO - 'XPC_SERVICE_NAME': '0', 12:13:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:12 INFO - Downloading/unpacking psutil>=3.1.1 12:13:12 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 12:13:12 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 12:13:12 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 12:13:12 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 12:13:12 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 12:13:12 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 12:13:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:13:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:13:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:13:15 INFO - Installing collected packages: psutil 12:13:15 INFO - Running setup.py install for psutil 12:13:15 INFO - building 'psutil._psutil_osx' extension 12:13:15 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 12:13:16 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 12:13:16 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 12:13:16 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 12:13:16 INFO - building 'psutil._psutil_posix' extension 12:13:16 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 12:13:16 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:13:16 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:13:16 INFO - ^ 12:13:16 INFO - 1 warning generated. 12:13:16 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 12:13:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:13:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:13:16 INFO - Successfully installed psutil 12:13:16 INFO - Cleaning up... 12:13:16 INFO - Return code: 0 12:13:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:13:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:16 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 0x101584490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014fa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2da4ba540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101398d30>, '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 0x1011fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101390b20>, '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.AnY54q9qpZ/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.S3YEp20sQV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 12:13:16 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 12:13:16 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 12:13:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AnY54q9qpZ/Render', 12:13:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:16 INFO - 'HOME': '/Users/cltbld', 12:13:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:16 INFO - 'LOGNAME': 'cltbld', 12:13:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:16 INFO - 'MOZ_NO_REMOTE': '1', 12:13:16 INFO - 'NO_EM_RESTART': '1', 12:13:16 INFO - 'PAGER': '/bin/cat', 12:13:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:16 INFO - 'PWD': '/builds/slave/test', 12:13:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:16 INFO - 'SHELL': '/bin/bash', 12:13:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S3YEp20sQV/Listeners', 12:13:16 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 12:13:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:16 INFO - 'USER': 'cltbld', 12:13:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:16 INFO - 'XPC_FLAGS': '0x0', 12:13:16 INFO - 'XPC_SERVICE_NAME': '0', 12:13:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:13:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:13:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:13:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:13:19 INFO - Installing collected packages: mozsystemmonitor 12:13:19 INFO - Running setup.py install for mozsystemmonitor 12:13:19 INFO - Successfully installed mozsystemmonitor 12:13:19 INFO - Cleaning up... 12:13:19 INFO - Return code: 0 12:13:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:13:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101584490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014fa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2da4ba540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101398d30>, '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 0x1011fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101390b20>, '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.AnY54q9qpZ/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.S3YEp20sQV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 12:13:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:13:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:13:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AnY54q9qpZ/Render', 12:13:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:19 INFO - 'HOME': '/Users/cltbld', 12:13:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:19 INFO - 'LOGNAME': 'cltbld', 12:13:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:19 INFO - 'MOZ_NO_REMOTE': '1', 12:13:19 INFO - 'NO_EM_RESTART': '1', 12:13:19 INFO - 'PAGER': '/bin/cat', 12:13:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:19 INFO - 'PWD': '/builds/slave/test', 12:13:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:19 INFO - 'SHELL': '/bin/bash', 12:13:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S3YEp20sQV/Listeners', 12:13:19 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 12:13:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:19 INFO - 'USER': 'cltbld', 12:13:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:19 INFO - 'XPC_FLAGS': '0x0', 12:13:19 INFO - 'XPC_SERVICE_NAME': '0', 12:13:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:20 INFO - Downloading/unpacking jsonschema==2.5.1 12:13:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:13:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:13:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:23 INFO - Downloading functools32-3.2.3-2.tar.gz 12:13:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:13:23 INFO - warning: no files found matching '*.txt' 12:13:23 INFO - no previously-included directories found matching 'build' 12:13:23 INFO - no previously-included directories found matching 'dist' 12:13:23 INFO - no previously-included directories found matching '.git*' 12:13:23 INFO - Installing collected packages: jsonschema, functools32 12:13:23 INFO - Running setup.py install for functools32 12:13:23 INFO - warning: no files found matching '*.txt' 12:13:23 INFO - no previously-included directories found matching 'build' 12:13:23 INFO - no previously-included directories found matching 'dist' 12:13:23 INFO - no previously-included directories found matching '.git*' 12:13:23 INFO - Successfully installed jsonschema functools32 12:13:23 INFO - Cleaning up... 12:13:23 INFO - Return code: 0 12:13:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:13: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')]} 12:13:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13: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')]} 12:13:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:23 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 0x101584490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014fa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2da4ba540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101398d30>, '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 0x1011fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101390b20>, '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.AnY54q9qpZ/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.S3YEp20sQV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 12:13:23 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 12:13:23 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 12:13:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AnY54q9qpZ/Render', 12:13:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:23 INFO - 'HOME': '/Users/cltbld', 12:13:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:23 INFO - 'LOGNAME': 'cltbld', 12:13:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:23 INFO - 'MOZ_NO_REMOTE': '1', 12:13:23 INFO - 'NO_EM_RESTART': '1', 12:13:23 INFO - 'PAGER': '/bin/cat', 12:13:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:23 INFO - 'PWD': '/builds/slave/test', 12:13:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:23 INFO - 'SHELL': '/bin/bash', 12:13:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S3YEp20sQV/Listeners', 12:13:23 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 12:13:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:23 INFO - 'USER': 'cltbld', 12:13:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:23 INFO - 'XPC_FLAGS': '0x0', 12:13:23 INFO - 'XPC_SERVICE_NAME': '0', 12:13:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:13:23 INFO - Cleaning up... 12:13:23 INFO - Return code: 0 12:13:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:13: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')]} 12:13:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13: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')]} 12:13:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:23 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 0x101584490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014fa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2da4ba540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101398d30>, '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 0x1011fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101390b20>, '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.AnY54q9qpZ/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.S3YEp20sQV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 12:13:23 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 12:13:23 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 12:13:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AnY54q9qpZ/Render', 12:13:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:23 INFO - 'HOME': '/Users/cltbld', 12:13:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:23 INFO - 'LOGNAME': 'cltbld', 12:13:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:23 INFO - 'MOZ_NO_REMOTE': '1', 12:13:23 INFO - 'NO_EM_RESTART': '1', 12:13:23 INFO - 'PAGER': '/bin/cat', 12:13:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:23 INFO - 'PWD': '/builds/slave/test', 12:13:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:23 INFO - 'SHELL': '/bin/bash', 12:13:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S3YEp20sQV/Listeners', 12:13:23 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 12:13:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:23 INFO - 'USER': 'cltbld', 12:13:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:23 INFO - 'XPC_FLAGS': '0x0', 12:13:23 INFO - 'XPC_SERVICE_NAME': '0', 12:13:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:24 INFO - Downloading/unpacking blobuploader==1.2.4 12:13:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:26 INFO - Downloading blobuploader-1.2.4.tar.gz 12:13:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:13:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:13:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:13:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:13:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:13:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:13:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:13:27 INFO - Downloading docopt-0.6.1.tar.gz 12:13:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:13:27 INFO - Installing collected packages: blobuploader, requests, docopt 12:13:27 INFO - Running setup.py install for blobuploader 12:13:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:13:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:13:28 INFO - Running setup.py install for requests 12:13:28 INFO - Running setup.py install for docopt 12:13:28 INFO - Successfully installed blobuploader requests docopt 12:13:28 INFO - Cleaning up... 12:13:28 INFO - Return code: 0 12:13:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:13:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:28 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 0x101584490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014fa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2da4ba540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101398d30>, '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 0x1011fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101390b20>, '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.AnY54q9qpZ/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.S3YEp20sQV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 12:13:28 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 12:13:28 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 12:13:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AnY54q9qpZ/Render', 12:13:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:28 INFO - 'HOME': '/Users/cltbld', 12:13:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:28 INFO - 'LOGNAME': 'cltbld', 12:13:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:28 INFO - 'MOZ_NO_REMOTE': '1', 12:13:28 INFO - 'NO_EM_RESTART': '1', 12:13:28 INFO - 'PAGER': '/bin/cat', 12:13:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:28 INFO - 'PWD': '/builds/slave/test', 12:13:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:28 INFO - 'SHELL': '/bin/bash', 12:13:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S3YEp20sQV/Listeners', 12:13:28 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 12:13:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:28 INFO - 'USER': 'cltbld', 12:13:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:28 INFO - 'XPC_FLAGS': '0x0', 12:13:28 INFO - 'XPC_SERVICE_NAME': '0', 12:13:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:13:29 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-wf91gH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:13:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:13:29 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-rgiVLg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:13:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:13:29 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-9xLNvD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:13:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:13:29 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-ZurxId-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:13:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:13:29 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-HqYKjw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:13:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:13:29 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-Ioh4Ay-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:13:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:13:29 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-iUjUd5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:13:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:13:29 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-QA47zW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:13:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:13:29 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-vsjfKu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:13:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:13:29 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-X5qc44-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:13:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:13:30 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-BwAoWf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:13:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:13:30 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-GmAkUZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:13:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:13:30 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-NycKbF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:13:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:13:30 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-Z43PW1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:13:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:13:30 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-6sdu3i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:13:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:13:30 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-Fclf_s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:13:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:13:30 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-qCUwFj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:13:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:13:30 INFO - Running setup.py install for manifestparser 12:13:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:13:30 INFO - Running setup.py install for mozcrash 12:13:31 INFO - Running setup.py install for mozdebug 12:13:31 INFO - Running setup.py install for mozdevice 12:13:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:13:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:13:31 INFO - Running setup.py install for mozfile 12:13:31 INFO - Running setup.py install for mozhttpd 12:13:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:13:31 INFO - Running setup.py install for mozinfo 12:13:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:13:31 INFO - Running setup.py install for mozInstall 12:13:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:13:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:13:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:13:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:13:31 INFO - Running setup.py install for mozleak 12:13:32 INFO - Running setup.py install for mozlog 12:13:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:13:32 INFO - Running setup.py install for moznetwork 12:13:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:13:32 INFO - Running setup.py install for mozprocess 12:13:32 INFO - Running setup.py install for mozprofile 12:13:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:13:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:13:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:13:32 INFO - Running setup.py install for mozrunner 12:13:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:13:32 INFO - Running setup.py install for mozscreenshot 12:13:33 INFO - Running setup.py install for moztest 12:13:33 INFO - Running setup.py install for mozversion 12:13:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:13:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:13:33 INFO - Cleaning up... 12:13:33 INFO - Return code: 0 12:13:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:13:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:33 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 0x101584490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014fa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2da4ba540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101398d30>, '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 0x1011fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101390b20>, '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.AnY54q9qpZ/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.S3YEp20sQV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 12:13:33 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 12:13:33 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 12:13:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AnY54q9qpZ/Render', 12:13:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:33 INFO - 'HOME': '/Users/cltbld', 12:13:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:33 INFO - 'LOGNAME': 'cltbld', 12:13:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:33 INFO - 'MOZ_NO_REMOTE': '1', 12:13:33 INFO - 'NO_EM_RESTART': '1', 12:13:33 INFO - 'PAGER': '/bin/cat', 12:13:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:33 INFO - 'PWD': '/builds/slave/test', 12:13:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:33 INFO - 'SHELL': '/bin/bash', 12:13:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S3YEp20sQV/Listeners', 12:13:33 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 12:13:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:33 INFO - 'USER': 'cltbld', 12:13:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:33 INFO - 'XPC_FLAGS': '0x0', 12:13:33 INFO - 'XPC_SERVICE_NAME': '0', 12:13:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:13:33 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-MXVoJQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:13:33 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)) 12:13:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:13:33 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-Wjh5rq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:13:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 12:13:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:13:33 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-fzw6X8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:13:33 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)) 12:13:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:13:33 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-a7Y4Jo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:13:34 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)) 12:13:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:13:34 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-q1gwJY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:13:34 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)) 12:13:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:13:34 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-Hjlslw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:13:34 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)) 12:13:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:13:34 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-1BL2Dv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:13:34 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)) 12:13:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:13:34 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-rzUKND-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:13:34 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)) 12:13:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:13:34 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-GEKOHJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:13:34 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)) 12:13:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:13:34 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-osipEJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:13:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 12:13:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:13:34 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-ByrF7B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:13:34 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)) 12:13:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:13:34 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-H8MPfO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:13:34 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)) 12:13:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:13:34 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-oboo1n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:13:35 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)) 12:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:13:35 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-Gx1lT6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:13:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 12:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:13:35 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-TWUAAY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:13:35 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)) 12:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:13:35 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-bAB724-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:13:35 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)) 12:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:13:35 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-PduxHi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:13:35 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)) 12:13:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:13:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:13:35 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)) 12:13:35 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)) 12:13:35 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)) 12:13:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:13: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 12:13: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 12:13: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 12:13: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 12:13: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 12:13: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 12:13:37 INFO - Downloading blessings-1.6.tar.gz 12:13:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:13:38 INFO - Installing collected packages: blessings 12:13:38 INFO - Running setup.py install for blessings 12:13:38 INFO - Successfully installed blessings 12:13:38 INFO - Cleaning up... 12:13:38 INFO - Return code: 0 12:13:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:13:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:13:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:13:38 INFO - Reading from file tmpfile_stdout 12:13:38 INFO - Current package versions: 12:13:38 INFO - blessings == 1.6 12:13:38 INFO - blobuploader == 1.2.4 12:13:38 INFO - docopt == 0.6.1 12:13:38 INFO - functools32 == 3.2.3-2 12:13:38 INFO - jsonschema == 2.5.1 12:13:38 INFO - manifestparser == 1.1 12:13:38 INFO - mozInstall == 1.12 12:13:38 INFO - mozcrash == 1.0 12:13:38 INFO - mozdebug == 0.1 12:13:38 INFO - mozdevice == 0.48 12:13:38 INFO - mozfile == 1.2 12:13:38 INFO - mozhttpd == 0.7 12:13:38 INFO - mozinfo == 0.9 12:13:38 INFO - mozleak == 0.1 12:13:38 INFO - mozlog == 3.4 12:13:38 INFO - moznetwork == 0.27 12:13:38 INFO - mozprocess == 0.23 12:13:38 INFO - mozprofile == 0.28 12:13:38 INFO - mozrunner == 6.13 12:13:38 INFO - mozscreenshot == 0.1 12:13:38 INFO - mozsystemmonitor == 0.3 12:13:38 INFO - moztest == 0.7 12:13:38 INFO - mozversion == 1.4 12:13:38 INFO - psutil == 3.1.1 12:13:38 INFO - requests == 1.2.3 12:13:38 INFO - wsgiref == 0.1.2 12:13:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:13: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')]} 12:13:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:39 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 0x101584490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014fa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2da4ba540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101398d30>, '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 0x1011fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101390b20>, '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.AnY54q9qpZ/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.S3YEp20sQV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 12:13:39 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 12:13:39 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 12:13:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AnY54q9qpZ/Render', 12:13:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:39 INFO - 'HOME': '/Users/cltbld', 12:13:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:39 INFO - 'LOGNAME': 'cltbld', 12:13:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:39 INFO - 'MOZ_NO_REMOTE': '1', 12:13:39 INFO - 'NO_EM_RESTART': '1', 12:13:39 INFO - 'PAGER': '/bin/cat', 12:13:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:39 INFO - 'PWD': '/builds/slave/test', 12:13:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:39 INFO - 'SHELL': '/bin/bash', 12:13:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S3YEp20sQV/Listeners', 12:13:39 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 12:13:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:39 INFO - 'USER': 'cltbld', 12:13:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:39 INFO - 'XPC_FLAGS': '0x0', 12:13:39 INFO - 'XPC_SERVICE_NAME': '0', 12:13:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:39 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)) 12:13:39 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)) 12:13:39 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)) 12:13:39 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)) 12:13:39 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)) 12:13:39 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)) 12:13:39 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)) 12:13:39 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)) 12:13:39 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)) 12:13:39 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)) 12:13:39 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)) 12:13:39 INFO - Cleaning up... 12:13:39 INFO - Return code: 0 12:13:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:13:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:13:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:13:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:13:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:13:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101584490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014fa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2da4ba540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101398d30>, '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 0x1011fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101390b20>, '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.AnY54q9qpZ/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.S3YEp20sQV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 12:13:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:13:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:13:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AnY54q9qpZ/Render', 12:13:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:13:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:13:40 INFO - 'HOME': '/Users/cltbld', 12:13:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:13:40 INFO - 'LOGNAME': 'cltbld', 12:13:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:13:40 INFO - 'MOZ_NO_REMOTE': '1', 12:13:40 INFO - 'NO_EM_RESTART': '1', 12:13:40 INFO - 'PAGER': '/bin/cat', 12:13:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:13:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:13:40 INFO - 'PWD': '/builds/slave/test', 12:13:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:13:40 INFO - 'SHELL': '/bin/bash', 12:13:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S3YEp20sQV/Listeners', 12:13:40 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 12:13:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:13:40 INFO - 'USER': 'cltbld', 12:13:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:13:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:13:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:13:40 INFO - 'XPC_FLAGS': '0x0', 12:13:40 INFO - 'XPC_SERVICE_NAME': '0', 12:13:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:13:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:13:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:13:40 INFO - Cleaning up... 12:13:40 INFO - Return code: 0 12:13:40 INFO - Running post-action listener: _resource_record_post_action 12:13:40 INFO - Running post-action listener: _start_resource_monitoring 12:13:40 INFO - Starting resource monitoring. 12:13:40 INFO - [mozharness: 2016-11-22 20:13:40.733199Z] Finished create-virtualenv step (success) 12:13:40 INFO - [mozharness: 2016-11-22 20:13:40.734260Z] Running install step. 12:13:40 INFO - Running pre-action listener: _resource_record_pre_action 12:13:40 INFO - Running main action method: install 12:13:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:13:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:13:40 INFO - Reading from file tmpfile_stdout 12:13:40 INFO - Detecting whether we're running mozinstall >=1.0... 12:13:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:13:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:13:41 INFO - Reading from file tmpfile_stdout 12:13:41 INFO - Output received: 12:13:41 INFO - Usage: mozinstall [options] installer 12:13:41 INFO - Options: 12:13:41 INFO - -h, --help show this help message and exit 12:13:41 INFO - -d DEST, --destination=DEST 12:13:41 INFO - Directory to install application into. [default: 12:13:41 INFO - "/builds/slave/test"] 12:13:41 INFO - --app=APP Application being installed. [default: firefox] 12:13:41 INFO - mkdir: /builds/slave/test/build/application 12:13:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:13:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 12:14:12 INFO - Reading from file tmpfile_stdout 12:14:12 INFO - Output received: 12:14:12 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 12:14:12 INFO - Running post-action listener: _resource_record_post_action 12:14:12 INFO - [mozharness: 2016-11-22 20:14:12.203242Z] Finished install step (success) 12:14:12 INFO - [mozharness: 2016-11-22 20:14:12.203371Z] Running run-tests step. 12:14:12 INFO - Running pre-action listener: _resource_record_pre_action 12:14:12 INFO - Running main action method: run_tests 12:14:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:14:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:14:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:14:12 INFO - 2016-11-22 12:14:12.216 screenresolution[1621:7312] starting screenresolution argv=screenresolution get 12:14:12 INFO - 2016-11-22 12:14:12.219 screenresolution[1621:7312] Display 0: 1600x1200x32@60 12:14:12 INFO - 2016-11-22 12:14:12.224 screenresolution[1622:7314] starting screenresolution argv=screenresolution list 12:14:12 INFO - Available Modes on Display 0 12:14:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:14:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:14:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:14:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:14:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:14:12 INFO - Intel Iris: 12:14:12 INFO - Chipset Model: Intel Iris 12:14:12 INFO - Type: GPU 12:14:12 INFO - Bus: Built-In 12:14:12 INFO - VRAM (Dynamic, Max): 1536 MB 12:14:12 INFO - Vendor: Intel (0x8086) 12:14:12 INFO - Device ID: 0x0a2e 12:14:12 INFO - Revision ID: 0x0009 12:14:12 INFO - Displays: 12:14:12 INFO - Display: 12:14:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:14:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:14:12 INFO - Display Serial Number: 1600x1200 60 12:14:12 INFO - Main Display: Yes 12:14:12 INFO - Mirror: Off 12:14:12 INFO - Online: Yes 12:14:12 INFO - Rotation: Supported 12:14:12 INFO - Return code: 0 12:14:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:14:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:14:12 INFO - Python 2.7.3 12:14:12 INFO - Return code: 0 12:14:12 INFO - grabbing minidump binary from tooltool 12:14:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:12 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 0x101398d30>, '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 0x1011fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101390b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:14:12 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 12:14:12 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 12:14:12 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 12:14:12 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:14:12 INFO - Return code: 0 12:14:12 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:14:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:14:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:14:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:14:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:14:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HWZAwR0QTWiC28oo2Qr6oA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0173', '--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 12:14:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HWZAwR0QTWiC28oo2Qr6oA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0173 --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 12:14:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AnY54q9qpZ/Render', 12:14:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:14:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:14:12 INFO - 'HOME': '/Users/cltbld', 12:14:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:14:12 INFO - 'LOGNAME': 'cltbld', 12:14:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:14:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:14:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:12 INFO - 'MOZ_NO_REMOTE': '1', 12:14:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:14:12 INFO - 'NO_EM_RESTART': '1', 12:14:12 INFO - 'PAGER': '/bin/cat', 12:14:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:14:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:12 INFO - 'PWD': '/builds/slave/test', 12:14:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:14:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:14:12 INFO - 'SHELL': '/bin/bash', 12:14:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S3YEp20sQV/Listeners', 12:14:12 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 12:14:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:14:12 INFO - 'USER': 'cltbld', 12:14:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:14:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:14:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:12 INFO - 'XPC_FLAGS': '0x0', 12:14:12 INFO - 'XPC_SERVICE_NAME': '0', 12:14:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:14:12 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HWZAwR0QTWiC28oo2Qr6oA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0173', '--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 12:14:12 INFO - mozversion application_buildid: 20161122095451 12:14:12 INFO - mozversion application_changeset: c684ebca8623a319e2ebd93a8140de680bf88456 12:14:12 INFO - mozversion application_display_name: FirefoxDeveloperEdition 12:14:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:14:12 INFO - mozversion application_name: Firefox 12:14:12 INFO - mozversion application_remotingname: firefox-dev 12:14:12 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:14:12 INFO - mozversion application_vendor: Mozilla 12:14:12 INFO - mozversion application_version: 52.0a2 12:14:12 INFO - mozversion platform_buildid: 20161122095451 12:14:12 INFO - mozversion platform_changeset: c684ebca8623a319e2ebd93a8140de680bf88456 12:14:12 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:14:12 INFO - mozversion platform_version: 52.0a2 12:14:12 INFO - using testdate: 1479845652 12:14:12 INFO - actual date: 1479845652 12:14:12 INFO - starting webserver on 'localhost:49243' 12:14:12 INFO - SUITE-START | Running 2 tests 12:14:12 INFO - TEST-START | dromaeo_css 12:14:12 INFO - operating with platform_type : mac_ 12:14:12 INFO - Initialising browser for dromaeo_css test... 12:14:12 INFO - TEST-INFO | started process 1632 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/tmpfPO_H7/profile http://localhost:49243/getInfo.html) 12:14:14 ERROR - PROCESS | 1632 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5373: TypeError: parent.firstChild is null 12:14:15 INFO - PROCESS | 1632 | __metrics Screen width/height:1600/1200 12:14:15 INFO - PROCESS | 1632 | colorDepth:24 12:14:15 INFO - PROCESS | 1632 | Browser inner width/height: 1024/689 12:14:15 INFO - PROCESS | 1632 | __metrics 12:14:15 INFO - PROCESS | 1632 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:14:15 INFO - PROCESS | 1632 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:14:16 INFO - TEST-INFO | 1632: exit 0 12:14:16 INFO - Browser initialized. 12:14:16 INFO - Running cycle 1/1 for dromaeo_css test... 12:14:16 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/tmpfPO_H7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:14:28 INFO - PROCESS | 1634 | RSS: Main: 222998528 12:14:28 INFO - PROCESS | 1634 | 12:15:19 INFO - PROCESS | 1634 | 1479845719577 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] 12:17:34 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 12:17:36 INFO - PROCESS | 1634 | RSS: Main: 394539008 12:17:36 INFO - PROCESS | 1634 | 12:20:39 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 12:20:39 INFO - PROCESS | 1634 | RSS: Main: 350085120 12:20:39 INFO - PROCESS | 1634 | 12:23:42 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 12:23:42 INFO - PROCESS | 1634 | RSS: Main: 345956352 12:23:42 INFO - PROCESS | 1634 | 12:26:45 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 12:26:45 INFO - PROCESS | 1634 | RSS: Main: 318439424 12:26:45 INFO - PROCESS | 1634 | 12:29:49 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 12:29:49 INFO - PROCESS | 1634 | RSS: Main: 306638848 12:29:49 INFO - PROCESS | 1634 | 12:32:52 INFO - PROCESS | 1634 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 12:32:52 INFO - PROCESS | 1634 | RSS: Main: 360222720 12:32:52 INFO - PROCESS | 1634 | 12:32:52 INFO - PROCESS | 1634 | __start_tp_report 12:32:52 INFO - PROCESS | 1634 | _x_x_mozilla_page_load 12:32:52 INFO - PROCESS | 1634 | _x_x_mozilla_page_load_details 12:32:52 INFO - PROCESS | 1634 | |i|pagename|runs| 12:32:52 INFO - PROCESS | 1634 | |0;dojo.html;3216.4532029554975;3335.983320083401;3341.298327351256;3374.510695949087;3376.27410106827;4354.804033818478;5002.849914502571;5369.5704343652515;5673.035583950728;5743.971280143599;5693.516051135648;5722.685252311132;5752.28096487939;5754.395788442205;5787.21872547206;5186.896262074756;5197.428282888889;5227.607929405291;5263.921041184385;5368.70472124033;5177.378714554259;5190.273361729361;5197.714125723083;5240.056789777838;5332.466753324676;5256.710880901549;5272.762725677354;5279.3928698199625;5288.814891478799;5302;5294.258803767476;5298.496642818926;5299.443558426367;5300.25796388504;5307.708076055816;5121.975604879039;5229.267902493654;5239.318888544503;5245.186996015624;5281.577473802086;5822.9417705822825;5824.184614154022;5831.883362332772;5833.766649334021;5841.47426731592;6092.784833972854;6153.815385538396;6177.413145751147;6187.690615469209;6194.907076393858;5194;6205.503559715212;6207.099970504297;6213.813585592439;6248.218972628421;5782.508486778629;5830.4169583041785;5837.328707198684;5862.325832529228;5866.296044474648;5147.588192944556;6257.874842503215;6269.87460250797;6273.62358258506;6278;6078.908816367758;6110.992327696444;6113.424164191987;6130.348467163483;6135.877282454326;6233.688315584202;6240.5319601030105;6241.875162496724;6244.531660125509;6253.030780229072;6226.688665566703;6240.438360547572;6245.500359971192;6246.593971391845;6255.374462553708;6120.571559990847;6170.938290617126;6184.536159787944;6221.844453888599;6239.875202495925;4968.72672003036;4969.527894849984;4973.154563724103;4993.825216117419;5003.074431230234;4142.233686767958;4371.693981421334;4466.731996080245;4468.262736648417;4484.080763443486;5674.546036317085;5697.857553561112;5719.799807006735;5725.627834190765;5819.912701309484;6199.783007594712;6207.255129384503;6209.285932117864;6210.782622608187;6224.284207316126;5800.0429929062075;5811.157382179645;5812.912806307824;5815.592908496449;5821.476067153892;5314.813981510629;5362.597805164706;5430.456954304538;5446.1558458439;5446.373667028342;5245.344331958505;6190.535709821871;6194.969025154845;6225.782097626561;6238.15784869055;6188.133661287334;6200.81397558074;6226.719797609209;6247.718852651732;6260.248770147611;5584.748686309207;6088.0719949679715;6257.624542527463;6308.053791931247;6329.778457753957;6042.1843051187525;6155.945302618301;6173.00582274324;6206.751729930752;6213.720382582705;6099.969500152471;6106.877862442815;6145.525073982123;6211;6226.564140510302;5946.494547963472;5966.910496342472;5967.433093856077;5980.940190598212;5997.790077347272;6090.543209259235;6165.290991536031;6168.25980882297;6168.629882207082;6192.442680158807;5834.620749651259;5884.705764711662;5924.585279030426;5929.61457505278;6059.394060593905;5979.790707325396;6061.090836374579;6073.058675905242;6109.708332504907;6146.58628515435;5970.283565972111;6011.549133814894;6033.276006879202;6035.305939816977;6110.969445152746 12:32:52 INFO - PROCESS | 1634 | |1;ext.html;465.5902805531133;482.08404032338564;533.5971341637063;550.5402988504599;559.2394343692578;23727.525449491022;31844.681553184462;39890.40164397536;40864.38703419451;41065.79467102668;42341;42468;42493.362599561035;42500.57499425005;42697.14605707884;9192.63229470822;9367.718968430923;9376.8593471098;9399.671011514598;9403.764905877339;9216.17054465098;9311.115444032806;9317.487538185396;9320.067993200693;9340;9296.62813487463;9338.159565639091;9343.299252556051;9347.906520934806;9359.29805264608;9258.305627077962;9318.928323242846;9329.813403731887;9344.112309330603;9351.532423378803;9279;9353.39202951806;9354.438733676001;9356;9369.297302702256;39201.60798392008;39513.40729889054;39532.802335988425;39541.01147471307;39577.60422395768;3131.5146152346424;3233.7413006959387;3383.390989621867;3603.5135256740527;3819.5607505136986;29916;29962.55056174159;30093.548596771067;30315.848420757975;30974.380512389627;3170.4134735074085;3177.412178746916;3188.4101441233447;3234.3692979868933;3235.288236587947;398478;399788;400011;400591.9970400159;402994;33198.170045748564;58359;58683.413165868646;58695;58727.70636146792;35207;35228;35291.294174116374;35348;35410;14627.414903403958;14786.482473113389;14827.332770025374;14840.777388339184;14843.406263749544;12503.937480312541;12580.993520518337;12593.874061259454;12596.18124821902;12604.928581070712;7729.961350193213;7764.456488045896;7770.0170868431;7829.843403131906;7839.09850367215;3255.869765209365;3272.8156833169014;3273.8199399033283;3285.671432856695;3325.235195904907;903.9638414463479;911.2482202183226;964.0985678390728;982.8378317577663;1003.7139415266614;28663.57004644932;29335.266618334284;29520.704792952227;30271.243218919702;30288.69711302903;1035.1563475767225;1060.1095080132725;1075.876274228458;1082.2045796339685;1087.3475914451267;14881.776773348409;15073;15494.767578486331;15552.522510361498;15576.766348504781;639492;644209;644814;645817;647723.76138119;58220.4177958215;58522;58682.41317586855;58689.41310586839;58715.82568348609;1332.6468485851258;1373.3888419653158;1399.0766094378068;1405.2762827144234;1405.6626409661608;29147.125586232447;29270.7072929268;29499.852500737357;29962.70037299599;30102.397952041276;50989.49010509996;51112.48887511226;51214.74392628162;51235;51360.22959655683;568064;568969.1551542216;569292;570086;571075;26608.866955665097;26710.86644566765;27433.177004689092;27489;27959.720402796524;46160.53839461562;46168.538314616424;46345.76827115843;46495.83760405882;46895.531044690484;30983.535246970863;38587.807060964515;38593;39108.413373798845;39645;38485.230295393376;39014.6098539011;39411;39704;39966.600333996284;37059.62940370562;37170.8141459291;37447;37591;38006.42990355202;32696.50955235626;33656.65373385037;34272;34336.141596460286;34405.483917740756;25243.87378063098;25310.746892531577;25463.10879119222;25561.488770224118;25821.870890645427 12:32:52 INFO - PROCESS | 1634 | |2;jquery.html;348.65483171660065;370.1468115992637;370.19482625290004;382.3442795605537;397.94826672532565;14907.776383354258;15131;15899.443519476807;20183.69724454131;21215.893920530416;20457.590848183027;20639.896800515977;20950;21020.36938891832;21241.68137477939;5790.507806836424;5812.593118481708;6097.786577469789;6117.724702388403;6135.171751813493;5919.408059194067;5957.255343082114;5979.222701048858;5995.790147344822;6111.786087486938;5670.8015219467325;6006.969965150168;6041.697915104249;6092.238470191226;6116.8470788230425;5811.6803575803315;5989.9101513477335;6011.068284415923;6066.514678825683;6066.8786624267705;5840.065589505659;5856.443637854397;5856.648601083949;5857.062869940832;5870.73581688825;13236.272005039718;19362.903185484123;24886;25481;25756.484870302487;2161.794629510194;2261.129465155927;2361.1853910401055;2530;2634.749698778613;9825.90174098257;9911.950440247825;9920.255980801396;10382.9480852596;10467.581296748122;1801.9909900450414;1859.1262106809777;1878.7745470543623;1950.0834607734341;1951.4828570428847;113714.86285137126;124222.37888810589;125752;127919;129298;11185.217034807485;11481.655550333504;11583.478743456564;11647.068234541217;12104.273743575413;14352;14606.9775115743;14615.196164210858;14625.976181667394;14646;8312.625931833081;8543.316534677211;8544.786380340542;8681.739547813575;8685.305175586065;8461.534615596202;8468.491890486468;8489.4481858679;8634.395592308478;8636.136386361313;4467.553244675506;4525.637948964075;4581.33739236838;4618.399608050931;4622.653301002371;1729.8183690712547;1840.3374785077363;1913.3590247267043;1914.5692219250836;1919.821003161394;769.8267889724767;773.9380849532024;870.6822009966317;888.9066648001996;957.8036502516967;21113.894430527747;21285.255016074363;21870.781292186875;21920.671189931843;22128.557428851655;939.1266122505998;969.1665167955553;973.3330673953317;1003.0270637481631;1006.9697909062739;8549.658013679384;11556.826647600292;11568.768624627628;11613.76772464563;11660.734994201637;590824;599854;606728;610838;612912;116784;125573;125835.37082314712;128165;131017;959.4866746290701;980.9705708828747;1007;1009.6062535611118;1013.6502906497246;19029.904850476214;19635.41093767189;19742.40772776819;19782.40652780419;19903.502412439804;51052.23421648604;54036.18945715896;54892;55385.892282154935;56239.437605625055;76582.61708691421;79192;79256.60371698104;79683;81523.77714334171;6933.75192465361;7708.843673449028;7958.960205198937;8322.791930201793;8341.20758527939;10428.27002109845;12084.577039803564;12164.695882603006;12290.877091228973;13512.864871351554;11136.053435458076;11155;11186.944065279622;11430.599929002445;11507.942460287644;7698.34564062061;10384;10533;10641.255112142077;11224.831627525431;11492.36791976416;11496.88503114958;11795.351255680676;11804.76390472203;11866.940665296963;7659.348955338859;8158.184181581794;8173.141820108803;8557.657693692432;8570.571471426529;10878.40168790724;11274.943625281821;11286.943565282121;11562.94218528902;11600 12:32:52 INFO - PROCESS | 1634 | |3;mootools.html;1450.4923276853103;1494.8953573249871;1629.3726915137668;1756.841884230419;1820.7724034495689;6602.43879270262;8624.65501379944;8638.352123590732;8647.481151130936;8648.481091134521;7521.887171692429;7802.336801371876;7838.490498117632;8276.958615206915;8484.660613575465;2298.287530865433;2332.9066837326486;2334.7898689117974;2341.098677009355;2342.4143964009;1986.275009621485;2340.3447034830274;2362.8109751219863;2384.2251268337773;2388.95222095558;2319.5708793873105;2325.883705814711;2345.167465549731;2351.200591798788;2353.6116540770754;2300.7584203658625;2308.90764369425;2329.988350058256;2330.8251881108813;2331.6968794056666;2342.0982921575146;2349.483113714997;2358.952820943589;2359.6460530920326;2365.8935347909387;8451.281641060517;8526.829463410759;8528.189821966904;8549.273311768507;8559.572021398904;4232.4286221360035;5218.478152184789;5260.026895024433;5261.631685781997;5267.947320526822;3230.48312270038;3423.760336776427;3521;3598.9280214395826;3604.495370648085;931.9207867331285;1050.502319698367;1052.2055847834913;1080.4975686305906;1094.8357746338033;9364.43813371193;11124.944375278154;11224.943875280653;11232.3260604364;11241.718957026058;4467.262901621261;4580.519045500175;4665.160271151225;4665.720056796603;4742.19382704941;9600.231981441608;9603.183729383081;9618.086281803218;9624.615015399446;9634.90365096354;5235.73821308933;5236.319278493771;5239.685618862881;5241.816536421207;5249.763760630781;4335.176316499855;4459.063596644676;4680.7191568506;4791.065742180242;4832.9275060874115;3776.2258736958856;3800.4299355096955;3861.806909654506;3912.9021774455296;3916.2559113768293;1246.214884622682;1252.473960936409;1256.114439320277;1268.5496648689766;1268.7018550640591;774.3147314626596;810.4974915552277;895.3106108296705;910.3309067835136;915.1031988651159;5372.221027950925;5643.915341269884;5813.186153938452;5979.431953964367;6018.6990650467305;922.3543519536352;931.3480563605368;943.7640589852537;959.6737109382843;978.0610613810821;3650.6896913762625;3829.6744776694295;3865.2462769759627;3910;3954.980225098856;10060.899391006142;10371.585136594602;10374.066334029974;10490.370577765358;10511.947440262902;10426.68719938403;10430.791384172426;10434.634787782392;10437.791244175225;10440.895591043993;7785.571793551296;7991.720289789946;8015.7595272141925;8171.387145964077;8192.385571082194;5884.735186916513;5918.970405147947;5957.285125784933;5988.251468566429;6071.574989750852;9173.312001599774;9604.711858644252;9697.806043878942;9774;9801.11789938909;9774.364666296668;9853.901460985586;9857.753556161153;9967.40195588267;10036.89963100389;7211.314925082109;7267.709291628275;7287.489875708649;7409.888851667121;7437.925620743723;7942.682292708227;8037.557934313435;8041.67833286662;8053.154418786177;8058.3150432213915;5382.703951282717;7128.073350464407;7199.388052015637;7226.891596625949;7369.8526029478035;7041.507094503336;7074.9292507076325;7112.50212485121;7147.964260178874;7210.603416812126;5733.397993210653;6728.024436456784;6751.831204219934;6812.489063320171;6819.965900170468;4873.342098816614;4907.263910413467;4913.091078150554;4934.087193869145;4940.580050695731;4717.245240761462;4718.669693121448;4832.347633069631;4935.136351138608;5017.247412888096 12:32:52 INFO - PROCESS | 1634 | |4;prototype.html;299.6628792608316;304.25609385053536;309.36580010977497;314.98425078746055;317.3367661587282;4744.786484608192;5085.237214417839;5187.495626268383;5287.973560132199;5311.415744268127;4480.185524862428;4508.887277818056;4674.275487299466;4915.861600203913;5022.598192144629;3190.457622204231;4005.399190121476;4010.2956243596354;4024.183071849702;4105.055837157463;3816.1985982943615;3995.3407687731487;4038.172174704179;4057.4928133983253;4084.264832330179;3262.7389808815237;3390.542276792626;3751.23099764549;3977.34373828318;3981.8208180631796;3266.3793879163004;3961.8217180226957;3985.1631157456823;3987.308566717454;4004.279229738646;3057.4649436348773;3355.446351352024;3500.8774692885627;3508.947365789515;3547.2373439710327;4924.433690125646;5004;5033.647644664875;5161.5354618084175;5163.277141200235;4558.726476411426;4577.656675749299;4581.129585378767;4585.647234065925;4633.32816741574;5450.310403774803;5473.8357849264585;5476.370217424967;5478.534324582415;5481.862953426156;3654.214343916071;3808.7143464240294;3869.0134015825784;3873.4383514390256;3884.7374603253775;4493.43915925336;4644.909790594246;4939.431965324034;5349.839504814861;5617.056989848907;3243.3999710053718;3846.538415390171;3852.845886164578;3869.496965394481;3896.376579747227;3935.6851451884354;3964.3458829292845;3967.6230758077936;3972.3445631471063;3974.463447434617;4132.566080561558;4394.318880573453;4475.373447717322;4530.22986092365;4584;5443.285365109978;5459.972700136474;5467.863303417367;5478.123500239943;5522.723863806874;1044.2272718188485;1210.5520957245876;1257.6289994451658;1271.523178807947;1289.5035411366691;734.2253922112222;845.8942632170979;855.6278019061729;902.5171533229822;903.1465265324206;4686;4785;4868.391451068616;4914.410270767531;4988.401391833003;4759.738214398241;4940.456549779521;5133.101707201299;5285.709285989307;5414.242006119146;4376.737395756265;5242.7116508591635;5302.125149350391;5315.335583052119;5323.760430780547;2351.729551101611;2734.7556861627913;2770.7229277072133;2783.582462630581;2824.5849798422055;4694.23040406162;4713.764311784397;5020.824271150492;5369.77154480886;5647.329235996635;4611.216093264153;4827.324174615556;4895.510448955076;5305.355339844583;5540.9168862467095;5006.823396501819;5096.337476128154;5114.261151208607;5546.419270507886;5590.2453168821685;4467.910641787081;4695.694779839299;4799.008411509185;5331.694349503333;5388.73056347189;5116.104681680766;5455.336122482681;5597.6641401516035;5665.348484924121;5667.1782591524825;4938.530839570235;5585.301837270341;5944.435278648694;5946.554008449297;6007.789727359522;3623.4021386470936;3786.848526058927;3905.9453947434604;3938.6455219030427;3982.8605998789903;4850.320955066364;5302.31069960918;5359.839204823861;5668.930840243199;5672.149177623224;4406.1934606811055;4662.626989840873;5454.481824226692;5491.972540137273;5573.080441727232;4338.7830608470085;4430.756308403069;5389.86525336854;5452.546354140573;5490.862728431821;5127.692338459704;5278.97360513195;5331.626786124934;5345.518903298722;5380.461953804588;4555.589996900295;4774.856754297238;5017.222330538772;5358.732063396893;5546.611737178358;5178.821178821179;5393.649412788156;5403;5404.513593776579;5408.918866216931 12:32:52 INFO - PROCESS | 1634 | |5;yui.html;683.7299266789618;690.9965450172751;726.0887586079473;728.4609389052104;809.9834707442158;14095.083819551723;14678.339474723636;14680.853191468083;14713.043652162602;14716.999999999987;11734.354610496419;12987;13096.541621043265;13097.607071787837;13124;655.3053763011211;797.4059325802257;833.3499870101331;837.7193640130564;838.4885220015785;826.9503829770233;830.4270053662983;831.8253166834942;834.9396266741235;837.8031162676766;687.3538873458957;880.6037283222544;881.180502133016;883.8762573239719;884.8407286688393;703.957762534247;839.3117643532337;870.334194341323;876.2114097312469;883.1874675298741;646.912666789982;647.0682217606678;648.173578687173;648.8215740671305;648.935106489352;12553.184043037174;14170.858291417057;14214.50249241282;14230.715385692229;14236.28818559068;12062.758744825054;13246.07277490576;13277.336133193301;13292.401841917137;13471.326433678278;11875.16873818833;12153.635390938285;12211.267323960503;12229.266244025386;12614;4302.763348015857;4312.547182545819;4327.069680018811;4370.978145109254;4496.842610508649;91124;91562.5421872893;92106.53946730224;93676.53161734148;94279;10068.69793906184;10397.272190946562;10447.164226861985;10517.579296828195;10519.894801052045;20130.19479220815;20142.899285503478;20222.393328200178;20442;20476;7152.392046676091;7437.367823735043;7492.400607951351;7597.430192735567;7666.501677390952;4010.258102251093;4046.1705350403095;4087.8773636790943;4102.261592913244;4125.484314460692;3411.4712219606;3522.559680039995;3524.418470952264;3524.8061356625117;3531.099569609733;637.2735082006516;647.2653538234138;654.3685343643311;658.90292662717;660.3561527510639;575.03625120214;646.7356318397616;647.6162053271712;650.5933791380387;680.2449281297755;8324.791880203044;8410.831783364298;8898.86651700225;9157.725268241964;9335.439873607605;459.4624289581198;589.5696141816499;605.2283338743138;618.8855061813579;645.2837350540896;4339.7396156230725;4512.593866551961;4526.864194074183;4548.317816807437;4556.589906908394;69200.3079969204;69411.65294173497;82421.17578824135;86406.56796715976;95771;62105.68947155235;64310;75514.62242688751;97162;106302.46848765861;90218;92671;92813.5359323226;93515.064849352;94590.52704736432;8587.448652214336;8886.644534218558;8895.243904268209;8901.065388134153;9022.503762293138;55972.72013639906;57787.844243115745;58182.4181758177;58420.539486513175;60043;79849.60451186473;81650.59174704089;83538;84929;86509.56745216233;10560.260781745204;10719.839202412126;10917;10961.958613931663;11033.613823516138;9207.283373414908;12156.209846359958;12264.75470490603;12426.37868106573;12501.187422817487;11601.477933492844;11602.361870097226;11606.214508518064;11911.285322880309;11951.581694640647;11487.932172209083;11540.365279909685;11643.53425862956;11830.17188796776;12416.317102559085;11465.254758441008;11587.362695051515;11609.6517104487;11625.534978600761;12137;9599;9725.951370243103;9964.302498825013;10218.875923648542;10349.327293725884;9859.852102218616;10140.54367553447;10144;10162.949185254027;10165.54255058539 12:32:52 INFO - PROCESS | 1634 | __end_tp_report 12:32:52 INFO - PROCESS | 1634 | __start_cc_report 12:32:52 INFO - PROCESS | 1634 | _x_x_mozilla_cycle_collect,2026 12:32:52 INFO - PROCESS | 1634 | __end_cc_report 12:32:52 INFO - PROCESS | 1634 | __startTimestamp1479846772779__endTimestamp 12:32:52 INFO - PROCESS | 1634 | 12:32:52 INFO - PROCESS | 1634 | ------- Summary: start ------- 12:32:52 INFO - PROCESS | 1634 | Number of tests: 6 12:32:52 INFO - PROCESS | 1634 | 12:32:52 INFO - PROCESS | 1634 | [#0] dojo.html Cycles:165 Average:5715.39 Median:5929.61 stddev:638.39 (10.8%) stddev-sans-first:609.51 12:32:52 INFO - PROCESS | 1634 | Values: 3216.5 3336.0 3341.3 3374.5 3376.3 4354.8 5002.8 5369.6 5673.0 5744.0 5693.5 5722.7 5752.3 5754.4 5787.2 5186.9 5197.4 5227.6 5263.9 5368.7 5177.4 5190.3 5197.7 5240.1 5332.5 5256.7 5272.8 5279.4 5288.8 5302.0 5294.3 5298.5 5299.4 5300.3 5307.7 5122.0 5229.3 5239.3 5245.2 5281.6 5822.9 5824.2 5831.9 5833.8 5841.5 6092.8 6153.8 6177.4 6187.7 6194.9 5194.0 6205.5 6207.1 6213.8 6248.2 5782.5 5830.4 5837.3 5862.3 5866.3 5147.6 6257.9 6269.9 6273.6 6278.0 6078.9 6111.0 6113.4 6130.3 6135.9 6233.7 6240.5 6241.9 6244.5 6253.0 6226.7 6240.4 6245.5 6246.6 6255.4 6120.6 6170.9 6184.5 6221.8 6239.9 4968.7 4969.5 4973.2 4993.8 5003.1 4142.2 4371.7 4466.7 4468.3 4484.1 5674.5 5697.9 5719.8 5725.6 5819.9 6199.8 6207.3 6209.3 6210.8 6224.3 5800.0 5811.2 5812.9 5815.6 5821.5 5314.8 5362.6 5430.5 5446.2 5446.4 5245.3 6190.5 6195.0 6225.8 6238.2 6188.1 6200.8 6226.7 6247.7 6260.2 5584.7 6088.1 6257.6 6308.1 6329.8 6042.2 6155.9 6173.0 6206.8 6213.7 6100.0 6106.9 6145.5 6211.0 6226.6 5946.5 5966.9 5967.4 5980.9 5997.8 6090.5 6165.3 6168.3 6168.6 6192.4 5834.6 5884.7 5924.6 5929.6 6059.4 5979.8 6061.1 6073.1 6109.7 6146.6 5970.3 6011.5 6033.3 6035.3 6111.0 12:32:52 INFO - PROCESS | 1634 | 12:32:52 INFO - PROCESS | 1634 | [#1] ext.html Cycles:180 Average:66052.53 Median:39306.30 stddev:146717.72 (373.3%) stddev-sans-first:147046.66 12:32:52 INFO - PROCESS | 1634 | Values: 465.6 482.1 533.6 550.5 559.2 23727.5 31844.7 39890.4 40864.4 41065.8 42341.0 42468.0 42493.4 42500.6 42697.1 9192.6 9367.7 9376.9 9399.7 9403.8 9216.2 9311.1 9317.5 9320.1 9340.0 9296.6 9338.2 9343.3 9347.9 9359.3 9258.3 9318.9 9329.8 9344.1 9351.5 9279.0 9353.4 9354.4 9356.0 9369.3 39201.6 39513.4 39532.8 39541.0 39577.6 3131.5 3233.7 3383.4 3603.5 3819.6 29916.0 29962.6 30093.5 30315.8 30974.4 3170.4 3177.4 3188.4 3234.4 3235.3 398478.0 399788.0 400011.0 400592.0 402994.0 33198.2 58359.0 58683.4 58695.0 58727.7 35207.0 35228.0 35291.3 35348.0 35410.0 14627.4 14786.5 14827.3 14840.8 14843.4 12503.9 12581.0 12593.9 12596.2 12604.9 7730.0 7764.5 7770.0 7829.8 7839.1 3255.9 3272.8 3273.8 3285.7 3325.2 904.0 911.2 964.1 982.8 1003.7 28663.6 29335.3 29520.7 30271.2 30288.7 1035.2 1060.1 1075.9 1082.2 1087.3 14881.8 15073.0 15494.8 15552.5 15576.8 639492.0 644209.0 644814.0 645817.0 647723.8 58220.4 58522.0 58682.4 58689.4 58715.8 1332.6 1373.4 1399.1 1405.3 1405.7 29147.1 29270.7 29499.9 29962.7 30102.4 50989.5 51112.5 51214.7 51235.0 51360.2 568064.0 568969.2 569292.0 570086.0 571075.0 26608.9 26710.9 27433.2 27489.0 27959.7 46160.5 46168.5 46345.8 46495.8 46895.5 30983.5 38587.8 38593.0 39108.4 39645.0 38485.2 39014.6 39411.0 39704.0 39966.6 37059.6 37170.8 37447.0 37591.0 38006.4 32696.5 33656.7 34272.0 34336.1 34405.5 25243.9 25310.7 25463.1 25561.5 25821.9 12:32:52 INFO - PROCESS | 1634 | 12:32:52 INFO - PROCESS | 1634 | [#2] jquery.html Cycles:180 Average:35262.44 Median:13708.00 stddev:100925.04 (736.2%) stddev-sans-first:101174.12 12:32:52 INFO - PROCESS | 1634 | Values: 348.7 370.1 370.2 382.3 397.9 14907.8 15131.0 15899.4 20183.7 21215.9 20457.6 20639.9 20950.0 21020.4 21241.7 5790.5 5812.6 6097.8 6117.7 6135.2 5919.4 5957.3 5979.2 5995.8 6111.8 5670.8 6007.0 6041.7 6092.2 6116.8 5811.7 5989.9 6011.1 6066.5 6066.9 5840.1 5856.4 5856.6 5857.1 5870.7 13236.3 19362.9 24886.0 25481.0 25756.5 2161.8 2261.1 2361.2 2530.0 2634.7 9825.9 9912.0 9920.3 10382.9 10467.6 1802.0 1859.1 1878.8 1950.1 1951.5 113714.9 124222.4 125752.0 127919.0 129298.0 11185.2 11481.7 11583.5 11647.1 12104.3 14352.0 14607.0 14615.2 14626.0 14646.0 8312.6 8543.3 8544.8 8681.7 8685.3 8461.5 8468.5 8489.4 8634.4 8636.1 4467.6 4525.6 4581.3 4618.4 4622.7 1729.8 1840.3 1913.4 1914.6 1919.8 769.8 773.9 870.7 888.9 957.8 21113.9 21285.3 21870.8 21920.7 22128.6 939.1 969.2 973.3 1003.0 1007.0 8549.7 11556.8 11568.8 11613.8 11660.7 590824.0 599854.0 606728.0 610838.0 612912.0 116784.0 125573.0 125835.4 128165.0 131017.0 959.5 981.0 1007.0 1009.6 1013.7 19029.9 19635.4 19742.4 19782.4 19903.5 51052.2 54036.2 54892.0 55385.9 56239.4 76582.6 79192.0 79256.6 79683.0 81523.8 6933.8 7708.8 7959.0 8322.8 8341.2 10428.3 12084.6 12164.7 12290.9 13512.9 11136.1 11155.0 11186.9 11430.6 11507.9 7698.3 10384.0 10533.0 10641.3 11224.8 11492.4 11496.9 11795.4 11804.8 11866.9 7659.3 8158.2 8173.1 8557.7 8570.6 10878.4 11274.9 11286.9 11562.9 11600.0 12:32:52 INFO - PROCESS | 1634 | 12:32:52 INFO - PROCESS | 1634 | [#3] mootools.html Cycles:180 Average:5527.64 Median:5227.11 stddev:3056.87 (58.5%) stddev-sans-first:3050.09 12:32:52 INFO - PROCESS | 1634 | Values: 1450.5 1494.9 1629.4 1756.8 1820.8 6602.4 8624.7 8638.4 8647.5 8648.5 7521.9 7802.3 7838.5 8277.0 8484.7 2298.3 2332.9 2334.8 2341.1 2342.4 1986.3 2340.3 2362.8 2384.2 2389.0 2319.6 2325.9 2345.2 2351.2 2353.6 2300.8 2308.9 2330.0 2330.8 2331.7 2342.1 2349.5 2359.0 2359.6 2365.9 8451.3 8526.8 8528.2 8549.3 8559.6 4232.4 5218.5 5260.0 5261.6 5267.9 3230.5 3423.8 3521.0 3598.9 3604.5 931.9 1050.5 1052.2 1080.5 1094.8 9364.4 11124.9 11224.9 11232.3 11241.7 4467.3 4580.5 4665.2 4665.7 4742.2 9600.2 9603.2 9618.1 9624.6 9634.9 5235.7 5236.3 5239.7 5241.8 5249.8 4335.2 4459.1 4680.7 4791.1 4832.9 3776.2 3800.4 3861.8 3912.9 3916.3 1246.2 1252.5 1256.1 1268.5 1268.7 774.3 810.5 895.3 910.3 915.1 5372.2 5643.9 5813.2 5979.4 6018.7 922.4 931.3 943.8 959.7 978.1 3650.7 3829.7 3865.2 3910.0 3955.0 10060.9 10371.6 10374.1 10490.4 10511.9 10426.7 10430.8 10434.6 10437.8 10440.9 7785.6 7991.7 8015.8 8171.4 8192.4 5884.7 5919.0 5957.3 5988.3 6071.6 9173.3 9604.7 9697.8 9774.0 9801.1 9774.4 9853.9 9857.8 9967.4 10036.9 7211.3 7267.7 7287.5 7409.9 7437.9 7942.7 8037.6 8041.7 8053.2 8058.3 5382.7 7128.1 7199.4 7226.9 7369.9 7041.5 7074.9 7112.5 7148.0 7210.6 5733.4 6728.0 6751.8 6812.5 6820.0 4873.3 4907.3 4913.1 4934.1 4940.6 4717.2 4718.7 4832.3 4935.1 5017.2 12:32:52 INFO - PROCESS | 1634 | 12:32:52 INFO - PROCESS | 1634 | [#4] prototype.html Cycles:180 Average:4357.52 Median:4792.00 stddev:1316.11 (27.5%) stddev-sans-first:1284.07 12:32:52 INFO - PROCESS | 1634 | Values: 299.7 304.3 309.4 315.0 317.3 4744.8 5085.2 5187.5 5288.0 5311.4 4480.2 4508.9 4674.3 4915.9 5022.6 3190.5 4005.4 4010.3 4024.2 4105.1 3816.2 3995.3 4038.2 4057.5 4084.3 3262.7 3390.5 3751.2 3977.3 3981.8 3266.4 3961.8 3985.2 3987.3 4004.3 3057.5 3355.4 3500.9 3508.9 3547.2 4924.4 5004.0 5033.6 5161.5 5163.3 4558.7 4577.7 4581.1 4585.6 4633.3 5450.3 5473.8 5476.4 5478.5 5481.9 3654.2 3808.7 3869.0 3873.4 3884.7 4493.4 4644.9 4939.4 5349.8 5617.1 3243.4 3846.5 3852.8 3869.5 3896.4 3935.7 3964.3 3967.6 3972.3 3974.5 4132.6 4394.3 4475.4 4530.2 4584.0 5443.3 5460.0 5467.9 5478.1 5522.7 1044.2 1210.6 1257.6 1271.5 1289.5 734.2 845.9 855.6 902.5 903.1 4686.0 4785.0 4868.4 4914.4 4988.4 4759.7 4940.5 5133.1 5285.7 5414.2 4376.7 5242.7 5302.1 5315.3 5323.8 2351.7 2734.8 2770.7 2783.6 2824.6 4694.2 4713.8 5020.8 5369.8 5647.3 4611.2 4827.3 4895.5 5305.4 5540.9 5006.8 5096.3 5114.3 5546.4 5590.2 4467.9 4695.7 4799.0 5331.7 5388.7 5116.1 5455.3 5597.7 5665.3 5667.2 4938.5 5585.3 5944.4 5946.6 6007.8 3623.4 3786.8 3905.9 3938.6 3982.9 4850.3 5302.3 5359.8 5668.9 5672.1 4406.2 4662.6 5454.5 5492.0 5573.1 4338.8 4430.8 5389.9 5452.5 5490.9 5127.7 5279.0 5331.6 5345.5 5380.5 4555.6 4774.9 5017.2 5358.7 5546.6 5178.8 5393.6 5403.0 5404.5 5408.9 12:32:52 INFO - PROCESS | 1634 | 12:32:52 INFO - PROCESS | 1634 | [#5] yui.html Cycles:180 Average:19766.59 Median:58301.48 stddev:28699.76 (49.2%) stddev-sans-first:28744.50 12:32:52 INFO - PROCESS | 1634 | Values: 683.7 691.0 726.1 728.5 810.0 14095.1 14678.3 14680.9 14713.0 14717.0 11734.4 12987.0 13096.5 13097.6 13124.0 655.3 797.4 833.3 837.7 838.5 827.0 830.4 831.8 834.9 837.8 687.4 880.6 881.2 883.9 884.8 704.0 839.3 870.3 876.2 883.2 646.9 647.1 648.2 648.8 648.9 12553.2 14170.9 14214.5 14230.7 14236.3 12062.8 13246.1 13277.3 13292.4 13471.3 11875.2 12153.6 12211.3 12229.3 12614.0 4302.8 4312.5 4327.1 4371.0 4496.8 91124.0 91562.5 92106.5 93676.5 94279.0 10068.7 10397.3 10447.2 10517.6 10519.9 20130.2 20142.9 20222.4 20442.0 20476.0 7152.4 7437.4 7492.4 7597.4 7666.5 4010.3 4046.2 4087.9 4102.3 4125.5 3411.5 3522.6 3524.4 3524.8 3531.1 637.3 647.3 654.4 658.9 660.4 575.0 646.7 647.6 650.6 680.2 8324.8 8410.8 8898.9 9157.7 9335.4 459.5 589.6 605.2 618.9 645.3 4339.7 4512.6 4526.9 4548.3 4556.6 69200.3 69411.7 82421.2 86406.6 95771.0 62105.7 64310.0 75514.6 97162.0 106302.5 90218.0 92671.0 92813.5 93515.1 94590.5 8587.4 8886.6 8895.2 8901.1 9022.5 55972.7 57787.8 58182.4 58420.5 60043.0 79849.6 81650.6 83538.0 84929.0 86509.6 10560.3 10719.8 10917.0 10962.0 11033.6 9207.3 12156.2 12264.8 12426.4 12501.2 11601.5 11602.4 11606.2 11911.3 11951.6 11487.9 11540.4 11643.5 11830.2 12416.3 11465.3 11587.4 11609.7 11625.5 12137.0 9599.0 9726.0 9964.3 10218.9 10349.3 9859.9 10140.5 10144.0 10162.9 10165.5 12:32:52 INFO - PROCESS | 1634 | -------- Summary: end -------- 12:32:52 INFO - PROCESS | 1634 | 12:32:53 INFO - TEST-INFO | 1634: exit 0 12:32:53 INFO - TEST-OK | dromaeo_css | took 1120612ms 12:32:53 INFO - TEST-START | kraken 12:32:53 INFO - operating with platform_type : mac_ 12:32:53 INFO - Initialising browser for kraken test... 12:32:53 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/tmpCZW3aR/profile http://localhost:49243/getInfo.html) 12:32:55 ERROR - PROCESS | 1644 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5373: TypeError: parent.firstChild is null 12:32:56 INFO - PROCESS | 1644 | __metrics Screen width/height:1600/1200 12:32:56 INFO - PROCESS | 1644 | colorDepth:24 12:32:56 INFO - PROCESS | 1644 | Browser inner width/height: 1024/689 12:32:56 INFO - PROCESS | 1644 | __metrics 12:32:56 INFO - PROCESS | 1644 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:32:56 INFO - PROCESS | 1644 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:32:57 INFO - TEST-INFO | 1644: exit 0 12:32:57 INFO - Browser initialized. 12:32:57 INFO - Running cycle 1/1 for kraken test... 12:32:57 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/tmpCZW3aR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:33:09 INFO - PROCESS | 1645 | RSS: Main: 222932992 12:33:09 INFO - PROCESS | 1645 | 12:33:12 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 12:33:13 INFO - PROCESS | 1645 | RSS: Main: 273264640 12:33:13 INFO - PROCESS | 1645 | 12:33:16 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 12:33:16 INFO - PROCESS | 1645 | RSS: Main: 283660288 12:33:16 INFO - PROCESS | 1645 | 12:33:19 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 12:33:20 INFO - PROCESS | 1645 | RSS: Main: 380506112 12:33:20 INFO - PROCESS | 1645 | 12:33:22 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 12:33:23 INFO - PROCESS | 1645 | RSS: Main: 282382336 12:33:23 INFO - PROCESS | 1645 | 12:33:25 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:33:25 INFO - PROCESS | 1645 | RSS: Main: 284364800 12:33:25 INFO - PROCESS | 1645 | 12:33:28 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 12:33:29 INFO - PROCESS | 1645 | RSS: Main: 326057984 12:33:29 INFO - PROCESS | 1645 | 12:33:31 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 12:33:32 INFO - PROCESS | 1645 | RSS: Main: 325169152 12:33:32 INFO - PROCESS | 1645 | 12:33:35 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 12:33:35 INFO - PROCESS | 1645 | RSS: Main: 324141056 12:33:35 INFO - PROCESS | 1645 | 12:33:37 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:33:38 INFO - PROCESS | 1645 | RSS: Main: 301465600 12:33:38 INFO - PROCESS | 1645 | 12:33:40 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:33:40 INFO - PROCESS | 1645 | RSS: Main: 303685632 12:33:40 INFO - PROCESS | 1645 | 12:33:42 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:33:43 INFO - PROCESS | 1645 | RSS: Main: 305827840 12:33:43 INFO - PROCESS | 1645 | 12:33:46 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:33:47 INFO - PROCESS | 1645 | RSS: Main: 315486208 12:33:47 INFO - PROCESS | 1645 | 12:33:50 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:33:50 INFO - PROCESS | 1645 | RSS: Main: 315076608 12:33:50 INFO - PROCESS | 1645 | 12:33:52 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 12:33:52 INFO - PROCESS | 1645 | RSS: Main: 358838272 12:33:52 INFO - PROCESS | 1645 | 12:33:52 INFO - PROCESS | 1645 | __start_tp_report 12:33:52 INFO - PROCESS | 1645 | _x_x_mozilla_page_load 12:33:52 INFO - PROCESS | 1645 | _x_x_mozilla_page_load_details 12:33:52 INFO - PROCESS | 1645 | |i|pagename|runs| 12:33:52 INFO - PROCESS | 1645 | |0;ai-astar;163;167;139;115;114;112;114;113;114;115 12:33:52 INFO - PROCESS | 1645 | |1;audio-beat-detection;144;113;113;144;112;114;108;117;142;112 12:33:52 INFO - PROCESS | 1645 | |2;audio-dft;183;183;241;247;184;181;185;184;182;183 12:33:52 INFO - PROCESS | 1645 | |3;audio-fft;80;59;58;90;63;76;81;66;82;58 12:33:52 INFO - PROCESS | 1645 | |4;audio-oscillator;90;68;94;69;68;68;90;69;70;92 12:33:52 INFO - PROCESS | 1645 | |5;imaging-gaussian-blur;89;86;90;86;90;85;90;86;90;86 12:33:52 INFO - PROCESS | 1645 | |6;imaging-darkroom;97;95;95;95;97;95;94;93;94;93 12:33:52 INFO - PROCESS | 1645 | |7;imaging-desaturate;84;83;79;78;81;80;84;82;83;80 12:33:52 INFO - PROCESS | 1645 | |8;json-parse-financial;55;54;55;53;56;56;57;56;55;56 12:33:52 INFO - PROCESS | 1645 | |9;json-stringify-tinderbox;64;67;72;72;74;69;71;100;102;89 12:33:52 INFO - PROCESS | 1645 | |10;stanford-crypto-aes;85;87;85;86;85;83;84;83;85;83 12:33:52 INFO - PROCESS | 1645 | |11;stanford-crypto-ccm;147;148;147;146;146;144;149;150;197;161 12:33:52 INFO - PROCESS | 1645 | |12;stanford-crypto-pbkdf2;170;167;170;167;169;170;166;170;169;167 12:33:52 INFO - PROCESS | 1645 | |13;stanford-crypto-sha256-iterative;64;67;66;66;67;66;66;66;68;70 12:33:52 INFO - PROCESS | 1645 | __end_tp_report 12:33:52 INFO - PROCESS | 1645 | __start_cc_report 12:33:52 INFO - PROCESS | 1645 | _x_x_mozilla_cycle_collect,2004 12:33:52 INFO - PROCESS | 1645 | __end_cc_report 12:33:52 INFO - PROCESS | 1645 | __startTimestamp1479846832624__endTimestamp 12:33:52 INFO - PROCESS | 1645 | 12:33:52 INFO - PROCESS | 1645 | ------- Summary: start ------- 12:33:52 INFO - PROCESS | 1645 | Number of tests: 14 12:33:52 INFO - PROCESS | 1645 | 12:33:52 INFO - PROCESS | 1645 | [#0] ai-astar Cycles:10 Average:126.60 Median:115.00 stddev:21.74 (18.9%) stddev-sans-first:18.65 12:33:52 INFO - PROCESS | 1645 | Values: 163.0 167.0 139.0 115.0 114.0 112.0 114.0 113.0 114.0 115.0 12:33:52 INFO - PROCESS | 1645 | 12:33:52 INFO - PROCESS | 1645 | [#1] audio-beat-detection Cycles:10 Average:121.90 Median:115.50 stddev:14.96 (13.0%) stddev-sans-first:13.57 12:33:52 INFO - PROCESS | 1645 | Values: 144.0 113.0 113.0 144.0 112.0 114.0 108.0 117.0 142.0 112.0 12:33:52 INFO - PROCESS | 1645 | 12:33:52 INFO - PROCESS | 1645 | [#2] audio-dft Cycles:10 Average:195.30 Median:184.00 stddev:25.73 (14.0%) stddev-sans-first:26.90 12:33:52 INFO - PROCESS | 1645 | Values: 183.0 183.0 241.0 247.0 184.0 181.0 185.0 184.0 182.0 183.0 12:33:52 INFO - PROCESS | 1645 | 12:33:52 INFO - PROCESS | 1645 | [#3] audio-fft Cycles:10 Average:71.30 Median:78.00 stddev:11.82 (15.2%) stddev-sans-first:12.11 12:33:52 INFO - PROCESS | 1645 | Values: 80.0 59.0 58.0 90.0 63.0 76.0 81.0 66.0 82.0 58.0 12:33:52 INFO - PROCESS | 1645 | 12:33:52 INFO - PROCESS | 1645 | [#4] audio-oscillator Cycles:10 Average:77.80 Median:80.00 stddev:11.86 (14.8%) stddev-sans-first:11.73 12:33:52 INFO - PROCESS | 1645 | Values: 90.0 68.0 94.0 69.0 68.0 68.0 90.0 69.0 70.0 92.0 12:33:52 INFO - PROCESS | 1645 | 12:33:52 INFO - PROCESS | 1645 | [#5] imaging-gaussian-blur Cycles:10 Average:87.80 Median:89.50 stddev:2.15 (2.4%) stddev-sans-first:2.24 12:33:52 INFO - PROCESS | 1645 | Values: 89.0 86.0 90.0 86.0 90.0 85.0 90.0 86.0 90.0 86.0 12:33:52 INFO - PROCESS | 1645 | 12:33:52 INFO - PROCESS | 1645 | [#6] imaging-darkroom Cycles:10 Average:94.80 Median:95.00 stddev:1.40 (1.5%) stddev-sans-first:1.24 12:33:52 INFO - PROCESS | 1645 | Values: 97.0 95.0 95.0 95.0 97.0 95.0 94.0 93.0 94.0 93.0 12:33:52 INFO - PROCESS | 1645 | 12:33:52 INFO - PROCESS | 1645 | [#7] imaging-desaturate Cycles:10 Average:81.40 Median:82.50 stddev:2.12 (2.6%) stddev-sans-first:2.03 12:33:52 INFO - PROCESS | 1645 | Values: 84.0 83.0 79.0 78.0 81.0 80.0 84.0 82.0 83.0 80.0 12:33:52 INFO - PROCESS | 1645 | 12:33:52 INFO - PROCESS | 1645 | [#8] json-parse-financial Cycles:10 Average:55.30 Median:56.00 stddev:1.16 (2.1%) stddev-sans-first:1.22 12:33:52 INFO - PROCESS | 1645 | Values: 55.0 54.0 55.0 53.0 56.0 56.0 57.0 56.0 55.0 56.0 12:33:52 INFO - PROCESS | 1645 | 12:33:52 INFO - PROCESS | 1645 | [#9] json-stringify-tinderbox Cycles:10 Average:78.00 Median:71.50 stddev:13.81 (19.3%) stddev-sans-first:13.68 12:33:52 INFO - PROCESS | 1645 | Values: 64.0 67.0 72.0 72.0 74.0 69.0 71.0 100.0 102.0 89.0 12:33:52 INFO - PROCESS | 1645 | 12:33:52 INFO - PROCESS | 1645 | [#10] stanford-crypto-aes Cycles:10 Average:84.60 Median:85.00 stddev:1.35 (1.6%) stddev-sans-first:1.42 12:33:52 INFO - PROCESS | 1645 | Values: 85.0 87.0 85.0 86.0 85.0 83.0 84.0 83.0 85.0 83.0 12:33:52 INFO - PROCESS | 1645 | 12:33:52 INFO - PROCESS | 1645 | [#11] stanford-crypto-ccm Cycles:10 Average:153.50 Median:148.50 stddev:15.98 (10.8%) stddev-sans-first:16.78 12:33:52 INFO - PROCESS | 1645 | Values: 147.0 148.0 147.0 146.0 146.0 144.0 149.0 150.0 197.0 161.0 12:33:52 INFO - PROCESS | 1645 | 12:33:52 INFO - PROCESS | 1645 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.50 Median:169.50 stddev:1.58 (0.9%) stddev-sans-first:1.58 12:33:52 INFO - PROCESS | 1645 | Values: 170.0 167.0 170.0 167.0 169.0 170.0 166.0 170.0 169.0 167.0 12:33:52 INFO - PROCESS | 1645 | 12:33:52 INFO - PROCESS | 1645 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:66.50 stddev:1.58 (2.4%) stddev-sans-first:1.36 12:33:52 INFO - PROCESS | 1645 | Values: 64.0 67.0 66.0 66.0 67.0 66.0 66.0 66.0 68.0 70.0 12:33:52 INFO - PROCESS | 1645 | -------- Summary: end -------- 12:33:52 INFO - PROCESS | 1645 | 12:33:53 INFO - TEST-INFO | 1645: exit 0 12:33:53 INFO - TEST-OK | kraken | took 60307ms 12:33:53 INFO - SUITE-END | took 1180s 12:33:53 INFO - Completed test suite (00:19:40) 12:33:53 INFO - javascript benchmark 12:33:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3216.4532029554975, 3335.983320083401, 3341.298327351256, 3374.510695949087, 3376.27410106827, 4354.804033818478, 5002.849914502571, 5369.5704343652515, 5673.035583950728, 5743.971280143599, 5693.516051135648, 5722.685252311132, 5752.28096487939, 5754.395788442205, 5787.21872547206, 5186.896262074756, 5197.428282888889, 5227.607929405291, 5263.921041184385, 5368.70472124033, 5177.378714554259, 5190.273361729361, 5197.714125723083, 5240.056789777838, 5332.466753324676, 5256.710880901549, 5272.762725677354, 5279.3928698199625, 5288.814891478799, 5302.0, 5294.258803767476, 5298.496642818926, 5299.443558426367, 5300.25796388504, 5307.708076055816, 5121.975604879039, 5229.267902493654, 5239.318888544503, 5245.186996015624, 5281.577473802086, 5822.9417705822825, 5824.184614154022, 5831.883362332772, 5833.766649334021, 5841.47426731592, 6092.784833972854, 6153.815385538396, 6177.413145751147, 6187.690615469209, 6194.907076393858, 5194.0, 6205.503559715212, 6207.099970504297, 6213.813585592439, 6248.218972628421, 5782.508486778629, 5830.4169583041785, 5837.328707198684, 5862.325832529228, 5866.296044474648, 5147.588192944556, 6257.874842503215, 6269.87460250797, 6273.62358258506, 6278.0, 6078.908816367758, 6110.992327696444, 6113.424164191987, 6130.348467163483, 6135.877282454326, 6233.688315584202, 6240.5319601030105, 6241.875162496724, 6244.531660125509, 6253.030780229072, 6226.688665566703, 6240.438360547572, 6245.500359971192, 6246.593971391845, 6255.374462553708, 6120.571559990847, 6170.938290617126, 6184.536159787944, 6221.844453888599, 6239.875202495925, 4968.72672003036, 4969.527894849984, 4973.154563724103, 4993.825216117419, 5003.074431230234, 4142.233686767958, 4371.693981421334, 4466.731996080245, 4468.262736648417, 4484.080763443486, 5674.546036317085, 5697.857553561112, 5719.799807006735, 5725.627834190765, 5819.912701309484, 6199.783007594712, 6207.255129384503, 6209.285932117864, 6210.782622608187, 6224.284207316126, 5800.0429929062075, 5811.157382179645, 5812.912806307824, 5815.592908496449, 5821.476067153892, 5314.813981510629, 5362.597805164706, 5430.456954304538, 5446.1558458439, 5446.373667028342, 5245.344331958505, 6190.535709821871, 6194.969025154845, 6225.782097626561, 6238.15784869055, 6188.133661287334, 6200.81397558074, 6226.719797609209, 6247.718852651732, 6260.248770147611, 5584.748686309207, 6088.0719949679715, 6257.624542527463, 6308.053791931247, 6329.778457753957, 6042.1843051187525, 6155.945302618301, 6173.00582274324, 6206.751729930752, 6213.720382582705, 6099.969500152471, 6106.877862442815, 6145.525073982123, 6211.0, 6226.564140510302, 5946.494547963472, 5966.910496342472, 5967.433093856077, 5980.940190598212, 5997.790077347272, 6090.543209259235, 6165.290991536031, 6168.25980882297, 6168.629882207082, 6192.442680158807, 5834.620749651259, 5884.705764711662, 5924.585279030426, 5929.61457505278, 6059.394060593905, 5979.790707325396, 6061.090836374579, 6073.058675905242, 6109.708332504907, 6146.58628515435, 5970.283565972111, 6011.549133814894, 6033.276006879202, 6035.305939816977, 6110.969445152746], "value": 5675.493867205094, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [465.5902805531133, 482.08404032338564, 533.5971341637063, 550.5402988504599, 559.2394343692578, 23727.525449491022, 31844.681553184462, 39890.40164397536, 40864.38703419451, 41065.79467102668, 42341.0, 42468.0, 42493.362599561035, 42500.57499425005, 42697.14605707884, 9192.63229470822, 9367.718968430923, 9376.8593471098, 9399.671011514598, 9403.764905877339, 9216.17054465098, 9311.115444032806, 9317.487538185396, 9320.067993200693, 9340.0, 9296.62813487463, 9338.159565639091, 9343.299252556051, 9347.906520934806, 9359.29805264608, 9258.305627077962, 9318.928323242846, 9329.813403731887, 9344.112309330603, 9351.532423378803, 9279.0, 9353.39202951806, 9354.438733676001, 9356.0, 9369.297302702256, 39201.60798392008, 39513.40729889054, 39532.802335988425, 39541.01147471307, 39577.60422395768, 3131.5146152346424, 3233.7413006959387, 3383.390989621867, 3603.5135256740527, 3819.5607505136986, 29916.0, 29962.55056174159, 30093.548596771067, 30315.848420757975, 30974.380512389627, 3170.4134735074085, 3177.412178746916, 3188.4101441233447, 3234.3692979868933, 3235.288236587947, 398478.0, 399788.0, 400011.0, 400591.9970400159, 402994.0, 33198.170045748564, 58359.0, 58683.413165868646, 58695.0, 58727.70636146792, 35207.0, 35228.0, 35291.294174116374, 35348.0, 35410.0, 14627.414903403958, 14786.482473113389, 14827.332770025374, 14840.777388339184, 14843.406263749544, 12503.937480312541, 12580.993520518337, 12593.874061259454, 12596.18124821902, 12604.928581070712, 7729.961350193213, 7764.456488045896, 7770.0170868431, 7829.843403131906, 7839.09850367215, 3255.869765209365, 3272.8156833169014, 3273.8199399033283, 3285.671432856695, 3325.235195904907, 903.9638414463479, 911.2482202183226, 964.0985678390728, 982.8378317577663, 1003.7139415266614, 28663.57004644932, 29335.266618334284, 29520.704792952227, 30271.243218919702, 30288.69711302903, 1035.1563475767225, 1060.1095080132725, 1075.876274228458, 1082.2045796339685, 1087.3475914451267, 14881.776773348409, 15073.0, 15494.767578486331, 15552.522510361498, 15576.766348504781, 639492.0, 644209.0, 644814.0, 645817.0, 647723.76138119, 58220.4177958215, 58522.0, 58682.41317586855, 58689.41310586839, 58715.82568348609, 1332.6468485851258, 1373.3888419653158, 1399.0766094378068, 1405.2762827144234, 1405.6626409661608, 29147.125586232447, 29270.7072929268, 29499.852500737357, 29962.70037299599, 30102.397952041276, 50989.49010509996, 51112.48887511226, 51214.74392628162, 51235.0, 51360.22959655683, 568064.0, 568969.1551542216, 569292.0, 570086.0, 571075.0, 26608.866955665097, 26710.86644566765, 27433.177004689092, 27489.0, 27959.720402796524, 46160.53839461562, 46168.538314616424, 46345.76827115843, 46495.83760405882, 46895.531044690484, 30983.535246970863, 38587.807060964515, 38593.0, 39108.413373798845, 39645.0, 38485.230295393376, 39014.6098539011, 39411.0, 39704.0, 39966.600333996284, 37059.62940370562, 37170.8141459291, 37447.0, 37591.0, 38006.42990355202, 32696.50955235626, 33656.65373385037, 34272.0, 34336.141596460286, 34405.483917740756, 25243.87378063098, 25310.746892531577, 25463.10879119222, 25561.488770224118, 25821.870890645427], "value": 18378.359057322523, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.65483171660065, 370.1468115992637, 370.19482625290004, 382.3442795605537, 397.94826672532565, 14907.776383354258, 15131.0, 15899.443519476807, 20183.69724454131, 21215.893920530416, 20457.590848183027, 20639.896800515977, 20950.0, 21020.36938891832, 21241.68137477939, 5790.507806836424, 5812.593118481708, 6097.786577469789, 6117.724702388403, 6135.171751813493, 5919.408059194067, 5957.255343082114, 5979.222701048858, 5995.790147344822, 6111.786087486938, 5670.8015219467325, 6006.969965150168, 6041.697915104249, 6092.238470191226, 6116.8470788230425, 5811.6803575803315, 5989.9101513477335, 6011.068284415923, 6066.514678825683, 6066.8786624267705, 5840.065589505659, 5856.443637854397, 5856.648601083949, 5857.062869940832, 5870.73581688825, 13236.272005039718, 19362.903185484123, 24886.0, 25481.0, 25756.484870302487, 2161.794629510194, 2261.129465155927, 2361.1853910401055, 2530.0, 2634.749698778613, 9825.90174098257, 9911.950440247825, 9920.255980801396, 10382.9480852596, 10467.581296748122, 1801.9909900450414, 1859.1262106809777, 1878.7745470543623, 1950.0834607734341, 1951.4828570428847, 113714.86285137126, 124222.37888810589, 125752.0, 127919.0, 129298.0, 11185.217034807485, 11481.655550333504, 11583.478743456564, 11647.068234541217, 12104.273743575413, 14352.0, 14606.9775115743, 14615.196164210858, 14625.976181667394, 14646.0, 8312.625931833081, 8543.316534677211, 8544.786380340542, 8681.739547813575, 8685.305175586065, 8461.534615596202, 8468.491890486468, 8489.4481858679, 8634.395592308478, 8636.136386361313, 4467.553244675506, 4525.637948964075, 4581.33739236838, 4618.399608050931, 4622.653301002371, 1729.8183690712547, 1840.3374785077363, 1913.3590247267043, 1914.5692219250836, 1919.821003161394, 769.8267889724767, 773.9380849532024, 870.6822009966317, 888.9066648001996, 957.8036502516967, 21113.894430527747, 21285.255016074363, 21870.781292186875, 21920.671189931843, 22128.557428851655, 939.1266122505998, 969.1665167955553, 973.3330673953317, 1003.0270637481631, 1006.9697909062739, 8549.658013679384, 11556.826647600292, 11568.768624627628, 11613.76772464563, 11660.734994201637, 590824.0, 599854.0, 606728.0, 610838.0, 612912.0, 116784.0, 125573.0, 125835.37082314712, 128165.0, 131017.0, 959.4866746290701, 980.9705708828747, 1007.0, 1009.6062535611118, 1013.6502906497246, 19029.904850476214, 19635.41093767189, 19742.40772776819, 19782.40652780419, 19903.502412439804, 51052.23421648604, 54036.18945715896, 54892.0, 55385.892282154935, 56239.437605625055, 76582.61708691421, 79192.0, 79256.60371698104, 79683.0, 81523.77714334171, 6933.75192465361, 7708.843673449028, 7958.960205198937, 8322.791930201793, 8341.20758527939, 10428.27002109845, 12084.577039803564, 12164.695882603006, 12290.877091228973, 13512.864871351554, 11136.053435458076, 11155.0, 11186.944065279622, 11430.599929002445, 11507.942460287644, 7698.34564062061, 10384.0, 10533.0, 10641.255112142077, 11224.831627525431, 11492.36791976416, 11496.88503114958, 11795.351255680676, 11804.76390472203, 11866.940665296963, 7659.348955338859, 8158.184181581794, 8173.141820108803, 8557.657693692432, 8570.571471426529, 10878.40168790724, 11274.943625281821, 11286.943565282121, 11562.94218528902, 11600.0], "value": 9561.015751432122, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1450.4923276853103, 1494.8953573249871, 1629.3726915137668, 1756.841884230419, 1820.7724034495689, 6602.43879270262, 8624.65501379944, 8638.352123590732, 8647.481151130936, 8648.481091134521, 7521.887171692429, 7802.336801371876, 7838.490498117632, 8276.958615206915, 8484.660613575465, 2298.287530865433, 2332.9066837326486, 2334.7898689117974, 2341.098677009355, 2342.4143964009, 1986.275009621485, 2340.3447034830274, 2362.8109751219863, 2384.2251268337773, 2388.95222095558, 2319.5708793873105, 2325.883705814711, 2345.167465549731, 2351.200591798788, 2353.6116540770754, 2300.7584203658625, 2308.90764369425, 2329.988350058256, 2330.8251881108813, 2331.6968794056666, 2342.0982921575146, 2349.483113714997, 2358.952820943589, 2359.6460530920326, 2365.8935347909387, 8451.281641060517, 8526.829463410759, 8528.189821966904, 8549.273311768507, 8559.572021398904, 4232.4286221360035, 5218.478152184789, 5260.026895024433, 5261.631685781997, 5267.947320526822, 3230.48312270038, 3423.760336776427, 3521.0, 3598.9280214395826, 3604.495370648085, 931.9207867331285, 1050.502319698367, 1052.2055847834913, 1080.4975686305906, 1094.8357746338033, 9364.43813371193, 11124.944375278154, 11224.943875280653, 11232.3260604364, 11241.718957026058, 4467.262901621261, 4580.519045500175, 4665.160271151225, 4665.720056796603, 4742.19382704941, 9600.231981441608, 9603.183729383081, 9618.086281803218, 9624.615015399446, 9634.90365096354, 5235.73821308933, 5236.319278493771, 5239.685618862881, 5241.816536421207, 5249.763760630781, 4335.176316499855, 4459.063596644676, 4680.7191568506, 4791.065742180242, 4832.9275060874115, 3776.2258736958856, 3800.4299355096955, 3861.806909654506, 3912.9021774455296, 3916.2559113768293, 1246.214884622682, 1252.473960936409, 1256.114439320277, 1268.5496648689766, 1268.7018550640591, 774.3147314626596, 810.4974915552277, 895.3106108296705, 910.3309067835136, 915.1031988651159, 5372.221027950925, 5643.915341269884, 5813.186153938452, 5979.431953964367, 6018.6990650467305, 922.3543519536352, 931.3480563605368, 943.7640589852537, 959.6737109382843, 978.0610613810821, 3650.6896913762625, 3829.6744776694295, 3865.2462769759627, 3910.0, 3954.980225098856, 10060.899391006142, 10371.585136594602, 10374.066334029974, 10490.370577765358, 10511.947440262902, 10426.68719938403, 10430.791384172426, 10434.634787782392, 10437.791244175225, 10440.895591043993, 7785.571793551296, 7991.720289789946, 8015.7595272141925, 8171.387145964077, 8192.385571082194, 5884.735186916513, 5918.970405147947, 5957.285125784933, 5988.251468566429, 6071.574989750852, 9173.312001599774, 9604.711858644252, 9697.806043878942, 9774.0, 9801.11789938909, 9774.364666296668, 9853.901460985586, 9857.753556161153, 9967.40195588267, 10036.89963100389, 7211.314925082109, 7267.709291628275, 7287.489875708649, 7409.888851667121, 7437.925620743723, 7942.682292708227, 8037.557934313435, 8041.67833286662, 8053.154418786177, 8058.3150432213915, 5382.703951282717, 7128.073350464407, 7199.388052015637, 7226.891596625949, 7369.8526029478035, 7041.507094503336, 7074.9292507076325, 7112.50212485121, 7147.964260178874, 7210.603416812126, 5733.397993210653, 6728.024436456784, 6751.831204219934, 6812.489063320171, 6819.965900170468, 4873.342098816614, 4907.263910413467, 4913.091078150554, 4934.087193869145, 4940.580050695731, 4717.245240761462, 4718.669693121448, 4832.347633069631, 4935.136351138608, 5017.247412888096], "value": 4470.369340994559, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.6628792608316, 304.25609385053536, 309.36580010977497, 314.98425078746055, 317.3367661587282, 4744.786484608192, 5085.237214417839, 5187.495626268383, 5287.973560132199, 5311.415744268127, 4480.185524862428, 4508.887277818056, 4674.275487299466, 4915.861600203913, 5022.598192144629, 3190.457622204231, 4005.399190121476, 4010.2956243596354, 4024.183071849702, 4105.055837157463, 3816.1985982943615, 3995.3407687731487, 4038.172174704179, 4057.4928133983253, 4084.264832330179, 3262.7389808815237, 3390.542276792626, 3751.23099764549, 3977.34373828318, 3981.8208180631796, 3266.3793879163004, 3961.8217180226957, 3985.1631157456823, 3987.308566717454, 4004.279229738646, 3057.4649436348773, 3355.446351352024, 3500.8774692885627, 3508.947365789515, 3547.2373439710327, 4924.433690125646, 5004.0, 5033.647644664875, 5161.5354618084175, 5163.277141200235, 4558.726476411426, 4577.656675749299, 4581.129585378767, 4585.647234065925, 4633.32816741574, 5450.310403774803, 5473.8357849264585, 5476.370217424967, 5478.534324582415, 5481.862953426156, 3654.214343916071, 3808.7143464240294, 3869.0134015825784, 3873.4383514390256, 3884.7374603253775, 4493.43915925336, 4644.909790594246, 4939.431965324034, 5349.839504814861, 5617.056989848907, 3243.3999710053718, 3846.538415390171, 3852.845886164578, 3869.496965394481, 3896.376579747227, 3935.6851451884354, 3964.3458829292845, 3967.6230758077936, 3972.3445631471063, 3974.463447434617, 4132.566080561558, 4394.318880573453, 4475.373447717322, 4530.22986092365, 4584.0, 5443.285365109978, 5459.972700136474, 5467.863303417367, 5478.123500239943, 5522.723863806874, 1044.2272718188485, 1210.5520957245876, 1257.6289994451658, 1271.523178807947, 1289.5035411366691, 734.2253922112222, 845.8942632170979, 855.6278019061729, 902.5171533229822, 903.1465265324206, 4686.0, 4785.0, 4868.391451068616, 4914.410270767531, 4988.401391833003, 4759.738214398241, 4940.456549779521, 5133.101707201299, 5285.709285989307, 5414.242006119146, 4376.737395756265, 5242.7116508591635, 5302.125149350391, 5315.335583052119, 5323.760430780547, 2351.729551101611, 2734.7556861627913, 2770.7229277072133, 2783.582462630581, 2824.5849798422055, 4694.23040406162, 4713.764311784397, 5020.824271150492, 5369.77154480886, 5647.329235996635, 4611.216093264153, 4827.324174615556, 4895.510448955076, 5305.355339844583, 5540.9168862467095, 5006.823396501819, 5096.337476128154, 5114.261151208607, 5546.419270507886, 5590.2453168821685, 4467.910641787081, 4695.694779839299, 4799.008411509185, 5331.694349503333, 5388.73056347189, 5116.104681680766, 5455.336122482681, 5597.6641401516035, 5665.348484924121, 5667.1782591524825, 4938.530839570235, 5585.301837270341, 5944.435278648694, 5946.554008449297, 6007.789727359522, 3623.4021386470936, 3786.848526058927, 3905.9453947434604, 3938.6455219030427, 3982.8605998789903, 4850.320955066364, 5302.31069960918, 5359.839204823861, 5668.930840243199, 5672.149177623224, 4406.1934606811055, 4662.626989840873, 5454.481824226692, 5491.972540137273, 5573.080441727232, 4338.7830608470085, 4430.756308403069, 5389.86525336854, 5452.546354140573, 5490.862728431821, 5127.692338459704, 5278.97360513195, 5331.626786124934, 5345.518903298722, 5380.461953804588, 4555.589996900295, 4774.856754297238, 5017.222330538772, 5358.732063396893, 5546.611737178358, 5178.821178821179, 5393.649412788156, 5403.0, 5404.513593776579, 5408.918866216931], "value": 3938.192810215306, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [683.7299266789618, 690.9965450172751, 726.0887586079473, 728.4609389052104, 809.9834707442158, 14095.083819551723, 14678.339474723636, 14680.853191468083, 14713.043652162602, 14716.999999999987, 11734.354610496419, 12987.0, 13096.541621043265, 13097.607071787837, 13124.0, 655.3053763011211, 797.4059325802257, 833.3499870101331, 837.7193640130564, 838.4885220015785, 826.9503829770233, 830.4270053662983, 831.8253166834942, 834.9396266741235, 837.8031162676766, 687.3538873458957, 880.6037283222544, 881.180502133016, 883.8762573239719, 884.8407286688393, 703.957762534247, 839.3117643532337, 870.334194341323, 876.2114097312469, 883.1874675298741, 646.912666789982, 647.0682217606678, 648.173578687173, 648.8215740671305, 648.935106489352, 12553.184043037174, 14170.858291417057, 14214.50249241282, 14230.715385692229, 14236.28818559068, 12062.758744825054, 13246.07277490576, 13277.336133193301, 13292.401841917137, 13471.326433678278, 11875.16873818833, 12153.635390938285, 12211.267323960503, 12229.266244025386, 12614.0, 4302.763348015857, 4312.547182545819, 4327.069680018811, 4370.978145109254, 4496.842610508649, 91124.0, 91562.5421872893, 92106.53946730224, 93676.53161734148, 94279.0, 10068.69793906184, 10397.272190946562, 10447.164226861985, 10517.579296828195, 10519.894801052045, 20130.19479220815, 20142.899285503478, 20222.393328200178, 20442.0, 20476.0, 7152.392046676091, 7437.367823735043, 7492.400607951351, 7597.430192735567, 7666.501677390952, 4010.258102251093, 4046.1705350403095, 4087.8773636790943, 4102.261592913244, 4125.484314460692, 3411.4712219606, 3522.559680039995, 3524.418470952264, 3524.8061356625117, 3531.099569609733, 637.2735082006516, 647.2653538234138, 654.3685343643311, 658.90292662717, 660.3561527510639, 575.03625120214, 646.7356318397616, 647.6162053271712, 650.5933791380387, 680.2449281297755, 8324.791880203044, 8410.831783364298, 8898.86651700225, 9157.725268241964, 9335.439873607605, 459.4624289581198, 589.5696141816499, 605.2283338743138, 618.8855061813579, 645.2837350540896, 4339.7396156230725, 4512.593866551961, 4526.864194074183, 4548.317816807437, 4556.589906908394, 69200.3079969204, 69411.65294173497, 82421.17578824135, 86406.56796715976, 95771.0, 62105.68947155235, 64310.0, 75514.62242688751, 97162.0, 106302.46848765861, 90218.0, 92671.0, 92813.5359323226, 93515.064849352, 94590.52704736432, 8587.448652214336, 8886.644534218558, 8895.243904268209, 8901.065388134153, 9022.503762293138, 55972.72013639906, 57787.844243115745, 58182.4181758177, 58420.539486513175, 60043.0, 79849.60451186473, 81650.59174704089, 83538.0, 84929.0, 86509.56745216233, 10560.260781745204, 10719.839202412126, 10917.0, 10961.958613931663, 11033.613823516138, 9207.283373414908, 12156.209846359958, 12264.75470490603, 12426.37868106573, 12501.187422817487, 11601.477933492844, 11602.361870097226, 11606.214508518064, 11911.285322880309, 11951.581694640647, 11487.932172209083, 11540.365279909685, 11643.53425862956, 11830.17188796776, 12416.317102559085, 11465.254758441008, 11587.362695051515, 11609.6517104487, 11625.534978600761, 12137.0, 9599.0, 9725.951370243103, 9964.302498825013, 10218.875923648542, 10349.327293725884, 9859.852102218616, 10140.54367553447, 10144.0, 10162.949185254027, 10165.54255058539], "value": 7094.843113024317, "unit": "score"}], "name": "dromaeo_css", "value": 7067.088244428146, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 167.0, 139.0, 115.0, 114.0, 112.0, 114.0, 113.0, 114.0, 115.0], "value": 126.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 113.0, 113.0, 144.0, 112.0, 114.0, 108.0, 117.0, 142.0, 112.0], "value": 121.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 183.0, 241.0, 247.0, 184.0, 181.0, 185.0, 184.0, 182.0, 183.0], "value": 195.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 58.0, 90.0, 63.0, 76.0, 81.0, 66.0, 82.0, 58.0], "value": 71.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 68.0, 94.0, 69.0, 68.0, 68.0, 90.0, 69.0, 70.0, 92.0], "value": 77.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 90.0, 86.0, 90.0, 85.0, 90.0, 86.0, 90.0, 86.0], "value": 87.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 95.0, 95.0, 95.0, 97.0, 95.0, 94.0, 93.0, 94.0, 93.0], "value": 94.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 79.0, 78.0, 81.0, 80.0, 84.0, 82.0, 83.0, 80.0], "value": 81.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 55.0, 53.0, 56.0, 56.0, 57.0, 56.0, 55.0, 56.0], "value": 55.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 67.0, 72.0, 72.0, 74.0, 69.0, 71.0, 100.0, 102.0, 89.0], "value": 78.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 85.0, 86.0, 85.0, 83.0, 84.0, 83.0, 85.0, 83.0], "value": 84.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 147.0, 146.0, 146.0, 144.0, 149.0, 150.0, 197.0, 161.0], "value": 153.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 167.0, 170.0, 167.0, 169.0, 170.0, 166.0, 170.0, 169.0, 167.0], "value": 168.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 67.0, 66.0, 66.0, 67.0, 66.0, 66.0, 66.0, 68.0, 70.0], "value": 66.6, "unit": "score"}], "name": "kraken", "value": 1463.3999999999996, "alertThreshold": 2.0}]} 12:33:53 INFO - Return code: 0 12:33:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:33:53 ERROR - # TBPL SUCCESS # 12:33:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:33:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:33:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:33:53 INFO - 2016-11-22 12:33:53.967 screenresolution[1649:9754] starting screenresolution argv=screenresolution get 12:33:53 INFO - 2016-11-22 12:33:53.971 screenresolution[1649:9754] Display 0: 1600x1200x32@60 12:33:53 INFO - 2016-11-22 12:33:53.977 screenresolution[1650:9757] starting screenresolution argv=screenresolution list 12:33:53 INFO - Available Modes on Display 0 12:33:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:33:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:33:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:33:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:33:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:33:54 INFO - Intel Iris: 12:33:54 INFO - Chipset Model: Intel Iris 12:33:54 INFO - Type: GPU 12:33:54 INFO - Bus: Built-In 12:33:54 INFO - VRAM (Dynamic, Max): 1536 MB 12:33:54 INFO - Vendor: Intel (0x8086) 12:33:54 INFO - Device ID: 0x0a2e 12:33:54 INFO - Revision ID: 0x0009 12:33:54 INFO - Displays: 12:33:54 INFO - Display: 12:33:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:33:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:33:54 INFO - Display Serial Number: 1600x1200 60 12:33:54 INFO - Main Display: Yes 12:33:54 INFO - Mirror: Off 12:33:54 INFO - Online: Yes 12:33:54 INFO - Rotation: Supported 12:33:54 INFO - Return code: 0 12:33:54 INFO - Running post-action listener: _resource_record_post_action 12:33:54 INFO - [mozharness: 2016-11-22 20:33:54.225140Z] Finished run-tests step (success) 12:33:54 INFO - Running post-run listener: _resource_record_post_run 12:33:54 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 38745088; Write bytes: 659680256; Read time: 379; Write time: 2091 12:33:54 INFO - TinderboxPrint: CPU usage
53.3% 12:33:54 INFO - TinderboxPrint: I/O read bytes / time
38,745,088 / 379 12:33:54 INFO - TinderboxPrint: I/O write bytes / time
659,680,256 / 2,091 12:33:54 INFO - TinderboxPrint: CPU idle
2,264.9 (46.7%) 12:33:54 INFO - TinderboxPrint: CPU system
971.5 (20.0%) 12:33:54 INFO - TinderboxPrint: CPU user
1,617.3 (33.3%) 12:33:54 INFO - TinderboxPrint: Swap in / out
386,801,664 / 0 12:33:54 INFO - install - Wall time: 31s; CPU: 55.0%; Read bytes: 344547840; Write bytes: 344356352; Read time: 26796; Write time: 576 12:33:54 INFO - run-tests - Wall time: 1182s; CPU: 53.0%; Read bytes: 36725760; Write bytes: 306621952; Read time: 356; Write time: 1375 12:33:54 INFO - Running post-run listener: _upload_blobber_files 12:33:54 INFO - Blob upload gear active. 12:33:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:33:54 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 12:33:54 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:33:54 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:33:55 INFO - (blobuploader) - INFO - Open directory for files ... 12:33:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:33:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:33:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:33:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:33:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:33:56 INFO - (blobuploader) - INFO - Done attempting. 12:33:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:33:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:33:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:33:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:33:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:33:57 INFO - (blobuploader) - INFO - Done attempting. 12:33:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:33:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:33:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:33:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:33:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:33:59 INFO - (blobuploader) - INFO - Done attempting. 12:33:59 INFO - (blobuploader) - INFO - Iteration through files over. 12:33:59 INFO - Return code: 0 12:33:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:33:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:33:59 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6b89055e72c742fda060ea4ee221e40a35c149e95408047753818ac34c6f509b8f3b635c5eb969725206c8659eaf4d704470576794dda8dcea0dd4dbf743118c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b5e00de1be4eacc9d91836573c431f882834f00d9f8c293805d1034155fa814b6523ac9777e3a55664ed9ba45b952832d6f3f8ccb54d7c1724b167fb132c5438", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b2c56c75763aeb277df05f233856372c4095ce4aa55c47f86613e15f75e92b2b4fe87b29f6269ccdb07d6319f78634c915b5acadedc3ea9c9322218c066265a"} 12:33:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:33:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:33:59 INFO - Contents: 12:33:59 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6b89055e72c742fda060ea4ee221e40a35c149e95408047753818ac34c6f509b8f3b635c5eb969725206c8659eaf4d704470576794dda8dcea0dd4dbf743118c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b5e00de1be4eacc9d91836573c431f882834f00d9f8c293805d1034155fa814b6523ac9777e3a55664ed9ba45b952832d6f3f8ccb54d7c1724b167fb132c5438", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b2c56c75763aeb277df05f233856372c4095ce4aa55c47f86613e15f75e92b2b4fe87b29f6269ccdb07d6319f78634c915b5acadedc3ea9c9322218c066265a"} 12:33:59 INFO - Running post-run listener: copy_logs_to_upload_dir 12:33:59 INFO - Copying logs to upload dir... 12:33:59 INFO - mkdir: /builds/slave/test/build/upload/logs 12:33:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.944257 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-22 12:33:59.540949) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-22 12:33:59.544971) ========= 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.AnY54q9qpZ/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.S3YEp20sQV/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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/Mozilla-Aurora/sha512/6b89055e72c742fda060ea4ee221e40a35c149e95408047753818ac34c6f509b8f3b635c5eb969725206c8659eaf4d704470576794dda8dcea0dd4dbf743118c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b5e00de1be4eacc9d91836573c431f882834f00d9f8c293805d1034155fa814b6523ac9777e3a55664ed9ba45b952832d6f3f8ccb54d7c1724b167fb132c5438", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b2c56c75763aeb277df05f233856372c4095ce4aa55c47f86613e15f75e92b2b4fe87b29f6269ccdb07d6319f78634c915b5acadedc3ea9c9322218c066265a"} build_url:https://queue.taskcluster.net/v1/task/HWZAwR0QTWiC28oo2Qr6oA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011032 build_url: 'https://queue.taskcluster.net/v1/task/HWZAwR0QTWiC28oo2Qr6oA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6b89055e72c742fda060ea4ee221e40a35c149e95408047753818ac34c6f509b8f3b635c5eb969725206c8659eaf4d704470576794dda8dcea0dd4dbf743118c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b5e00de1be4eacc9d91836573c431f882834f00d9f8c293805d1034155fa814b6523ac9777e3a55664ed9ba45b952832d6f3f8ccb54d7c1724b167fb132c5438", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b2c56c75763aeb277df05f233856372c4095ce4aa55c47f86613e15f75e92b2b4fe87b29f6269ccdb07d6319f78634c915b5acadedc3ea9c9322218c066265a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-22 12:33:59.579604) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 12:33:59.579924) ========= 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.AnY54q9qpZ/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.S3YEp20sQV/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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.005030 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 12:33:59.628586) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-22 12:33:59.628920) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-22 12:33:59.629253) ========= ========= Total master_lag: 0.18 =========