builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0371 starttime: 1472524722.25 results: success (0) buildid: 20160829174130 builduid: 8c00ce014b624fe2a83b5d52d1c191bb revision: 6c0d74730bd57da6c1c9bf3d05608cd8156bb65f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-29 19:38:42.246449) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-29 19:38:42.246992) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-29 19:38:42.247319) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-29 19:38:42.330909) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 19:38:42.331272) ========= 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.za6u9CLsa2/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.70fRKRFR8z/Listeners TMPDIR=/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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-29 19:38:42-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.0M=0.001s 2016-08-29 19:38:42 (19.0 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.118877 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 19:38:42.477379) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 19:38:42.477717) ========= 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.za6u9CLsa2/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.70fRKRFR8z/Listeners TMPDIR=/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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.026187 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 19:38:42.559975) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 19:38:42.560340) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 6c0d74730bd57da6c1c9bf3d05608cd8156bb65f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 6c0d74730bd57da6c1c9bf3d05608cd8156bb65f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.za6u9CLsa2/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.70fRKRFR8z/Listeners TMPDIR=/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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-29 19:38:42,654 truncating revision to first 12 chars 2016-08-29 19:38:42,654 Setting DEBUG logging. 2016-08-29 19:38:42,654 attempt 1/10 2016-08-29 19:38:42,654 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6c0d74730bd5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-29 19:38:43,286 unpacking tar archive at: mozilla-central-6c0d74730bd5/testing/mozharness/ program finished with exit code 0 elapsedTime=0.890560 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 19:38:43.502892) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-29 19:38:43.503281) ========= script_repo_revision: 6c0d74730bd57da6c1c9bf3d05608cd8156bb65f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-29 19:38:43.503751) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-29 19:38:43.504088) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-29 19:38:43.555826) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-08-29 19:38:43.556350) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.za6u9CLsa2/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.70fRKRFR8z/Listeners TMPDIR=/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 19:38:43 INFO - MultiFileLogger online at 20160829 19:38:43 in /builds/slave/test 19:38:43 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 19:38:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:38:43 INFO - {'append_to_log': False, 19:38:43 INFO - 'base_work_dir': '/builds/slave/test', 19:38:43 INFO - 'blob_upload_branch': 'Firefox', 19:38:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:38:43 INFO - 'branch': 'Firefox', 19:38:43 INFO - 'buildbot_json_path': 'buildprops.json', 19:38:43 INFO - 'config_files': ('talos/mac_config.py',), 19:38:43 INFO - 'default_actions': ('clobber', 19:38:43 INFO - 'read-buildbot-config', 19:38:43 INFO - 'download-and-extract', 19:38:43 INFO - 'populate-webroot', 19:38:43 INFO - 'create-virtualenv', 19:38:43 INFO - 'install', 19:38:43 INFO - 'run-tests'), 19:38:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:38:43 INFO - 'download_minidump_stackwalk': True, 19:38:43 INFO - 'download_symbols': 'ondemand', 19:38:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:38:43 INFO - 'tooltool.py': '/tools/tooltool.py', 19:38:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:38:43 INFO - '/tools/misc-python/virtualenv.py')}, 19:38:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:38:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:38:43 INFO - 'installer_path': 'installer.exe', 19:38:43 INFO - 'log_level': 'info', 19:38:43 INFO - 'log_name': 'talos', 19:38:43 INFO - 'log_to_console': True, 19:38:43 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:38:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:38:43 INFO - 'opt_config_files': (), 19:38:43 INFO - 'pip_index': False, 19:38:43 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:38:43 INFO - 'cmd': ('bash', 19:38:43 INFO - '-c', 19:38:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:38:43 INFO - 'enabled': True, 19:38:43 INFO - 'halt_on_failure': False, 19:38:43 INFO - 'name': 'check_screen_resolution'},), 19:38:43 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:38:43 INFO - 'cmd': ('bash', 19:38:43 INFO - '-c', 19:38:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:38:43 INFO - 'enabled': True, 19:38:43 INFO - 'halt_on_failure': False, 19:38:43 INFO - 'name': 'check_screen_resolution'},), 19:38:43 INFO - 'run_cmd_checks_enabled': True, 19:38:43 INFO - 'sps_profile': False, 19:38:43 INFO - 'sps_profile_interval': 0, 19:38:43 INFO - 'suite': 'dromaeojs', 19:38:43 INFO - 'system_bits': '32', 19:38:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:38:43 INFO - 'title': 't-yosemite-r7-0371', 19:38:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:38:43 INFO - 'use_talos_json': True, 19:38:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:38:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:38:43 INFO - 'work_dir': 'build'} 19:38:43 INFO - [mozharness: 2016-08-30 02:38:43.758174Z] Running clobber step. 19:38:43 INFO - Running pre-action listener: _resource_record_pre_action 19:38:43 INFO - Running main action method: clobber 19:38:43 INFO - rmtree: /builds/slave/test/build 19:38:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:38:45 INFO - Running post-action listener: _resource_record_post_action 19:38:45 INFO - [mozharness: 2016-08-30 02:38:45.837822Z] Finished clobber step (success) 19:38:45 INFO - [mozharness: 2016-08-30 02:38:45.838219Z] Running read-buildbot-config step. 19:38:45 INFO - Running pre-action listener: _resource_record_pre_action 19:38:45 INFO - Running main action method: read_buildbot_config 19:38:45 INFO - Using buildbot properties: 19:38:45 INFO - { 19:38:45 INFO - "project": "", 19:38:45 INFO - "product": "firefox", 19:38:45 INFO - "script_repo_revision": "production", 19:38:45 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 19:38:45 INFO - "repository": "", 19:38:45 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 19:38:45 INFO - "buildid": "20160829174130", 19:38:45 INFO - "pgo_build": "False", 19:38:45 INFO - "basedir": "/builds/slave/test", 19:38:45 INFO - "buildnumber": 24, 19:38:45 INFO - "slavename": "t-yosemite-r7-0371", 19:38:45 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 19:38:45 INFO - "platform": "yosemite_r7", 19:38:45 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 19:38:45 INFO - "branch": "mozilla-central", 19:38:45 INFO - "repo_path": "mozilla-central", 19:38:45 INFO - "revision": "6c0d74730bd57da6c1c9bf3d05608cd8156bb65f", 19:38:45 INFO - "stage_platform": "macosx64", 19:38:45 INFO - "builduid": "8c00ce014b624fe2a83b5d52d1c191bb", 19:38:45 INFO - "slavebuilddir": "test" 19:38:45 INFO - } 19:38:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/Ac9x4KLKQ6uMpYiV1gRkMQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 19:38:45 INFO - Running post-action listener: _resource_record_post_action 19:38:45 INFO - [mozharness: 2016-08-30 02:38:45.844223Z] Finished read-buildbot-config step (success) 19:38:45 INFO - [mozharness: 2016-08-30 02:38:45.844455Z] Running download-and-extract step. 19:38:45 INFO - Running pre-action listener: _resource_record_pre_action 19:38:45 INFO - Running main action method: download_and_extract 19:38:45 INFO - mkdir: /builds/slave/test/build/tests 19:38:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:38:45 INFO - trying https://queue.taskcluster.net/v1/task/Ac9x4KLKQ6uMpYiV1gRkMQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 19:38:45 INFO - Downloading https://queue.taskcluster.net/v1/task/Ac9x4KLKQ6uMpYiV1gRkMQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 19:38:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ac9x4KLKQ6uMpYiV1gRkMQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 19:38:46 INFO - Downloaded 1199 bytes. 19:38:46 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 19:38:46 INFO - Using the following test package requirements: 19:38:46 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 19:38:46 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:38:46 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 19:38:46 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:38:46 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 19:38:46 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:38:46 INFO - u'jsshell-mac.zip'], 19:38:46 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:38:46 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 19:38:46 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 19:38:46 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:38:46 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 19:38:46 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:38:46 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 19:38:46 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:38:46 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 19:38:46 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:38:46 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 19:38:46 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:38:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:38:46 INFO - trying https://queue.taskcluster.net/v1/task/Ac9x4KLKQ6uMpYiV1gRkMQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 19:38:46 INFO - Downloading https://queue.taskcluster.net/v1/task/Ac9x4KLKQ6uMpYiV1gRkMQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 19:38:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ac9x4KLKQ6uMpYiV1gRkMQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:38:47 INFO - Downloaded 16380383 bytes. 19:38:47 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 19:38:54 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 19:38: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')]} 19:38:54 INFO - trying https://queue.taskcluster.net/v1/task/Ac9x4KLKQ6uMpYiV1gRkMQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 19:38:54 INFO - Downloading https://queue.taskcluster.net/v1/task/Ac9x4KLKQ6uMpYiV1gRkMQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 19:38:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ac9x4KLKQ6uMpYiV1gRkMQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:38:55 INFO - Downloaded 16380383 bytes. 19:38:55 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 19:39:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:39:01 INFO - trying https://queue.taskcluster.net/v1/task/Ac9x4KLKQ6uMpYiV1gRkMQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 19:39:01 INFO - Downloading https://queue.taskcluster.net/v1/task/Ac9x4KLKQ6uMpYiV1gRkMQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 19:39:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ac9x4KLKQ6uMpYiV1gRkMQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:39:03 INFO - Downloaded 14413998 bytes. 19:39:03 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 19:39:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:39:03 INFO - trying https://queue.taskcluster.net/v1/task/Ac9x4KLKQ6uMpYiV1gRkMQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:39:03 INFO - Downloading https://queue.taskcluster.net/v1/task/Ac9x4KLKQ6uMpYiV1gRkMQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 19:39:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ac9x4KLKQ6uMpYiV1gRkMQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 19:39:06 INFO - Downloaded 111563537 bytes. 19:39:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ac9x4KLKQ6uMpYiV1gRkMQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:39:06 INFO - mkdir: /builds/slave/test/properties 19:39:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:39:06 INFO - Writing to file /builds/slave/test/properties/build_url 19:39:06 INFO - Contents: 19:39:06 INFO - build_url:https://queue.taskcluster.net/v1/task/Ac9x4KLKQ6uMpYiV1gRkMQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:39:08 INFO - Running post-action listener: _resource_record_post_action 19:39:08 INFO - Running post-action listener: set_extra_try_arguments 19:39:08 INFO - [mozharness: 2016-08-30 02:39:08.047809Z] Finished download-and-extract step (success) 19:39:08 INFO - [mozharness: 2016-08-30 02:39:08.047950Z] Running populate-webroot step. 19:39:08 INFO - Running pre-action listener: _resource_record_pre_action 19:39:08 INFO - Running main action method: populate_webroot 19:39:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:39:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:39:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:39:08 INFO - '--tppagecycles', 19:39:08 INFO - '7'], 19:39:08 INFO - 'tests': ['tsvgm']}}, 19:39:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:39:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:39:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:39:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:39:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:39:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:39:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:39:08 INFO - 'talos_options': ['--disable-e10s'], 19:39:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:39:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:39:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:39:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:39:08 INFO - 'talos_options': ['--disable-e10s'], 19:39:08 INFO - 'tests': ['damp', 'tps']}, 19:39:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:39:08 INFO - 'tests': ['damp', 'tps']}, 19:39:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:39:08 INFO - 'tests': ['dromaeo_dom']}, 19:39:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:39:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:39:08 INFO - 'tests': ['basic_compositor_video']}, 19:39:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:39:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:39:08 INFO - 'tests': ['a11yr', 19:39:08 INFO - 'ts_paint', 19:39:08 INFO - 'tpaint', 19:39:08 INFO - 'sessionrestore', 19:39:08 INFO - 'sessionrestore_no_auto_restore', 19:39:08 INFO - 'tabpaint']}, 19:39:08 INFO - 'other-e10s': {'tests': ['a11yr', 19:39:08 INFO - 'ts_paint', 19:39:08 INFO - 'tpaint', 19:39:08 INFO - 'sessionrestore', 19:39:08 INFO - 'sessionrestore_no_auto_restore', 19:39:08 INFO - 'tabpaint']}, 19:39:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:39:08 INFO - 'ts_paint', 19:39:08 INFO - 'tpaint', 19:39:08 INFO - 'sessionrestore', 19:39:08 INFO - 'sessionrestore_no_auto_restore', 19:39:08 INFO - 'tabpaint']}, 19:39:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:39:08 INFO - 'ts_paint', 19:39:08 INFO - 'tpaint', 19:39:08 INFO - 'sessionrestore', 19:39:08 INFO - 'sessionrestore_no_auto_restore', 19:39:08 INFO - 'tabpaint']}, 19:39:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:39:08 INFO - 'tests': ['a11yr', 19:39:08 INFO - 'ts_paint', 19:39:08 INFO - 'tpaint', 19:39:08 INFO - 'sessionrestore', 19:39:08 INFO - 'sessionrestore_no_auto_restore', 19:39:08 INFO - 'tabpaint']}, 19:39:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:39:08 INFO - 'tests': ['a11yr', 19:39:08 INFO - 'ts_paint', 19:39:08 INFO - 'tpaint', 19:39:08 INFO - 'sessionrestore', 19:39:08 INFO - 'sessionrestore_no_auto_restore', 19:39:08 INFO - 'tabpaint']}, 19:39:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:39:08 INFO - 'tests': ['tsvgx', 19:39:08 INFO - 'tsvgr_opacity', 19:39:08 INFO - 'tart', 19:39:08 INFO - 'tscrollx', 19:39:08 INFO - 'cart']}, 19:39:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:39:08 INFO - 'tsvgr_opacity', 19:39:08 INFO - 'tart', 19:39:08 INFO - 'tscrollx', 19:39:08 INFO - 'cart']}, 19:39:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:39:08 INFO - 'talos_options': ['--disable-e10s'], 19:39:08 INFO - 'tests': ['tp5o']}, 19:39:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:39:08 INFO - 'tests': ['tp5o']}, 19:39:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:39:08 INFO - 'talos_options': ['--disable-e10s', 19:39:08 INFO - '--xperf_path', 19:39:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:39:08 INFO - 'tests': ['tp5n']}, 19:39:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:39:08 INFO - 'talos_options': ['--xperf_path', 19:39:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:39:08 INFO - 'tests': ['tp5n']}}, 19:39:08 INFO - 'talos.zip': {'path': '', 19:39:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:39:08 INFO - Running post-action listener: _resource_record_post_action 19:39:08 INFO - [mozharness: 2016-08-30 02:39:08.066906Z] Finished populate-webroot step (success) 19:39:08 INFO - [mozharness: 2016-08-30 02:39:08.067022Z] Running create-virtualenv step. 19:39:08 INFO - Running pre-action listener: _resource_record_pre_action 19:39:08 INFO - Running main action method: create_virtualenv 19:39:08 INFO - Creating virtualenv /builds/slave/test/build/venv 19:39:08 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 19:39:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:39:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:39:08 INFO - Using real prefix '/tools/python27' 19:39:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:39:08 INFO - Installing distribute.............................................................................................................................................................................................done. 19:39:11 INFO - Installing pip.................done. 19:39:11 INFO - Return code: 0 19:39:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:39:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:39:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:39:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:39:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:39:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:39:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:39:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102924a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029b9618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9939dd57e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102888d30>, '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 0x1025eddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102880b20>, '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.za6u9CLsa2/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.70fRKRFR8z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:39:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:39:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:39:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.za6u9CLsa2/Render', 19:39:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:39:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:39:11 INFO - 'HOME': '/Users/cltbld', 19:39:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:39:11 INFO - 'LOGNAME': 'cltbld', 19:39:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:39:11 INFO - 'MOZ_NO_REMOTE': '1', 19:39:11 INFO - 'NO_EM_RESTART': '1', 19:39:11 INFO - 'PAGER': '/bin/cat', 19:39:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:39:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:39:11 INFO - 'PWD': '/builds/slave/test', 19:39:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:39:11 INFO - 'SHELL': '/bin/bash', 19:39:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.70fRKRFR8z/Listeners', 19:39:11 INFO - 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', 19:39:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:39:11 INFO - 'USER': 'cltbld', 19:39:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:39:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:39:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:39:11 INFO - 'XPC_FLAGS': '0x0', 19:39:11 INFO - 'XPC_SERVICE_NAME': '0', 19:39:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:39:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:39:12 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 19:39:12 INFO - Cleaning up... 19:39:12 INFO - Return code: 0 19:39:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:39:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:39:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:39:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:39:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:39:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:39:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:39:12 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 0x102924a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029b9618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9939dd57e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102888d30>, '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 0x1025eddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102880b20>, '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.za6u9CLsa2/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.70fRKRFR8z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:39:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:39:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:39:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.za6u9CLsa2/Render', 19:39:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:39:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:39:12 INFO - 'HOME': '/Users/cltbld', 19:39:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:39:12 INFO - 'LOGNAME': 'cltbld', 19:39:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:39:12 INFO - 'MOZ_NO_REMOTE': '1', 19:39:12 INFO - 'NO_EM_RESTART': '1', 19:39:12 INFO - 'PAGER': '/bin/cat', 19:39:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:39:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:39:12 INFO - 'PWD': '/builds/slave/test', 19:39:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:39:12 INFO - 'SHELL': '/bin/bash', 19:39:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.70fRKRFR8z/Listeners', 19:39:12 INFO - 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', 19:39:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:39:12 INFO - 'USER': 'cltbld', 19:39:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:39:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:39:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:39:12 INFO - 'XPC_FLAGS': '0x0', 19:39:12 INFO - 'XPC_SERVICE_NAME': '0', 19:39:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:39:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:39:12 INFO - Downloading/unpacking psutil>=3.1.1 19:39:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:39:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:39:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:39:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:39:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:39:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:39:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:39:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:39:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:39:16 INFO - Installing collected packages: psutil 19:39:16 INFO - Running setup.py install for psutil 19:39:16 INFO - building 'psutil._psutil_osx' extension 19:39:16 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 19:39:16 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 19:39:16 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 19:39:16 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 19:39:16 INFO - building 'psutil._psutil_posix' extension 19:39:16 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 19:39:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:39:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:39:17 INFO - ^ 19:39:17 INFO - 1 warning generated. 19:39:17 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 19:39:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:39:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:39:17 INFO - Successfully installed psutil 19:39:17 INFO - Cleaning up... 19:39:17 INFO - Return code: 0 19:39:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:39:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:39:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:39:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:39:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:39:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:39:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:39:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102924a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029b9618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9939dd57e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102888d30>, '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 0x1025eddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102880b20>, '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.za6u9CLsa2/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.70fRKRFR8z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:39:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:39:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:39:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.za6u9CLsa2/Render', 19:39:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:39:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:39:17 INFO - 'HOME': '/Users/cltbld', 19:39:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:39:17 INFO - 'LOGNAME': 'cltbld', 19:39:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:39:17 INFO - 'MOZ_NO_REMOTE': '1', 19:39:17 INFO - 'NO_EM_RESTART': '1', 19:39:17 INFO - 'PAGER': '/bin/cat', 19:39:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:39:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:39:17 INFO - 'PWD': '/builds/slave/test', 19:39:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:39:17 INFO - 'SHELL': '/bin/bash', 19:39:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.70fRKRFR8z/Listeners', 19:39:17 INFO - 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', 19:39:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:39:17 INFO - 'USER': 'cltbld', 19:39:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:39:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:39:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:39:17 INFO - 'XPC_FLAGS': '0x0', 19:39:17 INFO - 'XPC_SERVICE_NAME': '0', 19:39:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:39:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:39:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:39:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:39:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:39:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:39:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:39:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:39:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:39:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:39:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:39:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:39:20 INFO - Installing collected packages: mozsystemmonitor 19:39:20 INFO - Running setup.py install for mozsystemmonitor 19:39:20 INFO - Successfully installed mozsystemmonitor 19:39:20 INFO - Cleaning up... 19:39:20 INFO - Return code: 0 19:39:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:39:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:39:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:39:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:39:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:39:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:39:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:39:20 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 0x102924a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029b9618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9939dd57e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102888d30>, '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 0x1025eddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102880b20>, '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.za6u9CLsa2/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.70fRKRFR8z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:39:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:39:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 19:39:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.za6u9CLsa2/Render', 19:39:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:39:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:39:20 INFO - 'HOME': '/Users/cltbld', 19:39:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:39:20 INFO - 'LOGNAME': 'cltbld', 19:39:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:39:20 INFO - 'MOZ_NO_REMOTE': '1', 19:39:20 INFO - 'NO_EM_RESTART': '1', 19:39:20 INFO - 'PAGER': '/bin/cat', 19:39:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:39:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:39:20 INFO - 'PWD': '/builds/slave/test', 19:39:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:39:20 INFO - 'SHELL': '/bin/bash', 19:39:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.70fRKRFR8z/Listeners', 19:39:20 INFO - 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', 19:39:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:39:20 INFO - 'USER': 'cltbld', 19:39:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:39:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:39:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:39:20 INFO - 'XPC_FLAGS': '0x0', 19:39:20 INFO - 'XPC_SERVICE_NAME': '0', 19:39:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:39:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:39:20 INFO - Downloading/unpacking jsonschema==2.5.1 19:39:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:39:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:39:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:39:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:39:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:39:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:39:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:39:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:39:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:39:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:39:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:39:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:39:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:39:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:39:23 INFO - Downloading functools32-3.2.3-2.tar.gz 19:39:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:39:23 INFO - warning: no files found matching '*.txt' 19:39:23 INFO - no previously-included directories found matching 'build' 19:39:23 INFO - no previously-included directories found matching 'dist' 19:39:23 INFO - no previously-included directories found matching '.git*' 19:39:23 INFO - Installing collected packages: jsonschema, functools32 19:39:23 INFO - Running setup.py install for functools32 19:39:23 INFO - warning: no files found matching '*.txt' 19:39:23 INFO - no previously-included directories found matching 'build' 19:39:23 INFO - no previously-included directories found matching 'dist' 19:39:23 INFO - no previously-included directories found matching '.git*' 19:39:23 INFO - Successfully installed jsonschema functools32 19:39:23 INFO - Cleaning up... 19:39:23 INFO - Return code: 0 19:39:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:39:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:39:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:39:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:39:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:39:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:39:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:39:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102924a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029b9618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9939dd57e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102888d30>, '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 0x1025eddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102880b20>, '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.za6u9CLsa2/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.70fRKRFR8z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:39:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:39:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 19:39:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.za6u9CLsa2/Render', 19:39:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:39:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:39:23 INFO - 'HOME': '/Users/cltbld', 19:39:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:39:23 INFO - 'LOGNAME': 'cltbld', 19:39:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:39:23 INFO - 'MOZ_NO_REMOTE': '1', 19:39:23 INFO - 'NO_EM_RESTART': '1', 19:39:23 INFO - 'PAGER': '/bin/cat', 19:39:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:39:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:39:23 INFO - 'PWD': '/builds/slave/test', 19:39:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:39:23 INFO - 'SHELL': '/bin/bash', 19:39:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.70fRKRFR8z/Listeners', 19:39:23 INFO - 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', 19:39:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:39:23 INFO - 'USER': 'cltbld', 19:39:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:39:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:39:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:39:23 INFO - 'XPC_FLAGS': '0x0', 19:39:23 INFO - 'XPC_SERVICE_NAME': '0', 19:39:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:39:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:39:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:39:23 INFO - Cleaning up... 19:39:24 INFO - Return code: 0 19:39:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:39:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:39:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:39:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:39:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:39:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:39:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:39:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102924a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029b9618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9939dd57e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102888d30>, '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 0x1025eddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102880b20>, '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.za6u9CLsa2/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.70fRKRFR8z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:39:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:39:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:39:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.za6u9CLsa2/Render', 19:39:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:39:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:39:24 INFO - 'HOME': '/Users/cltbld', 19:39:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:39:24 INFO - 'LOGNAME': 'cltbld', 19:39:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:39:24 INFO - 'MOZ_NO_REMOTE': '1', 19:39:24 INFO - 'NO_EM_RESTART': '1', 19:39:24 INFO - 'PAGER': '/bin/cat', 19:39:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:39:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:39:24 INFO - 'PWD': '/builds/slave/test', 19:39:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:39:24 INFO - 'SHELL': '/bin/bash', 19:39:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.70fRKRFR8z/Listeners', 19:39:24 INFO - 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', 19:39:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:39:24 INFO - 'USER': 'cltbld', 19:39:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:39:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:39:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:39:24 INFO - 'XPC_FLAGS': '0x0', 19:39:24 INFO - 'XPC_SERVICE_NAME': '0', 19:39:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:39:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:39:24 INFO - Downloading/unpacking blobuploader==1.2.4 19:39:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:39:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:39:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:39:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:39:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:39:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:39:26 INFO - Downloading blobuploader-1.2.4.tar.gz 19:39:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:39:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:39:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:39:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:39:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:39:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:39:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:39:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:39:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:39:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:39:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:39:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:39:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:39:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:39:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:39:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:39:27 INFO - Downloading docopt-0.6.1.tar.gz 19:39:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:39:28 INFO - Installing collected packages: blobuploader, requests, docopt 19:39:28 INFO - Running setup.py install for blobuploader 19:39:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:39:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:39:28 INFO - Running setup.py install for requests 19:39:28 INFO - Running setup.py install for docopt 19:39:28 INFO - Successfully installed blobuploader requests docopt 19:39:28 INFO - Cleaning up... 19:39:28 INFO - Return code: 0 19:39:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:39:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:39:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:39:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:39:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:39:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:39:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:39:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102924a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029b9618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9939dd57e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102888d30>, '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 0x1025eddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102880b20>, '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.za6u9CLsa2/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.70fRKRFR8z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:39:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:39:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:39:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.za6u9CLsa2/Render', 19:39:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:39:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:39:28 INFO - 'HOME': '/Users/cltbld', 19:39:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:39:28 INFO - 'LOGNAME': 'cltbld', 19:39:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:39:28 INFO - 'MOZ_NO_REMOTE': '1', 19:39:28 INFO - 'NO_EM_RESTART': '1', 19:39:28 INFO - 'PAGER': '/bin/cat', 19:39:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:39:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:39:28 INFO - 'PWD': '/builds/slave/test', 19:39:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:39:28 INFO - 'SHELL': '/bin/bash', 19:39:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.70fRKRFR8z/Listeners', 19:39:28 INFO - 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', 19:39:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:39:28 INFO - 'USER': 'cltbld', 19:39:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:39:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:39:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:39:28 INFO - 'XPC_FLAGS': '0x0', 19:39:28 INFO - 'XPC_SERVICE_NAME': '0', 19:39:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:39:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:39:29 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-WBkOUB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:39:29 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-3kkuNt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:39:29 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-wZe63T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:39:29 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-KAjQWU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:39:29 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-80q0hK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:39:29 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-_NEISq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:39:29 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-XZPNrj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:39:29 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-oU0qAm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:39:30 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-kpoYQF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:39:30 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-j1eqy_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:39:30 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-fiy3za-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:39:30 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-sQlZvk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:39:30 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-M69mXb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:39:30 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-qABMy1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:39:30 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-SQqPqt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:39:30 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-etAuul-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:39:30 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-nBgwFH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:39:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:39:30 INFO - Running setup.py install for manifestparser 19:39:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:39:31 INFO - Running setup.py install for mozcrash 19:39:31 INFO - Running setup.py install for mozdebug 19:39:31 INFO - Running setup.py install for mozdevice 19:39:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:39:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:39:31 INFO - Running setup.py install for mozfile 19:39:31 INFO - Running setup.py install for mozhttpd 19:39:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:39:31 INFO - Running setup.py install for mozinfo 19:39:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:39:32 INFO - Running setup.py install for mozInstall 19:39:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:39:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:39:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:39:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:39:32 INFO - Running setup.py install for mozleak 19:39:32 INFO - Running setup.py install for mozlog 19:39:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:39:32 INFO - Running setup.py install for moznetwork 19:39:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:39:32 INFO - Running setup.py install for mozprocess 19:39:32 INFO - Running setup.py install for mozprofile 19:39:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:39:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:39:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:39:32 INFO - Running setup.py install for mozrunner 19:39:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:39:33 INFO - Running setup.py install for mozscreenshot 19:39:33 INFO - Running setup.py install for moztest 19:39:33 INFO - Running setup.py install for mozversion 19:39:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:39:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:39:33 INFO - Cleaning up... 19:39:33 INFO - Return code: 0 19:39:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:39:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:39:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:39:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:39:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:39:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:39:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:39:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102924a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029b9618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9939dd57e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102888d30>, '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 0x1025eddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102880b20>, '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.za6u9CLsa2/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.70fRKRFR8z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:39:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:39:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:39:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.za6u9CLsa2/Render', 19:39:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:39:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:39:33 INFO - 'HOME': '/Users/cltbld', 19:39:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:39:33 INFO - 'LOGNAME': 'cltbld', 19:39:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:39:33 INFO - 'MOZ_NO_REMOTE': '1', 19:39:33 INFO - 'NO_EM_RESTART': '1', 19:39:33 INFO - 'PAGER': '/bin/cat', 19:39:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:39:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:39:33 INFO - 'PWD': '/builds/slave/test', 19:39:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:39:33 INFO - 'SHELL': '/bin/bash', 19:39:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.70fRKRFR8z/Listeners', 19:39:33 INFO - 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', 19:39:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:39:33 INFO - 'USER': 'cltbld', 19:39:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:39:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:39:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:39:33 INFO - 'XPC_FLAGS': '0x0', 19:39:33 INFO - 'XPC_SERVICE_NAME': '0', 19:39:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:39:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:39:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:39:33 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-EbLZi6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:39:33 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 19:39:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:39:33 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-T8qEmh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:39:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:39:34 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-nufbeC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:39:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:39:34 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-VfocNr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:39:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:39:34 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-G42vUy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:39:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 19:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:39:34 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-pAlpdG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:39:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 19:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:39:34 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-PjHY6r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:39:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 19:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:39:34 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-fzZ3gt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:39:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 19:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:39:34 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-V4lQ6s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:39:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 19:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:39:34 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-Ns4Y0s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:39:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:39:34 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-TwKoCg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:39:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 19:39:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:39:35 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-PAaEvh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:39:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 19:39:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:39:35 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-vuqwkU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:39:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 19:39:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:39:35 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-RSIiuG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:39:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 19:39:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:39:35 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-uPvFI4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:39:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 19:39:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:39:35 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-134K1Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:39:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 19:39:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:39:35 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-0mowf3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:39:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 19:39:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:39:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:39:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:39:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:39:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:39:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:39:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:39:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:39:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:39:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:39:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:39:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:39:38 INFO - Downloading blessings-1.6.tar.gz 19:39:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:39:38 INFO - Installing collected packages: blessings 19:39:38 INFO - Running setup.py install for blessings 19:39:38 INFO - Successfully installed blessings 19:39:38 INFO - Cleaning up... 19:39:38 INFO - Return code: 0 19:39:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:39:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:39:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:39:38 INFO - Reading from file tmpfile_stdout 19:39:38 INFO - Current package versions: 19:39:38 INFO - blessings == 1.6 19:39:38 INFO - blobuploader == 1.2.4 19:39:38 INFO - docopt == 0.6.1 19:39:38 INFO - functools32 == 3.2.3-2 19:39:38 INFO - jsonschema == 2.5.1 19:39:38 INFO - manifestparser == 1.1 19:39:38 INFO - mozInstall == 1.12 19:39:38 INFO - mozcrash == 0.17 19:39:38 INFO - mozdebug == 0.1 19:39:38 INFO - mozdevice == 0.48 19:39:38 INFO - mozfile == 1.2 19:39:38 INFO - mozhttpd == 0.7 19:39:38 INFO - mozinfo == 0.9 19:39:38 INFO - mozleak == 0.1 19:39:38 INFO - mozlog == 3.3 19:39:38 INFO - moznetwork == 0.27 19:39:38 INFO - mozprocess == 0.23 19:39:38 INFO - mozprofile == 0.28 19:39:38 INFO - mozrunner == 6.12 19:39:38 INFO - mozscreenshot == 0.1 19:39:38 INFO - mozsystemmonitor == 0.3 19:39:38 INFO - moztest == 0.7 19:39:38 INFO - mozversion == 1.4 19:39:38 INFO - psutil == 3.1.1 19:39:38 INFO - requests == 1.2.3 19:39:38 INFO - wsgiref == 0.1.2 19:39:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:39:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:39:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:39:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:39:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:39:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:39:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:39:38 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 0x102924a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029b9618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9939dd57e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102888d30>, '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 0x1025eddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102880b20>, '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.za6u9CLsa2/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.70fRKRFR8z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:39:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 19:39:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:39:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.za6u9CLsa2/Render', 19:39:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:39:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:39:38 INFO - 'HOME': '/Users/cltbld', 19:39:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:39:38 INFO - 'LOGNAME': 'cltbld', 19:39:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:39:38 INFO - 'MOZ_NO_REMOTE': '1', 19:39:38 INFO - 'NO_EM_RESTART': '1', 19:39:38 INFO - 'PAGER': '/bin/cat', 19:39:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:39:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:39:38 INFO - 'PWD': '/builds/slave/test', 19:39:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:39:38 INFO - 'SHELL': '/bin/bash', 19:39:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.70fRKRFR8z/Listeners', 19:39:38 INFO - 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', 19:39:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:39:38 INFO - 'USER': 'cltbld', 19:39:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:39:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:39:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:39:38 INFO - 'XPC_FLAGS': '0x0', 19:39:38 INFO - 'XPC_SERVICE_NAME': '0', 19:39:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:39:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:39:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:39:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 19:39:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 19:39:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:39:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 19:39:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 19:39:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 19:39:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 19:39:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 19:39:39 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:39:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:39:39 INFO - Cleaning up... 19:39:39 INFO - Return code: 0 19:39:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:39:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:39:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:39:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:39:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:39:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:39:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:39:39 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 0x102924a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029b9618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9939dd57e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102888d30>, '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 0x1025eddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102880b20>, '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.za6u9CLsa2/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.70fRKRFR8z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 19:39:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:39:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:39:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.za6u9CLsa2/Render', 19:39:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:39:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:39:39 INFO - 'HOME': '/Users/cltbld', 19:39:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:39:39 INFO - 'LOGNAME': 'cltbld', 19:39:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:39:39 INFO - 'MOZ_NO_REMOTE': '1', 19:39:39 INFO - 'NO_EM_RESTART': '1', 19:39:39 INFO - 'PAGER': '/bin/cat', 19:39:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:39:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:39:39 INFO - 'PWD': '/builds/slave/test', 19:39:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:39:39 INFO - 'SHELL': '/bin/bash', 19:39:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.70fRKRFR8z/Listeners', 19:39:39 INFO - 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', 19:39:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:39:39 INFO - 'USER': 'cltbld', 19:39:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:39:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:39:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:39:39 INFO - 'XPC_FLAGS': '0x0', 19:39:39 INFO - 'XPC_SERVICE_NAME': '0', 19:39:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:39:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:39:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:39:39 INFO - Cleaning up... 19:39:39 INFO - Return code: 0 19:39:39 INFO - Running post-action listener: _resource_record_post_action 19:39:39 INFO - Running post-action listener: _start_resource_monitoring 19:39:39 INFO - Starting resource monitoring. 19:39:39 INFO - [mozharness: 2016-08-30 02:39:39.330039Z] Finished create-virtualenv step (success) 19:39:39 INFO - [mozharness: 2016-08-30 02:39:39.331061Z] Running install step. 19:39:39 INFO - Running pre-action listener: _resource_record_pre_action 19:39:39 INFO - Running main action method: install 19:39:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:39:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:39:39 INFO - Reading from file tmpfile_stdout 19:39:39 INFO - Detecting whether we're running mozinstall >=1.0... 19:39:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:39:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:39:39 INFO - Reading from file tmpfile_stdout 19:39:39 INFO - Output received: 19:39:39 INFO - Usage: mozinstall [options] installer 19:39:39 INFO - Options: 19:39:39 INFO - -h, --help show this help message and exit 19:39:39 INFO - -d DEST, --destination=DEST 19:39:39 INFO - Directory to install application into. [default: 19:39:39 INFO - "/builds/slave/test"] 19:39:39 INFO - --app=APP Application being installed. [default: firefox] 19:39:39 INFO - mkdir: /builds/slave/test/build/application 19:39:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:39:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 19:40:13 INFO - Reading from file tmpfile_stdout 19:40:13 INFO - Output received: 19:40:13 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:40:13 INFO - Running post-action listener: _resource_record_post_action 19:40:13 INFO - [mozharness: 2016-08-30 02:40:13.925943Z] Finished install step (success) 19:40:13 INFO - [mozharness: 2016-08-30 02:40:13.926070Z] Running run-tests step. 19:40:13 INFO - Running pre-action listener: _resource_record_pre_action 19:40:13 INFO - Running main action method: run_tests 19:40:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:40:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:40:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:40:13 INFO - 2016-08-29 19:40:13.938 screenresolution[1642:8219] starting screenresolution argv=screenresolution get 19:40:13 INFO - 2016-08-29 19:40:13.941 screenresolution[1642:8219] Display 0: 1600x1200x32@60 19:40:13 INFO - 2016-08-29 19:40:13.946 screenresolution[1643:8222] starting screenresolution argv=screenresolution list 19:40:13 INFO - Available Modes on Display 0 19:40:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:40:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:40:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:40:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:40:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:40:14 INFO - Intel Iris: 19:40:14 INFO - Chipset Model: Intel Iris 19:40:14 INFO - Type: GPU 19:40:14 INFO - Bus: Built-In 19:40:14 INFO - VRAM (Dynamic, Max): 1536 MB 19:40:14 INFO - Vendor: Intel (0x8086) 19:40:14 INFO - Device ID: 0x0a2e 19:40:14 INFO - Revision ID: 0x0009 19:40:14 INFO - Displays: 19:40:14 INFO - Display: 19:40:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:40:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:40:14 INFO - Display Serial Number: 1600x1200 60 19:40:14 INFO - Main Display: Yes 19:40:14 INFO - Mirror: Off 19:40:14 INFO - Online: Yes 19:40:14 INFO - Rotation: Supported 19:40:14 INFO - Return code: 0 19:40:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:40:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:40:14 INFO - Python 2.7.3 19:40:14 INFO - Return code: 0 19:40:14 INFO - grabbing minidump binary from tooltool 19:40:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:40:14 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 0x102888d30>, '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 0x1025eddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102880b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:40:14 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 19:40:14 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 19:40:14 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 19:40:14 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:40:14 INFO - Return code: 0 19:40:14 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:40:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:40:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:40:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:40:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:40:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ac9x4KLKQ6uMpYiV1gRkMQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0371', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 19:40:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Ac9x4KLKQ6uMpYiV1gRkMQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0371 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 19:40:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.za6u9CLsa2/Render', 19:40:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:40:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:40:14 INFO - 'HOME': '/Users/cltbld', 19:40:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:40:14 INFO - 'LOGNAME': 'cltbld', 19:40:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:40:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:40:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:40:14 INFO - 'MOZ_NO_REMOTE': '1', 19:40:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:40:14 INFO - 'NO_EM_RESTART': '1', 19:40:14 INFO - 'PAGER': '/bin/cat', 19:40:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:40:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:40:14 INFO - 'PWD': '/builds/slave/test', 19:40:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:40:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:40:14 INFO - 'SHELL': '/bin/bash', 19:40:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.70fRKRFR8z/Listeners', 19:40:14 INFO - 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', 19:40:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:40:14 INFO - 'USER': 'cltbld', 19:40:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:40:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:40:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:40:14 INFO - 'XPC_FLAGS': '0x0', 19:40:14 INFO - 'XPC_SERVICE_NAME': '0', 19:40:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:40:14 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ac9x4KLKQ6uMpYiV1gRkMQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0371', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 19:40:14 INFO - mozversion application_buildid: 20160829174130 19:40:14 INFO - mozversion application_changeset: 6c0d74730bd57da6c1c9bf3d05608cd8156bb65f 19:40:14 INFO - mozversion application_display_name: Nightly 19:40:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:40:14 INFO - mozversion application_name: Firefox 19:40:14 INFO - mozversion application_remotingname: firefox 19:40:14 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:40:14 INFO - mozversion application_vendor: Mozilla 19:40:14 INFO - mozversion application_version: 51.0a1 19:40:14 INFO - mozversion platform_buildid: 20160829174130 19:40:14 INFO - mozversion platform_changeset: 6c0d74730bd57da6c1c9bf3d05608cd8156bb65f 19:40:14 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:40:14 INFO - mozversion platform_version: 51.0a1 19:40:14 INFO - using testdate: 1472524814 19:40:14 INFO - actual date: 1472524814 19:40:14 INFO - starting webserver on 'localhost:49276' 19:40:14 INFO - SUITE-START | Running 2 tests 19:40:14 INFO - TEST-START | dromaeo_css 19:40:14 INFO - operating with platform_type : mac_ 19:40:14 INFO - Initialising browser for dromaeo_css test... 19:40:14 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/tmpWNxJF7/profile http://localhost:49276/getInfo.html) 19:40:16 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 19:40:16 INFO - PROCESS | 1653 | colorDepth:24 19:40:16 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 19:40:16 INFO - PROCESS | 1653 | __metrics 19:40:16 INFO - PROCESS | 1653 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:40:16 INFO - PROCESS | 1653 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:40:17 INFO - TEST-INFO | 1653: exit 0 19:40:17 INFO - Browser initialized. 19:40:17 INFO - Running cycle 1/1 for dromaeo_css test... 19:40:17 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/tmpWNxJF7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:40:29 INFO - PROCESS | 1656 | RSS: Main: 234524672 19:40:29 INFO - PROCESS | 1656 | 19:41:21 INFO - PROCESS | 1656 | 1472524881294 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 19:43:32 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49276/tests/dromaeo/cssquery-ext.html) 19:43:33 INFO - PROCESS | 1656 | RSS: Main: 387010560 19:43:33 INFO - PROCESS | 1656 | 19:46:37 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-ext.html (next: http://localhost:49276/tests/dromaeo/cssquery-jquery.html) 19:46:37 INFO - PROCESS | 1656 | RSS: Main: 371552256 19:46:37 INFO - PROCESS | 1656 | 19:49:39 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49276/tests/dromaeo/cssquery-mootools.html) 19:49:40 INFO - PROCESS | 1656 | RSS: Main: 337260544 19:49:40 INFO - PROCESS | 1656 | 19:52:42 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49276/tests/dromaeo/cssquery-prototype.html) 19:52:43 INFO - PROCESS | 1656 | RSS: Main: 333983744 19:52:43 INFO - PROCESS | 1656 | 19:55:46 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49276/tests/dromaeo/cssquery-yui.html) 19:55:47 INFO - PROCESS | 1656 | RSS: Main: 328921088 19:55:47 INFO - PROCESS | 1656 | 19:58:50 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-yui.html (next: http://localhost:49276/tests/dromaeo/cssquery-dojo.html) 19:58:51 INFO - PROCESS | 1656 | RSS: Main: 416157696 19:58:51 INFO - PROCESS | 1656 | 19:58:51 INFO - PROCESS | 1656 | __start_tp_report 19:58:51 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 19:58:51 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 19:58:51 INFO - PROCESS | 1656 | |i|pagename|runs| 19:58:51 INFO - PROCESS | 1656 | |0;dojo.html;3332.766706330557;3344.414727422701;3356.16095976006;3380.7322254154706;3405.6934875861184;5573.470520300565;5613.382527921925;5641;5738.081906894892;5783.537317014634;5652.152177173426;5706.457886500786;5785.27684039495;5799.565032622549;5839.503642190419;5163.457836927125;5178.9900969311;5235.712035838027;5238.240455134003;5328.333958255218;5311.097113490697;5314.867128321785;5339.199120131972;5348.91976620351;5353.544948679367;5164.664296820694;5227.60792940531;5236.1098613235845;5250.92123618148;5288.947110528884;5182.766775495112;5248.763805628756;5250.7637156328055;5259.789608415659;5260.263563101169;5200.011997720459;5203.219517072432;5210.114280572312;5210.6613070150315;5297.761600727938;5744.396838331999;5759.5968282220265;5764.567657425668;5773.393793651648;5775.537956963432;6066.029435290377;6081.695915204222;6158.6612736299485;6213.316535181127;6277.4664153547;6198.2252218472695;6260.686965651745;6302.716377763011;6333.398327158912;6344.555881088326;5810.709464526757;5820.18517407559;5828.038373668382;5832.679202643811;5832.766689332379;6251.74993000284;6316.30520642729;6317.9684101580115;6318.873622527523;6397.904031439532;6093.26880774301;6093.634381937098;6104.481119104926;6118.527810132562;6184.6598437086395;6289.40250676185;6299.023651333959;6307.2431308242385;6308.369163083655;6310.716017779211;6000.309964354068;6121.663308517986;6176.166217560662;6231.844203894939;6235.438810506986;6071.332153463204;6146.7233974471255;6152.953997820381;6185.165002724664;6232.065190221412;4941.283513890466;4945.208766597327;4945.431275403303;4946.802127914843;4981.701097934136;4409.404730361424;4439.800208990603;4447.221736196153;4450.488193857748;4470.977645111753;5566.387697353288;5665.773369065274;5672.914906276409;5691.402402747734;5721.742521586539;6170.876582468325;6293.402126797947;6321.652309123042;6333.588316759396;6334.968325158345;5674.26234589509;5727.427257274323;5735.254416925773;5745.626534275259;5781.797637082682;5310.654807437504;5379.623426360117;5398.271233383522;5413.566914646819;5415.431379705153;6345.302016778243;6349.174607301022;6369.49044076482;6370.6814659267775;6485.3514648534765;6326.3041065483685;6333.714982825692;6336.17629708135;6339.2392912850755;6342.49260059185;6217.533684973736;6279.120923070683;6280.811575652738;6289.591176573508;6323.20959880015;6123.724432400463;6145.754169833157;6163.285086908429;6196.752129914933;6213.37866213375;6096.207493025879;6219.222597175353;6284.371562843862;6285.779997700059;6346.524010699124;5986.790462333972;6023.939760602338;6106.633601983717;6107.938920610737;6124.448799608057;5047.87380315495;6097.054956481752;6108.205933228652;6122.234720659918;6126.5711400203345;5964.075568286922;5974.522038237017;5980.491658209101;5981.641101533922;5984.431479009487;5997.880042399215;6079.088136779518;6103.8779224414375;6111.174991376285;6116.418940200674;5944.8811023780145;5970.611910225822;5978.222831031938;5991.88016239664;6022.307434644897 19:58:51 INFO - PROCESS | 1656 | |1;ext.html;460.6729222252201;520.8359366799457;525.9421463639;529.987723448214;578.1097110449907;30136.698633013664;38815.80592097039;39598.604013959884;39784.80107599458;41953.79023104888;41598;41679.749607511745;41730.791346043225;41758.58241417585;41883.371749423786;9712.077352651522;9917.256205784559;9928.900710992906;9940.453275069869;9967.501624918761;8796.868046979269;8956.417832840845;12009.159358844885;12245.693857653585;12269.754604907897;12166.939165304206;12203.145779795417;12251.754964900696;12254.816177757297;12256.877431225663;12243.142979991404;12328.32194229317;12328.445219965033;12339.629811105591;12348.75302493954;11846.585369512111;11901;11972.70068248292;11986.580469683606;12001.879981200163;38889.61110388916;39073.609263907565;39083.8045809772;39113.413298800544;39291.607083929084;3335.466325387926;3759.812009399519;3980.44273801668;4016.4376987221813;4150.896227594304;27318;30552.388952221052;30580.84709576438;30617.08148755541;30687.69312306893;3204.3591281743743;3294;3347.7489188310974;3378.9662103378896;3387.864485420605;490889.5455522735;491365;491815;492831;499418;55978;56139.157912631345;56325.71837140788;56385.436145638836;56451.435485644615;57925.42074579284;58013.41986580165;58042;58147.70926145342;58183;15382.153981530899;15601;15605.297761600756;15623.062616243062;15671.843281567266;11002.50488728009;11169.329840209613;12200;12241.20432171906;12261.203021803554;14680.04579702316;14911.403543858341;14972.850271497364;15191;15340.463083792014;6163.876722465615;6457.418832305115;6782.626955517494;6796.592204467748;6875.931240687629;889.8264838356458;1035.699647102332;1056.9788604227872;1063.8723353197665;1101.1081024370126;29082.41835163285;29089.709102909124;29297.267568310537;29673.703262967527;30003;1047.7694907120422;1067.1836045425257;1085.1861104171871;1101.9724506887392;1138.5957984915235;14994.250287485582;15009.474668386554;15098.240952542668;15120.168390738614;15224.619384515476;651629;654629;655556;656591;914451;53929;54300.72849635727;54426;58651.70674146687;58667.70666146642;1332.3205165365732;1369.7329020840841;1377.1048818268005;1379.7585422551215;1382.5575815738866;29888.85055574708;30074.54888176723;30074.67820572895;31475;31558.526622100228;47143;54491.45508544864;54542.72728636331;54582.181267280226;54589.45410545844;573184;579704;579897;580781;580877;25194;25463.98144074216;25508.872455638346;25535.6169657459;25541.616875746506;44768.10463790771;46222.7688861567;46260;48090;48578.27132592943;36235.09412264715;36345.58389141769;37974.012728663096;39342.01644958899;39399.606003939596;37226.627733722315;37657.81171094237;37765.24469510539;39110;39288.6071139285;37026.814865925495;37793.433098502996;37990;38041.61958380381;38100.04749881275;29516.852415737783;33207.16982075467;33611.49582756309;33652.49521257232;33964.49053264252;20870.791292086884;23582.29253122409;24673.50652986966;25163.74836251687;25347.87326063358 19:58:51 INFO - PROCESS | 1656 | |2;jquery.html;343.44362133343986;361.726896193374;370.35003568736863;372.8415423445035;396.89283893348795;15774;17153.313867445304;20548.794512054876;21068;21132.0490577924;19718;20465.283715069938;20929.47676308093;21463.67804482934;21473.892630536866;5421.918671219925;5596.804111856086;5619.185218143367;5625.240592520019;6076.756929722828;3907.902302442433;6021.367756385582;6078.6048906820915;6108.236470441195;6123.8162855114415;5855.209546711203;6104.2674879014585;6137.079438084301;6156.907646385308;6175.258968923713;6129.846753831145;6158.353372895848;6171.876562468748;6680.465562754968;6792.49056320773;5946.672932988684;5956.583039187259;5958.970205148947;5963.254593175853;5967.075103358986;13328.133671311334;13432.664183395493;13594.796078058836;13671.111377760413;16593.336266549322;2379.559781440422;2534.2270607464716;2622.8459477829692;2635.551956167437;2877.568364745284;9952.353097048668;9993.950635183273;10045.748856278578;10306.330088544295;10458.63394781179;1846.1876774219436;1910.0545230111045;1917.51103468616;1926.0947354743248;1961.9803801961941;77674.83487747688;102904;128650.35674821566;129116;131912;10847.45762711877;11157.051650609788;11223.775524489627;11443.828342574701;11509.597164099216;14425.20661363614;14428.062175958528;14453.85546144546;14461.927690361481;14464.566063018126;8289.95855020721;8442.915570844336;8469.068402475723;8544.444611100258;8578.571071446402;6783.304173956511;8280.875786863202;8298.045724741733;8327.375446841512;8521.36089793268;4369.9126017479475;4455.420795296591;4458.576435238647;4505.211587972158;4508.684392092588;1597.72838617433;1829.6157806860708;1888.9622207556047;1895.1945423195086;1900.1449347793548;380.4483498926512;380.87811900191673;405.03601428599745;852.4757274276276;875.3609864798606;11543.134264930164;11779.293242405483;11803.586874459354;12038.518459261531;12077.45651445687;425.1327292323687;429.69921055261443;432.75765571280186;442.8427908092645;456.35881586371204;6092.116643086816;6176.8764624707255;10882.035497281746;11883.940580297216;11961.90666633353;597696;604042;615248;619820;620915;126636;130701.34649326882;134317;136439;144952;992.5136683025265;1065.280935368623;1096.1614365010773;1098.5001824169983;1100.5432745410726;19239.422817315502;19362.031898405305;19576;19583.804161958196;19588.412347629594;56884;57172.42827571671;58310.708446457495;58443.70778146082;58807;63626;78274;79546.61360159205;83463;83703.16296836954;5872.120921305142;7041.3310735480045;7349.265073492608;7805.375569954504;8397.496150231005;10418.791624167323;10919.890801092206;11514.712132196762;11845.940770296093;12901.419436125585;9453.338266321567;9903.851442228228;11128.777424451628;11169.609063682732;11241;10928.836067458835;11146.442677866236;11538.769224615293;11618.593349233075;11725.706857328294;11426.614112250765;11778.116641251769;11875.406229688308;11893.643190704293;11910.404479776149;6230.657313847782;7252.5648461092505;7476.626168691435;7519.135299440634;7524.962375188089;10284.074433300739;10939.890601093886;11130.833037504282;11641.708957276136;11676.474558645052 19:58:51 INFO - PROCESS | 1656 | |3;mootools.html;1322.8875545578626;1468.6475245940974;1493.4175671488117;1659.8838081334304;1846.7414561961327;7684.577348245844;8173.959130204348;8186.263236308731;8233.135520770322;8489.91510084899;8517.574121293927;8724.825503489927;8741.431806932545;8743.737687869358;8747.300215982723;2364.7398786133513;2388.773066558674;2390.8685022323766;2392.2942731894045;2399.1243196233336;2342.590046741822;2373.050779688121;2396.341006223294;2398.520295940819;2398.760123987605;2349.2717257650097;2383.1063351243283;2387.785099341059;2390.0917651292484;2393.1145476173842;2335.591271527476;2351.7177938647387;2358.3868194269444;2366;2375.085592047057;2012.5169959209684;2311.7457079721216;2341.285907798121;2352.6941497605203;2356.2695564374926;8479.06730259671;8553.059163492011;8570;8708.521031343274;8770.780730481725;5025.949740502585;5104.974475127638;5173.405058418292;5174.3790745110455;5180.481951804827;3626.9818650906577;3664.798436086014;3723.646253605903;3725.6646901778972;3729.179580492287;961.2502248246379;1093.7429704019548;1121.4504892602647;1122.3097794856187;1127.4926283172606;11371.658850234424;11479.737228904814;11488.712782180428;11491.42542872853;11498.367589782558;4428.269335559597;4502.594766471033;4502.774861256989;4563.931541026887;4581.221192397234;7728.381729461629;8950.194482496607;8965;8968.955155224183;8969.820603588023;5126.615503837227;5143.048536020848;5147.356580427447;5154.92267615986;5155.587552995751;4093.1199792044854;4346.9347959780625;4372.671055654879;4847.127517046965;4873.171560834667;3666.5783434904793;3676.6139555346303;3779.7165212609166;3780.432935059763;3827.0432391902023;1119.88801119889;1146.7534480086822;1151.702353804166;1154.3766366162347;1154.8440960470398;731.741404783771;884.5709830732089;895.5298468304062;900.3607438718583;900.7162743735834;5167.527254732383;5386.838394848082;5556.77772889088;5685.4883060523925;5934.762609495659;415.6612360925836;421.8671118597632;426.3860041540172;433.6985794872596;438.0472472372577;3436.244026314207;3738.5139931809235;3773.905652358713;3884.4173373993563;3921.117748506563;10319.638812641671;10569.63006294776;10658.78682426347;10667.946660266804;10687.358758474516;10638.042576168182;10639.095676867399;10641.787164256672;10650.254482186327;10687.091597214168;7829.608519573999;8055.395845311508;8191.426600137933;8216.055153657406;8232.094469608339;5640.802571909963;5665.886682266249;5686.2607860977805;5687.516561092353;5801.738921748616;9690.51547422612;10026.147777438708;10040.899591003996;10051.949740251544;10077.294589378673;9313.534323283944;9686.9031309686;9895.802083958424;10104.797904042025;10126.8480972784;6635.900461493178;6889.414399775875;6897.827554310981;6946.707586496343;6967.372936435745;8122.918770812453;8194.713185038496;8287.668493260202;8299.08710041907;8303.294219991369;7396.704131834666;7432.442566807619;7441.4418918582405;7449.106107267163;7453.850922981618;6239.532035097295;7284.453665974967;7295.416366690758;7299.781006569811;7363.521371110861;6804;6890.517663763489;6899.241083480913;6965.582065076112;6989.685464154024;4739.668223224341;4789.473157952692;4842.951570484391;4876.536729010738;4895.192293271571;4806.399200099988;4911.165101932608;4954.281629163823;4974.0797952378925;5047.2429135629945 19:58:51 INFO - PROCESS | 1656 | |4;prototype.html;296.88718287050926;302.9530422784468;306.01920843695956;310.4597999480904;311.778637167611;4440.933385999208;5155.4844515548475;5212.739363031852;5249.396319423267;5257.86855328617;4407.853957970927;5193.45468725784;5200.479952004797;5250;5257.8948421031555;3377.1894745261143;3837.808109594523;3890.513685789276;3999.1801680655394;4042.978510744628;3225.919352016195;3824.2351529694033;4021.959780402188;4023.4769479967563;4046.6560342370935;3491.860325586986;3512.016635342108;3871.541571102356;3904.6876249900083;4019.5578486366476;3518.888666799925;3733.757305775143;3736.4955730976376;3910.198409326081;3990.3615421532704;3091.0263267071027;3466.30673865228;3497;3497.45789402643;3500.562429696288;4241.978790106061;5169.888473978112;5190.870228244286;5197.870053248661;5230.424653288135;3527.805970671612;3594.101474631342;4022.5374081980362;4506.211413002744;4596.264597664391;4815.518448155191;5436.565074794007;5446.509814116748;5472.890542189174;5576;2892.898748543791;3578.588462326814;3746.6280347687025;3746.98831315546;3757.9436308455397;4765.523447655207;5549.806791539839;5809.506191973687;5877.412258774131;5887.470127688529;3232.595925509311;3752.399616061417;3819.3316169670197;3824.8852534424013;3828.368319227352;3256.902292931216;3591.9281614367565;3882.9805850970565;3886.4753258309765;3920.196359746245;4525.818967241339;4533.365328853963;4548.795304211319;4563.589276965089;4582.837841889086;5327.6702061211745;5460.5085542300585;5558.276934150426;5608.270924779834;5608.327000759935;1182.5920057580122;1303.7979113237463;1324.536412255722;1326.6351753267736;1334.552924770213;672.8923372260415;765.9655315510815;796.2793671727096;807.7132617920671;813.7224557444873;4359.956400436018;4909.410870695539;5097.388313402415;5112.3865136183895;5123.923141152885;5234.241035049897;5453.945460545423;5599.964006658781;5614.522765564891;5688.752942587655;5079.593632509391;5098.566621837185;5098.629137451103;5101.311322971425;5109.489051094861;2766.966279784357;2979.940401191964;2983.731464168235;2988.5218365061487;3016.56259842326;5868.765249389977;5889.617174883619;5890.646561206341;5907;5938.257717785277;4475.686701930898;5738.770449181986;5790.913136302959;5792.884142317214;5917.230760001258;5563.658887468076;5743.712814359265;5754.280714910637;5872.324682661463;5890.852728681817;5173.767180476812;5241.030409374278;5592.51798273466;5629.789595237044;5681;4883.243097319921;5236.6857988520815;5407.485903946901;5637.49262566371;5680.233168522197;5268.4994925482015;5912.526997840077;5932.783779325314;5944.762209511571;5948.345681975065;2855.9428811424073;2936.3246453316283;3053.0077724739103;3256.5603643507825;3282.458394364903;4161.188568229166;4200.852970146152;4602.176300289454;4607.958747226814;4646.512116227747;3901.5318161820765;4345.913081738411;4473.776311184362;4492.2831027421735;4604.654650901129;3918.9020274493364;4224.556421575813;4511.887202819956;4602.608778253886;4699.626688522274;4249.723767955073;4258.127083947845;4487.865364039084;4556.794944227451;4570.360149578995;4007.4589930360194;4313.50394704613;5211.957608478396;5373.629724420248;5404.972975135099;5188.896222075613;5272.55183309423;5273.973630131824;5286.709230992333;5359.598030147832 19:58:51 INFO - PROCESS | 1656 | |5;yui.html;667.582760774516;744.1070715141831;772.3126417488436;777.1102088109116;838.5220424358116;14804.555863324103;15010.84989150111;15599.532014039583;15626.453074142408;15801.920990395032;13880.444782208724;14116.364763585638;14126.57620271391;14127.445980942113;14617.269136543156;668.8796016716989;694.6075467360951;814.8370325934807;839.2698352433391;849.6389034660275;815.2621877201112;841.70540310891;848.0374774630777;848.1433751910583;855.4653341661461;880.4629176202511;882.2016075451694;883.1212943121603;883.5405589093667;883.960221790021;693.2755270742086;707.3492387003969;876.1763941894599;877.2543338162575;878.3105262369041;555.2448669809056;645.7481582182905;645.8514541655398;648.4326214562258;648.4552975500555;14681.192534410604;14716.779248311284;14734.042287251294;14760.852391476055;14766;13318.400671969785;13550.86449135506;13652.385642646104;13657.590272291747;13938.442462301497;12151.696207594792;12230.266184028987;12478.75042499154;12844;12998.350082495837;4461.643068554507;4474.507804141542;4474.8881277967985;4670.95329046712;4677.204875171229;119446.99999999913;122927.77072229343;124307;125413;129306;10767.784644307227;10788.51451684676;10794.352338859693;10804.405757683402;11192.608258710907;20826.85452300108;20911.581768364547;20936.95315234232;20942.581148377252;20987.475313117295;7691.692412289813;7760.805979850549;7839.412044096715;7898.170692077364;8016.118226995026;4356.738595684205;4359.498657654347;4477.194105061055;4554.3168524720895;4576.610988065985;3331.866725331008;3432.1248081738995;3439.673231043047;3440.3807314683095;3444.5522082129664;632.0550776589029;633.395107672172;639.1020616034479;639.9264084630234;641.2273210780997;513.4531723714232;588.3146134752965;590.768248202494;608.4523928464329;624.9127912771365;8591.785205369792;8816.193495175523;8822.558872056372;8839.116088391238;8918.598663060178;530.9787608495694;549.5191707256151;590.3948452835792;597.6055803169887;630.1209812311891;4195.181939521825;4306.4832220133785;4449.555044495589;4504.369388285642;4687.226607609706;160687;162927;164753;165924.38529302832;167605;114207.42896285631;114809;115263;128343;129103;75194.62402688061;104673;106337.40493892443;110342;112859.43570282096;9770.804583908424;9918.851217231602;9923.950380248052;9970.700878973643;9991.600335986479;56280.43719562752;61604.383956159865;63175.73648527096;63950.680246598466;64831.67584162049;88867.55566222128;90502.09497904936;93919.06080939376;94529.05470945203;97535.51232243793;10777.245592808427;12966.027547933754;12998.480060797463;13008.259766413319;13034.739305214032;14519.92740036293;14545.563633091024;14560.927195363955;14655.706885862437;14998.325075371853;13406.32968351598;13639;13647.11293765902;13711.588652340446;14021.228832414316;13597.320133993064;13603.455861765418;13714.382852771849;14208.71582568322;14254.857451425352;13209.207447553177;13327.733445331232;13376.665583360105;13391.104324892207;13931.58205253844;10240.436775977094;11270.703869054836;11296.774064518828;11339.546418143513;11689;10114.926440079913;11066.446677665923;11318;11511.251768635011;11518.481668324777 19:58:51 INFO - PROCESS | 1656 | __end_tp_report 19:58:51 INFO - PROCESS | 1656 | __start_cc_report 19:58:51 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,1519 19:58:51 INFO - PROCESS | 1656 | __end_cc_report 19:58:51 INFO - PROCESS | 1656 | __startTimestamp1472525931004__endTimestamp 19:58:51 INFO - PROCESS | 1656 | 19:58:51 INFO - PROCESS | 1656 | ------- Summary: start ------- 19:58:51 INFO - PROCESS | 1656 | Number of tests: 6 19:58:51 INFO - PROCESS | 1656 | 19:58:51 INFO - PROCESS | 1656 | [#0] dojo.html Cycles:165 Average:5758.49 Median:5981.64 stddev:637.23 (10.7%) stddev-sans-first:610.11 19:58:51 INFO - PROCESS | 1656 | Values: 3332.8 3344.4 3356.2 3380.7 3405.7 5573.5 5613.4 5641.0 5738.1 5783.5 5652.2 5706.5 5785.3 5799.6 5839.5 5163.5 5179.0 5235.7 5238.2 5328.3 5311.1 5314.9 5339.2 5348.9 5353.5 5164.7 5227.6 5236.1 5250.9 5288.9 5182.8 5248.8 5250.8 5259.8 5260.3 5200.0 5203.2 5210.1 5210.7 5297.8 5744.4 5759.6 5764.6 5773.4 5775.5 6066.0 6081.7 6158.7 6213.3 6277.5 6198.2 6260.7 6302.7 6333.4 6344.6 5810.7 5820.2 5828.0 5832.7 5832.8 6251.7 6316.3 6318.0 6318.9 6397.9 6093.3 6093.6 6104.5 6118.5 6184.7 6289.4 6299.0 6307.2 6308.4 6310.7 6000.3 6121.7 6176.2 6231.8 6235.4 6071.3 6146.7 6153.0 6185.2 6232.1 4941.3 4945.2 4945.4 4946.8 4981.7 4409.4 4439.8 4447.2 4450.5 4471.0 5566.4 5665.8 5672.9 5691.4 5721.7 6170.9 6293.4 6321.7 6333.6 6335.0 5674.3 5727.4 5735.3 5745.6 5781.8 5310.7 5379.6 5398.3 5413.6 5415.4 6345.3 6349.2 6369.5 6370.7 6485.4 6326.3 6333.7 6336.2 6339.2 6342.5 6217.5 6279.1 6280.8 6289.6 6323.2 6123.7 6145.8 6163.3 6196.8 6213.4 6096.2 6219.2 6284.4 6285.8 6346.5 5986.8 6023.9 6106.6 6107.9 6124.4 5047.9 6097.1 6108.2 6122.2 6126.6 5964.1 5974.5 5980.5 5981.6 5984.4 5997.9 6079.1 6103.9 6111.2 6116.4 5944.9 5970.6 5978.2 5991.9 6022.3 19:58:51 INFO - PROCESS | 1656 | 19:58:51 INFO - PROCESS | 1656 | [#1] ext.html Cycles:180 Average:71841.12 Median:36686.20 stddev:161436.72 (440.0%) stddev-sans-first:161800.63 19:58:51 INFO - PROCESS | 1656 | Values: 460.7 520.8 525.9 530.0 578.1 30136.7 38815.8 39598.6 39784.8 41953.8 41598.0 41679.7 41730.8 41758.6 41883.4 9712.1 9917.3 9928.9 9940.5 9967.5 8796.9 8956.4 12009.2 12245.7 12269.8 12166.9 12203.1 12251.8 12254.8 12256.9 12243.1 12328.3 12328.4 12339.6 12348.8 11846.6 11901.0 11972.7 11986.6 12001.9 38889.6 39073.6 39083.8 39113.4 39291.6 3335.5 3759.8 3980.4 4016.4 4150.9 27318.0 30552.4 30580.8 30617.1 30687.7 3204.4 3294.0 3347.7 3379.0 3387.9 490889.5 491365.0 491815.0 492831.0 499418.0 55978.0 56139.2 56325.7 56385.4 56451.4 57925.4 58013.4 58042.0 58147.7 58183.0 15382.2 15601.0 15605.3 15623.1 15671.8 11002.5 11169.3 12200.0 12241.2 12261.2 14680.0 14911.4 14972.9 15191.0 15340.5 6163.9 6457.4 6782.6 6796.6 6875.9 889.8 1035.7 1057.0 1063.9 1101.1 29082.4 29089.7 29297.3 29673.7 30003.0 1047.8 1067.2 1085.2 1102.0 1138.6 14994.3 15009.5 15098.2 15120.2 15224.6 651629.0 654629.0 655556.0 656591.0 914451.0 53929.0 54300.7 54426.0 58651.7 58667.7 1332.3 1369.7 1377.1 1379.8 1382.6 29888.9 30074.5 30074.7 31475.0 31558.5 47143.0 54491.5 54542.7 54582.2 54589.5 573184.0 579704.0 579897.0 580781.0 580877.0 25194.0 25464.0 25508.9 25535.6 25541.6 44768.1 46222.8 46260.0 48090.0 48578.3 36235.1 36345.6 37974.0 39342.0 39399.6 37226.6 37657.8 37765.2 39110.0 39288.6 37026.8 37793.4 37990.0 38041.6 38100.0 29516.9 33207.2 33611.5 33652.5 33964.5 20870.8 23582.3 24673.5 25163.7 25347.9 19:58:51 INFO - PROCESS | 1656 | 19:58:51 INFO - PROCESS | 1656 | [#2] jquery.html Cycles:180 Average:34913.41 Median:2756.56 stddev:102264.76 (3709.9%) stddev-sans-first:102518.70 19:58:51 INFO - PROCESS | 1656 | Values: 343.4 361.7 370.4 372.8 396.9 15774.0 17153.3 20548.8 21068.0 21132.0 19718.0 20465.3 20929.5 21463.7 21473.9 5421.9 5596.8 5619.2 5625.2 6076.8 3907.9 6021.4 6078.6 6108.2 6123.8 5855.2 6104.3 6137.1 6156.9 6175.3 6129.8 6158.4 6171.9 6680.5 6792.5 5946.7 5956.6 5959.0 5963.3 5967.1 13328.1 13432.7 13594.8 13671.1 16593.3 2379.6 2534.2 2622.8 2635.6 2877.6 9952.4 9994.0 10045.7 10306.3 10458.6 1846.2 1910.1 1917.5 1926.1 1962.0 77674.8 102904.0 128650.4 129116.0 131912.0 10847.5 11157.1 11223.8 11443.8 11509.6 14425.2 14428.1 14453.9 14461.9 14464.6 8290.0 8442.9 8469.1 8544.4 8578.6 6783.3 8280.9 8298.0 8327.4 8521.4 4369.9 4455.4 4458.6 4505.2 4508.7 1597.7 1829.6 1889.0 1895.2 1900.1 380.4 380.9 405.0 852.5 875.4 11543.1 11779.3 11803.6 12038.5 12077.5 425.1 429.7 432.8 442.8 456.4 6092.1 6176.9 10882.0 11883.9 11961.9 597696.0 604042.0 615248.0 619820.0 620915.0 126636.0 130701.3 134317.0 136439.0 144952.0 992.5 1065.3 1096.2 1098.5 1100.5 19239.4 19362.0 19576.0 19583.8 19588.4 56884.0 57172.4 58310.7 58443.7 58807.0 63626.0 78274.0 79546.6 83463.0 83703.2 5872.1 7041.3 7349.3 7805.4 8397.5 10418.8 10919.9 11514.7 11845.9 12901.4 9453.3 9903.9 11128.8 11169.6 11241.0 10928.8 11146.4 11538.8 11618.6 11725.7 11426.6 11778.1 11875.4 11893.6 11910.4 6230.7 7252.6 7476.6 7519.1 7525.0 10284.1 10939.9 11130.8 11641.7 11676.5 19:58:51 INFO - PROCESS | 1656 | 19:58:51 INFO - PROCESS | 1656 | [#3] mootools.html Cycles:180 Average:5547.18 Median:4824.68 stddev:3137.52 (65.0%) stddev-sans-first:3130.25 19:58:51 INFO - PROCESS | 1656 | Values: 1322.9 1468.6 1493.4 1659.9 1846.7 7684.6 8174.0 8186.3 8233.1 8489.9 8517.6 8724.8 8741.4 8743.7 8747.3 2364.7 2388.8 2390.9 2392.3 2399.1 2342.6 2373.1 2396.3 2398.5 2398.8 2349.3 2383.1 2387.8 2390.1 2393.1 2335.6 2351.7 2358.4 2366.0 2375.1 2012.5 2311.7 2341.3 2352.7 2356.3 8479.1 8553.1 8570.0 8708.5 8770.8 5025.9 5105.0 5173.4 5174.4 5180.5 3627.0 3664.8 3723.6 3725.7 3729.2 961.3 1093.7 1121.5 1122.3 1127.5 11371.7 11479.7 11488.7 11491.4 11498.4 4428.3 4502.6 4502.8 4563.9 4581.2 7728.4 8950.2 8965.0 8969.0 8969.8 5126.6 5143.0 5147.4 5154.9 5155.6 4093.1 4346.9 4372.7 4847.1 4873.2 3666.6 3676.6 3779.7 3780.4 3827.0 1119.9 1146.8 1151.7 1154.4 1154.8 731.7 884.6 895.5 900.4 900.7 5167.5 5386.8 5556.8 5685.5 5934.8 415.7 421.9 426.4 433.7 438.0 3436.2 3738.5 3773.9 3884.4 3921.1 10319.6 10569.6 10658.8 10667.9 10687.4 10638.0 10639.1 10641.8 10650.3 10687.1 7829.6 8055.4 8191.4 8216.1 8232.1 5640.8 5665.9 5686.3 5687.5 5801.7 9690.5 10026.1 10040.9 10051.9 10077.3 9313.5 9686.9 9895.8 10104.8 10126.8 6635.9 6889.4 6897.8 6946.7 6967.4 8122.9 8194.7 8287.7 8299.1 8303.3 7396.7 7432.4 7441.4 7449.1 7453.9 6239.5 7284.5 7295.4 7299.8 7363.5 6804.0 6890.5 6899.2 6965.6 6989.7 4739.7 4789.5 4843.0 4876.5 4895.2 4806.4 4911.2 4954.3 4974.1 5047.2 19:58:51 INFO - PROCESS | 1656 | 19:58:51 INFO - PROCESS | 1656 | [#4] prototype.html Cycles:180 Average:4312.69 Median:4599.22 stddev:1369.80 (29.8%) stddev-sans-first:1340.07 19:58:51 INFO - PROCESS | 1656 | Values: 296.9 303.0 306.0 310.5 311.8 4440.9 5155.5 5212.7 5249.4 5257.9 4407.9 5193.5 5200.5 5250.0 5257.9 3377.2 3837.8 3890.5 3999.2 4043.0 3225.9 3824.2 4022.0 4023.5 4046.7 3491.9 3512.0 3871.5 3904.7 4019.6 3518.9 3733.8 3736.5 3910.2 3990.4 3091.0 3466.3 3497.0 3497.5 3500.6 4242.0 5169.9 5190.9 5197.9 5230.4 3527.8 3594.1 4022.5 4506.2 4596.3 4815.5 5436.6 5446.5 5472.9 5576.0 2892.9 3578.6 3746.6 3747.0 3757.9 4765.5 5549.8 5809.5 5877.4 5887.5 3232.6 3752.4 3819.3 3824.9 3828.4 3256.9 3591.9 3883.0 3886.5 3920.2 4525.8 4533.4 4548.8 4563.6 4582.8 5327.7 5460.5 5558.3 5608.3 5608.3 1182.6 1303.8 1324.5 1326.6 1334.6 672.9 766.0 796.3 807.7 813.7 4360.0 4909.4 5097.4 5112.4 5123.9 5234.2 5453.9 5600.0 5614.5 5688.8 5079.6 5098.6 5098.6 5101.3 5109.5 2767.0 2979.9 2983.7 2988.5 3016.6 5868.8 5889.6 5890.6 5907.0 5938.3 4475.7 5738.8 5790.9 5792.9 5917.2 5563.7 5743.7 5754.3 5872.3 5890.9 5173.8 5241.0 5592.5 5629.8 5681.0 4883.2 5236.7 5407.5 5637.5 5680.2 5268.5 5912.5 5932.8 5944.8 5948.3 2855.9 2936.3 3053.0 3256.6 3282.5 4161.2 4200.9 4602.2 4608.0 4646.5 3901.5 4345.9 4473.8 4492.3 4604.7 3918.9 4224.6 4511.9 4602.6 4699.6 4249.7 4258.1 4487.9 4556.8 4570.4 4007.5 4313.5 5212.0 5373.6 5405.0 5188.9 5272.6 5274.0 5286.7 5359.6 19:58:51 INFO - PROCESS | 1656 | 19:58:51 INFO - PROCESS | 1656 | [#5] yui.html Cycles:180 Average:25237.78 Median:3435.90 stddev:41013.36 (1193.7%) stddev-sans-first:41086.93 19:58:51 INFO - PROCESS | 1656 | Values: 667.6 744.1 772.3 777.1 838.5 14804.6 15010.8 15599.5 15626.5 15801.9 13880.4 14116.4 14126.6 14127.4 14617.3 668.9 694.6 814.8 839.3 849.6 815.3 841.7 848.0 848.1 855.5 880.5 882.2 883.1 883.5 884.0 693.3 707.3 876.2 877.3 878.3 555.2 645.7 645.9 648.4 648.5 14681.2 14716.8 14734.0 14760.9 14766.0 13318.4 13550.9 13652.4 13657.6 13938.4 12151.7 12230.3 12478.8 12844.0 12998.4 4461.6 4474.5 4474.9 4671.0 4677.2 119447.0 122927.8 124307.0 125413.0 129306.0 10767.8 10788.5 10794.4 10804.4 11192.6 20826.9 20911.6 20937.0 20942.6 20987.5 7691.7 7760.8 7839.4 7898.2 8016.1 4356.7 4359.5 4477.2 4554.3 4576.6 3331.9 3432.1 3439.7 3440.4 3444.6 632.1 633.4 639.1 639.9 641.2 513.5 588.3 590.8 608.5 624.9 8591.8 8816.2 8822.6 8839.1 8918.6 531.0 549.5 590.4 597.6 630.1 4195.2 4306.5 4449.6 4504.4 4687.2 160687.0 162927.0 164753.0 165924.4 167605.0 114207.4 114809.0 115263.0 128343.0 129103.0 75194.6 104673.0 106337.4 110342.0 112859.4 9770.8 9918.9 9924.0 9970.7 9991.6 56280.4 61604.4 63175.7 63950.7 64831.7 88867.6 90502.1 93919.1 94529.1 97535.5 10777.2 12966.0 12998.5 13008.3 13034.7 14519.9 14545.6 14560.9 14655.7 14998.3 13406.3 13639.0 13647.1 13711.6 14021.2 13597.3 13603.5 13714.4 14208.7 14254.9 13209.2 13327.7 13376.7 13391.1 13931.6 10240.4 11270.7 11296.8 11339.5 11689.0 10114.9 11066.4 11318.0 11511.3 11518.5 19:58:51 INFO - PROCESS | 1656 | -------- Summary: end -------- 19:58:51 INFO - PROCESS | 1656 | 19:58:51 INFO - TEST-INFO | 1656: exit 0 19:58:51 INFO - TEST-OK | dromaeo_css | took 1117090ms 19:58:51 INFO - TEST-START | kraken 19:58:51 INFO - operating with platform_type : mac_ 19:58:51 INFO - Initialising browser for kraken test... 19:58:51 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/tmpK1C9al/profile http://localhost:49276/getInfo.html) 19:58:53 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 19:58:53 INFO - PROCESS | 1662 | colorDepth:24 19:58:53 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 19:58:53 INFO - PROCESS | 1662 | __metrics 19:58:54 INFO - PROCESS | 1662 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:58:54 INFO - PROCESS | 1662 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:58:54 INFO - TEST-INFO | 1662: exit 0 19:58:54 INFO - Browser initialized. 19:58:54 INFO - Running cycle 1/1 for kraken test... 19:58:54 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/tmpK1C9al/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:59:06 INFO - PROCESS | 1663 | RSS: Main: 234467328 19:59:06 INFO - PROCESS | 1663 | 19:59:09 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection) 19:59:10 INFO - PROCESS | 1663 | RSS: Main: 292507648 19:59:10 INFO - PROCESS | 1663 | 19:59:12 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-dft) 19:59:13 INFO - PROCESS | 1663 | RSS: Main: 288129024 19:59:13 INFO - PROCESS | 1663 | 19:59:17 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-fft) 19:59:17 INFO - PROCESS | 1663 | RSS: Main: 380469248 19:59:17 INFO - PROCESS | 1663 | 19:59:19 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator) 19:59:20 INFO - PROCESS | 1663 | RSS: Main: 280178688 19:59:20 INFO - PROCESS | 1663 | 19:59:22 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:59:23 INFO - PROCESS | 1663 | RSS: Main: 282771456 19:59:23 INFO - PROCESS | 1663 | 19:59:26 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom) 19:59:26 INFO - PROCESS | 1663 | RSS: Main: 323928064 19:59:26 INFO - PROCESS | 1663 | 19:59:29 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate) 19:59:30 INFO - PROCESS | 1663 | RSS: Main: 325353472 19:59:30 INFO - PROCESS | 1663 | 19:59:32 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial) 19:59:33 INFO - PROCESS | 1663 | RSS: Main: 323223552 19:59:33 INFO - PROCESS | 1663 | 19:59:34 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:59:35 INFO - PROCESS | 1663 | RSS: Main: 297013248 19:59:35 INFO - PROCESS | 1663 | 19:59:37 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:59:38 INFO - PROCESS | 1663 | RSS: Main: 303628288 19:59:38 INFO - PROCESS | 1663 | 19:59:40 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:59:41 INFO - PROCESS | 1663 | RSS: Main: 308596736 19:59:41 INFO - PROCESS | 1663 | 19:59:44 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:59:44 INFO - PROCESS | 1663 | RSS: Main: 321110016 19:59:44 INFO - PROCESS | 1663 | 19:59:47 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:59:48 INFO - PROCESS | 1663 | RSS: Main: 317304832 19:59:48 INFO - PROCESS | 1663 | 19:59:50 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49276/tests/kraken/driver.html?testName=ai-astar) 19:59:50 INFO - PROCESS | 1663 | RSS: Main: 360288256 19:59:50 INFO - PROCESS | 1663 | 19:59:50 INFO - PROCESS | 1663 | __start_tp_report 19:59:50 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 19:59:50 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 19:59:50 INFO - PROCESS | 1663 | |i|pagename|runs| 19:59:50 INFO - PROCESS | 1663 | |0;ai-astar;113;114;132;113;114;113;114;113;113;114 19:59:50 INFO - PROCESS | 1663 | |1;audio-beat-detection;145;115;129;159;105;128;128;107;158;104 19:59:50 INFO - PROCESS | 1663 | |2;audio-dft;185;186;183;184;185;184;182;261;182;184 19:59:50 INFO - PROCESS | 1663 | |3;audio-fft;77;73;77;153;57;58;57;68;144;72 19:59:50 INFO - PROCESS | 1663 | |4;audio-oscillator;82;84;160;71;73;88;108;77;94;140 19:59:50 INFO - PROCESS | 1663 | |5;imaging-gaussian-blur;89;86;90;86;126;86;90;87;88;85 19:59:50 INFO - PROCESS | 1663 | |6;imaging-darkroom;95;95;95;95;96;94;95;95;95;94 19:59:50 INFO - PROCESS | 1663 | |7;imaging-desaturate;86;86;87;86;86;85;86;85;88;84 19:59:50 INFO - PROCESS | 1663 | |8;json-parse-financial;55;53;55;51;51;55;53;53;54;52 19:59:50 INFO - PROCESS | 1663 | |9;json-stringify-tinderbox;66;64;69;75;76;72;70;73;99;103 19:59:50 INFO - PROCESS | 1663 | |10;stanford-crypto-aes;86;89;87;87;86;90;85;85;89;89 19:59:50 INFO - PROCESS | 1663 | |11;stanford-crypto-ccm;151;147;148;139;139;150;127;142;174;151 19:59:50 INFO - PROCESS | 1663 | |12;stanford-crypto-pbkdf2;171;172;172;170;172;229;210;172;176;168 19:59:50 INFO - PROCESS | 1663 | |13;stanford-crypto-sha256-iterative;68;68;71;78;86;88;72;67;66;69 19:59:50 INFO - PROCESS | 1663 | __end_tp_report 19:59:50 INFO - PROCESS | 1663 | __start_cc_report 19:59:50 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,2105 19:59:50 INFO - PROCESS | 1663 | __end_cc_report 19:59:50 INFO - PROCESS | 1663 | __startTimestamp1472525990439__endTimestamp 19:59:50 INFO - PROCESS | 1663 | 19:59:50 INFO - PROCESS | 1663 | ------- Summary: start ------- 19:59:50 INFO - PROCESS | 1663 | Number of tests: 14 19:59:50 INFO - PROCESS | 1663 | 19:59:50 INFO - PROCESS | 1663 | [#0] ai-astar Cycles:10 Average:115.30 Median:114.00 stddev:5.89 (5.2%) stddev-sans-first:6.19 19:59:50 INFO - PROCESS | 1663 | Values: 113.0 114.0 132.0 113.0 114.0 113.0 114.0 113.0 113.0 114.0 19:59:50 INFO - PROCESS | 1663 | 19:59:50 INFO - PROCESS | 1663 | [#1] audio-beat-detection Cycles:10 Average:127.80 Median:128.50 stddev:20.72 (16.1%) stddev-sans-first:21.03 19:59:50 INFO - PROCESS | 1663 | Values: 145.0 115.0 129.0 159.0 105.0 128.0 128.0 107.0 158.0 104.0 19:59:50 INFO - PROCESS | 1663 | 19:59:50 INFO - PROCESS | 1663 | [#2] audio-dft Cycles:10 Average:191.60 Median:184.50 stddev:24.42 (13.2%) stddev-sans-first:25.78 19:59:50 INFO - PROCESS | 1663 | Values: 185.0 186.0 183.0 184.0 185.0 184.0 182.0 261.0 182.0 184.0 19:59:50 INFO - PROCESS | 1663 | 19:59:50 INFO - PROCESS | 1663 | [#3] audio-fft Cycles:10 Average:83.60 Median:70.00 stddev:35.14 (50.2%) stddev-sans-first:37.19 19:59:50 INFO - PROCESS | 1663 | Values: 77.0 73.0 77.0 153.0 57.0 58.0 57.0 68.0 144.0 72.0 19:59:50 INFO - PROCESS | 1663 | 19:59:50 INFO - PROCESS | 1663 | [#4] audio-oscillator Cycles:10 Average:97.70 Median:79.50 stddev:29.94 (37.7%) stddev-sans-first:31.22 19:59:50 INFO - PROCESS | 1663 | Values: 82.0 84.0 160.0 71.0 73.0 88.0 108.0 77.0 94.0 140.0 19:59:50 INFO - PROCESS | 1663 | 19:59:50 INFO - PROCESS | 1663 | [#5] imaging-gaussian-blur Cycles:10 Average:91.30 Median:87.50 stddev:12.32 (14.1%) stddev-sans-first:13.04 19:59:50 INFO - PROCESS | 1663 | Values: 89.0 86.0 90.0 86.0 126.0 86.0 90.0 87.0 88.0 85.0 19:59:50 INFO - PROCESS | 1663 | 19:59:50 INFO - PROCESS | 1663 | [#6] imaging-darkroom Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 19:59:50 INFO - PROCESS | 1663 | Values: 95.0 95.0 95.0 95.0 96.0 94.0 95.0 95.0 95.0 94.0 19:59:50 INFO - PROCESS | 1663 | 19:59:50 INFO - PROCESS | 1663 | [#7] imaging-desaturate Cycles:10 Average:85.90 Median:86.00 stddev:1.10 (1.3%) stddev-sans-first:1.17 19:59:50 INFO - PROCESS | 1663 | Values: 86.0 86.0 87.0 86.0 86.0 85.0 86.0 85.0 88.0 84.0 19:59:50 INFO - PROCESS | 1663 | 19:59:50 INFO - PROCESS | 1663 | [#8] json-parse-financial Cycles:10 Average:53.20 Median:53.50 stddev:1.55 (2.9%) stddev-sans-first:1.50 19:59:50 INFO - PROCESS | 1663 | Values: 55.0 53.0 55.0 51.0 51.0 55.0 53.0 53.0 54.0 52.0 19:59:50 INFO - PROCESS | 1663 | 19:59:50 INFO - PROCESS | 1663 | [#9] json-stringify-tinderbox Cycles:10 Average:76.70 Median:72.50 stddev:13.37 (18.4%) stddev-sans-first:13.61 19:59:50 INFO - PROCESS | 1663 | Values: 66.0 64.0 69.0 75.0 76.0 72.0 70.0 73.0 99.0 103.0 19:59:50 INFO - PROCESS | 1663 | 19:59:50 INFO - PROCESS | 1663 | [#10] stanford-crypto-aes Cycles:10 Average:87.30 Median:88.00 stddev:1.83 (2.1%) stddev-sans-first:1.88 19:59:50 INFO - PROCESS | 1663 | Values: 86.0 89.0 87.0 87.0 86.0 90.0 85.0 85.0 89.0 89.0 19:59:50 INFO - PROCESS | 1663 | 19:59:50 INFO - PROCESS | 1663 | [#11] stanford-crypto-ccm Cycles:10 Average:146.80 Median:149.00 stddev:12.13 (8.1%) stddev-sans-first:12.77 19:59:50 INFO - PROCESS | 1663 | Values: 151.0 147.0 148.0 139.0 139.0 150.0 127.0 142.0 174.0 151.0 19:59:50 INFO - PROCESS | 1663 | 19:59:50 INFO - PROCESS | 1663 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.20 Median:172.00 stddev:20.77 (12.1%) stddev-sans-first:21.70 19:59:50 INFO - PROCESS | 1663 | Values: 171.0 172.0 172.0 170.0 172.0 229.0 210.0 172.0 176.0 168.0 19:59:50 INFO - PROCESS | 1663 | 19:59:50 INFO - PROCESS | 1663 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.30 Median:71.50 stddev:7.99 (11.2%) stddev-sans-first:8.24 19:59:50 INFO - PROCESS | 1663 | Values: 68.0 68.0 71.0 78.0 86.0 88.0 72.0 67.0 66.0 69.0 19:59:50 INFO - PROCESS | 1663 | -------- Summary: end -------- 19:59:50 INFO - PROCESS | 1663 | 19:59:51 INFO - TEST-INFO | 1663: exit 0 19:59:51 INFO - TEST-OK | kraken | took 59724ms 19:59:51 INFO - SUITE-END | took 1176s 19:59:51 INFO - Completed test suite (00:19:37) 19:59:51 INFO - javascript benchmark 19:59:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3332.766706330557, 3344.414727422701, 3356.16095976006, 3380.7322254154706, 3405.6934875861184, 5573.470520300565, 5613.382527921925, 5641.0, 5738.081906894892, 5783.537317014634, 5652.152177173426, 5706.457886500786, 5785.27684039495, 5799.565032622549, 5839.503642190419, 5163.457836927125, 5178.9900969311, 5235.712035838027, 5238.240455134003, 5328.333958255218, 5311.097113490697, 5314.867128321785, 5339.199120131972, 5348.91976620351, 5353.544948679367, 5164.664296820694, 5227.60792940531, 5236.1098613235845, 5250.92123618148, 5288.947110528884, 5182.766775495112, 5248.763805628756, 5250.7637156328055, 5259.789608415659, 5260.263563101169, 5200.011997720459, 5203.219517072432, 5210.114280572312, 5210.6613070150315, 5297.761600727938, 5744.396838331999, 5759.5968282220265, 5764.567657425668, 5773.393793651648, 5775.537956963432, 6066.029435290377, 6081.695915204222, 6158.6612736299485, 6213.316535181127, 6277.4664153547, 6198.2252218472695, 6260.686965651745, 6302.716377763011, 6333.398327158912, 6344.555881088326, 5810.709464526757, 5820.18517407559, 5828.038373668382, 5832.679202643811, 5832.766689332379, 6251.74993000284, 6316.30520642729, 6317.9684101580115, 6318.873622527523, 6397.904031439532, 6093.26880774301, 6093.634381937098, 6104.481119104926, 6118.527810132562, 6184.6598437086395, 6289.40250676185, 6299.023651333959, 6307.2431308242385, 6308.369163083655, 6310.716017779211, 6000.309964354068, 6121.663308517986, 6176.166217560662, 6231.844203894939, 6235.438810506986, 6071.332153463204, 6146.7233974471255, 6152.953997820381, 6185.165002724664, 6232.065190221412, 4941.283513890466, 4945.208766597327, 4945.431275403303, 4946.802127914843, 4981.701097934136, 4409.404730361424, 4439.800208990603, 4447.221736196153, 4450.488193857748, 4470.977645111753, 5566.387697353288, 5665.773369065274, 5672.914906276409, 5691.402402747734, 5721.742521586539, 6170.876582468325, 6293.402126797947, 6321.652309123042, 6333.588316759396, 6334.968325158345, 5674.26234589509, 5727.427257274323, 5735.254416925773, 5745.626534275259, 5781.797637082682, 5310.654807437504, 5379.623426360117, 5398.271233383522, 5413.566914646819, 5415.431379705153, 6345.302016778243, 6349.174607301022, 6369.49044076482, 6370.6814659267775, 6485.3514648534765, 6326.3041065483685, 6333.714982825692, 6336.17629708135, 6339.2392912850755, 6342.49260059185, 6217.533684973736, 6279.120923070683, 6280.811575652738, 6289.591176573508, 6323.20959880015, 6123.724432400463, 6145.754169833157, 6163.285086908429, 6196.752129914933, 6213.37866213375, 6096.207493025879, 6219.222597175353, 6284.371562843862, 6285.779997700059, 6346.524010699124, 5986.790462333972, 6023.939760602338, 6106.633601983717, 6107.938920610737, 6124.448799608057, 5047.87380315495, 6097.054956481752, 6108.205933228652, 6122.234720659918, 6126.5711400203345, 5964.075568286922, 5974.522038237017, 5980.491658209101, 5981.641101533922, 5984.431479009487, 5997.880042399215, 6079.088136779518, 6103.8779224414375, 6111.174991376285, 6116.418940200674, 5944.8811023780145, 5970.611910225822, 5978.222831031938, 5991.88016239664, 6022.307434644897], "value": 5717.3511091007485, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [460.6729222252201, 520.8359366799457, 525.9421463639, 529.987723448214, 578.1097110449907, 30136.698633013664, 38815.80592097039, 39598.604013959884, 39784.80107599458, 41953.79023104888, 41598.0, 41679.749607511745, 41730.791346043225, 41758.58241417585, 41883.371749423786, 9712.077352651522, 9917.256205784559, 9928.900710992906, 9940.453275069869, 9967.501624918761, 8796.868046979269, 8956.417832840845, 12009.159358844885, 12245.693857653585, 12269.754604907897, 12166.939165304206, 12203.145779795417, 12251.754964900696, 12254.816177757297, 12256.877431225663, 12243.142979991404, 12328.32194229317, 12328.445219965033, 12339.629811105591, 12348.75302493954, 11846.585369512111, 11901.0, 11972.70068248292, 11986.580469683606, 12001.879981200163, 38889.61110388916, 39073.609263907565, 39083.8045809772, 39113.413298800544, 39291.607083929084, 3335.466325387926, 3759.812009399519, 3980.44273801668, 4016.4376987221813, 4150.896227594304, 27318.0, 30552.388952221052, 30580.84709576438, 30617.08148755541, 30687.69312306893, 3204.3591281743743, 3294.0, 3347.7489188310974, 3378.9662103378896, 3387.864485420605, 490889.5455522735, 491365.0, 491815.0, 492831.0, 499418.0, 55978.0, 56139.157912631345, 56325.71837140788, 56385.436145638836, 56451.435485644615, 57925.42074579284, 58013.41986580165, 58042.0, 58147.70926145342, 58183.0, 15382.153981530899, 15601.0, 15605.297761600756, 15623.062616243062, 15671.843281567266, 11002.50488728009, 11169.329840209613, 12200.0, 12241.20432171906, 12261.203021803554, 14680.04579702316, 14911.403543858341, 14972.850271497364, 15191.0, 15340.463083792014, 6163.876722465615, 6457.418832305115, 6782.626955517494, 6796.592204467748, 6875.931240687629, 889.8264838356458, 1035.699647102332, 1056.9788604227872, 1063.8723353197665, 1101.1081024370126, 29082.41835163285, 29089.709102909124, 29297.267568310537, 29673.703262967527, 30003.0, 1047.7694907120422, 1067.1836045425257, 1085.1861104171871, 1101.9724506887392, 1138.5957984915235, 14994.250287485582, 15009.474668386554, 15098.240952542668, 15120.168390738614, 15224.619384515476, 651629.0, 654629.0, 655556.0, 656591.0, 914451.0, 53929.0, 54300.72849635727, 54426.0, 58651.70674146687, 58667.70666146642, 1332.3205165365732, 1369.7329020840841, 1377.1048818268005, 1379.7585422551215, 1382.5575815738866, 29888.85055574708, 30074.54888176723, 30074.67820572895, 31475.0, 31558.526622100228, 47143.0, 54491.45508544864, 54542.72728636331, 54582.181267280226, 54589.45410545844, 573184.0, 579704.0, 579897.0, 580781.0, 580877.0, 25194.0, 25463.98144074216, 25508.872455638346, 25535.6169657459, 25541.616875746506, 44768.10463790771, 46222.7688861567, 46260.0, 48090.0, 48578.27132592943, 36235.09412264715, 36345.58389141769, 37974.012728663096, 39342.01644958899, 39399.606003939596, 37226.627733722315, 37657.81171094237, 37765.24469510539, 39110.0, 39288.6071139285, 37026.814865925495, 37793.433098502996, 37990.0, 38041.61958380381, 38100.04749881275, 29516.852415737783, 33207.16982075467, 33611.49582756309, 33652.49521257232, 33964.49053264252, 20870.791292086884, 23582.29253122409, 24673.50652986966, 25163.74836251687, 25347.87326063358], "value": 20160.67378035695, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.44362133343986, 361.726896193374, 370.35003568736863, 372.8415423445035, 396.89283893348795, 15774.0, 17153.313867445304, 20548.794512054876, 21068.0, 21132.0490577924, 19718.0, 20465.283715069938, 20929.47676308093, 21463.67804482934, 21473.892630536866, 5421.918671219925, 5596.804111856086, 5619.185218143367, 5625.240592520019, 6076.756929722828, 3907.902302442433, 6021.367756385582, 6078.6048906820915, 6108.236470441195, 6123.8162855114415, 5855.209546711203, 6104.2674879014585, 6137.079438084301, 6156.907646385308, 6175.258968923713, 6129.846753831145, 6158.353372895848, 6171.876562468748, 6680.465562754968, 6792.49056320773, 5946.672932988684, 5956.583039187259, 5958.970205148947, 5963.254593175853, 5967.075103358986, 13328.133671311334, 13432.664183395493, 13594.796078058836, 13671.111377760413, 16593.336266549322, 2379.559781440422, 2534.2270607464716, 2622.8459477829692, 2635.551956167437, 2877.568364745284, 9952.353097048668, 9993.950635183273, 10045.748856278578, 10306.330088544295, 10458.63394781179, 1846.1876774219436, 1910.0545230111045, 1917.51103468616, 1926.0947354743248, 1961.9803801961941, 77674.83487747688, 102904.0, 128650.35674821566, 129116.0, 131912.0, 10847.45762711877, 11157.051650609788, 11223.775524489627, 11443.828342574701, 11509.597164099216, 14425.20661363614, 14428.062175958528, 14453.85546144546, 14461.927690361481, 14464.566063018126, 8289.95855020721, 8442.915570844336, 8469.068402475723, 8544.444611100258, 8578.571071446402, 6783.304173956511, 8280.875786863202, 8298.045724741733, 8327.375446841512, 8521.36089793268, 4369.9126017479475, 4455.420795296591, 4458.576435238647, 4505.211587972158, 4508.684392092588, 1597.72838617433, 1829.6157806860708, 1888.9622207556047, 1895.1945423195086, 1900.1449347793548, 380.4483498926512, 380.87811900191673, 405.03601428599745, 852.4757274276276, 875.3609864798606, 11543.134264930164, 11779.293242405483, 11803.586874459354, 12038.518459261531, 12077.45651445687, 425.1327292323687, 429.69921055261443, 432.75765571280186, 442.8427908092645, 456.35881586371204, 6092.116643086816, 6176.8764624707255, 10882.035497281746, 11883.940580297216, 11961.90666633353, 597696.0, 604042.0, 615248.0, 619820.0, 620915.0, 126636.0, 130701.34649326882, 134317.0, 136439.0, 144952.0, 992.5136683025265, 1065.280935368623, 1096.1614365010773, 1098.5001824169983, 1100.5432745410726, 19239.422817315502, 19362.031898405305, 19576.0, 19583.804161958196, 19588.412347629594, 56884.0, 57172.42827571671, 58310.708446457495, 58443.70778146082, 58807.0, 63626.0, 78274.0, 79546.61360159205, 83463.0, 83703.16296836954, 5872.120921305142, 7041.3310735480045, 7349.265073492608, 7805.375569954504, 8397.496150231005, 10418.791624167323, 10919.890801092206, 11514.712132196762, 11845.940770296093, 12901.419436125585, 9453.338266321567, 9903.851442228228, 11128.777424451628, 11169.609063682732, 11241.0, 10928.836067458835, 11146.442677866236, 11538.769224615293, 11618.593349233075, 11725.706857328294, 11426.614112250765, 11778.116641251769, 11875.406229688308, 11893.643190704293, 11910.404479776149, 6230.657313847782, 7252.5648461092505, 7476.626168691435, 7519.135299440634, 7524.962375188089, 10284.074433300739, 10939.890601093886, 11130.833037504282, 11641.708957276136, 11676.474558645052], "value": 8926.36371588603, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1322.8875545578626, 1468.6475245940974, 1493.4175671488117, 1659.8838081334304, 1846.7414561961327, 7684.577348245844, 8173.959130204348, 8186.263236308731, 8233.135520770322, 8489.91510084899, 8517.574121293927, 8724.825503489927, 8741.431806932545, 8743.737687869358, 8747.300215982723, 2364.7398786133513, 2388.773066558674, 2390.8685022323766, 2392.2942731894045, 2399.1243196233336, 2342.590046741822, 2373.050779688121, 2396.341006223294, 2398.520295940819, 2398.760123987605, 2349.2717257650097, 2383.1063351243283, 2387.785099341059, 2390.0917651292484, 2393.1145476173842, 2335.591271527476, 2351.7177938647387, 2358.3868194269444, 2366.0, 2375.085592047057, 2012.5169959209684, 2311.7457079721216, 2341.285907798121, 2352.6941497605203, 2356.2695564374926, 8479.06730259671, 8553.059163492011, 8570.0, 8708.521031343274, 8770.780730481725, 5025.949740502585, 5104.974475127638, 5173.405058418292, 5174.3790745110455, 5180.481951804827, 3626.9818650906577, 3664.798436086014, 3723.646253605903, 3725.6646901778972, 3729.179580492287, 961.2502248246379, 1093.7429704019548, 1121.4504892602647, 1122.3097794856187, 1127.4926283172606, 11371.658850234424, 11479.737228904814, 11488.712782180428, 11491.42542872853, 11498.367589782558, 4428.269335559597, 4502.594766471033, 4502.774861256989, 4563.931541026887, 4581.221192397234, 7728.381729461629, 8950.194482496607, 8965.0, 8968.955155224183, 8969.820603588023, 5126.615503837227, 5143.048536020848, 5147.356580427447, 5154.92267615986, 5155.587552995751, 4093.1199792044854, 4346.9347959780625, 4372.671055654879, 4847.127517046965, 4873.171560834667, 3666.5783434904793, 3676.6139555346303, 3779.7165212609166, 3780.432935059763, 3827.0432391902023, 1119.88801119889, 1146.7534480086822, 1151.702353804166, 1154.3766366162347, 1154.8440960470398, 731.741404783771, 884.5709830732089, 895.5298468304062, 900.3607438718583, 900.7162743735834, 5167.527254732383, 5386.838394848082, 5556.77772889088, 5685.4883060523925, 5934.762609495659, 415.6612360925836, 421.8671118597632, 426.3860041540172, 433.6985794872596, 438.0472472372577, 3436.244026314207, 3738.5139931809235, 3773.905652358713, 3884.4173373993563, 3921.117748506563, 10319.638812641671, 10569.63006294776, 10658.78682426347, 10667.946660266804, 10687.358758474516, 10638.042576168182, 10639.095676867399, 10641.787164256672, 10650.254482186327, 10687.091597214168, 7829.608519573999, 8055.395845311508, 8191.426600137933, 8216.055153657406, 8232.094469608339, 5640.802571909963, 5665.886682266249, 5686.2607860977805, 5687.516561092353, 5801.738921748616, 9690.51547422612, 10026.147777438708, 10040.899591003996, 10051.949740251544, 10077.294589378673, 9313.534323283944, 9686.9031309686, 9895.802083958424, 10104.797904042025, 10126.8480972784, 6635.900461493178, 6889.414399775875, 6897.827554310981, 6946.707586496343, 6967.372936435745, 8122.918770812453, 8194.713185038496, 8287.668493260202, 8299.08710041907, 8303.294219991369, 7396.704131834666, 7432.442566807619, 7441.4418918582405, 7449.106107267163, 7453.850922981618, 6239.532035097295, 7284.453665974967, 7295.416366690758, 7299.781006569811, 7363.521371110861, 6804.0, 6890.517663763489, 6899.241083480913, 6965.582065076112, 6989.685464154024, 4739.668223224341, 4789.473157952692, 4842.951570484391, 4876.536729010738, 4895.192293271571, 4806.399200099988, 4911.165101932608, 4954.281629163823, 4974.0797952378925, 5047.2429135629945], "value": 4379.423221185835, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.88718287050926, 302.9530422784468, 306.01920843695956, 310.4597999480904, 311.778637167611, 4440.933385999208, 5155.4844515548475, 5212.739363031852, 5249.396319423267, 5257.86855328617, 4407.853957970927, 5193.45468725784, 5200.479952004797, 5250.0, 5257.8948421031555, 3377.1894745261143, 3837.808109594523, 3890.513685789276, 3999.1801680655394, 4042.978510744628, 3225.919352016195, 3824.2351529694033, 4021.959780402188, 4023.4769479967563, 4046.6560342370935, 3491.860325586986, 3512.016635342108, 3871.541571102356, 3904.6876249900083, 4019.5578486366476, 3518.888666799925, 3733.757305775143, 3736.4955730976376, 3910.198409326081, 3990.3615421532704, 3091.0263267071027, 3466.30673865228, 3497.0, 3497.45789402643, 3500.562429696288, 4241.978790106061, 5169.888473978112, 5190.870228244286, 5197.870053248661, 5230.424653288135, 3527.805970671612, 3594.101474631342, 4022.5374081980362, 4506.211413002744, 4596.264597664391, 4815.518448155191, 5436.565074794007, 5446.509814116748, 5472.890542189174, 5576.0, 2892.898748543791, 3578.588462326814, 3746.6280347687025, 3746.98831315546, 3757.9436308455397, 4765.523447655207, 5549.806791539839, 5809.506191973687, 5877.412258774131, 5887.470127688529, 3232.595925509311, 3752.399616061417, 3819.3316169670197, 3824.8852534424013, 3828.368319227352, 3256.902292931216, 3591.9281614367565, 3882.9805850970565, 3886.4753258309765, 3920.196359746245, 4525.818967241339, 4533.365328853963, 4548.795304211319, 4563.589276965089, 4582.837841889086, 5327.6702061211745, 5460.5085542300585, 5558.276934150426, 5608.270924779834, 5608.327000759935, 1182.5920057580122, 1303.7979113237463, 1324.536412255722, 1326.6351753267736, 1334.552924770213, 672.8923372260415, 765.9655315510815, 796.2793671727096, 807.7132617920671, 813.7224557444873, 4359.956400436018, 4909.410870695539, 5097.388313402415, 5112.3865136183895, 5123.923141152885, 5234.241035049897, 5453.945460545423, 5599.964006658781, 5614.522765564891, 5688.752942587655, 5079.593632509391, 5098.566621837185, 5098.629137451103, 5101.311322971425, 5109.489051094861, 2766.966279784357, 2979.940401191964, 2983.731464168235, 2988.5218365061487, 3016.56259842326, 5868.765249389977, 5889.617174883619, 5890.646561206341, 5907.0, 5938.257717785277, 4475.686701930898, 5738.770449181986, 5790.913136302959, 5792.884142317214, 5917.230760001258, 5563.658887468076, 5743.712814359265, 5754.280714910637, 5872.324682661463, 5890.852728681817, 5173.767180476812, 5241.030409374278, 5592.51798273466, 5629.789595237044, 5681.0, 4883.243097319921, 5236.6857988520815, 5407.485903946901, 5637.49262566371, 5680.233168522197, 5268.4994925482015, 5912.526997840077, 5932.783779325314, 5944.762209511571, 5948.345681975065, 2855.9428811424073, 2936.3246453316283, 3053.0077724739103, 3256.5603643507825, 3282.458394364903, 4161.188568229166, 4200.852970146152, 4602.176300289454, 4607.958747226814, 4646.512116227747, 3901.5318161820765, 4345.913081738411, 4473.776311184362, 4492.2831027421735, 4604.654650901129, 3918.9020274493364, 4224.556421575813, 4511.887202819956, 4602.608778253886, 4699.626688522274, 4249.723767955073, 4258.127083947845, 4487.865364039084, 4556.794944227451, 4570.360149578995, 4007.4589930360194, 4313.50394704613, 5211.957608478396, 5373.629724420248, 5404.972975135099, 5188.896222075613, 5272.55183309423, 5273.973630131824, 5286.709230992333, 5359.598030147832], "value": 3881.34078941955, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [667.582760774516, 744.1070715141831, 772.3126417488436, 777.1102088109116, 838.5220424358116, 14804.555863324103, 15010.84989150111, 15599.532014039583, 15626.453074142408, 15801.920990395032, 13880.444782208724, 14116.364763585638, 14126.57620271391, 14127.445980942113, 14617.269136543156, 668.8796016716989, 694.6075467360951, 814.8370325934807, 839.2698352433391, 849.6389034660275, 815.2621877201112, 841.70540310891, 848.0374774630777, 848.1433751910583, 855.4653341661461, 880.4629176202511, 882.2016075451694, 883.1212943121603, 883.5405589093667, 883.960221790021, 693.2755270742086, 707.3492387003969, 876.1763941894599, 877.2543338162575, 878.3105262369041, 555.2448669809056, 645.7481582182905, 645.8514541655398, 648.4326214562258, 648.4552975500555, 14681.192534410604, 14716.779248311284, 14734.042287251294, 14760.852391476055, 14766.0, 13318.400671969785, 13550.86449135506, 13652.385642646104, 13657.590272291747, 13938.442462301497, 12151.696207594792, 12230.266184028987, 12478.75042499154, 12844.0, 12998.350082495837, 4461.643068554507, 4474.507804141542, 4474.8881277967985, 4670.95329046712, 4677.204875171229, 119446.99999999913, 122927.77072229343, 124307.0, 125413.0, 129306.0, 10767.784644307227, 10788.51451684676, 10794.352338859693, 10804.405757683402, 11192.608258710907, 20826.85452300108, 20911.581768364547, 20936.95315234232, 20942.581148377252, 20987.475313117295, 7691.692412289813, 7760.805979850549, 7839.412044096715, 7898.170692077364, 8016.118226995026, 4356.738595684205, 4359.498657654347, 4477.194105061055, 4554.3168524720895, 4576.610988065985, 3331.866725331008, 3432.1248081738995, 3439.673231043047, 3440.3807314683095, 3444.5522082129664, 632.0550776589029, 633.395107672172, 639.1020616034479, 639.9264084630234, 641.2273210780997, 513.4531723714232, 588.3146134752965, 590.768248202494, 608.4523928464329, 624.9127912771365, 8591.785205369792, 8816.193495175523, 8822.558872056372, 8839.116088391238, 8918.598663060178, 530.9787608495694, 549.5191707256151, 590.3948452835792, 597.6055803169887, 630.1209812311891, 4195.181939521825, 4306.4832220133785, 4449.555044495589, 4504.369388285642, 4687.226607609706, 160687.0, 162927.0, 164753.0, 165924.38529302832, 167605.0, 114207.42896285631, 114809.0, 115263.0, 128343.0, 129103.0, 75194.62402688061, 104673.0, 106337.40493892443, 110342.0, 112859.43570282096, 9770.804583908424, 9918.851217231602, 9923.950380248052, 9970.700878973643, 9991.600335986479, 56280.43719562752, 61604.383956159865, 63175.73648527096, 63950.680246598466, 64831.67584162049, 88867.55566222128, 90502.09497904936, 93919.06080939376, 94529.05470945203, 97535.51232243793, 10777.245592808427, 12966.027547933754, 12998.480060797463, 13008.259766413319, 13034.739305214032, 14519.92740036293, 14545.563633091024, 14560.927195363955, 14655.706885862437, 14998.325075371853, 13406.32968351598, 13639.0, 13647.11293765902, 13711.588652340446, 14021.228832414316, 13597.320133993064, 13603.455861765418, 13714.382852771849, 14208.71582568322, 14254.857451425352, 13209.207447553177, 13327.733445331232, 13376.665583360105, 13391.104324892207, 13931.58205253844, 10240.436775977094, 11270.703869054836, 11296.774064518828, 11339.546418143513, 11689.0, 10114.926440079913, 11066.446677665923, 11318.0, 11511.251768635011, 11518.481668324777], "value": 7756.843284725713, "unit": "score"}], "name": "dromaeo_css", "value": 7168.329815200949, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 132.0, 113.0, 114.0, 113.0, 114.0, 113.0, 113.0, 114.0], "value": 115.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 115.0, 129.0, 159.0, 105.0, 128.0, 128.0, 107.0, 158.0, 104.0], "value": 127.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 186.0, 183.0, 184.0, 185.0, 184.0, 182.0, 261.0, 182.0, 184.0], "value": 191.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 73.0, 77.0, 153.0, 57.0, 58.0, 57.0, 68.0, 144.0, 72.0], "value": 83.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 160.0, 71.0, 73.0, 88.0, 108.0, 77.0, 94.0, 140.0], "value": 97.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 90.0, 86.0, 126.0, 86.0, 90.0, 87.0, 88.0, 85.0], "value": 91.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 96.0, 94.0, 95.0, 95.0, 95.0, 94.0], "value": 94.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 87.0, 86.0, 86.0, 85.0, 86.0, 85.0, 88.0, 84.0], "value": 85.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 55.0, 51.0, 51.0, 55.0, 53.0, 53.0, 54.0, 52.0], "value": 53.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 69.0, 75.0, 76.0, 72.0, 70.0, 73.0, 99.0, 103.0], "value": 76.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 89.0, 87.0, 87.0, 86.0, 90.0, 85.0, 85.0, 89.0, 89.0], "value": 87.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 147.0, 148.0, 139.0, 139.0, 150.0, 127.0, 142.0, 174.0, 151.0], "value": 146.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 172.0, 172.0, 170.0, 172.0, 229.0, 210.0, 172.0, 176.0, 168.0], "value": 181.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 71.0, 78.0, 86.0, 88.0, 72.0, 67.0, 66.0, 69.0], "value": 73.3, "unit": "score"}], "name": "kraken", "value": 1506.6, "alertThreshold": 2.0}]} 19:59:51 INFO - Return code: 0 19:59:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:59:51 INFO - # TBPL SUCCESS # 19:59:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:59:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:59:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:59:51 INFO - 2016-08-29 19:59:51.784 screenresolution[1667:10511] starting screenresolution argv=screenresolution get 19:59:51 INFO - 2016-08-29 19:59:51.787 screenresolution[1667:10511] Display 0: 1600x1200x32@60 19:59:51 INFO - 2016-08-29 19:59:51.793 screenresolution[1668:10514] starting screenresolution argv=screenresolution list 19:59:51 INFO - Available Modes on Display 0 19:59:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:59:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:59:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:59:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:59:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:59:52 INFO - Intel Iris: 19:59:52 INFO - Chipset Model: Intel Iris 19:59:52 INFO - Type: GPU 19:59:52 INFO - Bus: Built-In 19:59:52 INFO - VRAM (Dynamic, Max): 1536 MB 19:59:52 INFO - Vendor: Intel (0x8086) 19:59:52 INFO - Device ID: 0x0a2e 19:59:52 INFO - Revision ID: 0x0009 19:59:52 INFO - Displays: 19:59:52 INFO - Display: 19:59:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:59:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:59:52 INFO - Display Serial Number: 1600x1200 60 19:59:52 INFO - Main Display: Yes 19:59:52 INFO - Mirror: Off 19:59:52 INFO - Online: Yes 19:59:52 INFO - Rotation: Supported 19:59:52 INFO - Return code: 0 19:59:52 INFO - Running post-action listener: _resource_record_post_action 19:59:52 INFO - [mozharness: 2016-08-30 02:59:52.035964Z] Finished run-tests step (success) 19:59:52 INFO - Running post-run listener: _resource_record_post_run 19:59:52 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 31939584; Write bytes: 707408384; Read time: 198; Write time: 2022 19:59:52 INFO - TinderboxPrint: CPU usage
53.8% 19:59:52 INFO - TinderboxPrint: I/O read bytes / time
31,939,584 / 198 19:59:52 INFO - TinderboxPrint: I/O write bytes / time
707,408,384 / 2,022 19:59:52 INFO - TinderboxPrint: CPU idle
2,240.9 (46.2%) 19:59:52 INFO - TinderboxPrint: CPU system
975.5 (20.1%) 19:59:52 INFO - TinderboxPrint: CPU user
1,633.3 (33.7%) 19:59:52 INFO - TinderboxPrint: Swap in / out
434,323,456 / 0 19:59:52 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 394496512; Write bytes: 389486592; Read time: 29171; Write time: 859 19:59:52 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 30174208; Write bytes: 297159168; Read time: 184; Write time: 1111 19:59:52 INFO - Running post-run listener: _upload_blobber_files 19:59:52 INFO - Blob upload gear active. 19:59:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:59:52 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 19:59:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:59:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:59:53 INFO - (blobuploader) - INFO - Open directory for files ... 19:59:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:59:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:59:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:59:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:59:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:59:54 INFO - (blobuploader) - INFO - Done attempting. 19:59:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:59:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:59:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:59:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:59:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:59:56 INFO - (blobuploader) - INFO - Done attempting. 19:59:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:59:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:59:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:59:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:59:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:59:58 INFO - (blobuploader) - INFO - Done attempting. 19:59:58 INFO - (blobuploader) - INFO - Iteration through files over. 19:59:58 INFO - Return code: 0 19:59:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:59:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:59:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2a30f2dd9156bb5b0fc894bb6a51e1cc8b4bebb5f2dd3b978a1f9b2312f5e4a40eb6a0070154e1be880d8d16afc32398cf57e674271b413187fc239bd57884d0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be442f6382e567293a56f3ab283cb013e901c58f8f1d9b2013291e9244cc3bcf8d8dfb159567400d4d0fc6b8ea83e2c294b5b8ac521f9790148882aa72d2dba2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c18377ffe34a9c52243646de6ab0fafa8e6af88af5860ab8827d31cf891a60fdae33b9e41b648e37bf9060596abc990d03a7d73a3b710dcedf0b5ea66559c7a"} 19:59:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:59:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:59:58 INFO - Contents: 19:59:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2a30f2dd9156bb5b0fc894bb6a51e1cc8b4bebb5f2dd3b978a1f9b2312f5e4a40eb6a0070154e1be880d8d16afc32398cf57e674271b413187fc239bd57884d0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be442f6382e567293a56f3ab283cb013e901c58f8f1d9b2013291e9244cc3bcf8d8dfb159567400d4d0fc6b8ea83e2c294b5b8ac521f9790148882aa72d2dba2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c18377ffe34a9c52243646de6ab0fafa8e6af88af5860ab8827d31cf891a60fdae33b9e41b648e37bf9060596abc990d03a7d73a3b710dcedf0b5ea66559c7a"} 19:59:58 INFO - Running post-run listener: copy_logs_to_upload_dir 19:59:58 INFO - Copying logs to upload dir... 19:59:58 INFO - mkdir: /builds/slave/test/build/upload/logs 19:59:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.046134 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-08-29 19:59:58.644956) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-29 19:59:58.648399) ========= 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.za6u9CLsa2/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.70fRKRFR8z/Listeners TMPDIR=/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2a30f2dd9156bb5b0fc894bb6a51e1cc8b4bebb5f2dd3b978a1f9b2312f5e4a40eb6a0070154e1be880d8d16afc32398cf57e674271b413187fc239bd57884d0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be442f6382e567293a56f3ab283cb013e901c58f8f1d9b2013291e9244cc3bcf8d8dfb159567400d4d0fc6b8ea83e2c294b5b8ac521f9790148882aa72d2dba2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c18377ffe34a9c52243646de6ab0fafa8e6af88af5860ab8827d31cf891a60fdae33b9e41b648e37bf9060596abc990d03a7d73a3b710dcedf0b5ea66559c7a"} build_url:https://queue.taskcluster.net/v1/task/Ac9x4KLKQ6uMpYiV1gRkMQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011165 build_url: 'https://queue.taskcluster.net/v1/task/Ac9x4KLKQ6uMpYiV1gRkMQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2a30f2dd9156bb5b0fc894bb6a51e1cc8b4bebb5f2dd3b978a1f9b2312f5e4a40eb6a0070154e1be880d8d16afc32398cf57e674271b413187fc239bd57884d0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be442f6382e567293a56f3ab283cb013e901c58f8f1d9b2013291e9244cc3bcf8d8dfb159567400d4d0fc6b8ea83e2c294b5b8ac521f9790148882aa72d2dba2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c18377ffe34a9c52243646de6ab0fafa8e6af88af5860ab8827d31cf891a60fdae33b9e41b648e37bf9060596abc990d03a7d73a3b710dcedf0b5ea66559c7a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-29 19:59:58.681874) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 19:59:58.682217) ========= 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.za6u9CLsa2/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.70fRKRFR8z/Listeners TMPDIR=/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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.005193 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-29 19:59:58.730967) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-29 19:59:58.731285) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-29 19:59:58.731632) ========= ========= Total master_lag: 0.24 =========