builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0325 starttime: 1472786328.04 results: success (0) buildid: 20160901175542 builduid: 2e92dedc2ebc4343863f17c27a033c44 revision: e12415c1560179174bd3bdaca11dd20e6b97d35a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 20:18:48.041596) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 20:18:48.042496) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 20:18:48.043202) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 20:18:48.068658) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 20:18:48.069190) ========= 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.jGMxSBs6Zi/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.b3Nn9wwfYJ/Listeners TMPDIR=/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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-09-01 20:18:48-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 20.9M=0.001s 2016-09-01 20:18:48 (20.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.132261 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 20:18:48.226442) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 20:18:48.226748) ========= 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.jGMxSBs6Zi/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.b3Nn9wwfYJ/Listeners TMPDIR=/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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.025723 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 20:18:48.274654) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-01 20:18:48.275004) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev e12415c1560179174bd3bdaca11dd20e6b97d35a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev e12415c1560179174bd3bdaca11dd20e6b97d35a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jGMxSBs6Zi/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.b3Nn9wwfYJ/Listeners TMPDIR=/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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-09-01 20:18:48,358 truncating revision to first 12 chars 2016-09-01 20:18:48,358 Setting DEBUG logging. 2016-09-01 20:18:48,358 attempt 1/10 2016-09-01 20:18:48,358 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/e12415c15601?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-01 20:18:49,661 unpacking tar archive at: autoland-e12415c15601/testing/mozharness/ program finished with exit code 0 elapsedTime=1.566879 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-01 20:18:49.864114) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 20:18:49.864436) ========= script_repo_revision: e12415c1560179174bd3bdaca11dd20e6b97d35a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 20:18:49.864835) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 20:18:49.865114) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 20:18:49.883479) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-01 20:18:49.883747) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jGMxSBs6Zi/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.b3Nn9wwfYJ/Listeners TMPDIR=/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 20:18:50 INFO - MultiFileLogger online at 20160901 20:18:50 in /builds/slave/test 20:18:50 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 20:18:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:18:50 INFO - {'append_to_log': False, 20:18:50 INFO - 'base_work_dir': '/builds/slave/test', 20:18:50 INFO - 'blob_upload_branch': 'Autoland', 20:18:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:18:50 INFO - 'branch': 'Autoland', 20:18:50 INFO - 'buildbot_json_path': 'buildprops.json', 20:18:50 INFO - 'config_files': ('talos/mac_config.py',), 20:18:50 INFO - 'default_actions': ('clobber', 20:18:50 INFO - 'read-buildbot-config', 20:18:50 INFO - 'download-and-extract', 20:18:50 INFO - 'populate-webroot', 20:18:50 INFO - 'create-virtualenv', 20:18:50 INFO - 'install', 20:18:50 INFO - 'run-tests'), 20:18:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:18:50 INFO - 'download_minidump_stackwalk': True, 20:18:50 INFO - 'download_symbols': 'ondemand', 20:18:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:18:50 INFO - 'tooltool.py': '/tools/tooltool.py', 20:18:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:18:50 INFO - '/tools/misc-python/virtualenv.py')}, 20:18:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:18:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:18:50 INFO - 'installer_path': 'installer.exe', 20:18:50 INFO - 'log_level': 'info', 20:18:50 INFO - 'log_name': 'talos', 20:18:50 INFO - 'log_to_console': True, 20:18:50 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:18:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:18:50 INFO - 'opt_config_files': (), 20:18:50 INFO - 'pip_index': False, 20:18:50 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:18:50 INFO - 'cmd': ('bash', 20:18:50 INFO - '-c', 20:18:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:18:50 INFO - 'enabled': True, 20:18:50 INFO - 'halt_on_failure': False, 20:18:50 INFO - 'name': 'check_screen_resolution'},), 20:18:50 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:18:50 INFO - 'cmd': ('bash', 20:18:50 INFO - '-c', 20:18:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:18:50 INFO - 'enabled': True, 20:18:50 INFO - 'halt_on_failure': False, 20:18:50 INFO - 'name': 'check_screen_resolution'},), 20:18:50 INFO - 'run_cmd_checks_enabled': True, 20:18:50 INFO - 'sps_profile': False, 20:18:50 INFO - 'sps_profile_interval': 0, 20:18:50 INFO - 'suite': 'dromaeojs', 20:18:50 INFO - 'system_bits': '32', 20:18:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:18:50 INFO - 'title': 't-yosemite-r7-0325', 20:18:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:18:50 INFO - 'use_talos_json': True, 20:18:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:18:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:18:50 INFO - 'work_dir': 'build'} 20:18:50 INFO - [mozharness: 2016-09-02 03:18:50.079600Z] Running clobber step. 20:18:50 INFO - Running pre-action listener: _resource_record_pre_action 20:18:50 INFO - Running main action method: clobber 20:18:50 INFO - rmtree: /builds/slave/test/build 20:18:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:18:52 INFO - Running post-action listener: _resource_record_post_action 20:18:52 INFO - [mozharness: 2016-09-02 03:18:52.148325Z] Finished clobber step (success) 20:18:52 INFO - [mozharness: 2016-09-02 03:18:52.148495Z] Running read-buildbot-config step. 20:18:52 INFO - Running pre-action listener: _resource_record_pre_action 20:18:52 INFO - Running main action method: read_buildbot_config 20:18:52 INFO - Using buildbot properties: 20:18:52 INFO - { 20:18:52 INFO - "project": "", 20:18:52 INFO - "product": "firefox", 20:18:52 INFO - "script_repo_revision": "production", 20:18:52 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 20:18:52 INFO - "repository": "", 20:18:52 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 20:18:52 INFO - "buildid": "20160901175542", 20:18:52 INFO - "pgo_build": "False", 20:18:52 INFO - "basedir": "/builds/slave/test", 20:18:52 INFO - "buildnumber": 215, 20:18:52 INFO - "slavename": "t-yosemite-r7-0325", 20:18:52 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 20:18:52 INFO - "platform": "yosemite_r7", 20:18:52 INFO - "scheduler": "tests-autoland-macosx64-talos", 20:18:52 INFO - "branch": "autoland", 20:18:52 INFO - "repo_path": "integration/autoland", 20:18:52 INFO - "revision": "e12415c1560179174bd3bdaca11dd20e6b97d35a", 20:18:52 INFO - "stage_platform": "macosx64", 20:18:52 INFO - "builduid": "2e92dedc2ebc4343863f17c27a033c44", 20:18:52 INFO - "slavebuilddir": "test" 20:18:52 INFO - } 20:18:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/d-fNWJ9XRVSurVLPiYbNuw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 20:18:52 INFO - Running post-action listener: _resource_record_post_action 20:18:52 INFO - [mozharness: 2016-09-02 03:18:52.151752Z] Finished read-buildbot-config step (success) 20:18:52 INFO - [mozharness: 2016-09-02 03:18:52.151909Z] Running download-and-extract step. 20:18:52 INFO - Running pre-action listener: _resource_record_pre_action 20:18:52 INFO - Running main action method: download_and_extract 20:18:52 INFO - mkdir: /builds/slave/test/build/tests 20:18:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:18:52 INFO - trying https://queue.taskcluster.net/v1/task/d-fNWJ9XRVSurVLPiYbNuw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 20:18:52 INFO - Downloading https://queue.taskcluster.net/v1/task/d-fNWJ9XRVSurVLPiYbNuw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 20:18:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/d-fNWJ9XRVSurVLPiYbNuw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 20:18:53 INFO - Downloaded 1199 bytes. 20:18:53 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 20:18:53 INFO - Using the following test package requirements: 20:18:53 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 20:18:53 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:18:53 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 20:18:53 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:18:53 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 20:18:53 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:18:53 INFO - u'jsshell-mac.zip'], 20:18:53 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:18:53 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 20:18:53 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 20:18:53 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:18:53 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 20:18:53 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:18:53 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 20:18:53 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:18:53 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 20:18:53 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 20:18:53 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 20:18:53 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 20:18:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:18:53 INFO - trying https://queue.taskcluster.net/v1/task/d-fNWJ9XRVSurVLPiYbNuw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 20:18:53 INFO - Downloading https://queue.taskcluster.net/v1/task/d-fNWJ9XRVSurVLPiYbNuw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 20:18:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/d-fNWJ9XRVSurVLPiYbNuw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:18:54 INFO - Downloaded 16399471 bytes. 20:18:54 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 20:19:01 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 20:19:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:01 INFO - trying https://queue.taskcluster.net/v1/task/d-fNWJ9XRVSurVLPiYbNuw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 20:19:01 INFO - Downloading https://queue.taskcluster.net/v1/task/d-fNWJ9XRVSurVLPiYbNuw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 20:19:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/d-fNWJ9XRVSurVLPiYbNuw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:19:02 INFO - Downloaded 16399471 bytes. 20:19:02 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 20:19:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:08 INFO - trying https://queue.taskcluster.net/v1/task/d-fNWJ9XRVSurVLPiYbNuw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 20:19:08 INFO - Downloading https://queue.taskcluster.net/v1/task/d-fNWJ9XRVSurVLPiYbNuw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 20:19:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/d-fNWJ9XRVSurVLPiYbNuw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 20:19:09 INFO - Downloaded 14413998 bytes. 20:19:09 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 20:19:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:09 INFO - trying https://queue.taskcluster.net/v1/task/d-fNWJ9XRVSurVLPiYbNuw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 20:19:09 INFO - Downloading https://queue.taskcluster.net/v1/task/d-fNWJ9XRVSurVLPiYbNuw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 20:19:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/d-fNWJ9XRVSurVLPiYbNuw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 20:19:12 INFO - Downloaded 111601513 bytes. 20:19:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/d-fNWJ9XRVSurVLPiYbNuw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 20:19:12 INFO - mkdir: /builds/slave/test/properties 20:19:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:19:12 INFO - Writing to file /builds/slave/test/properties/build_url 20:19:12 INFO - Contents: 20:19:12 INFO - build_url:https://queue.taskcluster.net/v1/task/d-fNWJ9XRVSurVLPiYbNuw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 20:19:13 INFO - Running post-action listener: _resource_record_post_action 20:19:13 INFO - Running post-action listener: set_extra_try_arguments 20:19:13 INFO - [mozharness: 2016-09-02 03:19:13.477368Z] Finished download-and-extract step (success) 20:19:13 INFO - [mozharness: 2016-09-02 03:19:13.477521Z] Running populate-webroot step. 20:19:13 INFO - Running pre-action listener: _resource_record_pre_action 20:19:13 INFO - Running main action method: populate_webroot 20:19:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:19:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:19:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:19:13 INFO - '--tppagecycles', 20:19:13 INFO - '7'], 20:19:13 INFO - 'tests': ['tsvgm']}}, 20:19:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:19:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:19:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:19:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:19:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:19:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:19:13 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:19:13 INFO - 'talos_options': ['--disable-e10s'], 20:19:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:19:13 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:19:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:19:13 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:19:13 INFO - 'talos_options': ['--disable-e10s'], 20:19:13 INFO - 'tests': ['damp', 'tps']}, 20:19:13 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:19:13 INFO - 'tests': ['damp', 'tps']}, 20:19:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:19:13 INFO - 'tests': ['dromaeo_dom']}, 20:19:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:19:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:19:13 INFO - 'tests': ['basic_compositor_video']}, 20:19:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:19:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:19:13 INFO - 'tests': ['a11yr', 20:19:13 INFO - 'ts_paint', 20:19:13 INFO - 'tpaint', 20:19:13 INFO - 'sessionrestore', 20:19:13 INFO - 'sessionrestore_no_auto_restore', 20:19:13 INFO - 'tabpaint']}, 20:19:13 INFO - 'other-e10s': {'tests': ['a11yr', 20:19:13 INFO - 'ts_paint', 20:19:13 INFO - 'tpaint', 20:19:13 INFO - 'sessionrestore', 20:19:13 INFO - 'sessionrestore_no_auto_restore', 20:19:13 INFO - 'tabpaint']}, 20:19:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:19:13 INFO - 'ts_paint', 20:19:13 INFO - 'tpaint', 20:19:13 INFO - 'sessionrestore', 20:19:13 INFO - 'sessionrestore_no_auto_restore', 20:19:13 INFO - 'tabpaint']}, 20:19:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:19:13 INFO - 'ts_paint', 20:19:13 INFO - 'tpaint', 20:19:13 INFO - 'sessionrestore', 20:19:13 INFO - 'sessionrestore_no_auto_restore', 20:19:13 INFO - 'tabpaint']}, 20:19:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:19:13 INFO - 'tests': ['a11yr', 20:19:13 INFO - 'ts_paint', 20:19:13 INFO - 'tpaint', 20:19:13 INFO - 'sessionrestore', 20:19:13 INFO - 'sessionrestore_no_auto_restore', 20:19:13 INFO - 'tabpaint']}, 20:19:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:19:13 INFO - 'tests': ['a11yr', 20:19:13 INFO - 'ts_paint', 20:19:13 INFO - 'tpaint', 20:19:13 INFO - 'sessionrestore', 20:19:13 INFO - 'sessionrestore_no_auto_restore', 20:19:13 INFO - 'tabpaint']}, 20:19:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:19:13 INFO - 'tests': ['tsvgx', 20:19:13 INFO - 'tsvgr_opacity', 20:19:13 INFO - 'tart', 20:19:13 INFO - 'tscrollx', 20:19:13 INFO - 'cart']}, 20:19:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:19:13 INFO - 'tsvgr_opacity', 20:19:13 INFO - 'tart', 20:19:13 INFO - 'tscrollx', 20:19:13 INFO - 'cart']}, 20:19:13 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:19:13 INFO - 'talos_options': ['--disable-e10s'], 20:19:13 INFO - 'tests': ['tp5o']}, 20:19:13 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:19:13 INFO - 'tests': ['tp5o']}, 20:19:13 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:19:13 INFO - 'talos_options': ['--disable-e10s', 20:19:13 INFO - '--xperf_path', 20:19:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:19:13 INFO - 'tests': ['tp5n']}, 20:19:13 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:19:13 INFO - 'talos_options': ['--xperf_path', 20:19:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:19:13 INFO - 'tests': ['tp5n']}}, 20:19:13 INFO - 'talos.zip': {'path': '', 20:19:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:19:13 INFO - Running post-action listener: _resource_record_post_action 20:19:13 INFO - [mozharness: 2016-09-02 03:19:13.496508Z] Finished populate-webroot step (success) 20:19:13 INFO - [mozharness: 2016-09-02 03:19:13.496644Z] Running create-virtualenv step. 20:19:13 INFO - Running pre-action listener: _resource_record_pre_action 20:19:13 INFO - Running main action method: create_virtualenv 20:19:13 INFO - Creating virtualenv /builds/slave/test/build/venv 20:19:13 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 20:19:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:19:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:19:13 INFO - Using real prefix '/tools/python27' 20:19:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:19:14 INFO - Installing distribute.............................................................................................................................................................................................done. 20:19:17 INFO - Installing pip.................done. 20:19:17 INFO - Return code: 0 20:19:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:19:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:19:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:19:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1049d2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a67618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc91aca1d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104936d30>, '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 0x10469bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10492eb20>, '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.jGMxSBs6Zi/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.b3Nn9wwfYJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 20:19:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 20:19:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 20:19:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jGMxSBs6Zi/Render', 20:19:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:19:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:19:17 INFO - 'HOME': '/Users/cltbld', 20:19:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:19:17 INFO - 'LOGNAME': 'cltbld', 20:19:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:17 INFO - 'MOZ_NO_REMOTE': '1', 20:19:17 INFO - 'NO_EM_RESTART': '1', 20:19:17 INFO - 'PAGER': '/bin/cat', 20:19:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:19:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:19:17 INFO - 'PWD': '/builds/slave/test', 20:19:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:19:17 INFO - 'SHELL': '/bin/bash', 20:19:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b3Nn9wwfYJ/Listeners', 20:19:17 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 20:19:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:19:17 INFO - 'USER': 'cltbld', 20:19:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:19:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:19:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:19:17 INFO - 'XPC_FLAGS': '0x0', 20:19:17 INFO - 'XPC_SERVICE_NAME': '0', 20:19:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:19:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:19:17 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 20:19:17 INFO - Cleaning up... 20:19:17 INFO - Return code: 0 20:19:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:19:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:19:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:19:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1049d2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a67618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc91aca1d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104936d30>, '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 0x10469bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10492eb20>, '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.jGMxSBs6Zi/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.b3Nn9wwfYJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 20:19:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 20:19:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 20:19:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jGMxSBs6Zi/Render', 20:19:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:19:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:19:17 INFO - 'HOME': '/Users/cltbld', 20:19:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:19:17 INFO - 'LOGNAME': 'cltbld', 20:19:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:17 INFO - 'MOZ_NO_REMOTE': '1', 20:19:17 INFO - 'NO_EM_RESTART': '1', 20:19:17 INFO - 'PAGER': '/bin/cat', 20:19:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:19:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:19:17 INFO - 'PWD': '/builds/slave/test', 20:19:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:19:17 INFO - 'SHELL': '/bin/bash', 20:19:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b3Nn9wwfYJ/Listeners', 20:19:17 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 20:19:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:19:17 INFO - 'USER': 'cltbld', 20:19:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:19:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:19:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:19:17 INFO - 'XPC_FLAGS': '0x0', 20:19:17 INFO - 'XPC_SERVICE_NAME': '0', 20:19:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:19:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:19:17 INFO - Downloading/unpacking psutil>=3.1.1 20:19: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 20:19: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 20:19: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 20:19: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 20:19: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 20:19: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 20:19:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:19:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:19:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:19:20 INFO - Installing collected packages: psutil 20:19:20 INFO - Running setup.py install for psutil 20:19:20 INFO - building 'psutil._psutil_osx' extension 20:19:20 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 20:19:21 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 20:19:21 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 20:19:21 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 20:19:21 INFO - building 'psutil._psutil_posix' extension 20:19:21 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 20:19:21 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:19:21 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:19:21 INFO - ^ 20:19:21 INFO - 1 warning generated. 20:19:21 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 20:19:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:19:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:19:21 INFO - Successfully installed psutil 20:19:21 INFO - Cleaning up... 20:19:21 INFO - Return code: 0 20:19:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:19:21 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')]} 20:19:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:19:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19:21 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')]} 20:19:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:19:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19:21 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 0x1049d2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a67618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc91aca1d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104936d30>, '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 0x10469bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10492eb20>, '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.jGMxSBs6Zi/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.b3Nn9wwfYJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 20:19:21 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 20:19:21 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 20:19:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jGMxSBs6Zi/Render', 20:19:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:19:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:19:21 INFO - 'HOME': '/Users/cltbld', 20:19:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:19:21 INFO - 'LOGNAME': 'cltbld', 20:19:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:21 INFO - 'MOZ_NO_REMOTE': '1', 20:19:21 INFO - 'NO_EM_RESTART': '1', 20:19:21 INFO - 'PAGER': '/bin/cat', 20:19:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:19:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:19:21 INFO - 'PWD': '/builds/slave/test', 20:19:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:19:21 INFO - 'SHELL': '/bin/bash', 20:19:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b3Nn9wwfYJ/Listeners', 20:19:21 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 20:19:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:19:21 INFO - 'USER': 'cltbld', 20:19:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:19:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:19:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:19:21 INFO - 'XPC_FLAGS': '0x0', 20:19:21 INFO - 'XPC_SERVICE_NAME': '0', 20:19:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:19:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:19:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:19: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 20:19: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 20:19: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 20:19: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 20:19: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 20:19: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 20:19:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:19:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:19:24 INFO - Installing collected packages: mozsystemmonitor 20:19:24 INFO - Running setup.py install for mozsystemmonitor 20:19:24 INFO - Successfully installed mozsystemmonitor 20:19:24 INFO - Cleaning up... 20:19:24 INFO - Return code: 0 20:19:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:19:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:19:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:19:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1049d2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a67618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc91aca1d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104936d30>, '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 0x10469bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10492eb20>, '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.jGMxSBs6Zi/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.b3Nn9wwfYJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 20:19:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 20:19:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 20:19:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jGMxSBs6Zi/Render', 20:19:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:19:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:19:24 INFO - 'HOME': '/Users/cltbld', 20:19:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:19:24 INFO - 'LOGNAME': 'cltbld', 20:19:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:24 INFO - 'MOZ_NO_REMOTE': '1', 20:19:24 INFO - 'NO_EM_RESTART': '1', 20:19:24 INFO - 'PAGER': '/bin/cat', 20:19:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:19:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:19:24 INFO - 'PWD': '/builds/slave/test', 20:19:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:19:24 INFO - 'SHELL': '/bin/bash', 20:19:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b3Nn9wwfYJ/Listeners', 20:19:24 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 20:19:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:19:24 INFO - 'USER': 'cltbld', 20:19:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:19:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:19:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:19:24 INFO - 'XPC_FLAGS': '0x0', 20:19:24 INFO - 'XPC_SERVICE_NAME': '0', 20:19:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:19:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:19:25 INFO - Downloading/unpacking jsonschema==2.5.1 20:19:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:19:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:19:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:19:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:19:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:19:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:19:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:19:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:19: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 20:19: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 20:19: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 20:19: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 20:19: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 20:19: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 20:19:27 INFO - Downloading functools32-3.2.3-2.tar.gz 20:19:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:19:27 INFO - warning: no files found matching '*.txt' 20:19:27 INFO - no previously-included directories found matching 'build' 20:19:27 INFO - no previously-included directories found matching 'dist' 20:19:27 INFO - no previously-included directories found matching '.git*' 20:19:28 INFO - Installing collected packages: jsonschema, functools32 20:19:28 INFO - Running setup.py install for functools32 20:19:28 INFO - warning: no files found matching '*.txt' 20:19:28 INFO - no previously-included directories found matching 'build' 20:19:28 INFO - no previously-included directories found matching 'dist' 20:19:28 INFO - no previously-included directories found matching '.git*' 20:19:28 INFO - Successfully installed jsonschema functools32 20:19:28 INFO - Cleaning up... 20:19:28 INFO - Return code: 0 20:19:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:19: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')]} 20:19:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:19:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19: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')]} 20:19:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:19:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1049d2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a67618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc91aca1d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104936d30>, '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 0x10469bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10492eb20>, '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.jGMxSBs6Zi/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.b3Nn9wwfYJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 20:19:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 20:19:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 20:19:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jGMxSBs6Zi/Render', 20:19:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:19:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:19:28 INFO - 'HOME': '/Users/cltbld', 20:19:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:19:28 INFO - 'LOGNAME': 'cltbld', 20:19:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:28 INFO - 'MOZ_NO_REMOTE': '1', 20:19:28 INFO - 'NO_EM_RESTART': '1', 20:19:28 INFO - 'PAGER': '/bin/cat', 20:19:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:19:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:19:28 INFO - 'PWD': '/builds/slave/test', 20:19:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:19:28 INFO - 'SHELL': '/bin/bash', 20:19:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b3Nn9wwfYJ/Listeners', 20:19:28 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 20:19:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:19:28 INFO - 'USER': 'cltbld', 20:19:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:19:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:19:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:19:28 INFO - 'XPC_FLAGS': '0x0', 20:19:28 INFO - 'XPC_SERVICE_NAME': '0', 20:19:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:19:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:19:28 INFO - Cleaning up... 20:19:28 INFO - Return code: 0 20:19:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:19: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')]} 20:19:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:19:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19: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')]} 20:19:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:19:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1049d2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a67618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc91aca1d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104936d30>, '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 0x10469bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10492eb20>, '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.jGMxSBs6Zi/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.b3Nn9wwfYJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 20:19:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:19:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 20:19:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jGMxSBs6Zi/Render', 20:19:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:19:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:19:28 INFO - 'HOME': '/Users/cltbld', 20:19:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:19:28 INFO - 'LOGNAME': 'cltbld', 20:19:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:28 INFO - 'MOZ_NO_REMOTE': '1', 20:19:28 INFO - 'NO_EM_RESTART': '1', 20:19:28 INFO - 'PAGER': '/bin/cat', 20:19:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:19:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:19:28 INFO - 'PWD': '/builds/slave/test', 20:19:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:19:28 INFO - 'SHELL': '/bin/bash', 20:19:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b3Nn9wwfYJ/Listeners', 20:19:28 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 20:19:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:19:28 INFO - 'USER': 'cltbld', 20:19:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:19:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:19:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:19:28 INFO - 'XPC_FLAGS': '0x0', 20:19:28 INFO - 'XPC_SERVICE_NAME': '0', 20:19:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:19:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:19:28 INFO - Downloading/unpacking blobuploader==1.2.4 20:19:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:19:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:19:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:19:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:19:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:19:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:19:31 INFO - Downloading blobuploader-1.2.4.tar.gz 20:19:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:19:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:19:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:19:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:19:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:19:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:19:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:19:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:19:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:19:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:19:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:19:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:19:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:19:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:19:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:19:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:19:32 INFO - Downloading docopt-0.6.1.tar.gz 20:19:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:19:32 INFO - Installing collected packages: blobuploader, requests, docopt 20:19:32 INFO - Running setup.py install for blobuploader 20:19:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:19:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:19:32 INFO - Running setup.py install for requests 20:19:33 INFO - Running setup.py install for docopt 20:19:33 INFO - Successfully installed blobuploader requests docopt 20:19:33 INFO - Cleaning up... 20:19:33 INFO - Return code: 0 20:19:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:19: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')]} 20:19:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:19:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19: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')]} 20:19:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:19:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1049d2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a67618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc91aca1d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104936d30>, '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 0x10469bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10492eb20>, '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.jGMxSBs6Zi/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.b3Nn9wwfYJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 20:19:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 20:19:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:19:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jGMxSBs6Zi/Render', 20:19:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:19:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:19:33 INFO - 'HOME': '/Users/cltbld', 20:19:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:19:33 INFO - 'LOGNAME': 'cltbld', 20:19:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:33 INFO - 'MOZ_NO_REMOTE': '1', 20:19:33 INFO - 'NO_EM_RESTART': '1', 20:19:33 INFO - 'PAGER': '/bin/cat', 20:19:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:19:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:19:33 INFO - 'PWD': '/builds/slave/test', 20:19:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:19:33 INFO - 'SHELL': '/bin/bash', 20:19:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b3Nn9wwfYJ/Listeners', 20:19:33 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 20:19:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:19:33 INFO - 'USER': 'cltbld', 20:19:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:19:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:19:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:19:33 INFO - 'XPC_FLAGS': '0x0', 20:19:33 INFO - 'XPC_SERVICE_NAME': '0', 20:19:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:19:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:19:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:19:33 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-baV7gd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:19:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:19:33 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-vGb21U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:19:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:19:33 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-ra8sLt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:19:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:19:33 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-RA_oGL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:19:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:19:34 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-y9byOj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:19:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:19:34 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-HpTmMk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:19:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:19:34 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-kbAPdo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:19:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:19:34 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-scIq6L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:19:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:19:34 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-dZM4pl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:19:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:19:34 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-fhvnJ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:19:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:19:34 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-aglojN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:19:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:19:34 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-kj7AKj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:19:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:19:34 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-5hzBbG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:19:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:19:35 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-IxGKHe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:19:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:19:35 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-PDmagE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:19:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:19:35 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-NwGZi4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:19:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:19:35 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-s1h_Sn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:19:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:19:35 INFO - Running setup.py install for manifestparser 20:19:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:19:35 INFO - Running setup.py install for mozcrash 20:19:35 INFO - Running setup.py install for mozdebug 20:19:35 INFO - Running setup.py install for mozdevice 20:19:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:19:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:19:36 INFO - Running setup.py install for mozfile 20:19:36 INFO - Running setup.py install for mozhttpd 20:19:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:19:36 INFO - Running setup.py install for mozinfo 20:19:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:19:36 INFO - Running setup.py install for mozInstall 20:19:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:19:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:19:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:19:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:19:36 INFO - Running setup.py install for mozleak 20:19:36 INFO - Running setup.py install for mozlog 20:19:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:19:36 INFO - Running setup.py install for moznetwork 20:19:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:19:37 INFO - Running setup.py install for mozprocess 20:19:37 INFO - Running setup.py install for mozprofile 20:19:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:19:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:19:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:19:37 INFO - Running setup.py install for mozrunner 20:19:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:19:37 INFO - Running setup.py install for mozscreenshot 20:19:37 INFO - Running setup.py install for moztest 20:19:37 INFO - Running setup.py install for mozversion 20:19:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:19:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:19:38 INFO - Cleaning up... 20:19:38 INFO - Return code: 0 20:19:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:19: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')]} 20:19:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:19:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19: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')]} 20:19:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:19:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19:38 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 0x1049d2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a67618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc91aca1d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104936d30>, '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 0x10469bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10492eb20>, '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.jGMxSBs6Zi/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.b3Nn9wwfYJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 20:19:38 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 20:19:38 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 20:19:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jGMxSBs6Zi/Render', 20:19:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:19:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:19:38 INFO - 'HOME': '/Users/cltbld', 20:19:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:19:38 INFO - 'LOGNAME': 'cltbld', 20:19:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:38 INFO - 'MOZ_NO_REMOTE': '1', 20:19:38 INFO - 'NO_EM_RESTART': '1', 20:19:38 INFO - 'PAGER': '/bin/cat', 20:19:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:19:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:19:38 INFO - 'PWD': '/builds/slave/test', 20:19:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:19:38 INFO - 'SHELL': '/bin/bash', 20:19:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b3Nn9wwfYJ/Listeners', 20:19:38 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 20:19:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:19:38 INFO - 'USER': 'cltbld', 20:19:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:19:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:19:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:19:38 INFO - 'XPC_FLAGS': '0x0', 20:19:38 INFO - 'XPC_SERVICE_NAME': '0', 20:19:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:19:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:19:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:19:38 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-2eYrIU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:19:38 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)) 20:19:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:19:38 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-oDSYV6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:19:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:19:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:19:38 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-RVAaPd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:19:38 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)) 20:19:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:19:38 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-3AFnj3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:19:38 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)) 20:19:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:19:38 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-IxZTjh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:19:38 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)) 20:19:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:19:38 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-QxAiwt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:19:38 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)) 20:19:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:19:38 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-OVVuJV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:19:39 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)) 20:19:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:19:39 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-hxIksZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:19:39 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)) 20:19:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:19:39 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-djEnK7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:19:39 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)) 20:19:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:19:39 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-KXZW4D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:19:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:19:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:19:39 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-7mS5kP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:19:39 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)) 20:19:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:19:39 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-5ad6Wy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:19:39 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)) 20:19:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:19:39 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-wE7io9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:19:39 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)) 20:19:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:19:39 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-LXCiZU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:19:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 20:19:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:19:39 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-d429qI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:19:39 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)) 20:19:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:19:39 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-t8OIgS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:19:40 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)) 20:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:19:40 INFO - Running setup.py (path:/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/pip-GrsPSF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:19:40 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)) 20:19:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:19:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:19:40 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)) 20:19:40 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)) 20:19:40 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)) 20:19:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:19:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:19:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:19:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:19:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:19:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:19:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:19:42 INFO - Downloading blessings-1.6.tar.gz 20:19:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:19:42 INFO - Installing collected packages: blessings 20:19:42 INFO - Running setup.py install for blessings 20:19:42 INFO - Successfully installed blessings 20:19:42 INFO - Cleaning up... 20:19:42 INFO - Return code: 0 20:19:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:19:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:19:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:19:43 INFO - Reading from file tmpfile_stdout 20:19:43 INFO - Current package versions: 20:19:43 INFO - blessings == 1.6 20:19:43 INFO - blobuploader == 1.2.4 20:19:43 INFO - docopt == 0.6.1 20:19:43 INFO - functools32 == 3.2.3-2 20:19:43 INFO - jsonschema == 2.5.1 20:19:43 INFO - manifestparser == 1.1 20:19:43 INFO - mozInstall == 1.12 20:19:43 INFO - mozcrash == 0.17 20:19:43 INFO - mozdebug == 0.1 20:19:43 INFO - mozdevice == 0.48 20:19:43 INFO - mozfile == 1.2 20:19:43 INFO - mozhttpd == 0.7 20:19:43 INFO - mozinfo == 0.9 20:19:43 INFO - mozleak == 0.1 20:19:43 INFO - mozlog == 3.3 20:19:43 INFO - moznetwork == 0.27 20:19:43 INFO - mozprocess == 0.23 20:19:43 INFO - mozprofile == 0.28 20:19:43 INFO - mozrunner == 6.12 20:19:43 INFO - mozscreenshot == 0.1 20:19:43 INFO - mozsystemmonitor == 0.3 20:19:43 INFO - moztest == 0.7 20:19:43 INFO - mozversion == 1.4 20:19:43 INFO - psutil == 3.1.1 20:19:43 INFO - requests == 1.2.3 20:19:43 INFO - wsgiref == 0.1.2 20:19:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:19:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:19:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:19:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19:43 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 0x1049d2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a67618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc91aca1d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104936d30>, '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 0x10469bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10492eb20>, '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.jGMxSBs6Zi/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.b3Nn9wwfYJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 20:19:43 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 20:19:43 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 20:19:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jGMxSBs6Zi/Render', 20:19:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:19:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:19:43 INFO - 'HOME': '/Users/cltbld', 20:19:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:19:43 INFO - 'LOGNAME': 'cltbld', 20:19:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:43 INFO - 'MOZ_NO_REMOTE': '1', 20:19:43 INFO - 'NO_EM_RESTART': '1', 20:19:43 INFO - 'PAGER': '/bin/cat', 20:19:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:19:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:19:43 INFO - 'PWD': '/builds/slave/test', 20:19:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:19:43 INFO - 'SHELL': '/bin/bash', 20:19:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b3Nn9wwfYJ/Listeners', 20:19:43 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 20:19:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:19:43 INFO - 'USER': 'cltbld', 20:19:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:19:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:19:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:19:43 INFO - 'XPC_FLAGS': '0x0', 20:19:43 INFO - 'XPC_SERVICE_NAME': '0', 20:19:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:19:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:19:43 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)) 20:19:43 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)) 20:19:43 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)) 20:19:43 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)) 20:19:43 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)) 20:19:43 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)) 20:19:43 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)) 20:19:43 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)) 20:19:43 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)) 20:19:43 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)) 20:19:43 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)) 20:19:43 INFO - Cleaning up... 20:19:43 INFO - Return code: 0 20:19:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:19:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:19:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:19:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:19:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:19:43 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 0x1049d2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a67618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc91aca1d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104936d30>, '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 0x10469bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10492eb20>, '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.jGMxSBs6Zi/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.b3Nn9wwfYJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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 20:19:43 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 20:19:43 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 20:19:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jGMxSBs6Zi/Render', 20:19:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:19:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:19:43 INFO - 'HOME': '/Users/cltbld', 20:19:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:19:43 INFO - 'LOGNAME': 'cltbld', 20:19:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:43 INFO - 'MOZ_NO_REMOTE': '1', 20:19:43 INFO - 'NO_EM_RESTART': '1', 20:19:43 INFO - 'PAGER': '/bin/cat', 20:19:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:19:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:19:43 INFO - 'PWD': '/builds/slave/test', 20:19:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:19:43 INFO - 'SHELL': '/bin/bash', 20:19:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b3Nn9wwfYJ/Listeners', 20:19:43 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 20:19:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:19:43 INFO - 'USER': 'cltbld', 20:19:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:19:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:19:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:19:43 INFO - 'XPC_FLAGS': '0x0', 20:19:43 INFO - 'XPC_SERVICE_NAME': '0', 20:19:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:19:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:19:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:19:43 INFO - Cleaning up... 20:19:43 INFO - Return code: 0 20:19:43 INFO - Running post-action listener: _resource_record_post_action 20:19:43 INFO - Running post-action listener: _start_resource_monitoring 20:19:43 INFO - Starting resource monitoring. 20:19:43 INFO - [mozharness: 2016-09-02 03:19:43.816684Z] Finished create-virtualenv step (success) 20:19:43 INFO - [mozharness: 2016-09-02 03:19:43.817670Z] Running install step. 20:19:43 INFO - Running pre-action listener: _resource_record_pre_action 20:19:43 INFO - Running main action method: install 20:19:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:19:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:19:44 INFO - Reading from file tmpfile_stdout 20:19:44 INFO - Detecting whether we're running mozinstall >=1.0... 20:19:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:19:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:19:44 INFO - Reading from file tmpfile_stdout 20:19:44 INFO - Output received: 20:19:44 INFO - Usage: mozinstall [options] installer 20:19:44 INFO - Options: 20:19:44 INFO - -h, --help show this help message and exit 20:19:44 INFO - -d DEST, --destination=DEST 20:19:44 INFO - Directory to install application into. [default: 20:19:44 INFO - "/builds/slave/test"] 20:19:44 INFO - --app=APP Application being installed. [default: firefox] 20:19:44 INFO - mkdir: /builds/slave/test/build/application 20:19:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 20:19:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 20:20:19 INFO - Reading from file tmpfile_stdout 20:20:19 INFO - Output received: 20:20:19 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 20:20:19 INFO - Running post-action listener: _resource_record_post_action 20:20:19 INFO - [mozharness: 2016-09-02 03:20:19.144756Z] Finished install step (success) 20:20:19 INFO - [mozharness: 2016-09-02 03:20:19.144904Z] Running run-tests step. 20:20:19 INFO - Running pre-action listener: _resource_record_pre_action 20:20:19 INFO - Running main action method: run_tests 20:20:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:20:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:20:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:20:19 INFO - 2016-09-01 20:20:19.158 screenresolution[1638:7559] starting screenresolution argv=screenresolution get 20:20:19 INFO - 2016-09-01 20:20:19.161 screenresolution[1638:7559] Display 0: 1600x1200x32@60 20:20:19 INFO - 2016-09-01 20:20:19.167 screenresolution[1639:7561] starting screenresolution argv=screenresolution list 20:20:19 INFO - Available Modes on Display 0 20:20:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:20:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:20:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:20:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:20:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:20:19 INFO - Intel Iris: 20:20:19 INFO - Chipset Model: Intel Iris 20:20:19 INFO - Type: GPU 20:20:19 INFO - Bus: Built-In 20:20:19 INFO - VRAM (Dynamic, Max): 1536 MB 20:20:19 INFO - Vendor: Intel (0x8086) 20:20:19 INFO - Device ID: 0x0a2e 20:20:19 INFO - Revision ID: 0x0009 20:20:19 INFO - Displays: 20:20:19 INFO - Display: 20:20:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:20:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:20:19 INFO - Display Serial Number: 1600x1200 60 20:20:19 INFO - Main Display: Yes 20:20:19 INFO - Mirror: Off 20:20:19 INFO - Online: Yes 20:20:19 INFO - Rotation: Supported 20:20:19 INFO - Return code: 0 20:20:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:20:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:20:19 INFO - Python 2.7.3 20:20:19 INFO - Return code: 0 20:20:19 INFO - grabbing minidump binary from tooltool 20:20: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')]} 20:20:19 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 0x104936d30>, '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 0x10469bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10492eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:20:19 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 20:20:19 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 20:20:19 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 20:20:19 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:20:19 INFO - Return code: 0 20:20:19 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:20:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:20:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:20:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:20:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:20:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/d-fNWJ9XRVSurVLPiYbNuw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0325', '--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 20:20:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/d-fNWJ9XRVSurVLPiYbNuw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0325 --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 20:20:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jGMxSBs6Zi/Render', 20:20:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:20:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:20:19 INFO - 'HOME': '/Users/cltbld', 20:20:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:20:19 INFO - 'LOGNAME': 'cltbld', 20:20:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:20:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:20:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:20:19 INFO - 'MOZ_NO_REMOTE': '1', 20:20:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:20:19 INFO - 'NO_EM_RESTART': '1', 20:20:19 INFO - 'PAGER': '/bin/cat', 20:20:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:20:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:20:19 INFO - 'PWD': '/builds/slave/test', 20:20:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:20:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:20:19 INFO - 'SHELL': '/bin/bash', 20:20:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.b3Nn9wwfYJ/Listeners', 20:20:19 INFO - 'TMPDIR': '/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/', 20:20:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:20:19 INFO - 'USER': 'cltbld', 20:20:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:20:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:20:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:20:19 INFO - 'XPC_FLAGS': '0x0', 20:20:19 INFO - 'XPC_SERVICE_NAME': '0', 20:20:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:20:19 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/d-fNWJ9XRVSurVLPiYbNuw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0325', '--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 20:20:19 INFO - mozversion application_buildid: 20160901175542 20:20:19 INFO - mozversion application_changeset: e12415c1560179174bd3bdaca11dd20e6b97d35a 20:20:19 INFO - mozversion application_display_name: Nightly 20:20:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:20:19 INFO - mozversion application_name: Firefox 20:20:19 INFO - mozversion application_remotingname: firefox 20:20:19 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 20:20:19 INFO - mozversion application_vendor: Mozilla 20:20:19 INFO - mozversion application_version: 51.0a1 20:20:19 INFO - mozversion platform_buildid: 20160901175542 20:20:19 INFO - mozversion platform_changeset: e12415c1560179174bd3bdaca11dd20e6b97d35a 20:20:19 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 20:20:19 INFO - mozversion platform_version: 51.0a1 20:20:19 INFO - using testdate: 1472786419 20:20:19 INFO - actual date: 1472786419 20:20:19 INFO - starting webserver on 'localhost:49274' 20:20:19 INFO - SUITE-START | Running 2 tests 20:20:19 INFO - TEST-START | dromaeo_css 20:20:19 INFO - operating with platform_type : mac_ 20:20:19 INFO - Initialising browser for dromaeo_css test... 20:20:19 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/tmp2lBnjq/profile http://localhost:49274/getInfo.html) 20:20:22 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 20:20:22 INFO - PROCESS | 1649 | colorDepth:24 20:20:22 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 20:20:22 INFO - PROCESS | 1649 | __metrics 20:20:22 INFO - PROCESS | 1649 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:20:22 INFO - PROCESS | 1649 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:20:22 INFO - TEST-INFO | 1649: exit 0 20:20:22 INFO - Browser initialized. 20:20:22 INFO - Running cycle 1/1 for dromaeo_css test... 20:20:22 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/tmp2lBnjq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:20:34 INFO - PROCESS | 1651 | RSS: Main: 234229760 20:20:34 INFO - PROCESS | 1651 | 20:21:25 INFO - PROCESS | 1651 | 1472786485792 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] 20:23:38 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49274/tests/dromaeo/cssquery-ext.html) 20:23:39 INFO - PROCESS | 1651 | RSS: Main: 376643584 20:23:39 INFO - PROCESS | 1651 | 20:26:43 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-ext.html (next: http://localhost:49274/tests/dromaeo/cssquery-jquery.html) 20:26:43 INFO - PROCESS | 1651 | RSS: Main: 369143808 20:26:43 INFO - PROCESS | 1651 | 20:29:46 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49274/tests/dromaeo/cssquery-mootools.html) 20:29:46 INFO - PROCESS | 1651 | RSS: Main: 347709440 20:29:46 INFO - PROCESS | 1651 | 20:32:49 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49274/tests/dromaeo/cssquery-prototype.html) 20:32:49 INFO - PROCESS | 1651 | RSS: Main: 344883200 20:32:49 INFO - PROCESS | 1651 | 20:35:52 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49274/tests/dromaeo/cssquery-yui.html) 20:35:53 INFO - PROCESS | 1651 | RSS: Main: 332091392 20:35:53 INFO - PROCESS | 1651 | 20:38:56 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-yui.html (next: http://localhost:49274/tests/dromaeo/cssquery-dojo.html) 20:38:57 INFO - PROCESS | 1651 | RSS: Main: 540311552 20:38:57 INFO - PROCESS | 1651 | 20:38:57 INFO - PROCESS | 1651 | __start_tp_report 20:38:57 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 20:38:57 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 20:38:57 INFO - PROCESS | 1651 | |i|pagename|runs| 20:38:57 INFO - PROCESS | 1651 | |0;dojo.html;3026.924326891828;3332.583427071616;3374.1395944034284;3416.7095796857266;3444.810535420551;5587.8603034924145;5648.491635752782;5668.631538949965;5725.14122881568;5765;5653.802116925908;5654.943450565494;5763.366029736726;5787.594868359206;5810.883782324351;5145.048166089286;5180.274761533388;5206.349206349207;5212.869678258055;5273.325275901022;5339.119045357511;5352.732363381835;5356.839294821142;5375.6774593524515;5399.974004939068;5160.071187186305;5214.687118772886;5217.113090774577;5252.343457067866;5271.42014378418;5221.2951251580935;5259.921101183485;5262.473752624745;5262.500062494095;5270.789168433296;5178.249153872705;5182.093133701587;5261.263423120766;5290.709011004393;5291.497307755757;5613.158026296048;5631.690257035862;5662.66024038559;5713.428657134295;5730.140478928152;6218.06728990655;6260.624362538217;6272.937270627326;6285.937140628626;6286.937130628727;6098.674287528765;6204.844878878019;6273.717682704244;6280.654563998978;6334.239891213084;5817.592768506249;5826.592138550345;5853.590248682636;5860.267466566679;5876.206712093898;6278.058291256257;6303.873922521524;6304.117423560705;6307.3377295383325;6313.210848643919;5985.700714964234;6050.213472248667;6080.695965201722;6113.4154954094565;6184.8763024739255;6267.467265282411;6281.811545653638;6303.842403939938;6308.053791931247;6316.273628532777;6182.938170618326;6218.968905155536;6239.719212635442;6249.625022498664;6250.593711408744;6101.4290607115445;6148.077788331786;6185.257769067741;6220.222472190976;6260.655663938436;5001.174806157016;5006.674566153189;5021;5050.242463630411;5059.443461219263;4204.4324016257415;4470.217711900469;4496.123255965055;4496.797644106023;4542.409486766699;5596.244506991586;5658.943410565924;5659.547236221092;5713.428657134336;5741.569382296261;6287.276963149205;6305.653189074553;6326.841828954221;6328.778492752732;6341.3341599132345;5689.573282003867;5713.857153571111;5714.514266287329;5746.224259724967;5773.1051686988585;5311.654742441729;5363.088274993261;5413.7834486620095;5453.100238460689;5467.480589344006;6236.220472440854;6278.4035516625845;6279.96860015697;6327.557071004985;6343.5876668016435;6337.7781777637565;6348.206474190726;6352.237731472253;6353;6361.522885783492;6182.907256391248;6314.905276420765;6315.147455093687;6342.587731797418;6345.333739957423;6080.2399700037495;6134.865219626364;6159;6188.288346840171;6239.812805615838;6254.781082662267;6270.561060725888;6284.780032698834;6287.905681414874;6292.905606415816;5868.4424979626865;6095.451409373;6123.479504242189;6124.050772130327;6128.295246046761;5360.544353729977;6031.5476339273855;6089.238845144357;6149.0161574149715;6149.569530133027;5920.763169473173;5986.371430999857;5988.790392336247;5991.970040149771;6042.8791424170395;5968.4031596841705;6120.234970628671;6168.845778855564;6171.47542458896;6180.567360284737;5870.17817505541;5933.910991335047;5941.762329506944;5983.431574000463;6036.185115009594 20:38:57 INFO - PROCESS | 1651 | |1;ext.html;486.4648886225153;489.47137091940687;512.19073863296;535.2533216163453;535.2907397698051;30382.24044398891;39124.804375978114;40402.19195616086;40648.99999999996;42155;41640.95897602554;42124.578754212445;42163.57836421635;42189.57810421895;42272.15455690884;9605.75985600362;9788.168005719486;9802.705918822447;9804.754881127958;9808.607655693764;8775.517346545937;8963.95518022409;11841.230320029214;12128.757424851497;12203.755924881498;12075.335856527887;12182.390880455987;12187.695307617292;12189.268643881396;12233;12054.638360849145;12133.514659413613;12138.635840924699;12145.39273036349;12163.331016794073;11831.881681183164;11912.821307680391;11914.523419063227;11917.761644767143;11923.972924602751;38724.80637596794;38969.41545876814;39024.414633780514;39087;39127.60872391296;3111.9222019449467;3278.885239016623;3518.5953615334306;3955.6439920407015;4010.83956641734;29799;30282.940097096496;30548;30661.386772264654;31424.37151256985;3145.339478709474;3145.543896135048;3211.6627754085716;3283.064326666912;3315.5192497087787;326998;331445;332141;332241;332705;31175.220619484695;54790.72604636951;54974.72512637411;55228.723856381264;55301.72349138228;31882.20294492656;32124.036278911204;32159;32201.35597288041;32240.677593224238;14337.713245735236;14466.05970611907;14479.565613031626;14499.565013049625;14514.129152250898;11294.60468883585;11521.884781152248;12356.443960021821;12463.875361246453;12472;7391.8152046199275;7512.849743005109;7526.285002924714;7573.166951635316;7667.808304792425;3214.8071115733133;3263.510473428957;3265.151060724229;3303.157694787814;3306.6032076151014;938.9342746007713;946.2571881073325;969.4474149734724;991.682661548312;991.9553620087113;28772.568411473843;29622.407551848843;29707.257318567208;30278.54582181269;30413.391732165233;1003.6098218890073;1029.248648486594;1096.3641088168843;1118.3066498770813;1143.8398624192619;14589.635259118395;15135.940484166003;15211.923940380228;15514.612134696723;15549.378024879104;602132;604961;610034;612815;613568;55379.72310138423;55837;55865.88268234613;55911.16133258004;55966.160507592416;1224.3082658298033;1351.1217708489364;1375.5529452927838;1398.8111604933204;1401.2993503248376;29990.100296991124;30372.544411834282;30446.695533044385;30680.84745000735;31282.843585781924;47225.763871180425;47420.7628961853;47533.52466475291;47549.52450475451;47773.283400749715;492341;493911;494487;494583;495110;25287.839020122483;25803;26389.60415593806;26462.898250771163;27239.455210895274;44755;44997.32504012377;45018.0996380064;45152.54847451615;46250.768746156056;36987.26025479529;37316;37818.0545486365;38457.807710961264;38510.42234366543;37106.44340334951;37323;37740.62259377371;38554.80722596481;38782.764326815515;36418.4537231947;36627.81686091659;37150.814245928595;37153.81423092867;37336.06659833526;30846.845765771028;31912.68087319097;33215.169620759676;33641.327173456884;33798;24707.505849883262;25273.43984323446;25586.61620075663;25691.614625780254;26006.47987040211 20:38:57 INFO - PROCESS | 1651 | |2;jquery.html;345.7994363269304;368.68661637608034;370.74603896330996;377.2135098320003;400.43738547340996;14507.927460362696;15107.924460377697;19976.001199940016;20703.171873125095;20722.067506962187;16593.585160370996;20426.591468170664;20432.387028389134;20514.38456846297;20521;4212.304969679999;5608.5793565482545;5826.446487583673;6111.786087486917;6117.602355846878;6243.313235544087;6677.632730199837;6760.8309792255095;6800.149981252343;6807.387335139836;5777.508911742507;6028.728707208186;6127.9080813787605;6729.495287853406;6730.697118629673;6042.305134909498;6044.637321760686;6102.481289090432;6113.296970848342;6127.356627554132;5708.942910570924;5835.970820145914;5881.500072493843;5885.411458854123;5898.321693005317;13099.952003839766;13120.60638180856;13144.802827957588;13339.132956357806;13383.933080334633;1951.6291904538093;2145.2062736787334;2211.7345918489723;2215.1471683401846;2297.632378819381;9620.037996200394;9774.462404567746;9833.065858743408;10229.283950123494;10391.324563903323;1687.5443630219772;1883.3502441657606;1894.5737209127974;1917.2810196176433;1932.9033548322527;88263.55868220681;105763;109501;111565;129528.99999999905;11181.72045698864;11252.943735281271;11276.605318813803;11596.826047609293;11683.76632467346;14471.565853024425;14474;14508.927455362867;14525.564233072813;14538.491152809813;8407.369447291356;8735.91264087351;8757.555003424492;8833.734987950373;8889.111088891059;8046.557439340892;8087;8204.548749818818;8212.8357432851;8244.628991695388;4063.9796801015805;4229.619334259932;4514.232580461329;4538.704984176018;4610.838620648328;1878.8684792064423;1907.9332223372116;1913.8373238274903;1946.951326216856;1955.7946415626438;365.8536585365875;380.37048684427316;396.57764480828405;828.8135169586915;900.7928176519438;9654.75863103428;11607.883921160848;11996.820047699292;12058.939705301593;12156.756864862653;417.21771678103556;424.0860944664253;431.43697474795067;834.9754184829065;905.9139381758722;10253.436061016715;10567.207459440573;11109.555617775199;11111.38887361203;11158.497867595815;568216;569735;571254;584047;585379;110112;112789;113040;118567;120732.3963380195;987.0918754745651;997.5261750668375;1013.3413281367023;1048.2452634103433;1048.6539441984128;18446.538836529195;18532.444026679223;18702.719459207852;18739.34412295563;18761.968091755083;41236;53588.46411535835;54215.45784542104;54450;55767.44232557622;58661;82006.58996704979;82047;82319;82723;6292.685365731787;7843.098043725045;7896.131425543301;7955.24425179607;8168.018673184364;10704.785904282027;12812.359382031047;13095.95232381388;13203.603891883258;13341.86658133445;10250.436226007343;11195.04842088399;11215.55137794479;11294.265872718248;11552.306861588331;9611.894632117395;11042.447877606248;11100.278481898651;11265.324080555192;11547.94226028898;10696.411697356409;11398.202125851443;11670.94164529172;11714.1800073994;11818.054555635357;7866.292033716993;7907.406944479302;7929.405294603043;7954.284114429498;8190.099089100313;9021.864672029793;10678.985496377833;10964.725881853017;10981.560737570728;11008.944955275172 20:38:57 INFO - PROCESS | 1651 | |3;mootools.html;1341.2228796844183;1496.5211132437623;1532.341093329868;1664.8179792347432;1731.4545918035817;7838.608069596526;8625.78435539111;8854.026057133718;8868.733937981871;8971.775705607362;8763.91236087639;8842.5136617486;8845.823083538326;8849.026607073232;8850.291976641869;2358.021421110237;2387.259949415678;2391.820613453989;2393.2939782764124;2393.533261014103;2338.0764597983784;2368.6683864259016;2382.630692242705;2384.6065399209197;2388.558116748407;2339.263132113387;2353.376355265856;2360.6459031145296;2377.3581133094053;2379.95240095198;2323;2355.858648481088;2356.1988923765916;2363.8818059096975;2370.4666450048603;2290.0191334495707;2329.301209637102;2331.009321038569;2332.2303639798997;2336.883155842201;8546.05993340732;8745.956270218672;8852.734417967407;8854.424462409987;8863.025067242597;5132.8460146195675;5328.600354973392;5354.250404943311;5357.116075847481;5365.892682146375;3056.541518772202;3563.501109844624;3577.678008979204;3642.562892452896;3642.690371318441;1076.160594736107;1084.3656460970342;1093.7703082352716;1095.8849320821278;1108.2906939558688;11400;11421.771564568662;11576.999999999916;11591;11594.420278986017;4126.711130220856;4276.465441819772;4607.861764147081;4670.813167473263;4845.394325709286;8577.742667719978;10068.949655251678;10070.395776253448;10081;10087;5413.2692086568595;5415.729213539307;5419.3225846769155;5424.918626220609;5451.209574611659;4317.050248945227;4443.4445694288215;4449.310356894686;4596.195665758546;4963.156263435225;3916.5300163979937;4008.8797336079965;4038.071243613985;4040.5151381834366;4044.2720310343834;1104.5968221599219;1281.5514569900647;1287.8969682425384;1298.5974347952165;1303.0683061610994;768.8347005393867;783.6669415498391;823.6005537314397;884.9159329863653;918.9678361257323;5697.971510142423;5791.913121303184;6059.939400605937;6104.481119104926;6118.755249790048;901.6753968571309;932.2728271947891;953.8378475659155;980.5832521178471;1019.169376957777;3822.847086116524;3864.6715029222833;3884.3590807516443;3992.3612222044912;4018.9952511872034;10431.321964072311;10690.465476726133;10823.945880270549;10827.187960902964;10884.07485363753;10736.409497477714;10739.892601074045;10752.892471075345;10768.192385570957;11046.116310695124;7784.610769461504;7924.683012679428;8018.077921039155;8095.514269143752;8190.262876341158;5851.824445266649;5977.22296101504;6068.423499767515;6080.331163572092;6177.227846519185;9960.352577082467;10089.848652270368;10119.241056920908;10134.290599658249;10136.239782016526;8882.15619516145;9830.606775728891;10089.949550252495;10108.64619738335;10287.125594324267;6081.908771368521;7529.510581812165;7540;7602.3537999268465;7602.353799927068;8188.2630563249595;8193.50838949641;8211.712590059558;8280.544570048705;8280.792980175544;7400.333969942731;7438.628068596657;7522.059742532184;7522.398208143226;7546.849063018599;7063.788086357417;7238.167610724834;7262.527935684164;7312.926870731225;7326.560406375635;6838.897416538855;6880.071190389233;7004.859902802017;7008.088948436671;7019.684114214975;4913.950860491349;5008.348914641073;5023;5059.848204553869;5179.533841954242;4976.2038073908;4999.250112483011;5076.517730815566;5226.45122262157;5258.211268309631 20:38:57 INFO - PROCESS | 1651 | |4;prototype.html;264.68502482046364;290.49308955872004;306.9355435358575;308.5972805488836;311.3337457840221;4364.581511008919;4791.329213910055;5269.578433725303;5321.308229930109;5354.411014788375;4557.498675145731;4778.447004723462;4787.941185290324;5241.764120614572;5326.387465441476;3200.9199770005703;3493.9301213975705;4065.9796701016453;4067.694922880781;4099.569545197749;3894.4742459767854;3900.8049597520153;4070.3894415837567;4091.9386209206887;4107.835686572534;3652.6712595866366;4072.7963601819793;4072.9613948443107;4078.3270760324513;4101.856435024774;3669.1744357519347;4067.59667914569;4081;4087.182563487285;4089.4683691120113;3404.472306792451;3426.1777173478185;3546.8935931922083;3567.4292113261754;3583.7994271918938;5058.114829904789;5169.767360468751;5171.982926658606;5179.456157103526;5183.559397451221;3983.8008099594904;4601.493835678073;4635.235186194308;4652.511196417148;4671.556202160789;4949.579285760715;5264.394594621591;5329.573634109262;5507.118860982264;5678.43215678433;3910.5726409860195;3927.9018024549327;3930.2117536520814;3945.2640838031175;3946.980265098685;5766.223123853841;5767.61577221468;5776.956130708479;5795.3335366432975;5800.665846855247;3510.350585141757;3751.2309976454762;3950.6246906543834;3954.940675889864;3976.721629485966;4010.8195131219163;4015.8393664253686;4024.7182697210915;4025.2352053109817;4048.959510404858;4584.7992963377765;4624.491305956342;4643.2802915548145;4667.929981050287;4668.29607193372;5186.429492755794;5506.476884695948;5578.581606379537;5593.440655934455;5676.602637815313;1286.4339690536135;1339.6382976596265;1376.4769387632637;1382.3088455772113;1385.058160450903;707.6284950401081;723.3851226457574;752.5221484357363;828.4863384701524;864.5072308783933;3573.4282514797505;3819.2189732164957;4272.718184544624;4306.440162778881;4320.135972805451;3973.589375771617;4078.979605102015;4408.294672852328;4741.83403580873;4819.7108173509705;4004.6395824375945;4094.61101195386;4179.176053910638;4232.513260974965;4301.677374196947;2154.666026765854;2251.414632195641;2387.283814855571;2543.4357869909873;2544.7073586537413;4713;4749.881252968635;4779.996200797802;4998.825276060123;5088.363954505687;4116.58834116592;4745.982957243158;4767.046590681877;4799.904001919942;4808.182608956486;4115.032967252693;4595.540445955445;4866.323522062851;4868.805247790048;4934.753262336869;3954.7033972451604;4075.91848163041;4681;4692.1554120257315;4695.2487601984885;4184.707070505157;4700.9657875267385;4741.146593613044;4754.312219162286;4777.880552986203;4175.475951277823;4631.86519302773;4882.557796753352;4929.531694489017;4951.084049450863;3248.9350212995137;3342.665733426735;3675.3751862182958;3796.4684944107294;3848.4034974578985;5068.873278168076;5598.636088654224;5608.766071464272;5661.150827375926;5672.602917795714;5508.944910550843;5511.586631002771;5512.91730624033;5554.416786237387;5571.9721401392735;4133.545310015836;4723.834665786681;5097.6941383517105;5253.896681696887;5392.811251606175;4732.414641095272;4904.190808516555;5355.518003379714;5396.109641909041;5435.456454354533;4378.2119218540965;5235.528802407802;5273.973630131824;5459.918101228564;5461;4554.817807287672;5325.97337013328;5391.973040134774;5401.2168235606405;5417.43116972728 20:38:57 INFO - PROCESS | 1651 | |5;yui.html;694.930506949305;701.3757755597519;721.3291638775936;771.1748429180778;771.8610650082983;14987.47543835966;15762.921185394085;15768.290426930787;15771.842281577181;15785.684286314281;14128.788068178985;14262.072965257277;14290.57128286153;14291.571252862404;14711.779323310158;670.4334837062675;695.3984803145269;818.2186012358213;831.1439217605875;846.196113691992;755.3353049316621;819.7335865843595;833.9291160251386;835.3734698975768;849.855524560823;899.851524498457;900.18083543975;902.1970446302763;903.8011637439786;904.1636486250225;696.613379574336;717.7057406463377;886.7206829848577;887.4497811357;888.5468411110315;532.7070111438693;533.2747463449705;568.3123420661005;645.6965226343611;671.3487916720773;12157.452914618863;14653.780193297003;14657.853421465756;14683.486077987325;14722;13430.32848357588;13484.772118230047;13519.459221631123;13988.300584970711;14259.78610320846;11527.827082593767;11769.705757356049;11840.70398240051;11869.406529673568;12184;2842.829430234172;3799.202167544819;3804.087019115392;3819.4079917612817;3869.071422858522;96583;100019;108488.45755771249;108735;111248.20645625776;10929.781404371868;11104.555817767361;11148.721281967853;11172;11317.320960742218;21639.56720865574;22007.559848802935;22031.118755249932;22038.88980555087;22063.558728825334;6752;7821.423078258808;8148.592570371577;8225.465344752572;8311.33509319253;4205.201011807747;4286.121345124305;4412.933805992912;4452.1763473757455;4574.954250457519;3491.1621210909198;3561.6972557332397;3612.8916132516065;3620.9818950905606;3626.8730594429067;633.7465013994347;646.1277275677892;649.5583003557626;654.7839213059679;663.4857985061519;568.7980766827717;608.6804427675506;636.5416899832113;661.2593894837812;683.6957553888471;9057.592408341641;9186.448813071238;9213.447193168297;9301.627934882665;9459.810803783887;473.17903437535847;550.2708910693348;576.7952376906222;578.8552861784553;670.5205777868847;4235.02594403289;4469.396631454712;4498.347739577743;4508.706934049276;4536.61438777701;101056.49471752594;156187;159409;160257;165942.3405765951;98560.50719746355;133986.33006834902;135316.40886684667;137460;140593;96623.40519137608;116870;117234.41382793032;120942;121193.39403302927;7583.696652133853;8719.91280087191;8736.344774142091;8747.868781968149;8789.472631642122;60470;60685.69657151686;62891.685541572;63646.36353636404;64541;91079.54460227922;91111.54444227736;94691.05308946823;94857.52571237375;98151;9427.481488518197;11220.270682405617;11328.150388720715;11561.248518846249;11630.592929247436;12821.102522823312;12925.030622703523;13098.672533186747;13259.535916242885;13290.335483225606;10887.782244355227;11691.707707307045;12008;12373.93813030929;12644.494220231089;11916.404179791149;12005.024524464801;12059.638210853689;12080.51677932873;12468.812967805308;11795.705107372385;12141.39293035327;12167.756644866877;12485.875141248835;12552;9229.861552076858;9589.664361747306;9683.661071862733;9702.175315098295;9764.267679924176;8980.730578082668;9263.675771347971;10408.219383546113;10414.375137491472;10432.895671043496 20:38:57 INFO - PROCESS | 1651 | __end_tp_report 20:38:57 INFO - PROCESS | 1651 | __start_cc_report 20:38:57 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,1312 20:38:57 INFO - PROCESS | 1651 | __end_cc_report 20:38:57 INFO - PROCESS | 1651 | __startTimestamp1472787537051__endTimestamp 20:38:57 INFO - PROCESS | 1651 | 20:38:57 INFO - PROCESS | 1651 | ------- Summary: start ------- 20:38:57 INFO - PROCESS | 1651 | Number of tests: 6 20:38:57 INFO - PROCESS | 1651 | 20:38:57 INFO - PROCESS | 1651 | [#0] dojo.html Cycles:165 Average:5763.34 Median:5983.43 stddev:640.38 (10.7%) stddev-sans-first:605.30 20:38:57 INFO - PROCESS | 1651 | Values: 3026.9 3332.6 3374.1 3416.7 3444.8 5587.9 5648.5 5668.6 5725.1 5765.0 5653.8 5654.9 5763.4 5787.6 5810.9 5145.0 5180.3 5206.3 5212.9 5273.3 5339.1 5352.7 5356.8 5375.7 5400.0 5160.1 5214.7 5217.1 5252.3 5271.4 5221.3 5259.9 5262.5 5262.5 5270.8 5178.2 5182.1 5261.3 5290.7 5291.5 5613.2 5631.7 5662.7 5713.4 5730.1 6218.1 6260.6 6272.9 6285.9 6286.9 6098.7 6204.8 6273.7 6280.7 6334.2 5817.6 5826.6 5853.6 5860.3 5876.2 6278.1 6303.9 6304.1 6307.3 6313.2 5985.7 6050.2 6080.7 6113.4 6184.9 6267.5 6281.8 6303.8 6308.1 6316.3 6182.9 6219.0 6239.7 6249.6 6250.6 6101.4 6148.1 6185.3 6220.2 6260.7 5001.2 5006.7 5021.0 5050.2 5059.4 4204.4 4470.2 4496.1 4496.8 4542.4 5596.2 5658.9 5659.5 5713.4 5741.6 6287.3 6305.7 6326.8 6328.8 6341.3 5689.6 5713.9 5714.5 5746.2 5773.1 5311.7 5363.1 5413.8 5453.1 5467.5 6236.2 6278.4 6280.0 6327.6 6343.6 6337.8 6348.2 6352.2 6353.0 6361.5 6182.9 6314.9 6315.1 6342.6 6345.3 6080.2 6134.9 6159.0 6188.3 6239.8 6254.8 6270.6 6284.8 6287.9 6292.9 5868.4 6095.5 6123.5 6124.1 6128.3 5360.5 6031.5 6089.2 6149.0 6149.6 5920.8 5986.4 5988.8 5992.0 6042.9 5968.4 6120.2 6168.8 6171.5 6180.6 5870.2 5933.9 5941.8 5983.4 6036.2 20:38:57 INFO - PROCESS | 1651 | 20:38:57 INFO - PROCESS | 1651 | [#1] ext.html Cycles:180 Average:60896.28 Median:167728.26 stddev:131321.08 (78.3%) stddev-sans-first:131611.14 20:38:57 INFO - PROCESS | 1651 | Values: 486.5 489.5 512.2 535.3 535.3 30382.2 39124.8 40402.2 40649.0 42155.0 41641.0 42124.6 42163.6 42189.6 42272.2 9605.8 9788.2 9802.7 9804.8 9808.6 8775.5 8964.0 11841.2 12128.8 12203.8 12075.3 12182.4 12187.7 12189.3 12233.0 12054.6 12133.5 12138.6 12145.4 12163.3 11831.9 11912.8 11914.5 11917.8 11924.0 38724.8 38969.4 39024.4 39087.0 39127.6 3111.9 3278.9 3518.6 3955.6 4010.8 29799.0 30282.9 30548.0 30661.4 31424.4 3145.3 3145.5 3211.7 3283.1 3315.5 326998.0 331445.0 332141.0 332241.0 332705.0 31175.2 54790.7 54974.7 55228.7 55301.7 31882.2 32124.0 32159.0 32201.4 32240.7 14337.7 14466.1 14479.6 14499.6 14514.1 11294.6 11521.9 12356.4 12463.9 12472.0 7391.8 7512.8 7526.3 7573.2 7667.8 3214.8 3263.5 3265.2 3303.2 3306.6 938.9 946.3 969.4 991.7 992.0 28772.6 29622.4 29707.3 30278.5 30413.4 1003.6 1029.2 1096.4 1118.3 1143.8 14589.6 15135.9 15211.9 15514.6 15549.4 602132.0 604961.0 610034.0 612815.0 613568.0 55379.7 55837.0 55865.9 55911.2 55966.2 1224.3 1351.1 1375.6 1398.8 1401.3 29990.1 30372.5 30446.7 30680.8 31282.8 47225.8 47420.8 47533.5 47549.5 47773.3 492341.0 493911.0 494487.0 494583.0 495110.0 25287.8 25803.0 26389.6 26462.9 27239.5 44755.0 44997.3 45018.1 45152.5 46250.8 36987.3 37316.0 37818.1 38457.8 38510.4 37106.4 37323.0 37740.6 38554.8 38782.8 36418.5 36627.8 37150.8 37153.8 37336.1 30846.8 31912.7 33215.2 33641.3 33798.0 24707.5 25273.4 25586.6 25691.6 26006.5 20:38:57 INFO - PROCESS | 1651 | 20:38:57 INFO - PROCESS | 1651 | [#2] jquery.html Cycles:180 Average:33042.61 Median:1321.72 stddev:95921.64 (7257.4%) stddev-sans-first:96159.30 20:38:57 INFO - PROCESS | 1651 | Values: 345.8 368.7 370.7 377.2 400.4 14507.9 15107.9 19976.0 20703.2 20722.1 16593.6 20426.6 20432.4 20514.4 20521.0 4212.3 5608.6 5826.4 6111.8 6117.6 6243.3 6677.6 6760.8 6800.1 6807.4 5777.5 6028.7 6127.9 6729.5 6730.7 6042.3 6044.6 6102.5 6113.3 6127.4 5708.9 5836.0 5881.5 5885.4 5898.3 13100.0 13120.6 13144.8 13339.1 13383.9 1951.6 2145.2 2211.7 2215.1 2297.6 9620.0 9774.5 9833.1 10229.3 10391.3 1687.5 1883.4 1894.6 1917.3 1932.9 88263.6 105763.0 109501.0 111565.0 129529.0 11181.7 11252.9 11276.6 11596.8 11683.8 14471.6 14474.0 14508.9 14525.6 14538.5 8407.4 8735.9 8757.6 8833.7 8889.1 8046.6 8087.0 8204.5 8212.8 8244.6 4064.0 4229.6 4514.2 4538.7 4610.8 1878.9 1907.9 1913.8 1947.0 1955.8 365.9 380.4 396.6 828.8 900.8 9654.8 11607.9 11996.8 12058.9 12156.8 417.2 424.1 431.4 835.0 905.9 10253.4 10567.2 11109.6 11111.4 11158.5 568216.0 569735.0 571254.0 584047.0 585379.0 110112.0 112789.0 113040.0 118567.0 120732.4 987.1 997.5 1013.3 1048.2 1048.7 18446.5 18532.4 18702.7 18739.3 18762.0 41236.0 53588.5 54215.5 54450.0 55767.4 58661.0 82006.6 82047.0 82319.0 82723.0 6292.7 7843.1 7896.1 7955.2 8168.0 10704.8 12812.4 13096.0 13203.6 13341.9 10250.4 11195.0 11215.6 11294.3 11552.3 9611.9 11042.4 11100.3 11265.3 11547.9 10696.4 11398.2 11670.9 11714.2 11818.1 7866.3 7907.4 7929.4 7954.3 8190.1 9021.9 10679.0 10964.7 10981.6 11008.9 20:38:57 INFO - PROCESS | 1651 | 20:38:57 INFO - PROCESS | 1651 | [#3] mootools.html Cycles:180 Average:5694.82 Median:4879.67 stddev:3188.17 (65.3%) stddev-sans-first:3180.32 20:38:57 INFO - PROCESS | 1651 | Values: 1341.2 1496.5 1532.3 1664.8 1731.5 7838.6 8625.8 8854.0 8868.7 8971.8 8763.9 8842.5 8845.8 8849.0 8850.3 2358.0 2387.3 2391.8 2393.3 2393.5 2338.1 2368.7 2382.6 2384.6 2388.6 2339.3 2353.4 2360.6 2377.4 2380.0 2323.0 2355.9 2356.2 2363.9 2370.5 2290.0 2329.3 2331.0 2332.2 2336.9 8546.1 8746.0 8852.7 8854.4 8863.0 5132.8 5328.6 5354.3 5357.1 5365.9 3056.5 3563.5 3577.7 3642.6 3642.7 1076.2 1084.4 1093.8 1095.9 1108.3 11400.0 11421.8 11577.0 11591.0 11594.4 4126.7 4276.5 4607.9 4670.8 4845.4 8577.7 10068.9 10070.4 10081.0 10087.0 5413.3 5415.7 5419.3 5424.9 5451.2 4317.1 4443.4 4449.3 4596.2 4963.2 3916.5 4008.9 4038.1 4040.5 4044.3 1104.6 1281.6 1287.9 1298.6 1303.1 768.8 783.7 823.6 884.9 919.0 5698.0 5791.9 6059.9 6104.5 6118.8 901.7 932.3 953.8 980.6 1019.2 3822.8 3864.7 3884.4 3992.4 4019.0 10431.3 10690.5 10823.9 10827.2 10884.1 10736.4 10739.9 10752.9 10768.2 11046.1 7784.6 7924.7 8018.1 8095.5 8190.3 5851.8 5977.2 6068.4 6080.3 6177.2 9960.4 10089.8 10119.2 10134.3 10136.2 8882.2 9830.6 10089.9 10108.6 10287.1 6081.9 7529.5 7540.0 7602.4 7602.4 8188.3 8193.5 8211.7 8280.5 8280.8 7400.3 7438.6 7522.1 7522.4 7546.8 7063.8 7238.2 7262.5 7312.9 7326.6 6838.9 6880.1 7004.9 7008.1 7019.7 4914.0 5008.3 5023.0 5059.8 5179.5 4976.2 4999.3 5076.5 5226.5 5258.2 20:38:57 INFO - PROCESS | 1651 | 20:38:57 INFO - PROCESS | 1651 | [#4] prototype.html Cycles:180 Average:4235.08 Median:4639.26 stddev:1273.26 (27.4%) stddev-sans-first:1241.46 20:38:57 INFO - PROCESS | 1651 | Values: 264.7 290.5 306.9 308.6 311.3 4364.6 4791.3 5269.6 5321.3 5354.4 4557.5 4778.4 4787.9 5241.8 5326.4 3200.9 3493.9 4066.0 4067.7 4099.6 3894.5 3900.8 4070.4 4091.9 4107.8 3652.7 4072.8 4073.0 4078.3 4101.9 3669.2 4067.6 4081.0 4087.2 4089.5 3404.5 3426.2 3546.9 3567.4 3583.8 5058.1 5169.8 5172.0 5179.5 5183.6 3983.8 4601.5 4635.2 4652.5 4671.6 4949.6 5264.4 5329.6 5507.1 5678.4 3910.6 3927.9 3930.2 3945.3 3947.0 5766.2 5767.6 5777.0 5795.3 5800.7 3510.4 3751.2 3950.6 3954.9 3976.7 4010.8 4015.8 4024.7 4025.2 4049.0 4584.8 4624.5 4643.3 4667.9 4668.3 5186.4 5506.5 5578.6 5593.4 5676.6 1286.4 1339.6 1376.5 1382.3 1385.1 707.6 723.4 752.5 828.5 864.5 3573.4 3819.2 4272.7 4306.4 4320.1 3973.6 4079.0 4408.3 4741.8 4819.7 4004.6 4094.6 4179.2 4232.5 4301.7 2154.7 2251.4 2387.3 2543.4 2544.7 4713.0 4749.9 4780.0 4998.8 5088.4 4116.6 4746.0 4767.0 4799.9 4808.2 4115.0 4595.5 4866.3 4868.8 4934.8 3954.7 4075.9 4681.0 4692.2 4695.2 4184.7 4701.0 4741.1 4754.3 4777.9 4175.5 4631.9 4882.6 4929.5 4951.1 3248.9 3342.7 3675.4 3796.5 3848.4 5068.9 5598.6 5608.8 5661.2 5672.6 5508.9 5511.6 5512.9 5554.4 5572.0 4133.5 4723.8 5097.7 5253.9 5392.8 4732.4 4904.2 5355.5 5396.1 5435.5 4378.2 5235.5 5274.0 5459.9 5461.0 4554.8 5326.0 5392.0 5401.2 5417.4 20:38:57 INFO - PROCESS | 1651 | 20:38:57 INFO - PROCESS | 1651 | [#5] yui.html Cycles:180 Average:24571.10 Median:4260.57 stddev:40129.72 (941.9%) stddev-sans-first:40202.25 20:38:57 INFO - PROCESS | 1651 | Values: 694.9 701.4 721.3 771.2 771.9 14987.5 15762.9 15768.3 15771.8 15785.7 14128.8 14262.1 14290.6 14291.6 14711.8 670.4 695.4 818.2 831.1 846.2 755.3 819.7 833.9 835.4 849.9 899.9 900.2 902.2 903.8 904.2 696.6 717.7 886.7 887.4 888.5 532.7 533.3 568.3 645.7 671.3 12157.5 14653.8 14657.9 14683.5 14722.0 13430.3 13484.8 13519.5 13988.3 14259.8 11527.8 11769.7 11840.7 11869.4 12184.0 2842.8 3799.2 3804.1 3819.4 3869.1 96583.0 100019.0 108488.5 108735.0 111248.2 10929.8 11104.6 11148.7 11172.0 11317.3 21639.6 22007.6 22031.1 22038.9 22063.6 6752.0 7821.4 8148.6 8225.5 8311.3 4205.2 4286.1 4412.9 4452.2 4575.0 3491.2 3561.7 3612.9 3621.0 3626.9 633.7 646.1 649.6 654.8 663.5 568.8 608.7 636.5 661.3 683.7 9057.6 9186.4 9213.4 9301.6 9459.8 473.2 550.3 576.8 578.9 670.5 4235.0 4469.4 4498.3 4508.7 4536.6 101056.5 156187.0 159409.0 160257.0 165942.3 98560.5 133986.3 135316.4 137460.0 140593.0 96623.4 116870.0 117234.4 120942.0 121193.4 7583.7 8719.9 8736.3 8747.9 8789.5 60470.0 60685.7 62891.7 63646.4 64541.0 91079.5 91111.5 94691.1 94857.5 98151.0 9427.5 11220.3 11328.2 11561.2 11630.6 12821.1 12925.0 13098.7 13259.5 13290.3 10887.8 11691.7 12008.0 12373.9 12644.5 11916.4 12005.0 12059.6 12080.5 12468.8 11795.7 12141.4 12167.8 12485.9 12552.0 9229.9 9589.7 9683.7 9702.2 9764.3 8980.7 9263.7 10408.2 10414.4 10432.9 20:38:57 INFO - PROCESS | 1651 | -------- Summary: end -------- 20:38:57 INFO - PROCESS | 1651 | 20:38:57 INFO - TEST-INFO | 1651: exit 0 20:38:57 INFO - TEST-OK | dromaeo_css | took 1117964ms 20:38:57 INFO - TEST-START | kraken 20:38:57 INFO - operating with platform_type : mac_ 20:38:57 INFO - Initialising browser for kraken test... 20:38:57 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/tmpbOwNpS/profile http://localhost:49274/getInfo.html) 20:39:00 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 20:39:00 INFO - PROCESS | 1658 | colorDepth:24 20:39:00 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 20:39:00 INFO - PROCESS | 1658 | __metrics 20:39:00 INFO - PROCESS | 1658 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:39:00 INFO - PROCESS | 1658 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:39:00 INFO - TEST-INFO | 1658: exit 0 20:39:00 INFO - Browser initialized. 20:39:00 INFO - Running cycle 1/1 for kraken test... 20:39:00 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/T/tmpbOwNpS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:39:12 INFO - PROCESS | 1659 | RSS: Main: 234033152 20:39:12 INFO - PROCESS | 1659 | 20:39:15 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection) 20:39:16 INFO - PROCESS | 1659 | RSS: Main: 293019648 20:39:16 INFO - PROCESS | 1659 | 20:39:19 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-dft) 20:39:19 INFO - PROCESS | 1659 | RSS: Main: 288690176 20:39:19 INFO - PROCESS | 1659 | 20:39:23 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-fft) 20:39:23 INFO - PROCESS | 1659 | RSS: Main: 379289600 20:39:23 INFO - PROCESS | 1659 | 20:39:25 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator) 20:39:26 INFO - PROCESS | 1659 | RSS: Main: 280207360 20:39:26 INFO - PROCESS | 1659 | 20:39:28 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:39:29 INFO - PROCESS | 1659 | RSS: Main: 283086848 20:39:29 INFO - PROCESS | 1659 | 20:39:32 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom) 20:39:32 INFO - PROCESS | 1659 | RSS: Main: 324153344 20:39:32 INFO - PROCESS | 1659 | 20:39:35 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate) 20:39:36 INFO - PROCESS | 1659 | RSS: Main: 324689920 20:39:36 INFO - PROCESS | 1659 | 20:39:38 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial) 20:39:39 INFO - PROCESS | 1659 | RSS: Main: 324308992 20:39:39 INFO - PROCESS | 1659 | 20:39:41 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:39:41 INFO - PROCESS | 1659 | RSS: Main: 297074688 20:39:41 INFO - PROCESS | 1659 | 20:39:43 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:39:44 INFO - PROCESS | 1659 | RSS: Main: 304136192 20:39:44 INFO - PROCESS | 1659 | 20:39:46 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:39:47 INFO - PROCESS | 1659 | RSS: Main: 306028544 20:39:47 INFO - PROCESS | 1659 | 20:39:50 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:39:50 INFO - PROCESS | 1659 | RSS: Main: 321314816 20:39:50 INFO - PROCESS | 1659 | 20:39:53 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:39:54 INFO - PROCESS | 1659 | RSS: Main: 317378560 20:39:54 INFO - PROCESS | 1659 | 20:39:56 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49274/tests/kraken/driver.html?testName=ai-astar) 20:39:56 INFO - PROCESS | 1659 | RSS: Main: 361521152 20:39:56 INFO - PROCESS | 1659 | 20:39:56 INFO - PROCESS | 1659 | __start_tp_report 20:39:56 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 20:39:56 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 20:39:56 INFO - PROCESS | 1659 | |i|pagename|runs| 20:39:56 INFO - PROCESS | 1659 | |0;ai-astar;113;121;165;158;113;112;113;113;113;123 20:39:56 INFO - PROCESS | 1659 | |1;audio-beat-detection;148;114;131;161;108;131;129;111;158;107 20:39:56 INFO - PROCESS | 1659 | |2;audio-dft;184;183;181;185;219;186;182;183;185;181 20:39:56 INFO - PROCESS | 1659 | |3;audio-fft;78;74;79;155;58;57;57;61;145;58 20:39:56 INFO - PROCESS | 1659 | |4;audio-oscillator;82;83;137;70;69;80;119;69;69;118 20:39:56 INFO - PROCESS | 1659 | |5;imaging-gaussian-blur;102;130;113;86;88;85;90;86;89;85 20:39:56 INFO - PROCESS | 1659 | |6;imaging-darkroom;93;98;94;94;94;94;94;93;93;93 20:39:56 INFO - PROCESS | 1659 | |7;imaging-desaturate;87;86;82;86;84;85;85;84;83;100 20:39:56 INFO - PROCESS | 1659 | |8;json-parse-financial;53;53;55;54;54;54;54;54;53;54 20:39:56 INFO - PROCESS | 1659 | |9;json-stringify-tinderbox;66;66;74;78;77;73;72;71;73;73 20:39:56 INFO - PROCESS | 1659 | |10;stanford-crypto-aes;96;104;87;87;85;84;86;84;85;90 20:39:56 INFO - PROCESS | 1659 | |11;stanford-crypto-ccm;129;138;139;138;138;150;133;129;193;155 20:39:56 INFO - PROCESS | 1659 | |12;stanford-crypto-pbkdf2;168;169;168;163;170;170;170;169;169;171 20:39:56 INFO - PROCESS | 1659 | |13;stanford-crypto-sha256-iterative;68;67;68;67;67;67;67;67;67;66 20:39:56 INFO - PROCESS | 1659 | __end_tp_report 20:39:56 INFO - PROCESS | 1659 | __start_cc_report 20:39:56 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,2134 20:39:56 INFO - PROCESS | 1659 | __end_cc_report 20:39:56 INFO - PROCESS | 1659 | __startTimestamp1472787596301__endTimestamp 20:39:56 INFO - PROCESS | 1659 | 20:39:56 INFO - PROCESS | 1659 | ------- Summary: start ------- 20:39:56 INFO - PROCESS | 1659 | Number of tests: 14 20:39:56 INFO - PROCESS | 1659 | 20:39:56 INFO - PROCESS | 1659 | [#0] ai-astar Cycles:10 Average:124.40 Median:117.00 stddev:19.98 (17.1%) stddev-sans-first:20.77 20:39:56 INFO - PROCESS | 1659 | Values: 113.0 121.0 165.0 158.0 113.0 112.0 113.0 113.0 113.0 123.0 20:39:56 INFO - PROCESS | 1659 | 20:39:56 INFO - PROCESS | 1659 | [#1] audio-beat-detection Cycles:10 Average:129.80 Median:131.00 stddev:20.28 (15.5%) stddev-sans-first:20.41 20:39:56 INFO - PROCESS | 1659 | Values: 148.0 114.0 131.0 161.0 108.0 131.0 129.0 111.0 158.0 107.0 20:39:56 INFO - PROCESS | 1659 | 20:39:56 INFO - PROCESS | 1659 | [#2] audio-dft Cycles:10 Average:186.90 Median:184.50 stddev:11.41 (6.2%) stddev-sans-first:12.05 20:39:56 INFO - PROCESS | 1659 | Values: 184.0 183.0 181.0 185.0 219.0 186.0 182.0 183.0 185.0 181.0 20:39:56 INFO - PROCESS | 1659 | 20:39:56 INFO - PROCESS | 1659 | [#3] audio-fft Cycles:10 Average:82.20 Median:59.50 stddev:36.86 (62.0%) stddev-sans-first:39.07 20:39:56 INFO - PROCESS | 1659 | Values: 78.0 74.0 79.0 155.0 58.0 57.0 57.0 61.0 145.0 58.0 20:39:56 INFO - PROCESS | 1659 | 20:39:56 INFO - PROCESS | 1659 | [#4] audio-oscillator Cycles:10 Average:89.60 Median:69.50 stddev:25.32 (36.4%) stddev-sans-first:26.70 20:39:56 INFO - PROCESS | 1659 | Values: 82.0 83.0 137.0 70.0 69.0 80.0 119.0 69.0 69.0 118.0 20:39:56 INFO - PROCESS | 1659 | 20:39:56 INFO - PROCESS | 1659 | [#5] imaging-gaussian-blur Cycles:10 Average:95.40 Median:86.00 stddev:15.16 (17.6%) stddev-sans-first:15.89 20:39:56 INFO - PROCESS | 1659 | Values: 102.0 130.0 113.0 86.0 88.0 85.0 90.0 86.0 89.0 85.0 20:39:56 INFO - PROCESS | 1659 | 20:39:56 INFO - PROCESS | 1659 | [#6] imaging-darkroom Cycles:10 Average:94.00 Median:94.00 stddev:1.49 (1.6%) stddev-sans-first:1.54 20:39:56 INFO - PROCESS | 1659 | Values: 93.0 98.0 94.0 94.0 94.0 94.0 94.0 93.0 93.0 93.0 20:39:56 INFO - PROCESS | 1659 | 20:39:56 INFO - PROCESS | 1659 | [#7] imaging-desaturate Cycles:10 Average:86.20 Median:85.00 stddev:5.07 (6.0%) stddev-sans-first:5.37 20:39:56 INFO - PROCESS | 1659 | Values: 87.0 86.0 82.0 86.0 84.0 85.0 85.0 84.0 83.0 100.0 20:39:56 INFO - PROCESS | 1659 | 20:39:56 INFO - PROCESS | 1659 | [#8] json-parse-financial Cycles:10 Average:53.80 Median:54.00 stddev:0.63 (1.2%) stddev-sans-first:0.60 20:39:56 INFO - PROCESS | 1659 | Values: 53.0 53.0 55.0 54.0 54.0 54.0 54.0 54.0 53.0 54.0 20:39:56 INFO - PROCESS | 1659 | 20:39:56 INFO - PROCESS | 1659 | [#9] json-stringify-tinderbox Cycles:10 Average:72.30 Median:73.00 stddev:3.95 (5.4%) stddev-sans-first:3.46 20:39:56 INFO - PROCESS | 1659 | Values: 66.0 66.0 74.0 78.0 77.0 73.0 72.0 71.0 73.0 73.0 20:39:56 INFO - PROCESS | 1659 | 20:39:56 INFO - PROCESS | 1659 | [#10] stanford-crypto-aes Cycles:10 Average:88.80 Median:86.50 stddev:6.44 (7.4%) stddev-sans-first:6.28 20:39:56 INFO - PROCESS | 1659 | Values: 96.0 104.0 87.0 87.0 85.0 84.0 86.0 84.0 85.0 90.0 20:39:56 INFO - PROCESS | 1659 | 20:39:56 INFO - PROCESS | 1659 | [#11] stanford-crypto-ccm Cycles:10 Average:144.20 Median:138.50 stddev:19.04 (13.7%) stddev-sans-first:19.38 20:39:56 INFO - PROCESS | 1659 | Values: 129.0 138.0 139.0 138.0 138.0 150.0 133.0 129.0 193.0 155.0 20:39:56 INFO - PROCESS | 1659 | 20:39:56 INFO - PROCESS | 1659 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.70 Median:169.50 stddev:2.21 (1.3%) stddev-sans-first:2.33 20:39:56 INFO - PROCESS | 1659 | Values: 168.0 169.0 168.0 163.0 170.0 170.0 170.0 169.0 169.0 171.0 20:39:56 INFO - PROCESS | 1659 | 20:39:56 INFO - PROCESS | 1659 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.00 stddev:0.57 (0.8%) stddev-sans-first:0.50 20:39:56 INFO - PROCESS | 1659 | Values: 68.0 67.0 68.0 67.0 67.0 67.0 67.0 67.0 67.0 66.0 20:39:56 INFO - PROCESS | 1659 | -------- Summary: end -------- 20:39:56 INFO - PROCESS | 1659 | 20:39:57 INFO - TEST-INFO | 1659: exit 0 20:39:57 INFO - TEST-OK | kraken | took 59428ms 20:39:57 INFO - SUITE-END | took 1177s 20:39:57 INFO - Completed test suite (00:19:37) 20:39:57 INFO - javascript benchmark 20:39:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3026.924326891828, 3332.583427071616, 3374.1395944034284, 3416.7095796857266, 3444.810535420551, 5587.8603034924145, 5648.491635752782, 5668.631538949965, 5725.14122881568, 5765.0, 5653.802116925908, 5654.943450565494, 5763.366029736726, 5787.594868359206, 5810.883782324351, 5145.048166089286, 5180.274761533388, 5206.349206349207, 5212.869678258055, 5273.325275901022, 5339.119045357511, 5352.732363381835, 5356.839294821142, 5375.6774593524515, 5399.974004939068, 5160.071187186305, 5214.687118772886, 5217.113090774577, 5252.343457067866, 5271.42014378418, 5221.2951251580935, 5259.921101183485, 5262.473752624745, 5262.500062494095, 5270.789168433296, 5178.249153872705, 5182.093133701587, 5261.263423120766, 5290.709011004393, 5291.497307755757, 5613.158026296048, 5631.690257035862, 5662.66024038559, 5713.428657134295, 5730.140478928152, 6218.06728990655, 6260.624362538217, 6272.937270627326, 6285.937140628626, 6286.937130628727, 6098.674287528765, 6204.844878878019, 6273.717682704244, 6280.654563998978, 6334.239891213084, 5817.592768506249, 5826.592138550345, 5853.590248682636, 5860.267466566679, 5876.206712093898, 6278.058291256257, 6303.873922521524, 6304.117423560705, 6307.3377295383325, 6313.210848643919, 5985.700714964234, 6050.213472248667, 6080.695965201722, 6113.4154954094565, 6184.8763024739255, 6267.467265282411, 6281.811545653638, 6303.842403939938, 6308.053791931247, 6316.273628532777, 6182.938170618326, 6218.968905155536, 6239.719212635442, 6249.625022498664, 6250.593711408744, 6101.4290607115445, 6148.077788331786, 6185.257769067741, 6220.222472190976, 6260.655663938436, 5001.174806157016, 5006.674566153189, 5021.0, 5050.242463630411, 5059.443461219263, 4204.4324016257415, 4470.217711900469, 4496.123255965055, 4496.797644106023, 4542.409486766699, 5596.244506991586, 5658.943410565924, 5659.547236221092, 5713.428657134336, 5741.569382296261, 6287.276963149205, 6305.653189074553, 6326.841828954221, 6328.778492752732, 6341.3341599132345, 5689.573282003867, 5713.857153571111, 5714.514266287329, 5746.224259724967, 5773.1051686988585, 5311.654742441729, 5363.088274993261, 5413.7834486620095, 5453.100238460689, 5467.480589344006, 6236.220472440854, 6278.4035516625845, 6279.96860015697, 6327.557071004985, 6343.5876668016435, 6337.7781777637565, 6348.206474190726, 6352.237731472253, 6353.0, 6361.522885783492, 6182.907256391248, 6314.905276420765, 6315.147455093687, 6342.587731797418, 6345.333739957423, 6080.2399700037495, 6134.865219626364, 6159.0, 6188.288346840171, 6239.812805615838, 6254.781082662267, 6270.561060725888, 6284.780032698834, 6287.905681414874, 6292.905606415816, 5868.4424979626865, 6095.451409373, 6123.479504242189, 6124.050772130327, 6128.295246046761, 5360.544353729977, 6031.5476339273855, 6089.238845144357, 6149.0161574149715, 6149.569530133027, 5920.763169473173, 5986.371430999857, 5988.790392336247, 5991.970040149771, 6042.8791424170395, 5968.4031596841705, 6120.234970628671, 6168.845778855564, 6171.47542458896, 6180.567360284737, 5870.17817505541, 5933.910991335047, 5941.762329506944, 5983.431574000463, 6036.185115009594], "value": 5721.329813215208, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [486.4648886225153, 489.47137091940687, 512.19073863296, 535.2533216163453, 535.2907397698051, 30382.24044398891, 39124.804375978114, 40402.19195616086, 40648.99999999996, 42155.0, 41640.95897602554, 42124.578754212445, 42163.57836421635, 42189.57810421895, 42272.15455690884, 9605.75985600362, 9788.168005719486, 9802.705918822447, 9804.754881127958, 9808.607655693764, 8775.517346545937, 8963.95518022409, 11841.230320029214, 12128.757424851497, 12203.755924881498, 12075.335856527887, 12182.390880455987, 12187.695307617292, 12189.268643881396, 12233.0, 12054.638360849145, 12133.514659413613, 12138.635840924699, 12145.39273036349, 12163.331016794073, 11831.881681183164, 11912.821307680391, 11914.523419063227, 11917.761644767143, 11923.972924602751, 38724.80637596794, 38969.41545876814, 39024.414633780514, 39087.0, 39127.60872391296, 3111.9222019449467, 3278.885239016623, 3518.5953615334306, 3955.6439920407015, 4010.83956641734, 29799.0, 30282.940097096496, 30548.0, 30661.386772264654, 31424.37151256985, 3145.339478709474, 3145.543896135048, 3211.6627754085716, 3283.064326666912, 3315.5192497087787, 326998.0, 331445.0, 332141.0, 332241.0, 332705.0, 31175.220619484695, 54790.72604636951, 54974.72512637411, 55228.723856381264, 55301.72349138228, 31882.20294492656, 32124.036278911204, 32159.0, 32201.35597288041, 32240.677593224238, 14337.713245735236, 14466.05970611907, 14479.565613031626, 14499.565013049625, 14514.129152250898, 11294.60468883585, 11521.884781152248, 12356.443960021821, 12463.875361246453, 12472.0, 7391.8152046199275, 7512.849743005109, 7526.285002924714, 7573.166951635316, 7667.808304792425, 3214.8071115733133, 3263.510473428957, 3265.151060724229, 3303.157694787814, 3306.6032076151014, 938.9342746007713, 946.2571881073325, 969.4474149734724, 991.682661548312, 991.9553620087113, 28772.568411473843, 29622.407551848843, 29707.257318567208, 30278.54582181269, 30413.391732165233, 1003.6098218890073, 1029.248648486594, 1096.3641088168843, 1118.3066498770813, 1143.8398624192619, 14589.635259118395, 15135.940484166003, 15211.923940380228, 15514.612134696723, 15549.378024879104, 602132.0, 604961.0, 610034.0, 612815.0, 613568.0, 55379.72310138423, 55837.0, 55865.88268234613, 55911.16133258004, 55966.160507592416, 1224.3082658298033, 1351.1217708489364, 1375.5529452927838, 1398.8111604933204, 1401.2993503248376, 29990.100296991124, 30372.544411834282, 30446.695533044385, 30680.84745000735, 31282.843585781924, 47225.763871180425, 47420.7628961853, 47533.52466475291, 47549.52450475451, 47773.283400749715, 492341.0, 493911.0, 494487.0, 494583.0, 495110.0, 25287.839020122483, 25803.0, 26389.60415593806, 26462.898250771163, 27239.455210895274, 44755.0, 44997.32504012377, 45018.0996380064, 45152.54847451615, 46250.768746156056, 36987.26025479529, 37316.0, 37818.0545486365, 38457.807710961264, 38510.42234366543, 37106.44340334951, 37323.0, 37740.62259377371, 38554.80722596481, 38782.764326815515, 36418.4537231947, 36627.81686091659, 37150.814245928595, 37153.81423092867, 37336.06659833526, 30846.845765771028, 31912.68087319097, 33215.169620759676, 33641.327173456884, 33798.0, 24707.505849883262, 25273.43984323446, 25586.61620075663, 25691.614625780254, 26006.47987040211], "value": 18471.86010205278, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [345.7994363269304, 368.68661637608034, 370.74603896330996, 377.2135098320003, 400.43738547340996, 14507.927460362696, 15107.924460377697, 19976.001199940016, 20703.171873125095, 20722.067506962187, 16593.585160370996, 20426.591468170664, 20432.387028389134, 20514.38456846297, 20521.0, 4212.304969679999, 5608.5793565482545, 5826.446487583673, 6111.786087486917, 6117.602355846878, 6243.313235544087, 6677.632730199837, 6760.8309792255095, 6800.149981252343, 6807.387335139836, 5777.508911742507, 6028.728707208186, 6127.9080813787605, 6729.495287853406, 6730.697118629673, 6042.305134909498, 6044.637321760686, 6102.481289090432, 6113.296970848342, 6127.356627554132, 5708.942910570924, 5835.970820145914, 5881.500072493843, 5885.411458854123, 5898.321693005317, 13099.952003839766, 13120.60638180856, 13144.802827957588, 13339.132956357806, 13383.933080334633, 1951.6291904538093, 2145.2062736787334, 2211.7345918489723, 2215.1471683401846, 2297.632378819381, 9620.037996200394, 9774.462404567746, 9833.065858743408, 10229.283950123494, 10391.324563903323, 1687.5443630219772, 1883.3502441657606, 1894.5737209127974, 1917.2810196176433, 1932.9033548322527, 88263.55868220681, 105763.0, 109501.0, 111565.0, 129528.99999999905, 11181.72045698864, 11252.943735281271, 11276.605318813803, 11596.826047609293, 11683.76632467346, 14471.565853024425, 14474.0, 14508.927455362867, 14525.564233072813, 14538.491152809813, 8407.369447291356, 8735.91264087351, 8757.555003424492, 8833.734987950373, 8889.111088891059, 8046.557439340892, 8087.0, 8204.548749818818, 8212.8357432851, 8244.628991695388, 4063.9796801015805, 4229.619334259932, 4514.232580461329, 4538.704984176018, 4610.838620648328, 1878.8684792064423, 1907.9332223372116, 1913.8373238274903, 1946.951326216856, 1955.7946415626438, 365.8536585365875, 380.37048684427316, 396.57764480828405, 828.8135169586915, 900.7928176519438, 9654.75863103428, 11607.883921160848, 11996.820047699292, 12058.939705301593, 12156.756864862653, 417.21771678103556, 424.0860944664253, 431.43697474795067, 834.9754184829065, 905.9139381758722, 10253.436061016715, 10567.207459440573, 11109.555617775199, 11111.38887361203, 11158.497867595815, 568216.0, 569735.0, 571254.0, 584047.0, 585379.0, 110112.0, 112789.0, 113040.0, 118567.0, 120732.3963380195, 987.0918754745651, 997.5261750668375, 1013.3413281367023, 1048.2452634103433, 1048.6539441984128, 18446.538836529195, 18532.444026679223, 18702.719459207852, 18739.34412295563, 18761.968091755083, 41236.0, 53588.46411535835, 54215.45784542104, 54450.0, 55767.44232557622, 58661.0, 82006.58996704979, 82047.0, 82319.0, 82723.0, 6292.685365731787, 7843.098043725045, 7896.131425543301, 7955.24425179607, 8168.018673184364, 10704.785904282027, 12812.359382031047, 13095.95232381388, 13203.603891883258, 13341.86658133445, 10250.436226007343, 11195.04842088399, 11215.55137794479, 11294.265872718248, 11552.306861588331, 9611.894632117395, 11042.447877606248, 11100.278481898651, 11265.324080555192, 11547.94226028898, 10696.411697356409, 11398.202125851443, 11670.94164529172, 11714.1800073994, 11818.054555635357, 7866.292033716993, 7907.406944479302, 7929.405294603043, 7954.284114429498, 8190.099089100313, 9021.864672029793, 10678.985496377833, 10964.725881853017, 10981.560737570728, 11008.944955275172], "value": 8910.226124661634, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1341.2228796844183, 1496.5211132437623, 1532.341093329868, 1664.8179792347432, 1731.4545918035817, 7838.608069596526, 8625.78435539111, 8854.026057133718, 8868.733937981871, 8971.775705607362, 8763.91236087639, 8842.5136617486, 8845.823083538326, 8849.026607073232, 8850.291976641869, 2358.021421110237, 2387.259949415678, 2391.820613453989, 2393.2939782764124, 2393.533261014103, 2338.0764597983784, 2368.6683864259016, 2382.630692242705, 2384.6065399209197, 2388.558116748407, 2339.263132113387, 2353.376355265856, 2360.6459031145296, 2377.3581133094053, 2379.95240095198, 2323.0, 2355.858648481088, 2356.1988923765916, 2363.8818059096975, 2370.4666450048603, 2290.0191334495707, 2329.301209637102, 2331.009321038569, 2332.2303639798997, 2336.883155842201, 8546.05993340732, 8745.956270218672, 8852.734417967407, 8854.424462409987, 8863.025067242597, 5132.8460146195675, 5328.600354973392, 5354.250404943311, 5357.116075847481, 5365.892682146375, 3056.541518772202, 3563.501109844624, 3577.678008979204, 3642.562892452896, 3642.690371318441, 1076.160594736107, 1084.3656460970342, 1093.7703082352716, 1095.8849320821278, 1108.2906939558688, 11400.0, 11421.771564568662, 11576.999999999916, 11591.0, 11594.420278986017, 4126.711130220856, 4276.465441819772, 4607.861764147081, 4670.813167473263, 4845.394325709286, 8577.742667719978, 10068.949655251678, 10070.395776253448, 10081.0, 10087.0, 5413.2692086568595, 5415.729213539307, 5419.3225846769155, 5424.918626220609, 5451.209574611659, 4317.050248945227, 4443.4445694288215, 4449.310356894686, 4596.195665758546, 4963.156263435225, 3916.5300163979937, 4008.8797336079965, 4038.071243613985, 4040.5151381834366, 4044.2720310343834, 1104.5968221599219, 1281.5514569900647, 1287.8969682425384, 1298.5974347952165, 1303.0683061610994, 768.8347005393867, 783.6669415498391, 823.6005537314397, 884.9159329863653, 918.9678361257323, 5697.971510142423, 5791.913121303184, 6059.939400605937, 6104.481119104926, 6118.755249790048, 901.6753968571309, 932.2728271947891, 953.8378475659155, 980.5832521178471, 1019.169376957777, 3822.847086116524, 3864.6715029222833, 3884.3590807516443, 3992.3612222044912, 4018.9952511872034, 10431.321964072311, 10690.465476726133, 10823.945880270549, 10827.187960902964, 10884.07485363753, 10736.409497477714, 10739.892601074045, 10752.892471075345, 10768.192385570957, 11046.116310695124, 7784.610769461504, 7924.683012679428, 8018.077921039155, 8095.514269143752, 8190.262876341158, 5851.824445266649, 5977.22296101504, 6068.423499767515, 6080.331163572092, 6177.227846519185, 9960.352577082467, 10089.848652270368, 10119.241056920908, 10134.290599658249, 10136.239782016526, 8882.15619516145, 9830.606775728891, 10089.949550252495, 10108.64619738335, 10287.125594324267, 6081.908771368521, 7529.510581812165, 7540.0, 7602.3537999268465, 7602.353799927068, 8188.2630563249595, 8193.50838949641, 8211.712590059558, 8280.544570048705, 8280.792980175544, 7400.333969942731, 7438.628068596657, 7522.059742532184, 7522.398208143226, 7546.849063018599, 7063.788086357417, 7238.167610724834, 7262.527935684164, 7312.926870731225, 7326.560406375635, 6838.897416538855, 6880.071190389233, 7004.859902802017, 7008.088948436671, 7019.684114214975, 4913.950860491349, 5008.348914641073, 5023.0, 5059.848204553869, 5179.533841954242, 4976.2038073908, 4999.250112483011, 5076.517730815566, 5226.45122262157, 5258.211268309631], "value": 4573.097835850128, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [264.68502482046364, 290.49308955872004, 306.9355435358575, 308.5972805488836, 311.3337457840221, 4364.581511008919, 4791.329213910055, 5269.578433725303, 5321.308229930109, 5354.411014788375, 4557.498675145731, 4778.447004723462, 4787.941185290324, 5241.764120614572, 5326.387465441476, 3200.9199770005703, 3493.9301213975705, 4065.9796701016453, 4067.694922880781, 4099.569545197749, 3894.4742459767854, 3900.8049597520153, 4070.3894415837567, 4091.9386209206887, 4107.835686572534, 3652.6712595866366, 4072.7963601819793, 4072.9613948443107, 4078.3270760324513, 4101.856435024774, 3669.1744357519347, 4067.59667914569, 4081.0, 4087.182563487285, 4089.4683691120113, 3404.472306792451, 3426.1777173478185, 3546.8935931922083, 3567.4292113261754, 3583.7994271918938, 5058.114829904789, 5169.767360468751, 5171.982926658606, 5179.456157103526, 5183.559397451221, 3983.8008099594904, 4601.493835678073, 4635.235186194308, 4652.511196417148, 4671.556202160789, 4949.579285760715, 5264.394594621591, 5329.573634109262, 5507.118860982264, 5678.43215678433, 3910.5726409860195, 3927.9018024549327, 3930.2117536520814, 3945.2640838031175, 3946.980265098685, 5766.223123853841, 5767.61577221468, 5776.956130708479, 5795.3335366432975, 5800.665846855247, 3510.350585141757, 3751.2309976454762, 3950.6246906543834, 3954.940675889864, 3976.721629485966, 4010.8195131219163, 4015.8393664253686, 4024.7182697210915, 4025.2352053109817, 4048.959510404858, 4584.7992963377765, 4624.491305956342, 4643.2802915548145, 4667.929981050287, 4668.29607193372, 5186.429492755794, 5506.476884695948, 5578.581606379537, 5593.440655934455, 5676.602637815313, 1286.4339690536135, 1339.6382976596265, 1376.4769387632637, 1382.3088455772113, 1385.058160450903, 707.6284950401081, 723.3851226457574, 752.5221484357363, 828.4863384701524, 864.5072308783933, 3573.4282514797505, 3819.2189732164957, 4272.718184544624, 4306.440162778881, 4320.135972805451, 3973.589375771617, 4078.979605102015, 4408.294672852328, 4741.83403580873, 4819.7108173509705, 4004.6395824375945, 4094.61101195386, 4179.176053910638, 4232.513260974965, 4301.677374196947, 2154.666026765854, 2251.414632195641, 2387.283814855571, 2543.4357869909873, 2544.7073586537413, 4713.0, 4749.881252968635, 4779.996200797802, 4998.825276060123, 5088.363954505687, 4116.58834116592, 4745.982957243158, 4767.046590681877, 4799.904001919942, 4808.182608956486, 4115.032967252693, 4595.540445955445, 4866.323522062851, 4868.805247790048, 4934.753262336869, 3954.7033972451604, 4075.91848163041, 4681.0, 4692.1554120257315, 4695.2487601984885, 4184.707070505157, 4700.9657875267385, 4741.146593613044, 4754.312219162286, 4777.880552986203, 4175.475951277823, 4631.86519302773, 4882.557796753352, 4929.531694489017, 4951.084049450863, 3248.9350212995137, 3342.665733426735, 3675.3751862182958, 3796.4684944107294, 3848.4034974578985, 5068.873278168076, 5598.636088654224, 5608.766071464272, 5661.150827375926, 5672.602917795714, 5508.944910550843, 5511.586631002771, 5512.91730624033, 5554.416786237387, 5571.9721401392735, 4133.545310015836, 4723.834665786681, 5097.6941383517105, 5253.896681696887, 5392.811251606175, 4732.414641095272, 4904.190808516555, 5355.518003379714, 5396.109641909041, 5435.456454354533, 4378.2119218540965, 5235.528802407802, 5273.973630131824, 5459.918101228564, 5461.0, 4554.817807287672, 5325.97337013328, 5391.973040134774, 5401.2168235606405, 5417.43116972728], "value": 3830.090220624302, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [694.930506949305, 701.3757755597519, 721.3291638775936, 771.1748429180778, 771.8610650082983, 14987.47543835966, 15762.921185394085, 15768.290426930787, 15771.842281577181, 15785.684286314281, 14128.788068178985, 14262.072965257277, 14290.57128286153, 14291.571252862404, 14711.779323310158, 670.4334837062675, 695.3984803145269, 818.2186012358213, 831.1439217605875, 846.196113691992, 755.3353049316621, 819.7335865843595, 833.9291160251386, 835.3734698975768, 849.855524560823, 899.851524498457, 900.18083543975, 902.1970446302763, 903.8011637439786, 904.1636486250225, 696.613379574336, 717.7057406463377, 886.7206829848577, 887.4497811357, 888.5468411110315, 532.7070111438693, 533.2747463449705, 568.3123420661005, 645.6965226343611, 671.3487916720773, 12157.452914618863, 14653.780193297003, 14657.853421465756, 14683.486077987325, 14722.0, 13430.32848357588, 13484.772118230047, 13519.459221631123, 13988.300584970711, 14259.78610320846, 11527.827082593767, 11769.705757356049, 11840.70398240051, 11869.406529673568, 12184.0, 2842.829430234172, 3799.202167544819, 3804.087019115392, 3819.4079917612817, 3869.071422858522, 96583.0, 100019.0, 108488.45755771249, 108735.0, 111248.20645625776, 10929.781404371868, 11104.555817767361, 11148.721281967853, 11172.0, 11317.320960742218, 21639.56720865574, 22007.559848802935, 22031.118755249932, 22038.88980555087, 22063.558728825334, 6752.0, 7821.423078258808, 8148.592570371577, 8225.465344752572, 8311.33509319253, 4205.201011807747, 4286.121345124305, 4412.933805992912, 4452.1763473757455, 4574.954250457519, 3491.1621210909198, 3561.6972557332397, 3612.8916132516065, 3620.9818950905606, 3626.8730594429067, 633.7465013994347, 646.1277275677892, 649.5583003557626, 654.7839213059679, 663.4857985061519, 568.7980766827717, 608.6804427675506, 636.5416899832113, 661.2593894837812, 683.6957553888471, 9057.592408341641, 9186.448813071238, 9213.447193168297, 9301.627934882665, 9459.810803783887, 473.17903437535847, 550.2708910693348, 576.7952376906222, 578.8552861784553, 670.5205777868847, 4235.02594403289, 4469.396631454712, 4498.347739577743, 4508.706934049276, 4536.61438777701, 101056.49471752594, 156187.0, 159409.0, 160257.0, 165942.3405765951, 98560.50719746355, 133986.33006834902, 135316.40886684667, 137460.0, 140593.0, 96623.40519137608, 116870.0, 117234.41382793032, 120942.0, 121193.39403302927, 7583.696652133853, 8719.91280087191, 8736.344774142091, 8747.868781968149, 8789.472631642122, 60470.0, 60685.69657151686, 62891.685541572, 63646.36353636404, 64541.0, 91079.54460227922, 91111.54444227736, 94691.05308946823, 94857.52571237375, 98151.0, 9427.481488518197, 11220.270682405617, 11328.150388720715, 11561.248518846249, 11630.592929247436, 12821.102522823312, 12925.030622703523, 13098.672533186747, 13259.535916242885, 13290.335483225606, 10887.782244355227, 11691.707707307045, 12008.0, 12373.93813030929, 12644.494220231089, 11916.404179791149, 12005.024524464801, 12059.638210853689, 12080.51677932873, 12468.812967805308, 11795.705107372385, 12141.39293035327, 12167.756644866877, 12485.875141248835, 12552.0, 9229.861552076858, 9589.664361747306, 9683.661071862733, 9702.175315098295, 9764.267679924176, 8980.730578082668, 9263.675771347971, 10408.219383546113, 10414.375137491472, 10432.895671043496], "value": 7493.4369704917735, "unit": "score"}], "name": "dromaeo_css", "value": 7057.870619381213, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 121.0, 165.0, 158.0, 113.0, 112.0, 113.0, 113.0, 113.0, 123.0], "value": 124.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 114.0, 131.0, 161.0, 108.0, 131.0, 129.0, 111.0, 158.0, 107.0], "value": 129.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 181.0, 185.0, 219.0, 186.0, 182.0, 183.0, 185.0, 181.0], "value": 186.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 74.0, 79.0, 155.0, 58.0, 57.0, 57.0, 61.0, 145.0, 58.0], "value": 82.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 137.0, 70.0, 69.0, 80.0, 119.0, 69.0, 69.0, 118.0], "value": 89.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 130.0, 113.0, 86.0, 88.0, 85.0, 90.0, 86.0, 89.0, 85.0], "value": 95.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 98.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0, 93.0, 93.0], "value": 94.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 82.0, 86.0, 84.0, 85.0, 85.0, 84.0, 83.0, 100.0], "value": 86.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 55.0, 54.0, 54.0, 54.0, 54.0, 54.0, 53.0, 54.0], "value": 53.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 74.0, 78.0, 77.0, 73.0, 72.0, 71.0, 73.0, 73.0], "value": 72.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 104.0, 87.0, 87.0, 85.0, 84.0, 86.0, 84.0, 85.0, 90.0], "value": 88.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 138.0, 139.0, 138.0, 138.0, 150.0, 133.0, 129.0, 193.0, 155.0], "value": 144.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 169.0, 168.0, 163.0, 170.0, 170.0, 170.0, 169.0, 169.0, 171.0], "value": 168.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 67.0, 67.0, 67.0, 67.0, 67.0, 67.0, 66.0], "value": 67.1, "unit": "score"}], "name": "kraken", "value": 1483.4, "alertThreshold": 2.0}]} 20:39:57 INFO - Return code: 0 20:39:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:39:57 INFO - # TBPL SUCCESS # 20:39:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:39:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:39:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:39:57 INFO - 2016-09-01 20:39:57.629 screenresolution[1663:9796] starting screenresolution argv=screenresolution get 20:39:57 INFO - 2016-09-01 20:39:57.631 screenresolution[1663:9796] Display 0: 1600x1200x32@60 20:39:57 INFO - 2016-09-01 20:39:57.637 screenresolution[1664:9799] starting screenresolution argv=screenresolution list 20:39:57 INFO - Available Modes on Display 0 20:39:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:39:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:39:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:39:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:39:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:39:57 INFO - Intel Iris: 20:39:57 INFO - Chipset Model: Intel Iris 20:39:57 INFO - Type: GPU 20:39:57 INFO - Bus: Built-In 20:39:57 INFO - VRAM (Dynamic, Max): 1536 MB 20:39:57 INFO - Vendor: Intel (0x8086) 20:39:57 INFO - Device ID: 0x0a2e 20:39:57 INFO - Revision ID: 0x0009 20:39:57 INFO - Displays: 20:39:57 INFO - Display: 20:39:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:39:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:39:57 INFO - Display Serial Number: 1600x1200 60 20:39:57 INFO - Main Display: Yes 20:39:57 INFO - Mirror: Off 20:39:57 INFO - Online: Yes 20:39:57 INFO - Rotation: Supported 20:39:57 INFO - Return code: 0 20:39:57 INFO - Running post-action listener: _resource_record_post_action 20:39:57 INFO - [mozharness: 2016-09-02 03:39:57.874701Z] Finished run-tests step (success) 20:39:57 INFO - Running post-run listener: _resource_record_post_run 20:39:57 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 37069824; Write bytes: 705509888; Read time: 246; Write time: 1935 20:39:57 INFO - TinderboxPrint: CPU usage
53.5% 20:39:57 INFO - TinderboxPrint: I/O read bytes / time
37,069,824 / 246 20:39:57 INFO - TinderboxPrint: I/O write bytes / time
705,509,888 / 1,935 20:39:57 INFO - TinderboxPrint: CPU idle
2,256.2 (46.5%) 20:39:57 INFO - TinderboxPrint: CPU system
967.4 (19.9%) 20:39:57 INFO - TinderboxPrint: CPU user
1,630.0 (33.6%) 20:39:57 INFO - TinderboxPrint: Swap in / out
442,793,984 / 0 20:39:57 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 406204928; Write bytes: 394786304; Read time: 30014; Write time: 778 20:39:57 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 29744128; Write bytes: 293907968; Read time: 183; Write time: 1105 20:39:58 INFO - Running post-run listener: _upload_blobber_files 20:39:58 INFO - Blob upload gear active. 20:39:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:39:58 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 20:39:58 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:39:58 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:39:58 INFO - (blobuploader) - INFO - Open directory for files ... 20:39:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:39:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:39:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:40:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:40:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:40:00 INFO - (blobuploader) - INFO - Done attempting. 20:40:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:40:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:40:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:40:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:40:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:40:01 INFO - (blobuploader) - INFO - Done attempting. 20:40:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:40:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:40:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:40:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:40:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:40:04 INFO - (blobuploader) - INFO - Done attempting. 20:40:04 INFO - (blobuploader) - INFO - Iteration through files over. 20:40:04 INFO - Return code: 0 20:40:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:40:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:40:04 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/844216552bda0906ac81522faae73de77dc3455cb812b81029e99cda6dc048d8b2ecee9bf4427540457a28d92774cc4ebf65a0dfdfa52c0e85898118453e00cc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/565c6409852771c93cee00489833039ef63ac616a99faa5c75303c29b5bda2d29edc2862b13a6578b46157b80a438866edc7aeca2e6892a9b0e24be423162332", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9d509772efa2038ab5a2a5fb30fa0931e6787888f5f62182d625a3abe64783c0be80085c0c8b020a78f375308e933273798c65c8d6a05248f203c02f86770a46"} 20:40:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:40:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:40:04 INFO - Contents: 20:40:04 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/844216552bda0906ac81522faae73de77dc3455cb812b81029e99cda6dc048d8b2ecee9bf4427540457a28d92774cc4ebf65a0dfdfa52c0e85898118453e00cc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/565c6409852771c93cee00489833039ef63ac616a99faa5c75303c29b5bda2d29edc2862b13a6578b46157b80a438866edc7aeca2e6892a9b0e24be423162332", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9d509772efa2038ab5a2a5fb30fa0931e6787888f5f62182d625a3abe64783c0be80085c0c8b020a78f375308e933273798c65c8d6a05248f203c02f86770a46"} 20:40:04 INFO - Running post-run listener: copy_logs_to_upload_dir 20:40:04 INFO - Copying logs to upload dir... 20:40:04 INFO - mkdir: /builds/slave/test/build/upload/logs 20:40:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.437783 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-01 20:40:04.361058) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 20:40:04.366421) ========= 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.jGMxSBs6Zi/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.b3Nn9wwfYJ/Listeners TMPDIR=/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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/Autoland/sha512/844216552bda0906ac81522faae73de77dc3455cb812b81029e99cda6dc048d8b2ecee9bf4427540457a28d92774cc4ebf65a0dfdfa52c0e85898118453e00cc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/565c6409852771c93cee00489833039ef63ac616a99faa5c75303c29b5bda2d29edc2862b13a6578b46157b80a438866edc7aeca2e6892a9b0e24be423162332", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9d509772efa2038ab5a2a5fb30fa0931e6787888f5f62182d625a3abe64783c0be80085c0c8b020a78f375308e933273798c65c8d6a05248f203c02f86770a46"} build_url:https://queue.taskcluster.net/v1/task/d-fNWJ9XRVSurVLPiYbNuw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011114 build_url: 'https://queue.taskcluster.net/v1/task/d-fNWJ9XRVSurVLPiYbNuw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/844216552bda0906ac81522faae73de77dc3455cb812b81029e99cda6dc048d8b2ecee9bf4427540457a28d92774cc4ebf65a0dfdfa52c0e85898118453e00cc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/565c6409852771c93cee00489833039ef63ac616a99faa5c75303c29b5bda2d29edc2862b13a6578b46157b80a438866edc7aeca2e6892a9b0e24be423162332", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9d509772efa2038ab5a2a5fb30fa0931e6787888f5f62182d625a3abe64783c0be80085c0c8b020a78f375308e933273798c65c8d6a05248f203c02f86770a46"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 20:40:04.399967) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 20:40:04.400340) ========= 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.jGMxSBs6Zi/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.b3Nn9wwfYJ/Listeners TMPDIR=/var/folders/9g/44pf50cs62vfzf8l355hkc3800000w/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.005081 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 20:40:04.448594) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 20:40:04.448947) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 20:40:04.449245) ========= ========= Total master_lag: 0.17 =========