builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0164 starttime: 1470945001.12 results: success (0) buildid: 20160811110430 builduid: ecfa7cbdb8a7470da12f4b0f609b0cc7 revision: 1c7f1b07be44709c0d17eb380b56db32bfa78cf6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-11 12:50:01.123924) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-11 12:50:01.124429) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-11 12:50:01.124716) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-11 12:50:01.150002) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 12:50:01.150424) ========= 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.By26prBcOC/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.WJuqLnJhHy/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-08-11 12:50:01-- 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% 49.1M=0s 2016-08-11 12:50:01 (49.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.102842 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 12:50:01.275891) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 12:50:01.276223) ========= 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.By26prBcOC/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.WJuqLnJhHy/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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.029396 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 12:50:01.327293) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-11 12:50:01.327702) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 1c7f1b07be44709c0d17eb380b56db32bfa78cf6 --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 1c7f1b07be44709c0d17eb380b56db32bfa78cf6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.By26prBcOC/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.WJuqLnJhHy/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-08-11 12:50:01,404 truncating revision to first 12 chars 2016-08-11 12:50:01,404 Setting DEBUG logging. 2016-08-11 12:50:01,404 attempt 1/10 2016-08-11 12:50:01,404 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/1c7f1b07be44?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-11 12:50:02,145 unpacking tar archive at: autoland-1c7f1b07be44/testing/mozharness/ program finished with exit code 0 elapsedTime=1.015466 ========= master_lag: 0.34 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-11 12:50:02.687806) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-11 12:50:02.688185) ========= script_repo_revision: 1c7f1b07be44709c0d17eb380b56db32bfa78cf6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-11 12:50:02.688649) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-11 12:50:02.688927) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-11 12:50:02.708218) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-08-11 12:50:02.708644) ========= /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.By26prBcOC/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.WJuqLnJhHy/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 12:50:02 INFO - MultiFileLogger online at 20160811 12:50:02 in /builds/slave/test 12:50:02 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 12:50:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:50:02 INFO - {'append_to_log': False, 12:50:02 INFO - 'base_work_dir': '/builds/slave/test', 12:50:02 INFO - 'blob_upload_branch': 'Autoland', 12:50:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:50:02 INFO - 'branch': 'Autoland', 12:50:02 INFO - 'buildbot_json_path': 'buildprops.json', 12:50:02 INFO - 'config_files': ('talos/mac_config.py',), 12:50:02 INFO - 'default_actions': ('clobber', 12:50:02 INFO - 'read-buildbot-config', 12:50:02 INFO - 'download-and-extract', 12:50:02 INFO - 'populate-webroot', 12:50:02 INFO - 'create-virtualenv', 12:50:02 INFO - 'install', 12:50:02 INFO - 'run-tests'), 12:50:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:50:02 INFO - 'download_minidump_stackwalk': True, 12:50:02 INFO - 'download_symbols': 'ondemand', 12:50:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:50:02 INFO - 'tooltool.py': '/tools/tooltool.py', 12:50:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:50:02 INFO - '/tools/misc-python/virtualenv.py')}, 12:50:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:50:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:50:02 INFO - 'installer_path': 'installer.exe', 12:50:02 INFO - 'log_level': 'info', 12:50:02 INFO - 'log_name': 'talos', 12:50:02 INFO - 'log_to_console': True, 12:50:02 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:50:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:50:02 INFO - 'opt_config_files': (), 12:50:02 INFO - 'pip_index': False, 12:50:02 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:50:02 INFO - 'cmd': ('bash', 12:50:02 INFO - '-c', 12:50:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:50:02 INFO - 'enabled': True, 12:50:02 INFO - 'halt_on_failure': False, 12:50:02 INFO - 'name': 'check_screen_resolution'},), 12:50:02 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:50:02 INFO - 'cmd': ('bash', 12:50:02 INFO - '-c', 12:50:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:50:02 INFO - 'enabled': True, 12:50:02 INFO - 'halt_on_failure': False, 12:50:02 INFO - 'name': 'check_screen_resolution'},), 12:50:02 INFO - 'run_cmd_checks_enabled': True, 12:50:02 INFO - 'sps_profile': False, 12:50:02 INFO - 'sps_profile_interval': 0, 12:50:02 INFO - 'suite': 'dromaeojs', 12:50:02 INFO - 'system_bits': '32', 12:50:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:50:02 INFO - 'title': 't-yosemite-r7-0164', 12:50:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:50:02 INFO - 'use_talos_json': True, 12:50:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:50:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:50:02 INFO - 'work_dir': 'build'} 12:50:02 INFO - [mozharness: 2016-08-11 19:50:02.921693Z] Running clobber step. 12:50:02 INFO - Running pre-action listener: _resource_record_pre_action 12:50:02 INFO - Running main action method: clobber 12:50:02 INFO - rmtree: /builds/slave/test/build 12:50:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:50:05 INFO - Running post-action listener: _resource_record_post_action 12:50:05 INFO - [mozharness: 2016-08-11 19:50:05.123027Z] Finished clobber step (success) 12:50:05 INFO - [mozharness: 2016-08-11 19:50:05.123156Z] Running read-buildbot-config step. 12:50:05 INFO - Running pre-action listener: _resource_record_pre_action 12:50:05 INFO - Running main action method: read_buildbot_config 12:50:05 INFO - Using buildbot properties: 12:50:05 INFO - { 12:50:05 INFO - "project": "", 12:50:05 INFO - "product": "firefox", 12:50:05 INFO - "script_repo_revision": "production", 12:50:05 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 12:50:05 INFO - "repository": "", 12:50:05 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 12:50:05 INFO - "buildid": "20160811110430", 12:50:05 INFO - "pgo_build": "False", 12:50:05 INFO - "basedir": "/builds/slave/test", 12:50:05 INFO - "buildnumber": 160, 12:50:05 INFO - "slavename": "t-yosemite-r7-0164", 12:50:05 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 12:50:05 INFO - "platform": "yosemite_r7", 12:50:05 INFO - "scheduler": "tests-autoland-macosx64-talos", 12:50:05 INFO - "branch": "autoland", 12:50:05 INFO - "repo_path": "integration/autoland", 12:50:05 INFO - "revision": "1c7f1b07be44709c0d17eb380b56db32bfa78cf6", 12:50:05 INFO - "stage_platform": "macosx64", 12:50:05 INFO - "builduid": "ecfa7cbdb8a7470da12f4b0f609b0cc7", 12:50:05 INFO - "slavebuilddir": "test" 12:50:05 INFO - } 12:50:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/UMexgxeNQiu-ekcAEOXQ2Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 12:50:05 INFO - Running post-action listener: _resource_record_post_action 12:50:05 INFO - [mozharness: 2016-08-11 19:50:05.126457Z] Finished read-buildbot-config step (success) 12:50:05 INFO - [mozharness: 2016-08-11 19:50:05.126600Z] Running download-and-extract step. 12:50:05 INFO - Running pre-action listener: _resource_record_pre_action 12:50:05 INFO - Running main action method: download_and_extract 12:50:05 INFO - mkdir: /builds/slave/test/build/tests 12:50:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:50:05 INFO - trying https://queue.taskcluster.net/v1/task/UMexgxeNQiu-ekcAEOXQ2Q/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 12:50:05 INFO - Downloading https://queue.taskcluster.net/v1/task/UMexgxeNQiu-ekcAEOXQ2Q/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 12:50:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UMexgxeNQiu-ekcAEOXQ2Q/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 12:50:05 INFO - Downloaded 1199 bytes. 12:50:05 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 12:50:05 INFO - Using the following test package requirements: 12:50:05 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 12:50:05 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:50:05 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 12:50:05 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:50:05 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 12:50:05 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:50:05 INFO - u'jsshell-mac.zip'], 12:50:05 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:50:05 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 12:50:05 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 12:50:05 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:50:05 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 12:50:05 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:50:05 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 12:50:05 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:50:05 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 12:50:05 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 12:50:05 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 12:50:05 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:50:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:50:05 INFO - trying https://queue.taskcluster.net/v1/task/UMexgxeNQiu-ekcAEOXQ2Q/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 12:50:05 INFO - Downloading https://queue.taskcluster.net/v1/task/UMexgxeNQiu-ekcAEOXQ2Q/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 12:50:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UMexgxeNQiu-ekcAEOXQ2Q/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 12:50:06 INFO - Downloaded 16328241 bytes. 12:50:06 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 12:50:13 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 12:50:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:50:13 INFO - trying https://queue.taskcluster.net/v1/task/UMexgxeNQiu-ekcAEOXQ2Q/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 12:50:13 INFO - Downloading https://queue.taskcluster.net/v1/task/UMexgxeNQiu-ekcAEOXQ2Q/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 12:50:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UMexgxeNQiu-ekcAEOXQ2Q/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 12:50:14 INFO - Downloaded 16328241 bytes. 12:50:14 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 12:50: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')]} 12:50:21 INFO - trying https://queue.taskcluster.net/v1/task/UMexgxeNQiu-ekcAEOXQ2Q/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 12:50:21 INFO - Downloading https://queue.taskcluster.net/v1/task/UMexgxeNQiu-ekcAEOXQ2Q/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 12:50:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UMexgxeNQiu-ekcAEOXQ2Q/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 12:50:22 INFO - Downloaded 14413719 bytes. 12:50:22 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 12:50:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:50:22 INFO - trying https://queue.taskcluster.net/v1/task/UMexgxeNQiu-ekcAEOXQ2Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 12:50:22 INFO - Downloading https://queue.taskcluster.net/v1/task/UMexgxeNQiu-ekcAEOXQ2Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 12:50:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UMexgxeNQiu-ekcAEOXQ2Q/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 12:50:28 INFO - Downloaded 110917407 bytes. 12:50:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UMexgxeNQiu-ekcAEOXQ2Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 12:50:28 INFO - mkdir: /builds/slave/test/properties 12:50:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:50:28 INFO - Writing to file /builds/slave/test/properties/build_url 12:50:28 INFO - Contents: 12:50:28 INFO - build_url:https://queue.taskcluster.net/v1/task/UMexgxeNQiu-ekcAEOXQ2Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 12:50:28 INFO - Running post-action listener: _resource_record_post_action 12:50:28 INFO - Running post-action listener: set_extra_try_arguments 12:50:28 INFO - [mozharness: 2016-08-11 19:50:28.861925Z] Finished download-and-extract step (success) 12:50:28 INFO - [mozharness: 2016-08-11 19:50:28.862062Z] Running populate-webroot step. 12:50:28 INFO - Running pre-action listener: _resource_record_pre_action 12:50:28 INFO - Running main action method: populate_webroot 12:50:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:50:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:50:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:50:28 INFO - '--tppagecycles', 12:50:28 INFO - '7'], 12:50:28 INFO - 'tests': ['tsvgm']}}, 12:50:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:50:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:50:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:50:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:50:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:50:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:50:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:50:28 INFO - 'talos_options': ['--disable-e10s'], 12:50:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:50:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:50:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:50:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:50:28 INFO - 'talos_options': ['--disable-e10s'], 12:50:28 INFO - 'tests': ['damp', 'tps']}, 12:50:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:50:28 INFO - 'tests': ['damp', 'tps']}, 12:50:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:50:28 INFO - 'tests': ['dromaeo_dom']}, 12:50:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:50:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:50:28 INFO - 'tests': ['basic_compositor_video']}, 12:50:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:50:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:50:28 INFO - 'tests': ['a11yr', 12:50:28 INFO - 'ts_paint', 12:50:28 INFO - 'tpaint', 12:50:28 INFO - 'sessionrestore', 12:50:28 INFO - 'sessionrestore_no_auto_restore', 12:50:28 INFO - 'tabpaint']}, 12:50:28 INFO - 'other-e10s': {'tests': ['a11yr', 12:50:28 INFO - 'ts_paint', 12:50:28 INFO - 'tpaint', 12:50:28 INFO - 'sessionrestore', 12:50:28 INFO - 'sessionrestore_no_auto_restore', 12:50:28 INFO - 'tabpaint']}, 12:50:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:50:28 INFO - 'ts_paint', 12:50:28 INFO - 'tpaint', 12:50:28 INFO - 'sessionrestore', 12:50:28 INFO - 'sessionrestore_no_auto_restore', 12:50:28 INFO - 'tabpaint']}, 12:50:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:50:28 INFO - 'ts_paint', 12:50:28 INFO - 'tpaint', 12:50:28 INFO - 'sessionrestore', 12:50:28 INFO - 'sessionrestore_no_auto_restore', 12:50:28 INFO - 'tabpaint']}, 12:50:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:50:28 INFO - 'tests': ['a11yr', 12:50:28 INFO - 'ts_paint', 12:50:28 INFO - 'tpaint', 12:50:28 INFO - 'sessionrestore', 12:50:28 INFO - 'sessionrestore_no_auto_restore', 12:50:28 INFO - 'tabpaint']}, 12:50:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:50:28 INFO - 'tests': ['a11yr', 12:50:28 INFO - 'ts_paint', 12:50:28 INFO - 'tpaint', 12:50:28 INFO - 'sessionrestore', 12:50:28 INFO - 'sessionrestore_no_auto_restore', 12:50:28 INFO - 'tabpaint']}, 12:50:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:50:28 INFO - 'tests': ['tsvgx', 12:50:28 INFO - 'tsvgr_opacity', 12:50:28 INFO - 'tart', 12:50:28 INFO - 'tscrollx', 12:50:28 INFO - 'cart']}, 12:50:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:50:28 INFO - 'tsvgr_opacity', 12:50:28 INFO - 'tart', 12:50:28 INFO - 'tscrollx', 12:50:28 INFO - 'cart']}, 12:50:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:50:28 INFO - 'talos_options': ['--disable-e10s'], 12:50:28 INFO - 'tests': ['tp5o']}, 12:50:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:50:28 INFO - 'tests': ['tp5o']}, 12:50:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:50:28 INFO - 'talos_options': ['--disable-e10s', 12:50:28 INFO - '--xperf_path', 12:50:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:50:28 INFO - 'tests': ['tp5n']}, 12:50:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:50:28 INFO - 'talos_options': ['--xperf_path', 12:50:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:50:28 INFO - 'tests': ['tp5n']}}, 12:50:28 INFO - 'talos.zip': {'path': '', 12:50:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:50:28 INFO - Running post-action listener: _resource_record_post_action 12:50:28 INFO - [mozharness: 2016-08-11 19:50:28.880610Z] Finished populate-webroot step (success) 12:50:28 INFO - [mozharness: 2016-08-11 19:50:28.880732Z] Running create-virtualenv step. 12:50:28 INFO - Running pre-action listener: _resource_record_pre_action 12:50:28 INFO - Running main action method: create_virtualenv 12:50:28 INFO - Creating virtualenv /builds/slave/test/build/venv 12:50:28 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:50:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:50:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:50:29 INFO - Using real prefix '/tools/python27' 12:50:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:50:29 INFO - Installing distribute.............................................................................................................................................................................................done. 12:50:32 INFO - Installing pip.................done. 12:50:32 INFO - Return code: 0 12:50:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:50:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:50:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:50:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:50:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:50:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:50:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:50:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d1e2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d277618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa24ac8d9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d146d30>, '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 0x10ceabdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d13eb20>, '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.By26prBcOC/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.WJuqLnJhHy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:50:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:50:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:50:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.By26prBcOC/Render', 12:50:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:50:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:50:32 INFO - 'HOME': '/Users/cltbld', 12:50:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:50:32 INFO - 'LOGNAME': 'cltbld', 12:50:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:50:32 INFO - 'MOZ_NO_REMOTE': '1', 12:50:32 INFO - 'NO_EM_RESTART': '1', 12:50:32 INFO - 'PAGER': '/bin/cat', 12:50:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:50:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:50:32 INFO - 'PWD': '/builds/slave/test', 12:50:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:50:32 INFO - 'SHELL': '/bin/bash', 12:50:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WJuqLnJhHy/Listeners', 12:50:32 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 12:50:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:50:32 INFO - 'USER': 'cltbld', 12:50:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:50:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:50:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:50:32 INFO - 'XPC_FLAGS': '0x0', 12:50:32 INFO - 'XPC_SERVICE_NAME': '0', 12:50:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:50:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:50:32 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 12:50:32 INFO - Cleaning up... 12:50:32 INFO - Return code: 0 12:50:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:50:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:50:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:50:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:50:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:50:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:50:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:50:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d1e2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d277618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa24ac8d9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d146d30>, '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 0x10ceabdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d13eb20>, '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.By26prBcOC/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.WJuqLnJhHy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:50:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:50:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:50:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.By26prBcOC/Render', 12:50:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:50:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:50:32 INFO - 'HOME': '/Users/cltbld', 12:50:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:50:32 INFO - 'LOGNAME': 'cltbld', 12:50:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:50:32 INFO - 'MOZ_NO_REMOTE': '1', 12:50:32 INFO - 'NO_EM_RESTART': '1', 12:50:32 INFO - 'PAGER': '/bin/cat', 12:50:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:50:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:50:32 INFO - 'PWD': '/builds/slave/test', 12:50:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:50:32 INFO - 'SHELL': '/bin/bash', 12:50:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WJuqLnJhHy/Listeners', 12:50:32 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 12:50:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:50:32 INFO - 'USER': 'cltbld', 12:50:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:50:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:50:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:50:32 INFO - 'XPC_FLAGS': '0x0', 12:50:32 INFO - 'XPC_SERVICE_NAME': '0', 12:50:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:50:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:50:33 INFO - Downloading/unpacking psutil>=3.1.1 12:50:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:50:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:50:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:50:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:50:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:50:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:50:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:50:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:50:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:50:36 INFO - Installing collected packages: psutil 12:50:36 INFO - Running setup.py install for psutil 12:50:36 INFO - building 'psutil._psutil_osx' extension 12:50:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 12:50:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 12:50:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 12:50:37 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 12:50:37 INFO - building 'psutil._psutil_posix' extension 12:50:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 12:50:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:50:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:50:37 INFO - ^ 12:50:37 INFO - 1 warning generated. 12:50:37 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 12:50:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:50:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:50:37 INFO - Successfully installed psutil 12:50:37 INFO - Cleaning up... 12:50:37 INFO - Return code: 0 12:50:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:50:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:50:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:50:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:50:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:50:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:50:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:50:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d1e2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d277618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa24ac8d9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d146d30>, '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 0x10ceabdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d13eb20>, '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.By26prBcOC/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.WJuqLnJhHy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:50:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:50:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:50:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.By26prBcOC/Render', 12:50:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:50:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:50:37 INFO - 'HOME': '/Users/cltbld', 12:50:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:50:37 INFO - 'LOGNAME': 'cltbld', 12:50:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:50:37 INFO - 'MOZ_NO_REMOTE': '1', 12:50:37 INFO - 'NO_EM_RESTART': '1', 12:50:37 INFO - 'PAGER': '/bin/cat', 12:50:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:50:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:50:37 INFO - 'PWD': '/builds/slave/test', 12:50:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:50:37 INFO - 'SHELL': '/bin/bash', 12:50:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WJuqLnJhHy/Listeners', 12:50:37 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 12:50:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:50:37 INFO - 'USER': 'cltbld', 12:50:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:50:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:50:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:50:37 INFO - 'XPC_FLAGS': '0x0', 12:50:37 INFO - 'XPC_SERVICE_NAME': '0', 12:50:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:50:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:50:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:50:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:50:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:50:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:50:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:50:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:50:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:50:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:50:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:50:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:50:40 INFO - Installing collected packages: mozsystemmonitor 12:50:40 INFO - Running setup.py install for mozsystemmonitor 12:50:40 INFO - Successfully installed mozsystemmonitor 12:50:40 INFO - Cleaning up... 12:50:40 INFO - Return code: 0 12:50:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:50:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:50:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:50:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:50:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:50:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:50:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:50:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d1e2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d277618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa24ac8d9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d146d30>, '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 0x10ceabdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d13eb20>, '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.By26prBcOC/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.WJuqLnJhHy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:50:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:50:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:50:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.By26prBcOC/Render', 12:50:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:50:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:50:40 INFO - 'HOME': '/Users/cltbld', 12:50:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:50:40 INFO - 'LOGNAME': 'cltbld', 12:50:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:50:40 INFO - 'MOZ_NO_REMOTE': '1', 12:50:40 INFO - 'NO_EM_RESTART': '1', 12:50:40 INFO - 'PAGER': '/bin/cat', 12:50:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:50:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:50:40 INFO - 'PWD': '/builds/slave/test', 12:50:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:50:40 INFO - 'SHELL': '/bin/bash', 12:50:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WJuqLnJhHy/Listeners', 12:50:40 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 12:50:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:50:40 INFO - 'USER': 'cltbld', 12:50:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:50:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:50:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:50:40 INFO - 'XPC_FLAGS': '0x0', 12:50:40 INFO - 'XPC_SERVICE_NAME': '0', 12:50:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:50:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:50:40 INFO - Downloading/unpacking jsonschema==2.5.1 12:50: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 12:50: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 12:50: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 12:50: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 12:50: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 12:50: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 12:50:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:50:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:50:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:50:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:50:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:50:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:50:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:50:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:50:43 INFO - Downloading functools32-3.2.3-2.tar.gz 12:50:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:50:43 INFO - warning: no files found matching '*.txt' 12:50:43 INFO - no previously-included directories found matching 'build' 12:50:43 INFO - no previously-included directories found matching 'dist' 12:50:43 INFO - no previously-included directories found matching '.git*' 12:50:43 INFO - Installing collected packages: jsonschema, functools32 12:50:43 INFO - Running setup.py install for functools32 12:50:44 INFO - warning: no files found matching '*.txt' 12:50:44 INFO - no previously-included directories found matching 'build' 12:50:44 INFO - no previously-included directories found matching 'dist' 12:50:44 INFO - no previously-included directories found matching '.git*' 12:50:44 INFO - Successfully installed jsonschema functools32 12:50:44 INFO - Cleaning up... 12:50:44 INFO - Return code: 0 12:50:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:50:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:50:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:50:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:50:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:50:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:50:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:50:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d1e2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d277618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa24ac8d9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d146d30>, '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 0x10ceabdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d13eb20>, '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.By26prBcOC/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.WJuqLnJhHy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:50:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:50:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:50:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.By26prBcOC/Render', 12:50:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:50:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:50:44 INFO - 'HOME': '/Users/cltbld', 12:50:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:50:44 INFO - 'LOGNAME': 'cltbld', 12:50:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:50:44 INFO - 'MOZ_NO_REMOTE': '1', 12:50:44 INFO - 'NO_EM_RESTART': '1', 12:50:44 INFO - 'PAGER': '/bin/cat', 12:50:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:50:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:50:44 INFO - 'PWD': '/builds/slave/test', 12:50:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:50:44 INFO - 'SHELL': '/bin/bash', 12:50:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WJuqLnJhHy/Listeners', 12:50:44 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 12:50:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:50:44 INFO - 'USER': 'cltbld', 12:50:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:50:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:50:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:50:44 INFO - 'XPC_FLAGS': '0x0', 12:50:44 INFO - 'XPC_SERVICE_NAME': '0', 12:50:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:50:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:50:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:50:44 INFO - Cleaning up... 12:50:44 INFO - Return code: 0 12:50:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:50:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:50:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:50:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:50:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:50:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:50:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:50:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d1e2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d277618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa24ac8d9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d146d30>, '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 0x10ceabdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d13eb20>, '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.By26prBcOC/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.WJuqLnJhHy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:50:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:50:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:50:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.By26prBcOC/Render', 12:50:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:50:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:50:44 INFO - 'HOME': '/Users/cltbld', 12:50:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:50:44 INFO - 'LOGNAME': 'cltbld', 12:50:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:50:44 INFO - 'MOZ_NO_REMOTE': '1', 12:50:44 INFO - 'NO_EM_RESTART': '1', 12:50:44 INFO - 'PAGER': '/bin/cat', 12:50:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:50:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:50:44 INFO - 'PWD': '/builds/slave/test', 12:50:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:50:44 INFO - 'SHELL': '/bin/bash', 12:50:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WJuqLnJhHy/Listeners', 12:50:44 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 12:50:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:50:44 INFO - 'USER': 'cltbld', 12:50:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:50:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:50:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:50:44 INFO - 'XPC_FLAGS': '0x0', 12:50:44 INFO - 'XPC_SERVICE_NAME': '0', 12:50:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:50:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:50:44 INFO - Downloading/unpacking blobuploader==1.2.4 12:50:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:50:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:50:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:50:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:50:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:50:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:50:47 INFO - Downloading blobuploader-1.2.4.tar.gz 12:50:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:50:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:50:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:50:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:50:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:50:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:50:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:50:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:50:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:50:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:50:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:50:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:50:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:50:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:50:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:50:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:50:48 INFO - Downloading docopt-0.6.1.tar.gz 12:50:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:50:48 INFO - Installing collected packages: blobuploader, requests, docopt 12:50:48 INFO - Running setup.py install for blobuploader 12:50:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:50:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:50:48 INFO - Running setup.py install for requests 12:50:49 INFO - Running setup.py install for docopt 12:50:49 INFO - Successfully installed blobuploader requests docopt 12:50:49 INFO - Cleaning up... 12:50:49 INFO - Return code: 0 12:50:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:50:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:50:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:50:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:50:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:50:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:50:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:50:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d1e2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d277618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa24ac8d9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d146d30>, '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 0x10ceabdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d13eb20>, '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.By26prBcOC/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.WJuqLnJhHy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:50:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:50:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:50:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.By26prBcOC/Render', 12:50:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:50:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:50:49 INFO - 'HOME': '/Users/cltbld', 12:50:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:50:49 INFO - 'LOGNAME': 'cltbld', 12:50:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:50:49 INFO - 'MOZ_NO_REMOTE': '1', 12:50:49 INFO - 'NO_EM_RESTART': '1', 12:50:49 INFO - 'PAGER': '/bin/cat', 12:50:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:50:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:50:49 INFO - 'PWD': '/builds/slave/test', 12:50:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:50:49 INFO - 'SHELL': '/bin/bash', 12:50:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WJuqLnJhHy/Listeners', 12:50:49 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 12:50:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:50:49 INFO - 'USER': 'cltbld', 12:50:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:50:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:50:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:50:49 INFO - 'XPC_FLAGS': '0x0', 12:50:49 INFO - 'XPC_SERVICE_NAME': '0', 12:50:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:50:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:50:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:50:49 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-o0uFt3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:50:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:50:49 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-KUcRB_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:50:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:50:49 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-Q0v52X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:50:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:50:49 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-niRSjv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:50:50 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-dvdoe_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:50:50 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-pXierM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:50:50 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-dMGwpA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:50:50 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-d1rbG0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:50:50 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-TIRB4W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:50:50 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-3Wqya3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:50:50 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-5RK9tc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:50:50 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-8JWwHa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:50:50 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-oI07lh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:50:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:50:50 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-xkUHS3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:50:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:50:51 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-dRG1LR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:50:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:50:51 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-Ry7Dre-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:50:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:50:51 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-_cIIGq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:50:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:50:51 INFO - Running setup.py install for manifestparser 12:50:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:50:51 INFO - Running setup.py install for mozcrash 12:50:51 INFO - Running setup.py install for mozdebug 12:50:51 INFO - Running setup.py install for mozdevice 12:50:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:50:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:50:52 INFO - Running setup.py install for mozfile 12:50:52 INFO - Running setup.py install for mozhttpd 12:50:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:50:52 INFO - Running setup.py install for mozinfo 12:50:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:50:52 INFO - Running setup.py install for mozInstall 12:50:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:50:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:50:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:50:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:50:52 INFO - Running setup.py install for mozleak 12:50:52 INFO - Running setup.py install for mozlog 12:50:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:50:52 INFO - Running setup.py install for moznetwork 12:50:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:50:53 INFO - Running setup.py install for mozprocess 12:50:53 INFO - Running setup.py install for mozprofile 12:50:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:50:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:50:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:50:53 INFO - Running setup.py install for mozrunner 12:50:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:50:53 INFO - Running setup.py install for mozscreenshot 12:50:53 INFO - Running setup.py install for moztest 12:50:53 INFO - Running setup.py install for mozversion 12:50:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:50:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:50:54 INFO - Cleaning up... 12:50:54 INFO - Return code: 0 12:50:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:50:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:50:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:50:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:50:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:50:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:50:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:50:54 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 0x10d1e2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d277618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa24ac8d9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d146d30>, '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 0x10ceabdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d13eb20>, '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.By26prBcOC/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.WJuqLnJhHy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:50:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:50:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:50:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.By26prBcOC/Render', 12:50:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:50:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:50:54 INFO - 'HOME': '/Users/cltbld', 12:50:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:50:54 INFO - 'LOGNAME': 'cltbld', 12:50:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:50:54 INFO - 'MOZ_NO_REMOTE': '1', 12:50:54 INFO - 'NO_EM_RESTART': '1', 12:50:54 INFO - 'PAGER': '/bin/cat', 12:50:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:50:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:50:54 INFO - 'PWD': '/builds/slave/test', 12:50:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:50:54 INFO - 'SHELL': '/bin/bash', 12:50:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WJuqLnJhHy/Listeners', 12:50:54 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 12:50:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:50:54 INFO - 'USER': 'cltbld', 12:50:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:50:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:50:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:50:54 INFO - 'XPC_FLAGS': '0x0', 12:50:54 INFO - 'XPC_SERVICE_NAME': '0', 12:50:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:50:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:50:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:50:54 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-mh6IMD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:50:54 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 12:50:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:50:54 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-pDTy57-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:50:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:50:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:50:54 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-L0Gz3W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:50:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:50:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:50:54 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-nwXmw0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:50:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:50:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:50:54 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-28K0xa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:50:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 12:50:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:50:54 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-nX4g85-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:50:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 12:50:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:50:54 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-uMNs3E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:50:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 12:50:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:50:55 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-aYYBEg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:50:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:50:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:50:55 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-DqWKZo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:50:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 12:50:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:50:55 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-hNTZbn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:50:55 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)) 12:50:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:50:55 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-BPFEfJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:50:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 12:50:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:50:55 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-gxTDwE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:50:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 12:50:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:50:55 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-50w06K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:50:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 12:50:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:50:55 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-uKdLPQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:50:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 12:50:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:50:55 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-yPcekt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:50:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 12:50:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:50:55 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-e6k691-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:50:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 12:50:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:50:56 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-AscJ6A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:50:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 12:50:56 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)) 12:50:56 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)) 12:50:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:50:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:50:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:50:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:50:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:50:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:50:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:50:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:50:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:50:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:50:58 INFO - Downloading blessings-1.6.tar.gz 12:50:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:50:58 INFO - Installing collected packages: blessings 12:50:58 INFO - Running setup.py install for blessings 12:50:58 INFO - Successfully installed blessings 12:50:58 INFO - Cleaning up... 12:50:58 INFO - Return code: 0 12:50:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:50:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:50:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:50:59 INFO - Reading from file tmpfile_stdout 12:50:59 INFO - Current package versions: 12:50:59 INFO - blessings == 1.6 12:50:59 INFO - blobuploader == 1.2.4 12:50:59 INFO - docopt == 0.6.1 12:50:59 INFO - functools32 == 3.2.3-2 12:50:59 INFO - jsonschema == 2.5.1 12:50:59 INFO - manifestparser == 1.1 12:50:59 INFO - mozInstall == 1.12 12:50:59 INFO - mozcrash == 0.17 12:50:59 INFO - mozdebug == 0.1 12:50:59 INFO - mozdevice == 0.48 12:50:59 INFO - mozfile == 1.2 12:50:59 INFO - mozhttpd == 0.7 12:50:59 INFO - mozinfo == 0.9 12:50:59 INFO - mozleak == 0.1 12:50:59 INFO - mozlog == 3.3 12:50:59 INFO - moznetwork == 0.27 12:50:59 INFO - mozprocess == 0.23 12:50:59 INFO - mozprofile == 0.28 12:50:59 INFO - mozrunner == 6.12 12:50:59 INFO - mozscreenshot == 0.1 12:50:59 INFO - mozsystemmonitor == 0.3 12:50:59 INFO - moztest == 0.7 12:50:59 INFO - mozversion == 1.4 12:50:59 INFO - psutil == 3.1.1 12:50:59 INFO - requests == 1.2.3 12:50:59 INFO - wsgiref == 0.1.2 12:50:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:50:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:50:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:50:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:50:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:50:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:50:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:50:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d1e2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d277618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa24ac8d9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d146d30>, '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 0x10ceabdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d13eb20>, '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.By26prBcOC/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.WJuqLnJhHy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:50:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 12:50:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:50:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.By26prBcOC/Render', 12:50:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:50:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:50:59 INFO - 'HOME': '/Users/cltbld', 12:50:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:50:59 INFO - 'LOGNAME': 'cltbld', 12:50:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:50:59 INFO - 'MOZ_NO_REMOTE': '1', 12:50:59 INFO - 'NO_EM_RESTART': '1', 12:50:59 INFO - 'PAGER': '/bin/cat', 12:50:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:50:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:50:59 INFO - 'PWD': '/builds/slave/test', 12:50:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:50:59 INFO - 'SHELL': '/bin/bash', 12:50:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WJuqLnJhHy/Listeners', 12:50:59 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 12:50:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:50:59 INFO - 'USER': 'cltbld', 12:50:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:50:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:50:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:50:59 INFO - 'XPC_FLAGS': '0x0', 12:50:59 INFO - 'XPC_SERVICE_NAME': '0', 12:50:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:50:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:50:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:50:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 12:50:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 12:50:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:50:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 12:50:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 12:50:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 12:50:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 12:50:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 12:50:59 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:50:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:50:59 INFO - Cleaning up... 12:50:59 INFO - Return code: 0 12:50:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:50:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:50:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:50:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:50:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:50:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:50:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:50:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d1e2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d277618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa24ac8d9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d146d30>, '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 0x10ceabdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d13eb20>, '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.By26prBcOC/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.WJuqLnJhHy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:50:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:50:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:50:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.By26prBcOC/Render', 12:50:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:50:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:50:59 INFO - 'HOME': '/Users/cltbld', 12:50:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:50:59 INFO - 'LOGNAME': 'cltbld', 12:50:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:50:59 INFO - 'MOZ_NO_REMOTE': '1', 12:50:59 INFO - 'NO_EM_RESTART': '1', 12:50:59 INFO - 'PAGER': '/bin/cat', 12:50:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:50:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:50:59 INFO - 'PWD': '/builds/slave/test', 12:50:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:50:59 INFO - 'SHELL': '/bin/bash', 12:50:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WJuqLnJhHy/Listeners', 12:50:59 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 12:50:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:50:59 INFO - 'USER': 'cltbld', 12:50:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:50:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:50:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:50:59 INFO - 'XPC_FLAGS': '0x0', 12:50:59 INFO - 'XPC_SERVICE_NAME': '0', 12:50:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:51:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:51:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:51:00 INFO - Cleaning up... 12:51:00 INFO - Return code: 0 12:51:00 INFO - Running post-action listener: _resource_record_post_action 12:51:00 INFO - Running post-action listener: _start_resource_monitoring 12:51:00 INFO - Starting resource monitoring. 12:51:00 INFO - [mozharness: 2016-08-11 19:51:00.172478Z] Finished create-virtualenv step (success) 12:51:00 INFO - [mozharness: 2016-08-11 19:51:00.173415Z] Running install step. 12:51:00 INFO - Running pre-action listener: _resource_record_pre_action 12:51:00 INFO - Running main action method: install 12:51:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:51:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:51:00 INFO - Reading from file tmpfile_stdout 12:51:00 INFO - Detecting whether we're running mozinstall >=1.0... 12:51:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:51:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:51:00 INFO - Reading from file tmpfile_stdout 12:51:00 INFO - Output received: 12:51:00 INFO - Usage: mozinstall [options] installer 12:51:00 INFO - Options: 12:51:00 INFO - -h, --help show this help message and exit 12:51:00 INFO - -d DEST, --destination=DEST 12:51:00 INFO - Directory to install application into. [default: 12:51:00 INFO - "/builds/slave/test"] 12:51:00 INFO - --app=APP Application being installed. [default: firefox] 12:51:00 INFO - mkdir: /builds/slave/test/build/application 12:51:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:51:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 12:51:34 INFO - Reading from file tmpfile_stdout 12:51:34 INFO - Output received: 12:51:34 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:51:34 INFO - Running post-action listener: _resource_record_post_action 12:51:34 INFO - [mozharness: 2016-08-11 19:51:34.384575Z] Finished install step (success) 12:51:34 INFO - [mozharness: 2016-08-11 19:51:34.384703Z] Running run-tests step. 12:51:34 INFO - Running pre-action listener: _resource_record_pre_action 12:51:34 INFO - Running main action method: run_tests 12:51:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:51:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:51:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:51:34 INFO - 2016-08-11 12:51:34.398 screenresolution[1636:7498] starting screenresolution argv=screenresolution get 12:51:34 INFO - 2016-08-11 12:51:34.400 screenresolution[1636:7498] Display 0: 1600x1200x32@60 12:51:34 INFO - 2016-08-11 12:51:34.406 screenresolution[1637:7500] starting screenresolution argv=screenresolution list 12:51:34 INFO - Available Modes on Display 0 12:51:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:51:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:51:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:51:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:51:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:51:34 INFO - Intel Iris: 12:51:34 INFO - Chipset Model: Intel Iris 12:51:34 INFO - Type: GPU 12:51:34 INFO - Bus: Built-In 12:51:34 INFO - VRAM (Dynamic, Max): 1536 MB 12:51:34 INFO - Vendor: Intel (0x8086) 12:51:34 INFO - Device ID: 0x0a2e 12:51:34 INFO - Revision ID: 0x0009 12:51:34 INFO - Displays: 12:51:34 INFO - Display: 12:51:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:51:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:51:34 INFO - Display Serial Number: 1600x1200 60 12:51:34 INFO - Main Display: Yes 12:51:34 INFO - Mirror: Off 12:51:34 INFO - Online: Yes 12:51:34 INFO - Rotation: Supported 12:51:34 INFO - Return code: 0 12:51:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:51:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:51:34 INFO - Python 2.7.3 12:51:34 INFO - Return code: 0 12:51:34 INFO - grabbing minidump binary from tooltool 12:51:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:51:34 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d146d30>, '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 0x10ceabdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d13eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:51:34 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 12:51:34 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 12:51:34 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 12:51:34 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:51:34 INFO - Return code: 0 12:51:34 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:51:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:51:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:51:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:51:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:51:34 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/UMexgxeNQiu-ekcAEOXQ2Q/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0164', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 12:51:34 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/UMexgxeNQiu-ekcAEOXQ2Q/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0164 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 12:51:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.By26prBcOC/Render', 12:51:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:51:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:51:34 INFO - 'HOME': '/Users/cltbld', 12:51:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:51:34 INFO - 'LOGNAME': 'cltbld', 12:51:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:51:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:51:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:51:34 INFO - 'MOZ_NO_REMOTE': '1', 12:51:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:51:34 INFO - 'NO_EM_RESTART': '1', 12:51:34 INFO - 'PAGER': '/bin/cat', 12:51:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:51:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:51:34 INFO - 'PWD': '/builds/slave/test', 12:51:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:51:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:51:34 INFO - 'SHELL': '/bin/bash', 12:51:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WJuqLnJhHy/Listeners', 12:51:34 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 12:51:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:51:34 INFO - 'USER': 'cltbld', 12:51:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:51:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:51:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:51:34 INFO - 'XPC_FLAGS': '0x0', 12:51:34 INFO - 'XPC_SERVICE_NAME': '0', 12:51:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:51:34 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/UMexgxeNQiu-ekcAEOXQ2Q/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0164', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 12:51:34 INFO - mozversion application_buildid: 20160811110430 12:51:34 INFO - mozversion application_changeset: 1c7f1b07be44709c0d17eb380b56db32bfa78cf6 12:51:34 INFO - mozversion application_display_name: Nightly 12:51:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:51:34 INFO - mozversion application_name: Firefox 12:51:34 INFO - mozversion application_remotingname: firefox 12:51:34 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 12:51:34 INFO - mozversion application_vendor: Mozilla 12:51:34 INFO - mozversion application_version: 51.0a1 12:51:34 INFO - mozversion platform_buildid: 20160811110430 12:51:34 INFO - mozversion platform_changeset: 1c7f1b07be44709c0d17eb380b56db32bfa78cf6 12:51:34 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 12:51:34 INFO - mozversion platform_version: 51.0a1 12:51:34 INFO - using testdate: 1470945094 12:51:34 INFO - actual date: 1470945094 12:51:34 INFO - starting webserver on 'localhost:49274' 12:51:34 INFO - SUITE-START | Running 2 tests 12:51:34 INFO - TEST-START | dromaeo_css 12:51:34 INFO - operating with platform_type : mac_ 12:51:34 INFO - Initialising browser for dromaeo_css test... 12:51:35 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/tmpRoEGaF/profile http://localhost:49274/getInfo.html) 12:51:37 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 12:51:37 INFO - PROCESS | 1647 | colorDepth:24 12:51:37 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 12:51:37 INFO - PROCESS | 1647 | __metrics 12:51:37 INFO - PROCESS | 1647 | 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" 12:51:37 INFO - PROCESS | 1647 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:51:38 INFO - TEST-INFO | 1647: exit 0 12:51:38 INFO - Browser initialized. 12:51:38 INFO - Running cycle 1/1 for dromaeo_css test... 12:51:38 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/tmpRoEGaF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:51:50 INFO - PROCESS | 1649 | RSS: Main: 243535872 12:51:50 INFO - PROCESS | 1649 | 12:52:41 INFO - PROCESS | 1649 | 1470945161575 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 12:54:53 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49274/tests/dromaeo/cssquery-ext.html) 12:54:55 INFO - PROCESS | 1649 | RSS: Main: 378871808 12:54:55 INFO - PROCESS | 1649 | 12:57:58 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-ext.html (next: http://localhost:49274/tests/dromaeo/cssquery-jquery.html) 12:57:58 INFO - PROCESS | 1649 | RSS: Main: 383827968 12:57:58 INFO - PROCESS | 1649 | 13:01:01 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49274/tests/dromaeo/cssquery-mootools.html) 13:01:02 INFO - PROCESS | 1649 | RSS: Main: 349126656 13:01:02 INFO - PROCESS | 1649 | 13:04:04 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49274/tests/dromaeo/cssquery-prototype.html) 13:04:05 INFO - PROCESS | 1649 | RSS: Main: 368295936 13:04:05 INFO - PROCESS | 1649 | 13:07:08 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49274/tests/dromaeo/cssquery-yui.html) 13:07:09 INFO - PROCESS | 1649 | RSS: Main: 342740992 13:07:09 INFO - PROCESS | 1649 | 13:10:12 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-yui.html (next: http://localhost:49274/tests/dromaeo/cssquery-dojo.html) 13:10:12 INFO - PROCESS | 1649 | RSS: Main: 390250496 13:10:12 INFO - PROCESS | 1649 | 13:10:12 INFO - PROCESS | 1649 | __start_tp_report 13:10:12 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 13:10:12 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 13:10:12 INFO - PROCESS | 1649 | |i|pagename|runs| 13:10:12 INFO - PROCESS | 1649 | |0;dojo.html;2776.9722302776977;3292.4073666739987;3308.123347312961;3331.5502407175054;3362.1090411041077;5441.7279136043235;5535.086710692736;5547.472990065938;5567.582431317647;5602.07565751651;5536.640118392302;5584.581156413265;5585.357683866357;5587.9941610510095;5591.049521581331;5054.317667114948;5090.643654944155;5115.565176959944;5161.122609156434;5178.974105129469;5101.183810590306;5173.30160428343;5177.974110129444;5182.766775495112;5198.064348417283;5087.516685914832;5101.617378696594;5107.948920510803;5131.794728210867;5132.871678208037;5187.714675692854;5199.818006369759;5207;5215.687058776485;5276.445973172838;5031.77357018935;5043.52086551777;5081.314022606938;5120.846374608767;5122.846314610568;5498.642588231752;5516.3380394352935;5556.361018482886;5586.720663966786;5590.692511911843;6094.097553225332;6094.207752992127;6107.881008323032;6116.746067056243;6117.339610596886;5153.716545589991;6041.818745437644;6131.988221943374;6164.6917654117115;6187.978983467768;5680;5716.657000579895;5719.456651618048;5725.341585717696;5729.541636669056;6319.052142178618;6320.55756097078;6328.272248691367;6333.841653958688;6342.778002769881;5796.478316951495;5831.446012628793;5844.649321040752;5896.705164741746;5978.461938425476;4814.422269327703;6053.297721791484;6090.634561926298;6097.451229389376;6109.84725381869;5019.29729837823;6074.453299203093;6098.756049758049;6099.146119543268;6104.969475152596;5010.799568017312;5982.46157845796;6089.878202436015;6092.177556029879;6126.142340072393;4810.278458231293;4906.165951788204;4911.336969509142;4923.286123512071;4941.184704523714;4106.486689163855;4421.248387774086;4461.888452788707;4462.48681401643;4470.418845550123;5559.527440167551;5584.329880414295;5593.440655934455;5608.579356548275;5676.091825307931;6137.355577664371;6187.876242475215;6191.5665903387235;6201.286852011986;6202.937970620236;5488.890222195534;5578.219049333096;5596.468335508121;5598.636088654224;5611.7474713637985;5315.335583052119;5315.707636080053;5354.625176237626;5360.865978350573;5395.865103372447;6240.31356550779;6242.344553821783;6242.406971337716;6255.655938923312;6272.905906411499;6083.939160608515;6141.785037523665;6158.876822463615;6165.599236049642;6178.907316390168;6141.88493709207;6142.590862071594;6212.658303793335;6223.96888015557;6242.906356404566;6053.576249662658;6116.480099191618;6124.234470691164;6133.110698948538;6133.570650054453;6104.992676208376;6126.203593532812;6136.048912418582;6142.109394137736;6158.969205153946;5866.706664666664;5947.762089516371;5970.194023806905;5986.221791167294;5987.520998320037;5288.550473209666;5728.856778580569;5837.29952405714;5875.618084824644;5969.582129250911;5723.8569035774435;5837.124431335334;5865.296164460292;5876.147958546072;5876.382979787061;5875.177475153568;5877.735501902339;5887.558433117448;5919.200907877382;5924.437178468039;5615.2700148980375;5666.688332141607;5673.404292549388;5703.714814259353;5781.710914454345 13:10:12 INFO - PROCESS | 1649 | |1;ext.html;483.50923812330467;485.973271470069;500.1647249094013;502.81144570785955;535.2987586261995;31213.531797023035;41134.794326028365;42195.36706949398;42501.7874910625;44253.77873110638;43263.78368108155;43390;43451.95836291461;43691.90770230746;43712.78143609277;8664.783380415509;9641.421514709122;9840.753981150458;9845.60617575296;9850.753731156707;8604.010538788025;8829.807975923233;8838.983516895574;8839.16027977341;11667.708307292301;11857.992070674003;11940.164188506807;11969.521219151267;11982.281063136195;12017.459214335333;11774.352410617412;11898.524059037629;11900.404979750978;11905.226160299639;11905.880941190608;11730.296182229093;11817;11846.17076804624;11855.347955862424;11856.288622682667;41055.58944410548;41470.1705965882;41478.1704365911;41496.79251603753;41512.58487415117;3145.716885480295;3651.415773476231;3739.9439008414897;4079.3269110596716;4311.202427550882;30699;31451.05646830576;32052.679473205437;32422;32818.3436331272;2995.266159790865;3210.1172177651333;3235.094173631387;3325.4346761050438;3393.9321213575836;497925.51037244947;513695;514046;515840;517040;57908;58235;58242.41757582455;58325.416745832845;58532;61644.38355616386;62039.37960620426;62566.687166564785;62739.372606273355;62773;16347.937384070236;16571.83428165703;16577.839551231296;16579.91710041442;16640.417585384454;12824.615261542169;12904.93547532275;12911.935440322739;12923;12937.353132343345;13750.381232844546;14211;14239.857601423853;14421.35103920326;15667.608309792118;5602.299712535933;6740.955151951455;6772;6796.116504854436;6830.624315662587;876.4653561327585;945.191860958884;951.871497347863;982.6292322210619;1053.9683809485728;29845.253868653024;30053.549196762066;30481.542776858365;31006.84496577548;31226.063218103493;1039.7244730146517;1059.7350662334416;1112.1769890281134;1130.5082289204058;1155.5146838327923;14625;15396.307166177548;15715.4499592516;15729.764053539206;16193.352265909467;641429;647486;648346;649281;651285;51393.486065139616;58412;58459;58842.41157588369;59034.704826475594;1375.2848518770384;1396.629893078335;1423.7437261293067;1425.7576212043978;1431.8424973253143;29757.809687612254;30666.23334416568;31195.688043119277;31458;32220.516692250105;51381.743091285796;51883.74058129685;51950.48049519456;51985.7400712994;52067.2189917144;600047;654892.4510755022;668683.3131668621;668822;859104;25827.612585810853;26258;26278.21165365042;26338.34154146085;26341.473170536865;46563.76718116388;46583;47558;47714.28428573644;48117.278240827116;36275;38303.65937192185;38691.890015619516;39763.60236397599;39896.800515998395;37719;38400.47074311539;39091.6090839088;39311.803440982614;39895.80312590627;34974.12564685802;36738.08154796151;37081.072973175884;37434.812825935696;37971.81014094912;32635.673643263264;34178.80374186891;34470.48294275538;34489.343938567974;34550.30899382049;23828.404289892893;24680.13619523308;24803.751962480143;24838.50322993494;25457.61813572835 13:10:12 INFO - PROCESS | 1649 | |2;jquery.html;349.59447041431946;351.4956038085347;369.2467366572193;378.7538100234847;400.911799404131;15383.307751151196;16669.49991500254;20267.391978240677;20780.792192078112;20916.954152292383;17757.822421775778;18216.089195540237;18231.270749170017;18371.265149394007;21167.57664846702;5319.909418569204;5761.423857614236;5971.701414929258;5991.700414979256;5992.430719081702;4277.251480990814;4509.932351014738;6016.037434010559;6238.781642642486;6628.237752658433;6851.663925534523;6857.794266172023;6897.586144831326;6984.3714065734075;7002.124734408199;5466.535344495723;6836.418904393107;6920.411764999981;6920.515563910529;6920.826979325507;5830.7084645767545;5890.263717035371;5924.9703751480965;5988.550858685616;5989.700514974234;24375.756242437525;25895.870520647277;26566.6015009775;26637.73362266372;26839.463210735677;2171.533120379126;2295.231097582312;2361.397843549901;2466.7409921958115;2591.3521619595103;15740.44908428211;15900.443484477988;15980.121093339862;16081.597960050975;16847.9891206528;1689.4171510828867;1877.4180004198606;1930.6428310762553;1962.4308950404366;1975.1309423853602;117384;122112;123173;123933.38033309866;127097;10748.355098694103;10819.026287634151;11249.156313276537;11258.71471663445;11443.027342675965;13954.441822326993;13979.580612581436;13999;14007.369668364949;14009.789853152211;8491.702790402307;8749.956250218707;8782.648694052294;8888.688895888612;8891.510966896753;8816.735497935073;8823.911760882438;8827.867581986275;8987.595558199897;9048.819023619491;6635.412189246198;6788.422984046312;6790.49071319653;6790.694418751168;6805.795826125224;1689.7465380192823;1747.851432628241;1822.5443639090226;1831.2726550474258;1867.1317837205765;774.44240147094;888.0276097673037;913.7898283394857;942.3733217410345;1039.5841663334727;21314.78685213159;22275.554488910457;22839;22843.771562284164;22860.885695571415;932.911373419524;948.4966328369611;956.9760755981156;959.4243453927727;989.5942663508071;11716.121290903217;11854.05167586591;11900.523979040914;12098.334591597371;12118.212316199419;530208;563752;565486.3451365517;566230;608834;109446;115695;117770.41114794371;124799;125686;973.5083782689838;991.5934466868549;1015.3907655406697;1016.9745756356002;1032.8037672842136;17204.70964677679;17974.2810287587;19410.90294548518;19940.60118797645;19975.900120499304;54685.1797223034;55127.724361377936;55676.88646227134;56755.43244567667;56771;79834.80247796171;79874.00314992171;85716.71407930147;87243.56378218322;92108;7919.960400197962;8042.477238979448;8411.966066378876;8448.281896038905;8488.533130677624;11908.821367679318;12910.80633790475;12914.935425322814;13339.733205336033;13533.932330338286;11211.55153793839;11273.887261127611;11600.129990250934;11614.535418583162;11755.235909665844;8792.384533082623;9405.905940940776;11470.311781293149;11515.8272625909;11784.646460606196;9422.905770942478;11250.381229032482;11373.488193031168;11785.292882427082;11921.701957451132;6885.173779146535;8397.748067557983;8480.872786908078;8481.618327175169;8555.61499732501;11175;11348.602798901999;11767;11773.52905883755;11846.763064738829 13:10:12 INFO - PROCESS | 1649 | |3;mootools.html;1502.7069721404328;1539.6689711711983;1714.2285971312913;1862.6647203503364;1893.2332405375819;8436.66253349866;9019.78232938554;9022.639094436232;9039.638414463414;9044.321675874311;7155.32024457677;7745.690172393098;7992.560409177493;8727.476351418904;8845.20393164615;2078.939740732222;2300.1834348806183;2331.288956868159;2351.247600767755;2367.112332875172;2295.0819672131197;2316.872572008539;2341.613633750429;2342.847714898535;2348.6711860339565;2312.410335364488;2331.2190416210506;2338.801201897832;2351.1888398502488;2351.9176828810996;2322.837401381904;2324.5815753164425;2330.883455827202;2331.2423462374795;2334.1947028275217;1946.6301402733436;2096.1301060060055;2211.7677643847487;2325.4883925536355;2338.777816107467;8898.733038008806;9030.683926062557;9034.548272586344;9047.18575328217;9059.864102038475;5138.897222055575;5312.521873031407;5313.7608807603365;5314.7608357624;5315.601329900234;3481.408160612702;3528.752987290891;3659.9268014639556;3692.2615476904457;3710.8330125144435;1057.3447176375935;1088.5754555723274;1098.9670309890716;1109.4619109731773;1113.582406597526;11525.077993760478;11662.125340599488;11666.066714662891;11670.183087183901;11687.7078073048;4535.546445355504;4617.79219935097;4791;4811.639127065484;4817.855464336076;9898.307118501636;9900.851487227697;9909.851352229722;9922.107010368956;9923.851142232872;5325.4142044375085;5327;5327.227552004938;5327.653702509325;5341.626086174008;4354.325079612665;4518.209313370213;4711.623070154379;4816.975915120402;4884.780184891689;3394.8981530554124;3875.593062728373;3947.940780888289;3999.6400323970984;4033.3748269018347;1153.5674122204173;1223.1804690857148;1272.8536218334825;1275.8787915148046;1296.5850927703234;897.5063714956748;926.4950601921969;927.3415874728884;944.2304521814699;964.7829238421299;4988.650794444354;5704.515116215085;5907.202527658711;5919.816036792573;5995.160677505153;443.18232860372564;940.5720397219249;1027.6454623155;1049.5277125293649;1098.4727330881221;3828.674562662149;3876.8836934891997;4007.8797636070963;4031.576684448091;4037.37420699792;10426.374417534973;10456.268061235793;10783.730406739738;10960.28758130719;11036.944815276032;11090.5563777448;11094.167937404727;11100.500477478532;11104.61133860311;11120.388378639253;7771.4559980800805;8252.711155109542;8336.124706905688;8350.457220280663;8364.205400486944;5907.911381329198;5918.378570250234;6060.333363329942;6089.54328425361;6140.263168419639;10136.543855526372;10142.036506531867;10163.796724065625;10179.287449878437;10187.796244075225;9820.361676491006;10265.948670256601;10268.794624107626;10434.478276086316;10454.790904181722;7014.508984371045;7225.855482890418;7235.565866048054;7562.697492100475;7723.382129429519;7681.385489160742;7947.046354437273;8344.207300306462;8466.74599762008;8614.267787238156;7435.814104647211;7560.773176804704;7565.470417070972;7664.34853037498;7724.150343462327;7292.890606640798;7411.0736157980255;7433.553986760811;7449.776506704808;7475.215102414386;7001.509894307554;7106.893396599158;7121.9643901780155;7122.287771222836;7122.3589876909255;4849.690583542353;4973.303737476828;5028.899422011466;5048.974755126201;5129.128048231884;4981.551660350441;5018.4981501849525;5147.948520514747;5157.561607263425;5251.107311756934 13:10:12 INFO - PROCESS | 1649 | |4;prototype.html;298.35257491244;301.5085410780428;311.9968800311997;317.3035187762861;319.86885376995434;5127.256547800566;5159.200323949789;5170.327857378545;5172.741362931857;5239.4281715485395;4009.8997525061886;4429.13631841791;4922.883160241095;4993.750312484371;5149.639525233235;3960.841566337343;3975.244703506339;3987.840486380556;4011.1376054148354;4017.774578753479;4003.75977441353;4005.5193376794828;4011.6590089842252;4022.396640503933;4028.939565906499;3607.6392360763975;3708.831718008832;3970.7617542947514;3986.9601303987024;4026.798660066985;3926.2736393767245;3947.842086316544;3971.6170297821363;3973.960260397388;3984.621460961204;3482.4428091505492;3488.8255587220538;3489.45913383426;3490.2844916791996;3516.982415087934;4337.371081193241;4379.6131108545915;5022.568567958151;5060.51925067118;5122;4509.436320459942;4569.1547063793305;4630.282306242538;4643.326717625959;4665.953340466586;4576.244919588264;5526.286851076179;5532.917006244909;5549.77800887964;5596.2165296858475;3463.9826800865835;3493.7290761005297;3765.171662234304;3950.6246906543834;3971.5035620547433;4718.9292160617615;4852.708837469692;5325.360956685223;5600.943990560124;5758.539316854642;3169.271067654406;3494.5282386877448;3520.8239588020497;3741.326561218952;3756;3837.4627552142724;3851.980740096282;3853.8843834685003;3862.841147655692;3869.2454971280895;3751.249750050001;4095.795210239476;4383.3863259143745;4468.597826195658;4482.529334419839;5449.509544141047;5479.75341109651;5500.80747173847;5510.393856675762;5544.805931792368;1149.8735139134687;1171.7949358862336;1214.5384753793503;1303.8656368958877;1378.600205940266;782.1123025366137;867.6225841758855;899.1413200393608;923.2936803345447;925.3013974449248;4199.97900010498;4214.3889136075095;4427.623651989553;4849.763310355836;5116.641835071584;4958.355413796184;5363.5440987516495;5488.506034456918;5490.231367608538;5498.752556134984;4541.977290113528;4744.288356746447;4995.675281106716;5040.152313942611;5072.644914855999;2490.1657944588405;2530.9499305562554;2694.474577457416;2873.7700983921236;2903.245156259388;5621.678905457215;5651.802186923438;5656.60403771732;5676.772929082873;5740.588952639901;5345.825571464964;5493.857395615713;5522.818486697732;5604.439556044363;5670.489655930904;4332;5261.263423120766;5455.918161227585;5713.885862231001;5744.425557444222;5080.136376815876;5472.480114389126;5513.58648101386;5546.750396232099;5562.860928476821;4729.716217026989;4971.477994810492;5346.97326513365;5401.864953376197;5449.455054494519;4476.015276639134;4996.950030499648;5375.973120134374;5687.440651706665;5696.522012924391;3643.9089022773583;3857.749246299094;3858.7105967053144;3862.208247309351;3870.8838734838;4691.225947718588;4835.782389792397;5197.402298735543;5293.861819708781;5460.563154947516;5289.5667992723775;5310.920336195038;5319.255304257485;5331.1470164773455;5386.814900721835;4585.72485650862;4996.77578993149;5343.236731878548;5381.062817385659;5408.648437851527;4382.342648602735;5077.213031980049;5212.270282160424;5231.633785634969;5261.657992230493;4256.191323648497;5257.237700533477;5411.269478620493;5413.9729301353245;5453.400125986218;4902.215385844737;5108.846734597968;5273.920891186559;5290.7354632269;5303.708296043755 13:10:12 INFO - PROCESS | 1649 | |5;yui.html;707.8619669164515;717.5587013986396;730.466759265736;782.9373650107992;837.7947402886292;15099;16664.083475408846;16716.83283167168;16742.83257167428;16788.41240556582;15055.473058442956;15176.468823591176;15212.239388030583;15220;15233.92383038086;701.9929800701979;725.1769241910417;728.191707205002;729.114126336501;837.5602808525528;712.4549719464587;719.1190791280666;840.2060053249681;867.1415298854121;870.2341939093589;906.7959709065441;915.1260546178383;921.3642586615248;922.0410772796317;922.2667978956746;906.8095699903029;909.1726528858707;911.0297533127232;911.1480765484293;912.6212621761961;537.6478406643615;560.5179545590788;684.1687349869932;686.8969654551807;686.9278725733826;14756.926215368961;15287.159206243763;15336.309866055943;15368.308426120851;15371.461998829987;12693.428795704216;14749.410023599043;15308.846911530853;15471.458498952596;15523;10400.843987340197;12092.87907120935;12130.696732581668;12136.757264854654;12260.816087758601;3933.7639741615594;3942.4874766280486;3984.183242435294;3996.200759848042;4129.132882094764;121433;122027.77972220253;122192;124125;129020.35489822585;10989.395583242998;11264.43677816106;11709.414529273503;11750.529978800752;12251.571195008131;22105.115795368325;22227.99974001174;22517.77482225157;22534.887325563268;22540.774592254194;8257.545834979013;8331.50010999342;8365.916340836635;8366.874496882552;8371.246587807127;4350.216960946997;4495.707778994355;4507.2112380333965;4512.52618475062;4660.906781864343;3594.4428613564414;3740.2893450244364;3740.4389341598435;3750.718696097749;3755.6995440364703;589.6756783768996;658.973641054362;675.4123912196322;686.1251903822566;700.4256509662029;544.3386285662939;629.8425393651587;648.8832010238202;670.5535033453526;702.9964850175717;8128.471649342774;9244.583993720298;9603.711888643353;9606.759831004281;9612.51937403127;647.3817504283398;676.3439463720183;686.2862622872157;696.3384784454788;696.853660731242;4600.3099535070005;4683.859484215479;4701.64737644678;4714.224451735766;4825.10735513931;125234;133319;164490;165240;168845;143595;144137.55862441452;146235.80646290313;148308.51691482947;149031;79672;124342;124551;125155.98752099993;129885.35057324653;9010.549472526478;9016.59425325875;9022.188003079755;9024.548772561477;9089.77275568116;69138.65430673015;70572.64713676399;70666.6466667684;70888.58222835617;71022.93465598124;102752;103506;105733;107914;108252.4587377058;11473.196876218584;11803.468843902217;11896.940515297369;12312;12563.74872502563;13235.80146297787;13454;13477.932610336886;14033.789493157406;14073;12312.26126432417;12326;12329;12337.814932775836;12391.442385092512;11250.774984500427;12497.625071247878;12537.310447925453;14240.287985600471;14478.493252736525;13731.519396821062;13764.93117534406;13809.585712428643;13970.161790292615;14036.508722194674;11693.953558127123;11787.057035437316;11866.40667966581;11869.151007426215;12137.939310303393;9048.50233237152;10173.694789156338;11507.194496385173;11569.942150289195;11596.1719640997 13:10:12 INFO - PROCESS | 1649 | __end_tp_report 13:10:12 INFO - PROCESS | 1649 | __start_cc_report 13:10:12 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,1416 13:10:12 INFO - PROCESS | 1649 | __end_cc_report 13:10:12 INFO - PROCESS | 1649 | __startTimestamp1470946212933__endTimestamp 13:10:12 INFO - PROCESS | 1649 | 13:10:12 INFO - PROCESS | 1649 | ------- Summary: start ------- 13:10:12 INFO - PROCESS | 1649 | Number of tests: 6 13:10:12 INFO - PROCESS | 1649 | 13:10:12 INFO - PROCESS | 1649 | [#0] dojo.html Cycles:165 Average:5607.47 Median:5723.86 stddev:634.10 (11.1%) stddev-sans-first:595.90 13:10:12 INFO - PROCESS | 1649 | Values: 2777.0 3292.4 3308.1 3331.6 3362.1 5441.7 5535.1 5547.5 5567.6 5602.1 5536.6 5584.6 5585.4 5588.0 5591.0 5054.3 5090.6 5115.6 5161.1 5179.0 5101.2 5173.3 5178.0 5182.8 5198.1 5087.5 5101.6 5107.9 5131.8 5132.9 5187.7 5199.8 5207.0 5215.7 5276.4 5031.8 5043.5 5081.3 5120.8 5122.8 5498.6 5516.3 5556.4 5586.7 5590.7 6094.1 6094.2 6107.9 6116.7 6117.3 5153.7 6041.8 6132.0 6164.7 6188.0 5680.0 5716.7 5719.5 5725.3 5729.5 6319.1 6320.6 6328.3 6333.8 6342.8 5796.5 5831.4 5844.6 5896.7 5978.5 4814.4 6053.3 6090.6 6097.5 6109.8 5019.3 6074.5 6098.8 6099.1 6105.0 5010.8 5982.5 6089.9 6092.2 6126.1 4810.3 4906.2 4911.3 4923.3 4941.2 4106.5 4421.2 4461.9 4462.5 4470.4 5559.5 5584.3 5593.4 5608.6 5676.1 6137.4 6187.9 6191.6 6201.3 6202.9 5488.9 5578.2 5596.5 5598.6 5611.7 5315.3 5315.7 5354.6 5360.9 5395.9 6240.3 6242.3 6242.4 6255.7 6272.9 6083.9 6141.8 6158.9 6165.6 6178.9 6141.9 6142.6 6212.7 6224.0 6242.9 6053.6 6116.5 6124.2 6133.1 6133.6 6105.0 6126.2 6136.0 6142.1 6159.0 5866.7 5947.8 5970.2 5986.2 5987.5 5288.6 5728.9 5837.3 5875.6 5969.6 5723.9 5837.1 5865.3 5876.1 5876.4 5875.2 5877.7 5887.6 5919.2 5924.4 5615.3 5666.7 5673.4 5703.7 5781.7 13:10:12 INFO - PROCESS | 1649 | 13:10:12 INFO - PROCESS | 1649 | [#1] ext.html Cycles:180 Average:74335.15 Median:37576.91 stddev:167019.37 (444.5%) stddev-sans-first:167395.86 13:10:12 INFO - PROCESS | 1649 | Values: 483.5 486.0 500.2 502.8 535.3 31213.5 41134.8 42195.4 42501.8 44253.8 43263.8 43390.0 43452.0 43691.9 43712.8 8664.8 9641.4 9840.8 9845.6 9850.8 8604.0 8829.8 8839.0 8839.2 11667.7 11858.0 11940.2 11969.5 11982.3 12017.5 11774.4 11898.5 11900.4 11905.2 11905.9 11730.3 11817.0 11846.2 11855.3 11856.3 41055.6 41470.2 41478.2 41496.8 41512.6 3145.7 3651.4 3739.9 4079.3 4311.2 30699.0 31451.1 32052.7 32422.0 32818.3 2995.3 3210.1 3235.1 3325.4 3393.9 497925.5 513695.0 514046.0 515840.0 517040.0 57908.0 58235.0 58242.4 58325.4 58532.0 61644.4 62039.4 62566.7 62739.4 62773.0 16347.9 16571.8 16577.8 16579.9 16640.4 12824.6 12904.9 12911.9 12923.0 12937.4 13750.4 14211.0 14239.9 14421.4 15667.6 5602.3 6741.0 6772.0 6796.1 6830.6 876.5 945.2 951.9 982.6 1054.0 29845.3 30053.5 30481.5 31006.8 31226.1 1039.7 1059.7 1112.2 1130.5 1155.5 14625.0 15396.3 15715.4 15729.8 16193.4 641429.0 647486.0 648346.0 649281.0 651285.0 51393.5 58412.0 58459.0 58842.4 59034.7 1375.3 1396.6 1423.7 1425.8 1431.8 29757.8 30666.2 31195.7 31458.0 32220.5 51381.7 51883.7 51950.5 51985.7 52067.2 600047.0 654892.5 668683.3 668822.0 859104.0 25827.6 26258.0 26278.2 26338.3 26341.5 46563.8 46583.0 47558.0 47714.3 48117.3 36275.0 38303.7 38691.9 39763.6 39896.8 37719.0 38400.5 39091.6 39311.8 39895.8 34974.1 36738.1 37081.1 37434.8 37971.8 32635.7 34178.8 34470.5 34489.3 34550.3 23828.4 24680.1 24803.8 24838.5 25457.6 13:10:12 INFO - PROCESS | 1649 | 13:10:12 INFO - PROCESS | 1649 | [#2] jquery.html Cycles:180 Average:34606.94 Median:14181.31 stddev:94931.44 (669.4%) stddev-sans-first:95162.90 13:10:12 INFO - PROCESS | 1649 | Values: 349.6 351.5 369.2 378.8 400.9 15383.3 16669.5 20267.4 20780.8 20917.0 17757.8 18216.1 18231.3 18371.3 21167.6 5319.9 5761.4 5971.7 5991.7 5992.4 4277.3 4509.9 6016.0 6238.8 6628.2 6851.7 6857.8 6897.6 6984.4 7002.1 5466.5 6836.4 6920.4 6920.5 6920.8 5830.7 5890.3 5925.0 5988.6 5989.7 24375.8 25895.9 26566.6 26637.7 26839.5 2171.5 2295.2 2361.4 2466.7 2591.4 15740.4 15900.4 15980.1 16081.6 16848.0 1689.4 1877.4 1930.6 1962.4 1975.1 117384.0 122112.0 123173.0 123933.4 127097.0 10748.4 10819.0 11249.2 11258.7 11443.0 13954.4 13979.6 13999.0 14007.4 14009.8 8491.7 8750.0 8782.6 8888.7 8891.5 8816.7 8823.9 8827.9 8987.6 9048.8 6635.4 6788.4 6790.5 6790.7 6805.8 1689.7 1747.9 1822.5 1831.3 1867.1 774.4 888.0 913.8 942.4 1039.6 21314.8 22275.6 22839.0 22843.8 22860.9 932.9 948.5 957.0 959.4 989.6 11716.1 11854.1 11900.5 12098.3 12118.2 530208.0 563752.0 565486.3 566230.0 608834.0 109446.0 115695.0 117770.4 124799.0 125686.0 973.5 991.6 1015.4 1017.0 1032.8 17204.7 17974.3 19410.9 19940.6 19975.9 54685.2 55127.7 55676.9 56755.4 56771.0 79834.8 79874.0 85716.7 87243.6 92108.0 7920.0 8042.5 8412.0 8448.3 8488.5 11908.8 12910.8 12914.9 13339.7 13533.9 11211.6 11273.9 11600.1 11614.5 11755.2 8792.4 9405.9 11470.3 11515.8 11784.6 9422.9 11250.4 11373.5 11785.3 11921.7 6885.2 8397.7 8480.9 8481.6 8555.6 11175.0 11348.6 11767.0 11773.5 11846.8 13:10:12 INFO - PROCESS | 1649 | 13:10:12 INFO - PROCESS | 1649 | [#3] mootools.html Cycles:180 Average:5730.26 Median:4817.42 stddev:3236.86 (67.2%) stddev-sans-first:3230.35 13:10:12 INFO - PROCESS | 1649 | Values: 1502.7 1539.7 1714.2 1862.7 1893.2 8436.7 9019.8 9022.6 9039.6 9044.3 7155.3 7745.7 7992.6 8727.5 8845.2 2078.9 2300.2 2331.3 2351.2 2367.1 2295.1 2316.9 2341.6 2342.8 2348.7 2312.4 2331.2 2338.8 2351.2 2351.9 2322.8 2324.6 2330.9 2331.2 2334.2 1946.6 2096.1 2211.8 2325.5 2338.8 8898.7 9030.7 9034.5 9047.2 9059.9 5138.9 5312.5 5313.8 5314.8 5315.6 3481.4 3528.8 3659.9 3692.3 3710.8 1057.3 1088.6 1099.0 1109.5 1113.6 11525.1 11662.1 11666.1 11670.2 11687.7 4535.5 4617.8 4791.0 4811.6 4817.9 9898.3 9900.9 9909.9 9922.1 9923.9 5325.4 5327.0 5327.2 5327.7 5341.6 4354.3 4518.2 4711.6 4817.0 4884.8 3394.9 3875.6 3947.9 3999.6 4033.4 1153.6 1223.2 1272.9 1275.9 1296.6 897.5 926.5 927.3 944.2 964.8 4988.7 5704.5 5907.2 5919.8 5995.2 443.2 940.6 1027.6 1049.5 1098.5 3828.7 3876.9 4007.9 4031.6 4037.4 10426.4 10456.3 10783.7 10960.3 11036.9 11090.6 11094.2 11100.5 11104.6 11120.4 7771.5 8252.7 8336.1 8350.5 8364.2 5907.9 5918.4 6060.3 6089.5 6140.3 10136.5 10142.0 10163.8 10179.3 10187.8 9820.4 10265.9 10268.8 10434.5 10454.8 7014.5 7225.9 7235.6 7562.7 7723.4 7681.4 7947.0 8344.2 8466.7 8614.3 7435.8 7560.8 7565.5 7664.3 7724.2 7292.9 7411.1 7433.6 7449.8 7475.2 7001.5 7106.9 7122.0 7122.3 7122.4 4849.7 4973.3 5028.9 5049.0 5129.1 4981.6 5018.5 5147.9 5157.6 5251.1 13:10:12 INFO - PROCESS | 1649 | 13:10:12 INFO - PROCESS | 1649 | [#4] prototype.html Cycles:180 Average:4363.57 Median:4877.46 stddev:1317.01 (27.0%) stddev-sans-first:1284.87 13:10:12 INFO - PROCESS | 1649 | Values: 298.4 301.5 312.0 317.3 319.9 5127.3 5159.2 5170.3 5172.7 5239.4 4009.9 4429.1 4922.9 4993.8 5149.6 3960.8 3975.2 3987.8 4011.1 4017.8 4003.8 4005.5 4011.7 4022.4 4028.9 3607.6 3708.8 3970.8 3987.0 4026.8 3926.3 3947.8 3971.6 3974.0 3984.6 3482.4 3488.8 3489.5 3490.3 3517.0 4337.4 4379.6 5022.6 5060.5 5122.0 4509.4 4569.2 4630.3 4643.3 4666.0 4576.2 5526.3 5532.9 5549.8 5596.2 3464.0 3493.7 3765.2 3950.6 3971.5 4718.9 4852.7 5325.4 5600.9 5758.5 3169.3 3494.5 3520.8 3741.3 3756.0 3837.5 3852.0 3853.9 3862.8 3869.2 3751.2 4095.8 4383.4 4468.6 4482.5 5449.5 5479.8 5500.8 5510.4 5544.8 1149.9 1171.8 1214.5 1303.9 1378.6 782.1 867.6 899.1 923.3 925.3 4200.0 4214.4 4427.6 4849.8 5116.6 4958.4 5363.5 5488.5 5490.2 5498.8 4542.0 4744.3 4995.7 5040.2 5072.6 2490.2 2530.9 2694.5 2873.8 2903.2 5621.7 5651.8 5656.6 5676.8 5740.6 5345.8 5493.9 5522.8 5604.4 5670.5 4332.0 5261.3 5455.9 5713.9 5744.4 5080.1 5472.5 5513.6 5546.8 5562.9 4729.7 4971.5 5347.0 5401.9 5449.5 4476.0 4997.0 5376.0 5687.4 5696.5 3643.9 3857.7 3858.7 3862.2 3870.9 4691.2 4835.8 5197.4 5293.9 5460.6 5289.6 5310.9 5319.3 5331.1 5386.8 4585.7 4996.8 5343.2 5381.1 5408.6 4382.3 5077.2 5212.3 5231.6 5261.7 4256.2 5257.2 5411.3 5414.0 5453.4 4902.2 5108.8 5273.9 5290.7 5303.7 13:10:12 INFO - PROCESS | 1649 | 13:10:12 INFO - PROCESS | 1649 | [#5] yui.html Cycles:180 Average:26649.72 Median:22526.33 stddev:43474.28 (193.0%) stddev-sans-first:43552.60 13:10:12 INFO - PROCESS | 1649 | Values: 707.9 717.6 730.5 782.9 837.8 15099.0 16664.1 16716.8 16742.8 16788.4 15055.5 15176.5 15212.2 15220.0 15233.9 702.0 725.2 728.2 729.1 837.6 712.5 719.1 840.2 867.1 870.2 906.8 915.1 921.4 922.0 922.3 906.8 909.2 911.0 911.1 912.6 537.6 560.5 684.2 686.9 686.9 14756.9 15287.2 15336.3 15368.3 15371.5 12693.4 14749.4 15308.8 15471.5 15523.0 10400.8 12092.9 12130.7 12136.8 12260.8 3933.8 3942.5 3984.2 3996.2 4129.1 121433.0 122027.8 122192.0 124125.0 129020.4 10989.4 11264.4 11709.4 11750.5 12251.6 22105.1 22228.0 22517.8 22534.9 22540.8 8257.5 8331.5 8365.9 8366.9 8371.2 4350.2 4495.7 4507.2 4512.5 4660.9 3594.4 3740.3 3740.4 3750.7 3755.7 589.7 659.0 675.4 686.1 700.4 544.3 629.8 648.9 670.6 703.0 8128.5 9244.6 9603.7 9606.8 9612.5 647.4 676.3 686.3 696.3 696.9 4600.3 4683.9 4701.6 4714.2 4825.1 125234.0 133319.0 164490.0 165240.0 168845.0 143595.0 144137.6 146235.8 148308.5 149031.0 79672.0 124342.0 124551.0 125156.0 129885.4 9010.5 9016.6 9022.2 9024.5 9089.8 69138.7 70572.6 70666.6 70888.6 71022.9 102752.0 103506.0 105733.0 107914.0 108252.5 11473.2 11803.5 11896.9 12312.0 12563.7 13235.8 13454.0 13477.9 14033.8 14073.0 12312.3 12326.0 12329.0 12337.8 12391.4 11250.8 12497.6 12537.3 14240.3 14478.5 13731.5 13764.9 13809.6 13970.2 14036.5 11694.0 11787.1 11866.4 11869.2 12137.9 9048.5 10173.7 11507.2 11569.9 11596.2 13:10:12 INFO - PROCESS | 1649 | -------- Summary: end -------- 13:10:12 INFO - PROCESS | 1649 | 13:10:13 INFO - TEST-INFO | 1649: exit 0 13:10:13 INFO - TEST-OK | dromaeo_css | took 1118540ms 13:10:13 INFO - TEST-START | kraken 13:10:13 INFO - operating with platform_type : mac_ 13:10:13 INFO - Initialising browser for kraken test... 13:10:13 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/tmpMkYUXw/profile http://localhost:49274/getInfo.html) 13:10:15 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 13:10:15 INFO - PROCESS | 1659 | colorDepth:24 13:10:15 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 13:10:15 INFO - PROCESS | 1659 | __metrics 13:10:15 INFO - PROCESS | 1659 | 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" 13:10:15 INFO - PROCESS | 1659 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:10:16 INFO - TEST-INFO | 1659: exit 0 13:10:16 INFO - Browser initialized. 13:10:16 INFO - Running cycle 1/1 for kraken test... 13:10:16 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/tmpMkYUXw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:10:28 INFO - PROCESS | 1660 | RSS: Main: 243572736 13:10:28 INFO - PROCESS | 1660 | 13:10:31 INFO - PROCESS | 1660 | 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) 13:10:32 INFO - PROCESS | 1660 | RSS: Main: 292892672 13:10:32 INFO - PROCESS | 1660 | 13:10:34 INFO - PROCESS | 1660 | 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) 13:10:35 INFO - PROCESS | 1660 | RSS: Main: 300548096 13:10:35 INFO - PROCESS | 1660 | 13:10:38 INFO - PROCESS | 1660 | 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) 13:10:39 INFO - PROCESS | 1660 | RSS: Main: 394346496 13:10:39 INFO - PROCESS | 1660 | 13:10:41 INFO - PROCESS | 1660 | 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) 13:10:42 INFO - PROCESS | 1660 | RSS: Main: 297238528 13:10:42 INFO - PROCESS | 1660 | 13:10:44 INFO - PROCESS | 1660 | 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) 13:10:44 INFO - PROCESS | 1660 | RSS: Main: 301383680 13:10:44 INFO - PROCESS | 1660 | 13:10:47 INFO - PROCESS | 1660 | 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) 13:10:48 INFO - PROCESS | 1660 | RSS: Main: 337461248 13:10:48 INFO - PROCESS | 1660 | 13:10:51 INFO - PROCESS | 1660 | 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) 13:10:51 INFO - PROCESS | 1660 | RSS: Main: 340627456 13:10:51 INFO - PROCESS | 1660 | 13:10:54 INFO - PROCESS | 1660 | 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) 13:10:55 INFO - PROCESS | 1660 | RSS: Main: 340115456 13:10:55 INFO - PROCESS | 1660 | 13:10:56 INFO - PROCESS | 1660 | 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) 13:10:57 INFO - PROCESS | 1660 | RSS: Main: 313696256 13:10:57 INFO - PROCESS | 1660 | 13:10:59 INFO - PROCESS | 1660 | 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) 13:10:59 INFO - PROCESS | 1660 | RSS: Main: 320212992 13:10:59 INFO - PROCESS | 1660 | 13:11:02 INFO - PROCESS | 1660 | 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) 13:11:02 INFO - PROCESS | 1660 | RSS: Main: 327991296 13:11:02 INFO - PROCESS | 1660 | 13:11:05 INFO - PROCESS | 1660 | 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) 13:11:06 INFO - PROCESS | 1660 | RSS: Main: 329007104 13:11:06 INFO - PROCESS | 1660 | 13:11:08 INFO - PROCESS | 1660 | 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) 13:11:09 INFO - PROCESS | 1660 | RSS: Main: 330432512 13:11:09 INFO - PROCESS | 1660 | 13:11:11 INFO - PROCESS | 1660 | 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) 13:11:11 INFO - PROCESS | 1660 | RSS: Main: 373481472 13:11:11 INFO - PROCESS | 1660 | 13:11:11 INFO - PROCESS | 1660 | __start_tp_report 13:11:11 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 13:11:11 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 13:11:11 INFO - PROCESS | 1660 | |i|pagename|runs| 13:11:11 INFO - PROCESS | 1660 | |0;ai-astar;101;112;113;113;111;113;112;112;112;111 13:11:11 INFO - PROCESS | 1660 | |1;audio-beat-detection;143;113;105;164;110;106;128;106;140;107 13:11:11 INFO - PROCESS | 1660 | |2;audio-dft;184;184;181;184;184;185;181;183;186;183 13:11:11 INFO - PROCESS | 1660 | |3;audio-fft;77;57;76;111;81;67;64;64;82;56 13:11:11 INFO - PROCESS | 1660 | |4;audio-oscillator;81;67;97;70;78;65;95;70;67;122 13:11:11 INFO - PROCESS | 1660 | |5;imaging-gaussian-blur;87;85;87;84;87;84;88;85;87;84 13:11:11 INFO - PROCESS | 1660 | |6;imaging-darkroom;93;93;93;100;92;92;93;92;93;93 13:11:11 INFO - PROCESS | 1660 | |7;imaging-desaturate;83;84;84;85;84;84;85;83;85;83 13:11:11 INFO - PROCESS | 1660 | |8;json-parse-financial;55;54;54;55;55;54;52;53;55;53 13:11:11 INFO - PROCESS | 1660 | |9;json-stringify-tinderbox;70;76;69;71;79;71;71;72;72;73 13:11:11 INFO - PROCESS | 1660 | |10;stanford-crypto-aes;86;87;80;105;85;85;79;86;84;85 13:11:11 INFO - PROCESS | 1660 | |11;stanford-crypto-ccm;138;137;137;136;135;135;133;137;156;139 13:11:11 INFO - PROCESS | 1660 | |12;stanford-crypto-pbkdf2;163;165;163;164;163;164;163;163;161;162 13:11:11 INFO - PROCESS | 1660 | |13;stanford-crypto-sha256-iterative;67;68;68;66;67;65;66;65;66;65 13:11:11 INFO - PROCESS | 1660 | __end_tp_report 13:11:11 INFO - PROCESS | 1660 | __start_cc_report 13:11:11 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,2081 13:11:11 INFO - PROCESS | 1660 | __end_cc_report 13:11:11 INFO - PROCESS | 1660 | __startTimestamp1470946271602__endTimestamp 13:11:11 INFO - PROCESS | 1660 | 13:11:11 INFO - PROCESS | 1660 | ------- Summary: start ------- 13:11:11 INFO - PROCESS | 1660 | Number of tests: 14 13:11:11 INFO - PROCESS | 1660 | 13:11:11 INFO - PROCESS | 1660 | [#0] ai-astar Cycles:10 Average:111.00 Median:112.00 stddev:3.59 (3.2%) stddev-sans-first:0.78 13:11:11 INFO - PROCESS | 1660 | Values: 101.0 112.0 113.0 113.0 111.0 113.0 112.0 112.0 112.0 111.0 13:11:11 INFO - PROCESS | 1660 | 13:11:11 INFO - PROCESS | 1660 | [#1] audio-beat-detection Cycles:10 Average:122.20 Median:120.50 stddev:20.59 (17.1%) stddev-sans-first:20.42 13:11:11 INFO - PROCESS | 1660 | Values: 143.0 113.0 105.0 164.0 110.0 106.0 128.0 106.0 140.0 107.0 13:11:11 INFO - PROCESS | 1660 | 13:11:11 INFO - PROCESS | 1660 | [#2] audio-dft Cycles:10 Average:183.50 Median:184.00 stddev:1.58 (0.9%) stddev-sans-first:1.67 13:11:11 INFO - PROCESS | 1660 | Values: 184.0 184.0 181.0 184.0 184.0 185.0 181.0 183.0 186.0 183.0 13:11:11 INFO - PROCESS | 1660 | 13:11:11 INFO - PROCESS | 1660 | [#3] audio-fft Cycles:10 Average:73.50 Median:71.50 stddev:16.17 (22.6%) stddev-sans-first:17.11 13:11:11 INFO - PROCESS | 1660 | Values: 77.0 57.0 76.0 111.0 81.0 67.0 64.0 64.0 82.0 56.0 13:11:11 INFO - PROCESS | 1660 | 13:11:11 INFO - PROCESS | 1660 | [#4] audio-oscillator Cycles:10 Average:81.20 Median:74.00 stddev:18.35 (24.8%) stddev-sans-first:19.47 13:11:11 INFO - PROCESS | 1660 | Values: 81.0 67.0 97.0 70.0 78.0 65.0 95.0 70.0 67.0 122.0 13:11:11 INFO - PROCESS | 1660 | 13:11:11 INFO - PROCESS | 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:85.80 Median:87.00 stddev:1.55 (1.8%) stddev-sans-first:1.58 13:11:11 INFO - PROCESS | 1660 | Values: 87.0 85.0 87.0 84.0 87.0 84.0 88.0 85.0 87.0 84.0 13:11:11 INFO - PROCESS | 1660 | 13:11:11 INFO - PROCESS | 1660 | [#6] imaging-darkroom Cycles:10 Average:93.40 Median:93.00 stddev:2.37 (2.5%) stddev-sans-first:2.51 13:11:11 INFO - PROCESS | 1660 | Values: 93.0 93.0 93.0 100.0 92.0 92.0 93.0 92.0 93.0 93.0 13:11:11 INFO - PROCESS | 1660 | 13:11:11 INFO - PROCESS | 1660 | [#7] imaging-desaturate Cycles:10 Average:84.00 Median:84.00 stddev:0.82 (1.0%) stddev-sans-first:0.78 13:11:11 INFO - PROCESS | 1660 | Values: 83.0 84.0 84.0 85.0 84.0 84.0 85.0 83.0 85.0 83.0 13:11:11 INFO - PROCESS | 1660 | 13:11:11 INFO - PROCESS | 1660 | [#8] json-parse-financial Cycles:10 Average:54.00 Median:54.50 stddev:1.05 (1.9%) stddev-sans-first:1.05 13:11:11 INFO - PROCESS | 1660 | Values: 55.0 54.0 54.0 55.0 55.0 54.0 52.0 53.0 55.0 53.0 13:11:11 INFO - PROCESS | 1660 | 13:11:11 INFO - PROCESS | 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:72.40 Median:72.00 stddev:2.99 (4.2%) stddev-sans-first:3.04 13:11:11 INFO - PROCESS | 1660 | Values: 70.0 76.0 69.0 71.0 79.0 71.0 71.0 72.0 72.0 73.0 13:11:11 INFO - PROCESS | 1660 | 13:11:11 INFO - PROCESS | 1660 | [#10] stanford-crypto-aes Cycles:10 Average:86.20 Median:85.00 stddev:7.10 (8.4%) stddev-sans-first:7.53 13:11:11 INFO - PROCESS | 1660 | Values: 86.0 87.0 80.0 105.0 85.0 85.0 79.0 86.0 84.0 85.0 13:11:11 INFO - PROCESS | 1660 | 13:11:11 INFO - PROCESS | 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:138.30 Median:137.00 stddev:6.45 (4.7%) stddev-sans-first:6.84 13:11:11 INFO - PROCESS | 1660 | Values: 138.0 137.0 137.0 136.0 135.0 135.0 133.0 137.0 156.0 139.0 13:11:11 INFO - PROCESS | 1660 | 13:11:11 INFO - PROCESS | 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.10 Median:163.00 stddev:1.10 (0.7%) stddev-sans-first:1.17 13:11:11 INFO - PROCESS | 1660 | Values: 163.0 165.0 163.0 164.0 163.0 164.0 163.0 163.0 161.0 162.0 13:11:11 INFO - PROCESS | 1660 | 13:11:11 INFO - PROCESS | 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:66.50 stddev:1.16 (1.7%) stddev-sans-first:1.20 13:11:11 INFO - PROCESS | 1660 | Values: 67.0 68.0 68.0 66.0 67.0 65.0 66.0 65.0 66.0 65.0 13:11:11 INFO - PROCESS | 1660 | -------- Summary: end -------- 13:11:11 INFO - PROCESS | 1660 | 13:11:12 INFO - TEST-INFO | 1660: exit 0 13:11:12 INFO - TEST-OK | kraken | took 58943ms 13:11:12 INFO - SUITE-END | took 1177s 13:11:12 INFO - Completed test suite (00:19:37) 13:11:12 INFO - javascript benchmark 13:11:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2776.9722302776977, 3292.4073666739987, 3308.123347312961, 3331.5502407175054, 3362.1090411041077, 5441.7279136043235, 5535.086710692736, 5547.472990065938, 5567.582431317647, 5602.07565751651, 5536.640118392302, 5584.581156413265, 5585.357683866357, 5587.9941610510095, 5591.049521581331, 5054.317667114948, 5090.643654944155, 5115.565176959944, 5161.122609156434, 5178.974105129469, 5101.183810590306, 5173.30160428343, 5177.974110129444, 5182.766775495112, 5198.064348417283, 5087.516685914832, 5101.617378696594, 5107.948920510803, 5131.794728210867, 5132.871678208037, 5187.714675692854, 5199.818006369759, 5207.0, 5215.687058776485, 5276.445973172838, 5031.77357018935, 5043.52086551777, 5081.314022606938, 5120.846374608767, 5122.846314610568, 5498.642588231752, 5516.3380394352935, 5556.361018482886, 5586.720663966786, 5590.692511911843, 6094.097553225332, 6094.207752992127, 6107.881008323032, 6116.746067056243, 6117.339610596886, 5153.716545589991, 6041.818745437644, 6131.988221943374, 6164.6917654117115, 6187.978983467768, 5680.0, 5716.657000579895, 5719.456651618048, 5725.341585717696, 5729.541636669056, 6319.052142178618, 6320.55756097078, 6328.272248691367, 6333.841653958688, 6342.778002769881, 5796.478316951495, 5831.446012628793, 5844.649321040752, 5896.705164741746, 5978.461938425476, 4814.422269327703, 6053.297721791484, 6090.634561926298, 6097.451229389376, 6109.84725381869, 5019.29729837823, 6074.453299203093, 6098.756049758049, 6099.146119543268, 6104.969475152596, 5010.799568017312, 5982.46157845796, 6089.878202436015, 6092.177556029879, 6126.142340072393, 4810.278458231293, 4906.165951788204, 4911.336969509142, 4923.286123512071, 4941.184704523714, 4106.486689163855, 4421.248387774086, 4461.888452788707, 4462.48681401643, 4470.418845550123, 5559.527440167551, 5584.329880414295, 5593.440655934455, 5608.579356548275, 5676.091825307931, 6137.355577664371, 6187.876242475215, 6191.5665903387235, 6201.286852011986, 6202.937970620236, 5488.890222195534, 5578.219049333096, 5596.468335508121, 5598.636088654224, 5611.7474713637985, 5315.335583052119, 5315.707636080053, 5354.625176237626, 5360.865978350573, 5395.865103372447, 6240.31356550779, 6242.344553821783, 6242.406971337716, 6255.655938923312, 6272.905906411499, 6083.939160608515, 6141.785037523665, 6158.876822463615, 6165.599236049642, 6178.907316390168, 6141.88493709207, 6142.590862071594, 6212.658303793335, 6223.96888015557, 6242.906356404566, 6053.576249662658, 6116.480099191618, 6124.234470691164, 6133.110698948538, 6133.570650054453, 6104.992676208376, 6126.203593532812, 6136.048912418582, 6142.109394137736, 6158.969205153946, 5866.706664666664, 5947.762089516371, 5970.194023806905, 5986.221791167294, 5987.520998320037, 5288.550473209666, 5728.856778580569, 5837.29952405714, 5875.618084824644, 5969.582129250911, 5723.8569035774435, 5837.124431335334, 5865.296164460292, 5876.147958546072, 5876.382979787061, 5875.177475153568, 5877.735501902339, 5887.558433117448, 5919.200907877382, 5924.437178468039, 5615.2700148980375, 5666.688332141607, 5673.404292549388, 5703.714814259353, 5781.710914454345], "value": 5567.475489426826, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [483.50923812330467, 485.973271470069, 500.1647249094013, 502.81144570785955, 535.2987586261995, 31213.531797023035, 41134.794326028365, 42195.36706949398, 42501.7874910625, 44253.77873110638, 43263.78368108155, 43390.0, 43451.95836291461, 43691.90770230746, 43712.78143609277, 8664.783380415509, 9641.421514709122, 9840.753981150458, 9845.60617575296, 9850.753731156707, 8604.010538788025, 8829.807975923233, 8838.983516895574, 8839.16027977341, 11667.708307292301, 11857.992070674003, 11940.164188506807, 11969.521219151267, 11982.281063136195, 12017.459214335333, 11774.352410617412, 11898.524059037629, 11900.404979750978, 11905.226160299639, 11905.880941190608, 11730.296182229093, 11817.0, 11846.17076804624, 11855.347955862424, 11856.288622682667, 41055.58944410548, 41470.1705965882, 41478.1704365911, 41496.79251603753, 41512.58487415117, 3145.716885480295, 3651.415773476231, 3739.9439008414897, 4079.3269110596716, 4311.202427550882, 30699.0, 31451.05646830576, 32052.679473205437, 32422.0, 32818.3436331272, 2995.266159790865, 3210.1172177651333, 3235.094173631387, 3325.4346761050438, 3393.9321213575836, 497925.51037244947, 513695.0, 514046.0, 515840.0, 517040.0, 57908.0, 58235.0, 58242.41757582455, 58325.416745832845, 58532.0, 61644.38355616386, 62039.37960620426, 62566.687166564785, 62739.372606273355, 62773.0, 16347.937384070236, 16571.83428165703, 16577.839551231296, 16579.91710041442, 16640.417585384454, 12824.615261542169, 12904.93547532275, 12911.935440322739, 12923.0, 12937.353132343345, 13750.381232844546, 14211.0, 14239.857601423853, 14421.35103920326, 15667.608309792118, 5602.299712535933, 6740.955151951455, 6772.0, 6796.116504854436, 6830.624315662587, 876.4653561327585, 945.191860958884, 951.871497347863, 982.6292322210619, 1053.9683809485728, 29845.253868653024, 30053.549196762066, 30481.542776858365, 31006.84496577548, 31226.063218103493, 1039.7244730146517, 1059.7350662334416, 1112.1769890281134, 1130.5082289204058, 1155.5146838327923, 14625.0, 15396.307166177548, 15715.4499592516, 15729.764053539206, 16193.352265909467, 641429.0, 647486.0, 648346.0, 649281.0, 651285.0, 51393.486065139616, 58412.0, 58459.0, 58842.41157588369, 59034.704826475594, 1375.2848518770384, 1396.629893078335, 1423.7437261293067, 1425.7576212043978, 1431.8424973253143, 29757.809687612254, 30666.23334416568, 31195.688043119277, 31458.0, 32220.516692250105, 51381.743091285796, 51883.74058129685, 51950.48049519456, 51985.7400712994, 52067.2189917144, 600047.0, 654892.4510755022, 668683.3131668621, 668822.0, 859104.0, 25827.612585810853, 26258.0, 26278.21165365042, 26338.34154146085, 26341.473170536865, 46563.76718116388, 46583.0, 47558.0, 47714.28428573644, 48117.278240827116, 36275.0, 38303.65937192185, 38691.890015619516, 39763.60236397599, 39896.800515998395, 37719.0, 38400.47074311539, 39091.6090839088, 39311.803440982614, 39895.80312590627, 34974.12564685802, 36738.08154796151, 37081.072973175884, 37434.812825935696, 37971.81014094912, 32635.673643263264, 34178.80374186891, 34470.48294275538, 34489.343938567974, 34550.30899382049, 23828.404289892893, 24680.13619523308, 24803.751962480143, 24838.50322993494, 25457.61813572835], "value": 20379.964884294888, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.59447041431946, 351.4956038085347, 369.2467366572193, 378.7538100234847, 400.911799404131, 15383.307751151196, 16669.49991500254, 20267.391978240677, 20780.792192078112, 20916.954152292383, 17757.822421775778, 18216.089195540237, 18231.270749170017, 18371.265149394007, 21167.57664846702, 5319.909418569204, 5761.423857614236, 5971.701414929258, 5991.700414979256, 5992.430719081702, 4277.251480990814, 4509.932351014738, 6016.037434010559, 6238.781642642486, 6628.237752658433, 6851.663925534523, 6857.794266172023, 6897.586144831326, 6984.3714065734075, 7002.124734408199, 5466.535344495723, 6836.418904393107, 6920.411764999981, 6920.515563910529, 6920.826979325507, 5830.7084645767545, 5890.263717035371, 5924.9703751480965, 5988.550858685616, 5989.700514974234, 24375.756242437525, 25895.870520647277, 26566.6015009775, 26637.73362266372, 26839.463210735677, 2171.533120379126, 2295.231097582312, 2361.397843549901, 2466.7409921958115, 2591.3521619595103, 15740.44908428211, 15900.443484477988, 15980.121093339862, 16081.597960050975, 16847.9891206528, 1689.4171510828867, 1877.4180004198606, 1930.6428310762553, 1962.4308950404366, 1975.1309423853602, 117384.0, 122112.0, 123173.0, 123933.38033309866, 127097.0, 10748.355098694103, 10819.026287634151, 11249.156313276537, 11258.71471663445, 11443.027342675965, 13954.441822326993, 13979.580612581436, 13999.0, 14007.369668364949, 14009.789853152211, 8491.702790402307, 8749.956250218707, 8782.648694052294, 8888.688895888612, 8891.510966896753, 8816.735497935073, 8823.911760882438, 8827.867581986275, 8987.595558199897, 9048.819023619491, 6635.412189246198, 6788.422984046312, 6790.49071319653, 6790.694418751168, 6805.795826125224, 1689.7465380192823, 1747.851432628241, 1822.5443639090226, 1831.2726550474258, 1867.1317837205765, 774.44240147094, 888.0276097673037, 913.7898283394857, 942.3733217410345, 1039.5841663334727, 21314.78685213159, 22275.554488910457, 22839.0, 22843.771562284164, 22860.885695571415, 932.911373419524, 948.4966328369611, 956.9760755981156, 959.4243453927727, 989.5942663508071, 11716.121290903217, 11854.05167586591, 11900.523979040914, 12098.334591597371, 12118.212316199419, 530208.0, 563752.0, 565486.3451365517, 566230.0, 608834.0, 109446.0, 115695.0, 117770.41114794371, 124799.0, 125686.0, 973.5083782689838, 991.5934466868549, 1015.3907655406697, 1016.9745756356002, 1032.8037672842136, 17204.70964677679, 17974.2810287587, 19410.90294548518, 19940.60118797645, 19975.900120499304, 54685.1797223034, 55127.724361377936, 55676.88646227134, 56755.43244567667, 56771.0, 79834.80247796171, 79874.00314992171, 85716.71407930147, 87243.56378218322, 92108.0, 7919.960400197962, 8042.477238979448, 8411.966066378876, 8448.281896038905, 8488.533130677624, 11908.821367679318, 12910.80633790475, 12914.935425322814, 13339.733205336033, 13533.932330338286, 11211.55153793839, 11273.887261127611, 11600.129990250934, 11614.535418583162, 11755.235909665844, 8792.384533082623, 9405.905940940776, 11470.311781293149, 11515.8272625909, 11784.646460606196, 9422.905770942478, 11250.381229032482, 11373.488193031168, 11785.292882427082, 11921.701957451132, 6885.173779146535, 8397.748067557983, 8480.872786908078, 8481.618327175169, 8555.61499732501, 11175.0, 11348.602798901999, 11767.0, 11773.52905883755, 11846.763064738829], "value": 9921.581370524153, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1502.7069721404328, 1539.6689711711983, 1714.2285971312913, 1862.6647203503364, 1893.2332405375819, 8436.66253349866, 9019.78232938554, 9022.639094436232, 9039.638414463414, 9044.321675874311, 7155.32024457677, 7745.690172393098, 7992.560409177493, 8727.476351418904, 8845.20393164615, 2078.939740732222, 2300.1834348806183, 2331.288956868159, 2351.247600767755, 2367.112332875172, 2295.0819672131197, 2316.872572008539, 2341.613633750429, 2342.847714898535, 2348.6711860339565, 2312.410335364488, 2331.2190416210506, 2338.801201897832, 2351.1888398502488, 2351.9176828810996, 2322.837401381904, 2324.5815753164425, 2330.883455827202, 2331.2423462374795, 2334.1947028275217, 1946.6301402733436, 2096.1301060060055, 2211.7677643847487, 2325.4883925536355, 2338.777816107467, 8898.733038008806, 9030.683926062557, 9034.548272586344, 9047.18575328217, 9059.864102038475, 5138.897222055575, 5312.521873031407, 5313.7608807603365, 5314.7608357624, 5315.601329900234, 3481.408160612702, 3528.752987290891, 3659.9268014639556, 3692.2615476904457, 3710.8330125144435, 1057.3447176375935, 1088.5754555723274, 1098.9670309890716, 1109.4619109731773, 1113.582406597526, 11525.077993760478, 11662.125340599488, 11666.066714662891, 11670.183087183901, 11687.7078073048, 4535.546445355504, 4617.79219935097, 4791.0, 4811.639127065484, 4817.855464336076, 9898.307118501636, 9900.851487227697, 9909.851352229722, 9922.107010368956, 9923.851142232872, 5325.4142044375085, 5327.0, 5327.227552004938, 5327.653702509325, 5341.626086174008, 4354.325079612665, 4518.209313370213, 4711.623070154379, 4816.975915120402, 4884.780184891689, 3394.8981530554124, 3875.593062728373, 3947.940780888289, 3999.6400323970984, 4033.3748269018347, 1153.5674122204173, 1223.1804690857148, 1272.8536218334825, 1275.8787915148046, 1296.5850927703234, 897.5063714956748, 926.4950601921969, 927.3415874728884, 944.2304521814699, 964.7829238421299, 4988.650794444354, 5704.515116215085, 5907.202527658711, 5919.816036792573, 5995.160677505153, 443.18232860372564, 940.5720397219249, 1027.6454623155, 1049.5277125293649, 1098.4727330881221, 3828.674562662149, 3876.8836934891997, 4007.8797636070963, 4031.576684448091, 4037.37420699792, 10426.374417534973, 10456.268061235793, 10783.730406739738, 10960.28758130719, 11036.944815276032, 11090.5563777448, 11094.167937404727, 11100.500477478532, 11104.61133860311, 11120.388378639253, 7771.4559980800805, 8252.711155109542, 8336.124706905688, 8350.457220280663, 8364.205400486944, 5907.911381329198, 5918.378570250234, 6060.333363329942, 6089.54328425361, 6140.263168419639, 10136.543855526372, 10142.036506531867, 10163.796724065625, 10179.287449878437, 10187.796244075225, 9820.361676491006, 10265.948670256601, 10268.794624107626, 10434.478276086316, 10454.790904181722, 7014.508984371045, 7225.855482890418, 7235.565866048054, 7562.697492100475, 7723.382129429519, 7681.385489160742, 7947.046354437273, 8344.207300306462, 8466.74599762008, 8614.267787238156, 7435.814104647211, 7560.773176804704, 7565.470417070972, 7664.34853037498, 7724.150343462327, 7292.890606640798, 7411.0736157980255, 7433.553986760811, 7449.776506704808, 7475.215102414386, 7001.509894307554, 7106.893396599158, 7121.9643901780155, 7122.287771222836, 7122.3589876909255, 4849.690583542353, 4973.303737476828, 5028.899422011466, 5048.974755126201, 5129.128048231884, 4981.551660350441, 5018.4981501849525, 5147.948520514747, 5157.561607263425, 5251.107311756934], "value": 4593.7368353344655, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.35257491244, 301.5085410780428, 311.9968800311997, 317.3035187762861, 319.86885376995434, 5127.256547800566, 5159.200323949789, 5170.327857378545, 5172.741362931857, 5239.4281715485395, 4009.8997525061886, 4429.13631841791, 4922.883160241095, 4993.750312484371, 5149.639525233235, 3960.841566337343, 3975.244703506339, 3987.840486380556, 4011.1376054148354, 4017.774578753479, 4003.75977441353, 4005.5193376794828, 4011.6590089842252, 4022.396640503933, 4028.939565906499, 3607.6392360763975, 3708.831718008832, 3970.7617542947514, 3986.9601303987024, 4026.798660066985, 3926.2736393767245, 3947.842086316544, 3971.6170297821363, 3973.960260397388, 3984.621460961204, 3482.4428091505492, 3488.8255587220538, 3489.45913383426, 3490.2844916791996, 3516.982415087934, 4337.371081193241, 4379.6131108545915, 5022.568567958151, 5060.51925067118, 5122.0, 4509.436320459942, 4569.1547063793305, 4630.282306242538, 4643.326717625959, 4665.953340466586, 4576.244919588264, 5526.286851076179, 5532.917006244909, 5549.77800887964, 5596.2165296858475, 3463.9826800865835, 3493.7290761005297, 3765.171662234304, 3950.6246906543834, 3971.5035620547433, 4718.9292160617615, 4852.708837469692, 5325.360956685223, 5600.943990560124, 5758.539316854642, 3169.271067654406, 3494.5282386877448, 3520.8239588020497, 3741.326561218952, 3756.0, 3837.4627552142724, 3851.980740096282, 3853.8843834685003, 3862.841147655692, 3869.2454971280895, 3751.249750050001, 4095.795210239476, 4383.3863259143745, 4468.597826195658, 4482.529334419839, 5449.509544141047, 5479.75341109651, 5500.80747173847, 5510.393856675762, 5544.805931792368, 1149.8735139134687, 1171.7949358862336, 1214.5384753793503, 1303.8656368958877, 1378.600205940266, 782.1123025366137, 867.6225841758855, 899.1413200393608, 923.2936803345447, 925.3013974449248, 4199.97900010498, 4214.3889136075095, 4427.623651989553, 4849.763310355836, 5116.641835071584, 4958.355413796184, 5363.5440987516495, 5488.506034456918, 5490.231367608538, 5498.752556134984, 4541.977290113528, 4744.288356746447, 4995.675281106716, 5040.152313942611, 5072.644914855999, 2490.1657944588405, 2530.9499305562554, 2694.474577457416, 2873.7700983921236, 2903.245156259388, 5621.678905457215, 5651.802186923438, 5656.60403771732, 5676.772929082873, 5740.588952639901, 5345.825571464964, 5493.857395615713, 5522.818486697732, 5604.439556044363, 5670.489655930904, 4332.0, 5261.263423120766, 5455.918161227585, 5713.885862231001, 5744.425557444222, 5080.136376815876, 5472.480114389126, 5513.58648101386, 5546.750396232099, 5562.860928476821, 4729.716217026989, 4971.477994810492, 5346.97326513365, 5401.864953376197, 5449.455054494519, 4476.015276639134, 4996.950030499648, 5375.973120134374, 5687.440651706665, 5696.522012924391, 3643.9089022773583, 3857.749246299094, 3858.7105967053144, 3862.208247309351, 3870.8838734838, 4691.225947718588, 4835.782389792397, 5197.402298735543, 5293.861819708781, 5460.563154947516, 5289.5667992723775, 5310.920336195038, 5319.255304257485, 5331.1470164773455, 5386.814900721835, 4585.72485650862, 4996.77578993149, 5343.236731878548, 5381.062817385659, 5408.648437851527, 4382.342648602735, 5077.213031980049, 5212.270282160424, 5231.633785634969, 5261.657992230493, 4256.191323648497, 5257.237700533477, 5411.269478620493, 5413.9729301353245, 5453.400125986218, 4902.215385844737, 5108.846734597968, 5273.920891186559, 5290.7354632269, 5303.708296043755], "value": 3947.602584683326, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [707.8619669164515, 717.5587013986396, 730.466759265736, 782.9373650107992, 837.7947402886292, 15099.0, 16664.083475408846, 16716.83283167168, 16742.83257167428, 16788.41240556582, 15055.473058442956, 15176.468823591176, 15212.239388030583, 15220.0, 15233.92383038086, 701.9929800701979, 725.1769241910417, 728.191707205002, 729.114126336501, 837.5602808525528, 712.4549719464587, 719.1190791280666, 840.2060053249681, 867.1415298854121, 870.2341939093589, 906.7959709065441, 915.1260546178383, 921.3642586615248, 922.0410772796317, 922.2667978956746, 906.8095699903029, 909.1726528858707, 911.0297533127232, 911.1480765484293, 912.6212621761961, 537.6478406643615, 560.5179545590788, 684.1687349869932, 686.8969654551807, 686.9278725733826, 14756.926215368961, 15287.159206243763, 15336.309866055943, 15368.308426120851, 15371.461998829987, 12693.428795704216, 14749.410023599043, 15308.846911530853, 15471.458498952596, 15523.0, 10400.843987340197, 12092.87907120935, 12130.696732581668, 12136.757264854654, 12260.816087758601, 3933.7639741615594, 3942.4874766280486, 3984.183242435294, 3996.200759848042, 4129.132882094764, 121433.0, 122027.77972220253, 122192.0, 124125.0, 129020.35489822585, 10989.395583242998, 11264.43677816106, 11709.414529273503, 11750.529978800752, 12251.571195008131, 22105.115795368325, 22227.99974001174, 22517.77482225157, 22534.887325563268, 22540.774592254194, 8257.545834979013, 8331.50010999342, 8365.916340836635, 8366.874496882552, 8371.246587807127, 4350.216960946997, 4495.707778994355, 4507.2112380333965, 4512.52618475062, 4660.906781864343, 3594.4428613564414, 3740.2893450244364, 3740.4389341598435, 3750.718696097749, 3755.6995440364703, 589.6756783768996, 658.973641054362, 675.4123912196322, 686.1251903822566, 700.4256509662029, 544.3386285662939, 629.8425393651587, 648.8832010238202, 670.5535033453526, 702.9964850175717, 8128.471649342774, 9244.583993720298, 9603.711888643353, 9606.759831004281, 9612.51937403127, 647.3817504283398, 676.3439463720183, 686.2862622872157, 696.3384784454788, 696.853660731242, 4600.3099535070005, 4683.859484215479, 4701.64737644678, 4714.224451735766, 4825.10735513931, 125234.0, 133319.0, 164490.0, 165240.0, 168845.0, 143595.0, 144137.55862441452, 146235.80646290313, 148308.51691482947, 149031.0, 79672.0, 124342.0, 124551.0, 125155.98752099993, 129885.35057324653, 9010.549472526478, 9016.59425325875, 9022.188003079755, 9024.548772561477, 9089.77275568116, 69138.65430673015, 70572.64713676399, 70666.6466667684, 70888.58222835617, 71022.93465598124, 102752.0, 103506.0, 105733.0, 107914.0, 108252.4587377058, 11473.196876218584, 11803.468843902217, 11896.940515297369, 12312.0, 12563.74872502563, 13235.80146297787, 13454.0, 13477.932610336886, 14033.789493157406, 14073.0, 12312.26126432417, 12326.0, 12329.0, 12337.814932775836, 12391.442385092512, 11250.774984500427, 12497.625071247878, 12537.310447925453, 14240.287985600471, 14478.493252736525, 13731.519396821062, 13764.93117534406, 13809.585712428643, 13970.161790292615, 14036.508722194674, 11693.953558127123, 11787.057035437316, 11866.40667966581, 11869.151007426215, 12137.939310303393, 9048.50233237152, 10173.694789156338, 11507.194496385173, 11569.942150289195, 11596.1719640997], "value": 7973.852913699393, "unit": "score"}], "name": "dromaeo_css", "value": 7389.413619522488, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 112.0, 113.0, 113.0, 111.0, 113.0, 112.0, 112.0, 112.0, 111.0], "value": 111.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 113.0, 105.0, 164.0, 110.0, 106.0, 128.0, 106.0, 140.0, 107.0], "value": 122.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 181.0, 184.0, 184.0, 185.0, 181.0, 183.0, 186.0, 183.0], "value": 183.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 57.0, 76.0, 111.0, 81.0, 67.0, 64.0, 64.0, 82.0, 56.0], "value": 73.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 67.0, 97.0, 70.0, 78.0, 65.0, 95.0, 70.0, 67.0, 122.0], "value": 81.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 87.0, 84.0, 87.0, 84.0, 88.0, 85.0, 87.0, 84.0], "value": 85.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 100.0, 92.0, 92.0, 93.0, 92.0, 93.0, 93.0], "value": 93.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 84.0, 85.0, 84.0, 84.0, 85.0, 83.0, 85.0, 83.0], "value": 84.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 54.0, 55.0, 55.0, 54.0, 52.0, 53.0, 55.0, 53.0], "value": 54.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 76.0, 69.0, 71.0, 79.0, 71.0, 71.0, 72.0, 72.0, 73.0], "value": 72.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 80.0, 105.0, 85.0, 85.0, 79.0, 86.0, 84.0, 85.0], "value": 86.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 137.0, 137.0, 136.0, 135.0, 135.0, 133.0, 137.0, 156.0, 139.0], "value": 138.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 165.0, 163.0, 164.0, 163.0, 164.0, 163.0, 163.0, 161.0, 162.0], "value": 163.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 68.0, 66.0, 67.0, 65.0, 66.0, 65.0, 66.0, 65.0], "value": 66.3, "unit": "score"}], "name": "kraken", "value": 1414.8999999999996, "alertThreshold": 2.0}]} 13:11:12 INFO - Return code: 0 13:11:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:11:12 INFO - # TBPL SUCCESS # 13:11:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:11:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:11:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:11:12 INFO - 2016-08-11 13:11:12.937 screenresolution[1664:9813] starting screenresolution argv=screenresolution get 13:11:12 INFO - 2016-08-11 13:11:12.940 screenresolution[1664:9813] Display 0: 1600x1200x32@60 13:11:12 INFO - 2016-08-11 13:11:12.946 screenresolution[1665:9817] starting screenresolution argv=screenresolution list 13:11:12 INFO - Available Modes on Display 0 13:11:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:11:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:11:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:11:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:11:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:11:13 INFO - Intel Iris: 13:11:13 INFO - Chipset Model: Intel Iris 13:11:13 INFO - Type: GPU 13:11:13 INFO - Bus: Built-In 13:11:13 INFO - VRAM (Dynamic, Max): 1536 MB 13:11:13 INFO - Vendor: Intel (0x8086) 13:11:13 INFO - Device ID: 0x0a2e 13:11:13 INFO - Revision ID: 0x0009 13:11:13 INFO - Displays: 13:11:13 INFO - Display: 13:11:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:11:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:11:13 INFO - Display Serial Number: 1600x1200 60 13:11:13 INFO - Main Display: Yes 13:11:13 INFO - Mirror: Off 13:11:13 INFO - Online: Yes 13:11:13 INFO - Rotation: Supported 13:11:13 INFO - Return code: 0 13:11:13 INFO - Running post-action listener: _resource_record_post_action 13:11:13 INFO - [mozharness: 2016-08-11 20:11:13.181236Z] Finished run-tests step (success) 13:11:13 INFO - Running post-run listener: _resource_record_post_run 13:11:13 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 37913600; Write bytes: 702824960; Read time: 387; Write time: 2101 13:11:13 INFO - TinderboxPrint: CPU usage
53.6% 13:11:13 INFO - TinderboxPrint: I/O read bytes / time
37,913,600 / 387 13:11:13 INFO - TinderboxPrint: I/O write bytes / time
702,824,960 / 2,101 13:11:13 INFO - TinderboxPrint: CPU idle
2,249.7 (46.4%) 13:11:13 INFO - TinderboxPrint: CPU system
971.4 (20.0%) 13:11:13 INFO - TinderboxPrint: CPU user
1,628.5 (33.6%) 13:11:13 INFO - TinderboxPrint: Swap in / out
441,962,496 / 0 13:11:13 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 403866112; Write bytes: 398792192; Read time: 29922; Write time: 731 13:11:13 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 34513920; Write bytes: 294128640; Read time: 357; Write time: 1338 13:11:13 INFO - Running post-run listener: _upload_blobber_files 13:11:13 INFO - Blob upload gear active. 13:11:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:11:13 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 13:11:13 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'] 13:11:13 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 13:11:14 INFO - (blobuploader) - INFO - Open directory for files ... 13:11:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:11:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:11:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:11:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:11:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:11:15 INFO - (blobuploader) - INFO - Done attempting. 13:11:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:11:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:11:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:11:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:11:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:11:16 INFO - (blobuploader) - INFO - Done attempting. 13:11:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:11:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:11:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:11:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:11:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:11:18 INFO - (blobuploader) - INFO - Done attempting. 13:11:18 INFO - (blobuploader) - INFO - Iteration through files over. 13:11:18 INFO - Return code: 0 13:11:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:11:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:11:18 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8a29e225643f8ce9b6d6ecf305b6b37370542e4caa3158e3ed7e21717320c13e6cef67fb6b638f23ce31f577c2a8931621c270e3492bdfb542b3b7558612f624", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/759c346e013db530a3b8e969124c8fb5a1118a9f1d551c484cba48c1a21596aa61c8e4925916a8862e2d1072fe93682cc76c6ab63cb70c971597d24ae226efb1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7fc53e80429c1ee429b46733d5bc31c372ee0eb4fd0a9dc2cf03ced62f79ea9d58f6fd096ff3ff93999c29c6e93c346e670f0936596b1590edcea1a2c2b06c26"} 13:11:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:11:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:11:18 INFO - Contents: 13:11:18 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8a29e225643f8ce9b6d6ecf305b6b37370542e4caa3158e3ed7e21717320c13e6cef67fb6b638f23ce31f577c2a8931621c270e3492bdfb542b3b7558612f624", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/759c346e013db530a3b8e969124c8fb5a1118a9f1d551c484cba48c1a21596aa61c8e4925916a8862e2d1072fe93682cc76c6ab63cb70c971597d24ae226efb1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7fc53e80429c1ee429b46733d5bc31c372ee0eb4fd0a9dc2cf03ced62f79ea9d58f6fd096ff3ff93999c29c6e93c346e670f0936596b1590edcea1a2c2b06c26"} 13:11:18 INFO - Running post-run listener: copy_logs_to_upload_dir 13:11:18 INFO - Copying logs to upload dir... 13:11:18 INFO - mkdir: /builds/slave/test/build/upload/logs 13:11:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.661363 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-08-11 13:11:18.406143) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-11 13:11:18.409748) ========= 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.By26prBcOC/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.WJuqLnJhHy/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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/8a29e225643f8ce9b6d6ecf305b6b37370542e4caa3158e3ed7e21717320c13e6cef67fb6b638f23ce31f577c2a8931621c270e3492bdfb542b3b7558612f624", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/759c346e013db530a3b8e969124c8fb5a1118a9f1d551c484cba48c1a21596aa61c8e4925916a8862e2d1072fe93682cc76c6ab63cb70c971597d24ae226efb1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7fc53e80429c1ee429b46733d5bc31c372ee0eb4fd0a9dc2cf03ced62f79ea9d58f6fd096ff3ff93999c29c6e93c346e670f0936596b1590edcea1a2c2b06c26"} build_url:https://queue.taskcluster.net/v1/task/UMexgxeNQiu-ekcAEOXQ2Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010757 build_url: 'https://queue.taskcluster.net/v1/task/UMexgxeNQiu-ekcAEOXQ2Q/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/8a29e225643f8ce9b6d6ecf305b6b37370542e4caa3158e3ed7e21717320c13e6cef67fb6b638f23ce31f577c2a8931621c270e3492bdfb542b3b7558612f624", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/759c346e013db530a3b8e969124c8fb5a1118a9f1d551c484cba48c1a21596aa61c8e4925916a8862e2d1072fe93682cc76c6ab63cb70c971597d24ae226efb1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7fc53e80429c1ee429b46733d5bc31c372ee0eb4fd0a9dc2cf03ced62f79ea9d58f6fd096ff3ff93999c29c6e93c346e670f0936596b1590edcea1a2c2b06c26"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-11 13:11:18.441517) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 13:11:18.441835) ========= 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.By26prBcOC/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.WJuqLnJhHy/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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.005049 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-11 13:11:18.492838) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-11 13:11:18.493145) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-11 13:11:18.493474) ========= ========= Total master_lag: 0.49 =========