builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0332 starttime: 1475109157.34 results: success (0) buildid: 20160928141419 builduid: ee5cf3a7102c44fa87251dee3c368d6b revision: 9df68a52f11882ad658e8a49fb37c325641556ed ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-28 17:32:37.348622) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-28 17:32:37.349230) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-28 17:32:37.349520) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-28 17:32:37.372400) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 17:32:37.372674) ========= 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.3cd8s1dQZr/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.mKaAI98gkB/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-09-28 17:32:37-- 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% 65.0M=0s 2016-09-28 17:32:37 (65.0 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.480871 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 17:32:37.875074) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 17:32:37.875388) ========= 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.3cd8s1dQZr/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.mKaAI98gkB/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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.020986 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 17:32:37.927930) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-28 17:32:37.928730) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 9df68a52f11882ad658e8a49fb37c325641556ed --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 9df68a52f11882ad658e8a49fb37c325641556ed --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.3cd8s1dQZr/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.mKaAI98gkB/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-09-28 17:32:38,003 truncating revision to first 12 chars 2016-09-28 17:32:38,003 Setting DEBUG logging. 2016-09-28 17:32:38,003 attempt 1/10 2016-09-28 17:32:38,004 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/9df68a52f118?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-28 17:32:39,237 unpacking tar archive at: autoland-9df68a52f118/testing/mozharness/ program finished with exit code 0 elapsedTime=1.486109 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-28 17:32:39.437086) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-28 17:32:39.437385) ========= script_repo_revision: 9df68a52f11882ad658e8a49fb37c325641556ed ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-28 17:32:39.437775) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-28 17:32:39.438054) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-28 17:32:39.455668) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-09-28 17:32:39.456000) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.3cd8s1dQZr/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.mKaAI98gkB/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 17:32:39 INFO - MultiFileLogger online at 20160928 17:32:39 in /builds/slave/test 17:32:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 17:32:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:32:39 INFO - {'append_to_log': False, 17:32:39 INFO - 'base_work_dir': '/builds/slave/test', 17:32:39 INFO - 'blob_upload_branch': 'Autoland', 17:32:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:32:39 INFO - 'branch': 'Autoland', 17:32:39 INFO - 'buildbot_json_path': 'buildprops.json', 17:32:39 INFO - 'config_files': ('talos/mac_config.py',), 17:32:39 INFO - 'default_actions': ('clobber', 17:32:39 INFO - 'read-buildbot-config', 17:32:39 INFO - 'download-and-extract', 17:32:39 INFO - 'populate-webroot', 17:32:39 INFO - 'create-virtualenv', 17:32:39 INFO - 'install', 17:32:39 INFO - 'run-tests'), 17:32:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:32:39 INFO - 'download_minidump_stackwalk': True, 17:32:39 INFO - 'download_symbols': 'ondemand', 17:32:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:32:39 INFO - 'tooltool.py': '/tools/tooltool.py', 17:32:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:32:39 INFO - '/tools/misc-python/virtualenv.py')}, 17:32:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:32:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:32:39 INFO - 'installer_path': 'installer.exe', 17:32:39 INFO - 'log_level': 'info', 17:32:39 INFO - 'log_name': 'talos', 17:32:39 INFO - 'log_to_console': True, 17:32:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:32:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:32:39 INFO - 'opt_config_files': (), 17:32:39 INFO - 'pip_index': False, 17:32:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:32:39 INFO - 'cmd': ('bash', 17:32:39 INFO - '-c', 17:32:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:32:39 INFO - 'enabled': True, 17:32:39 INFO - 'halt_on_failure': False, 17:32:39 INFO - 'name': 'check_screen_resolution'},), 17:32:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:32:39 INFO - 'cmd': ('bash', 17:32:39 INFO - '-c', 17:32:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:32:39 INFO - 'enabled': True, 17:32:39 INFO - 'halt_on_failure': False, 17:32:39 INFO - 'name': 'check_screen_resolution'},), 17:32:39 INFO - 'run_cmd_checks_enabled': True, 17:32:39 INFO - 'sps_profile': False, 17:32:39 INFO - 'sps_profile_interval': 0, 17:32:39 INFO - 'suite': 'dromaeojs', 17:32:39 INFO - 'system_bits': '32', 17:32:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:32:39 INFO - 'title': 't-yosemite-r7-0332', 17:32:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:32:39 INFO - 'use_talos_json': True, 17:32:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:32:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:32:39 INFO - 'work_dir': 'build'} 17:32:39 INFO - [mozharness: 2016-09-29 00:32:39.651596Z] Running clobber step. 17:32:39 INFO - Running pre-action listener: _resource_record_pre_action 17:32:39 INFO - Running main action method: clobber 17:32:39 INFO - rmtree: /builds/slave/test/build 17:32:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:32:41 INFO - Running post-action listener: _resource_record_post_action 17:32:41 INFO - [mozharness: 2016-09-29 00:32:41.108244Z] Finished clobber step (success) 17:32:41 INFO - [mozharness: 2016-09-29 00:32:41.108411Z] Running read-buildbot-config step. 17:32:41 INFO - Running pre-action listener: _resource_record_pre_action 17:32:41 INFO - Running main action method: read_buildbot_config 17:32:41 INFO - Using buildbot properties: 17:32:41 INFO - { 17:32:41 INFO - "project": "", 17:32:41 INFO - "product": "firefox", 17:32:41 INFO - "script_repo_revision": "production", 17:32:41 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 17:32:41 INFO - "repository": "", 17:32:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 17:32:41 INFO - "buildid": "20160928141419", 17:32:41 INFO - "pgo_build": "False", 17:32:41 INFO - "basedir": "/builds/slave/test", 17:32:41 INFO - "buildnumber": 252, 17:32:41 INFO - "slavename": "t-yosemite-r7-0332", 17:32:41 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 17:32:41 INFO - "platform": "yosemite_r7", 17:32:41 INFO - "scheduler": "tests-autoland-macosx64-talos", 17:32:41 INFO - "branch": "autoland", 17:32:41 INFO - "repo_path": "integration/autoland", 17:32:41 INFO - "revision": "9df68a52f11882ad658e8a49fb37c325641556ed", 17:32:41 INFO - "stage_platform": "macosx64", 17:32:41 INFO - "builduid": "ee5cf3a7102c44fa87251dee3c368d6b", 17:32:41 INFO - "slavebuilddir": "test" 17:32:41 INFO - } 17:32:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/HvFaEWqHS_WwFtLBw-HF0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 17:32:41 INFO - Running post-action listener: _resource_record_post_action 17:32:41 INFO - [mozharness: 2016-09-29 00:32:41.111794Z] Finished read-buildbot-config step (success) 17:32:41 INFO - [mozharness: 2016-09-29 00:32:41.111885Z] Running download-and-extract step. 17:32:41 INFO - Running pre-action listener: _resource_record_pre_action 17:32:41 INFO - Running main action method: download_and_extract 17:32:41 INFO - mkdir: /builds/slave/test/build/tests 17:32:41 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')]} 17:32:41 INFO - trying https://queue.taskcluster.net/v1/task/HvFaEWqHS_WwFtLBw-HF0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 17:32:41 INFO - Downloading https://queue.taskcluster.net/v1/task/HvFaEWqHS_WwFtLBw-HF0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 17:32:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HvFaEWqHS_WwFtLBw-HF0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 17:32:42 INFO - Downloaded 1199 bytes. 17:32:42 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 17:32:42 INFO - Using the following test package requirements: 17:32:42 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 17:32:42 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:32:42 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 17:32:42 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:32:42 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 17:32:42 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:32:42 INFO - u'jsshell-mac.zip'], 17:32:42 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:32:42 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 17:32:42 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 17:32:42 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:32:42 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 17:32:42 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:32:42 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 17:32:42 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:32:42 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 17:32:42 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 17:32:42 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 17:32:42 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:32:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HvFaEWqHS_WwFtLBw-HF0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 17:32:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HvFaEWqHS_WwFtLBw-HF0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:32:42 INFO - Fetch https://queue.taskcluster.net/v1/task/HvFaEWqHS_WwFtLBw-HF0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 17:32:42 INFO - Http code: 200 17:32:42 INFO - Content-Encoding: None 17:32:42 INFO - Content-Type: application/zip 17:32:42 INFO - via: 1.1 7f9e159e8ac7593f5d3d995abc6d2db3.cloudfront.net (CloudFront) 17:32:42 INFO - x-amz-cf-id: sgl5a3QKgkKW-808IbmNDIaYOaBb0fkz2tMeEqaUvpnF7yhwzfYSdw== 17:32:42 INFO - x-amz-version-id: RpZILE4Q2mnYP._sInr7wQ4aFVg4bqs7 17:32:42 INFO - x-cache: Hit from cloudfront 17:32:43 INFO - Expected file size: 16472961 17:32:43 INFO - Obtained file size: 16472961 17:32:49 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 17:32:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HvFaEWqHS_WwFtLBw-HF0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 17:32:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HvFaEWqHS_WwFtLBw-HF0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:32:49 INFO - Fetch https://queue.taskcluster.net/v1/task/HvFaEWqHS_WwFtLBw-HF0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 17:32:50 INFO - Http code: 200 17:32:50 INFO - Content-Encoding: None 17:32:50 INFO - Content-Type: application/zip 17:32:50 INFO - via: 1.1 bfc29822e21ecc352f71dce83e06ab74.cloudfront.net (CloudFront) 17:32:50 INFO - x-amz-cf-id: g0kr0HDAUyXW8r13XBLiw-LzRwXDFlJ7Qb_Jp-F-8NQyoOkZMZoC6A== 17:32:50 INFO - x-amz-version-id: RpZILE4Q2mnYP._sInr7wQ4aFVg4bqs7 17:32:50 INFO - x-cache: Hit from cloudfront 17:32:50 INFO - Expected file size: 16472961 17:32:50 INFO - Obtained file size: 16472961 17:32:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HvFaEWqHS_WwFtLBw-HF0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 17:32:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HvFaEWqHS_WwFtLBw-HF0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 17:32:57 INFO - Fetch https://queue.taskcluster.net/v1/task/HvFaEWqHS_WwFtLBw-HF0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 17:32:57 INFO - Http code: 200 17:32:57 INFO - Content-Encoding: None 17:32:57 INFO - Content-Type: application/zip 17:32:57 INFO - via: 1.1 6cd88b9fd84ad5daa3b3867fb8c5c91a.cloudfront.net (CloudFront) 17:32:57 INFO - x-amz-cf-id: oyi3w99hcK2nsYaeXBKkXuB54-4FNjyepWVXI9_h4QG-NEYOLmeuMg== 17:32:57 INFO - x-amz-version-id: DRA87z5rJi7V8KJx8P1Jzzw72.fF060z 17:32:57 INFO - x-cache: Miss from cloudfront 17:32:58 INFO - Expected file size: 14414067 17:32:58 INFO - Obtained file size: 14414067 17:32:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:32:59 INFO - trying https://queue.taskcluster.net/v1/task/HvFaEWqHS_WwFtLBw-HF0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 17:32:59 INFO - Downloading https://queue.taskcluster.net/v1/task/HvFaEWqHS_WwFtLBw-HF0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 17:32:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HvFaEWqHS_WwFtLBw-HF0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 17:33:01 INFO - Downloaded 111810845 bytes. 17:33:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HvFaEWqHS_WwFtLBw-HF0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 17:33:01 INFO - mkdir: /builds/slave/test/properties 17:33:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:33:01 INFO - Writing to file /builds/slave/test/properties/build_url 17:33:01 INFO - Contents: 17:33:01 INFO - build_url:https://queue.taskcluster.net/v1/task/HvFaEWqHS_WwFtLBw-HF0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 17:33:02 INFO - Running post-action listener: _resource_record_post_action 17:33:02 INFO - Running post-action listener: set_extra_try_arguments 17:33:02 INFO - [mozharness: 2016-09-29 00:33:02.493395Z] Finished download-and-extract step (success) 17:33:02 INFO - [mozharness: 2016-09-29 00:33:02.493524Z] Running populate-webroot step. 17:33:02 INFO - Running pre-action listener: _resource_record_pre_action 17:33:02 INFO - Running main action method: populate_webroot 17:33:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:33:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:33:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:33:02 INFO - '--tppagecycles', 17:33:02 INFO - '7'], 17:33:02 INFO - 'tests': ['tsvgm']}}, 17:33:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:33:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:33:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:33:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:33:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:33:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:33:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:33:02 INFO - 'talos_options': ['--disable-e10s'], 17:33:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:33:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:33:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:33:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:33:02 INFO - 'talos_options': ['--disable-e10s'], 17:33:02 INFO - 'tests': ['damp', 'tps']}, 17:33:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:33:02 INFO - 'tests': ['damp', 'tps']}, 17:33:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:33:02 INFO - 'tests': ['dromaeo_dom']}, 17:33:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:33:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:33:02 INFO - 'tests': ['basic_compositor_video']}, 17:33:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:33:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:33:02 INFO - 'tests': ['a11yr', 17:33:02 INFO - 'ts_paint', 17:33:02 INFO - 'tpaint', 17:33:02 INFO - 'sessionrestore', 17:33:02 INFO - 'sessionrestore_no_auto_restore', 17:33:02 INFO - 'tabpaint']}, 17:33:02 INFO - 'other-e10s': {'tests': ['a11yr', 17:33:02 INFO - 'ts_paint', 17:33:02 INFO - 'tpaint', 17:33:02 INFO - 'sessionrestore', 17:33:02 INFO - 'sessionrestore_no_auto_restore', 17:33:02 INFO - 'tabpaint']}, 17:33:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:33:02 INFO - 'ts_paint', 17:33:02 INFO - 'tpaint', 17:33:02 INFO - 'sessionrestore', 17:33:02 INFO - 'sessionrestore_no_auto_restore', 17:33:02 INFO - 'tabpaint']}, 17:33:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:33:02 INFO - 'ts_paint', 17:33:02 INFO - 'tpaint', 17:33:02 INFO - 'sessionrestore', 17:33:02 INFO - 'sessionrestore_no_auto_restore', 17:33:02 INFO - 'tabpaint']}, 17:33:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:33:02 INFO - 'tests': ['a11yr', 17:33:02 INFO - 'ts_paint', 17:33:02 INFO - 'tpaint', 17:33:02 INFO - 'sessionrestore', 17:33:02 INFO - 'sessionrestore_no_auto_restore', 17:33:02 INFO - 'tabpaint']}, 17:33:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:33:02 INFO - 'tests': ['a11yr', 17:33:02 INFO - 'ts_paint', 17:33:02 INFO - 'tpaint', 17:33:02 INFO - 'sessionrestore', 17:33:02 INFO - 'sessionrestore_no_auto_restore', 17:33:02 INFO - 'tabpaint']}, 17:33:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:33:02 INFO - 'tests': ['tsvgx', 17:33:02 INFO - 'tsvgr_opacity', 17:33:02 INFO - 'tart', 17:33:02 INFO - 'tscrollx', 17:33:02 INFO - 'cart']}, 17:33:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:33:02 INFO - 'tsvgr_opacity', 17:33:02 INFO - 'tart', 17:33:02 INFO - 'tscrollx', 17:33:02 INFO - 'cart']}, 17:33:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:33:02 INFO - 'talos_options': ['--disable-e10s'], 17:33:02 INFO - 'tests': ['tp5o']}, 17:33:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:33:02 INFO - 'tests': ['tp5o']}, 17:33:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:33:02 INFO - 'talos_options': ['--disable-e10s', 17:33:02 INFO - '--xperf_path', 17:33:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:33:02 INFO - 'tests': ['tp5n']}, 17:33:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:33:02 INFO - 'talos_options': ['--xperf_path', 17:33:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:33:02 INFO - 'tests': ['tp5n']}}, 17:33:02 INFO - 'talos.zip': {'path': '', 17:33:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:33:02 INFO - Running post-action listener: _resource_record_post_action 17:33:02 INFO - [mozharness: 2016-09-29 00:33:02.512152Z] Finished populate-webroot step (success) 17:33:02 INFO - [mozharness: 2016-09-29 00:33:02.512283Z] Running create-virtualenv step. 17:33:02 INFO - Running pre-action listener: _resource_record_pre_action 17:33:02 INFO - Running main action method: create_virtualenv 17:33:02 INFO - Creating virtualenv /builds/slave/test/build/venv 17:33:02 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 17:33:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:33:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:33:02 INFO - Using real prefix '/tools/python27' 17:33:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:33:03 INFO - Installing distribute.............................................................................................................................................................................................done. 17:33:06 INFO - Installing pip.................done. 17:33:06 INFO - Return code: 0 17:33:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:33:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:33:06 INFO - Reading from file tmpfile_stdout 17:33:06 INFO - Output received: 17:33:06 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 17:33:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:33:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:33:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:33:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:33:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:33:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:33:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:33:06 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 0x106db2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d28570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb70a456460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106bc6d30>, '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 0x1069ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106bbeb20>, '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.3cd8s1dQZr/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.mKaAI98gkB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 17:33:06 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 17:33:06 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 17:33:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3cd8s1dQZr/Render', 17:33:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:33:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:33:06 INFO - 'HOME': '/Users/cltbld', 17:33:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:33:06 INFO - 'LOGNAME': 'cltbld', 17:33:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:33:06 INFO - 'MOZ_NO_REMOTE': '1', 17:33:06 INFO - 'NO_EM_RESTART': '1', 17:33:06 INFO - 'PAGER': '/bin/cat', 17:33:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:33:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:33:06 INFO - 'PWD': '/builds/slave/test', 17:33:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:33:06 INFO - 'SHELL': '/bin/bash', 17:33:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mKaAI98gkB/Listeners', 17:33:06 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 17:33:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:33:06 INFO - 'USER': 'cltbld', 17:33:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:33:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:33:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:33:06 INFO - 'XPC_FLAGS': '0x0', 17:33:06 INFO - 'XPC_SERVICE_NAME': '0', 17:33:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:33:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:33:06 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 17:33:06 INFO - Cleaning up... 17:33:06 INFO - Return code: 0 17:33:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:33:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:33:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:33:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:33:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:33:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:33:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:33:06 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 0x106db2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d28570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb70a456460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106bc6d30>, '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 0x1069ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106bbeb20>, '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.3cd8s1dQZr/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.mKaAI98gkB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 17:33:06 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 17:33:06 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 17:33:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3cd8s1dQZr/Render', 17:33:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:33:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:33:06 INFO - 'HOME': '/Users/cltbld', 17:33:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:33:06 INFO - 'LOGNAME': 'cltbld', 17:33:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:33:06 INFO - 'MOZ_NO_REMOTE': '1', 17:33:06 INFO - 'NO_EM_RESTART': '1', 17:33:06 INFO - 'PAGER': '/bin/cat', 17:33:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:33:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:33:06 INFO - 'PWD': '/builds/slave/test', 17:33:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:33:06 INFO - 'SHELL': '/bin/bash', 17:33:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mKaAI98gkB/Listeners', 17:33:06 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 17:33:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:33:06 INFO - 'USER': 'cltbld', 17:33:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:33:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:33:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:33:06 INFO - 'XPC_FLAGS': '0x0', 17:33:06 INFO - 'XPC_SERVICE_NAME': '0', 17:33:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:33:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:33:06 INFO - Downloading/unpacking psutil>=3.1.1 17:33:06 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 17:33:06 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 17:33:06 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 17:33:06 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 17:33:06 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 17:33:06 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 17:33:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:33:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:33:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:33:10 INFO - Installing collected packages: psutil 17:33:10 INFO - Running setup.py install for psutil 17:33:10 INFO - building 'psutil._psutil_osx' extension 17:33:10 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 17:33:11 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 17:33:11 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 17:33:11 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 17:33:11 INFO - building 'psutil._psutil_posix' extension 17:33:11 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 17:33:11 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:33:11 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:33:11 INFO - ^ 17:33:11 INFO - 1 warning generated. 17:33:11 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 17:33:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:33:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:33:11 INFO - Successfully installed psutil 17:33:11 INFO - Cleaning up... 17:33:11 INFO - Return code: 0 17:33:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:33: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')]} 17:33:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:33:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:33: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')]} 17:33:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:33:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:33: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106db2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d28570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb70a456460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106bc6d30>, '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 0x1069ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106bbeb20>, '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.3cd8s1dQZr/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.mKaAI98gkB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 17:33: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:33: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 mozsystemmonitor==0.3 17:33:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3cd8s1dQZr/Render', 17:33:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:33:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:33:11 INFO - 'HOME': '/Users/cltbld', 17:33:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:33:11 INFO - 'LOGNAME': 'cltbld', 17:33:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:33:11 INFO - 'MOZ_NO_REMOTE': '1', 17:33:11 INFO - 'NO_EM_RESTART': '1', 17:33:11 INFO - 'PAGER': '/bin/cat', 17:33:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:33:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:33:11 INFO - 'PWD': '/builds/slave/test', 17:33:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:33:11 INFO - 'SHELL': '/bin/bash', 17:33:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mKaAI98gkB/Listeners', 17:33:11 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 17:33:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:33:11 INFO - 'USER': 'cltbld', 17:33:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:33:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:33:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:33:11 INFO - 'XPC_FLAGS': '0x0', 17:33:11 INFO - 'XPC_SERVICE_NAME': '0', 17:33:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:33:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:33:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:33:11 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 17:33:11 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 17:33:11 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 17:33:11 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 17:33:11 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 17:33:11 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 17:33:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:33:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:33:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:33:14 INFO - Installing collected packages: mozsystemmonitor 17:33:14 INFO - Running setup.py install for mozsystemmonitor 17:33:14 INFO - Successfully installed mozsystemmonitor 17:33:14 INFO - Cleaning up... 17:33:14 INFO - Return code: 0 17:33:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:33: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')]} 17:33:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:33:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:33: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')]} 17:33:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:33:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:33:14 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 0x106db2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d28570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb70a456460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106bc6d30>, '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 0x1069ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106bbeb20>, '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.3cd8s1dQZr/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.mKaAI98gkB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 17:33:14 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 17:33:14 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 17:33:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3cd8s1dQZr/Render', 17:33:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:33:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:33:14 INFO - 'HOME': '/Users/cltbld', 17:33:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:33:14 INFO - 'LOGNAME': 'cltbld', 17:33:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:33:14 INFO - 'MOZ_NO_REMOTE': '1', 17:33:14 INFO - 'NO_EM_RESTART': '1', 17:33:14 INFO - 'PAGER': '/bin/cat', 17:33:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:33:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:33:14 INFO - 'PWD': '/builds/slave/test', 17:33:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:33:14 INFO - 'SHELL': '/bin/bash', 17:33:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mKaAI98gkB/Listeners', 17:33:14 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 17:33:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:33:14 INFO - 'USER': 'cltbld', 17:33:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:33:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:33:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:33:14 INFO - 'XPC_FLAGS': '0x0', 17:33:14 INFO - 'XPC_SERVICE_NAME': '0', 17:33:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:33:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:33:14 INFO - Downloading/unpacking jsonschema==2.5.1 17:33:14 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 17:33:14 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 17:33:14 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 17:33:14 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 17:33:14 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 17:33:14 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 17:33:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:33:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:33: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 17:33: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 17:33: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 17:33: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 17:33: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 17:33: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 17:33:17 INFO - Downloading functools32-3.2.3-2.tar.gz 17:33:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:33:17 INFO - warning: no files found matching '*.txt' 17:33:17 INFO - no previously-included directories found matching 'build' 17:33:17 INFO - no previously-included directories found matching 'dist' 17:33:17 INFO - no previously-included directories found matching '.git*' 17:33:17 INFO - Installing collected packages: jsonschema, functools32 17:33:17 INFO - Running setup.py install for functools32 17:33:17 INFO - warning: no files found matching '*.txt' 17:33:17 INFO - no previously-included directories found matching 'build' 17:33:17 INFO - no previously-included directories found matching 'dist' 17:33:17 INFO - no previously-included directories found matching '.git*' 17:33:17 INFO - Successfully installed jsonschema functools32 17:33:17 INFO - Cleaning up... 17:33:18 INFO - Return code: 0 17:33:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:33:18 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')]} 17:33:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:33:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:33:18 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')]} 17:33:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:33:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:33:18 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 0x106db2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d28570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb70a456460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106bc6d30>, '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 0x1069ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106bbeb20>, '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.3cd8s1dQZr/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.mKaAI98gkB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 17:33:18 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 17:33:18 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 17:33:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3cd8s1dQZr/Render', 17:33:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:33:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:33:18 INFO - 'HOME': '/Users/cltbld', 17:33:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:33:18 INFO - 'LOGNAME': 'cltbld', 17:33:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:33:18 INFO - 'MOZ_NO_REMOTE': '1', 17:33:18 INFO - 'NO_EM_RESTART': '1', 17:33:18 INFO - 'PAGER': '/bin/cat', 17:33:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:33:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:33:18 INFO - 'PWD': '/builds/slave/test', 17:33:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:33:18 INFO - 'SHELL': '/bin/bash', 17:33:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mKaAI98gkB/Listeners', 17:33:18 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 17:33:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:33:18 INFO - 'USER': 'cltbld', 17:33:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:33:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:33:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:33:18 INFO - 'XPC_FLAGS': '0x0', 17:33:18 INFO - 'XPC_SERVICE_NAME': '0', 17:33:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:33:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:33:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:33:18 INFO - Cleaning up... 17:33:18 INFO - Return code: 0 17:33:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:33:18 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')]} 17:33:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:33:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:33:18 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')]} 17:33:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:33:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:33:18 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 0x106db2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d28570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb70a456460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106bc6d30>, '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 0x1069ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106bbeb20>, '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.3cd8s1dQZr/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.mKaAI98gkB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 17:33:18 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 17:33:18 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 17:33:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3cd8s1dQZr/Render', 17:33:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:33:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:33:18 INFO - 'HOME': '/Users/cltbld', 17:33:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:33:18 INFO - 'LOGNAME': 'cltbld', 17:33:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:33:18 INFO - 'MOZ_NO_REMOTE': '1', 17:33:18 INFO - 'NO_EM_RESTART': '1', 17:33:18 INFO - 'PAGER': '/bin/cat', 17:33:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:33:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:33:18 INFO - 'PWD': '/builds/slave/test', 17:33:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:33:18 INFO - 'SHELL': '/bin/bash', 17:33:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mKaAI98gkB/Listeners', 17:33:18 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 17:33:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:33:18 INFO - 'USER': 'cltbld', 17:33:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:33:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:33:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:33:18 INFO - 'XPC_FLAGS': '0x0', 17:33:18 INFO - 'XPC_SERVICE_NAME': '0', 17:33:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:33:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:33:18 INFO - Downloading/unpacking blobuploader==1.2.4 17:33:18 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 17:33:18 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 17:33:18 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 17:33:18 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 17:33:18 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 17:33:18 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 17:33:20 INFO - Downloading blobuploader-1.2.4.tar.gz 17:33:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:33:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:33:21 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 17:33:21 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 17:33:21 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 17:33:21 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 17:33:21 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 17:33:21 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 17:33:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:33:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:33:21 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 17:33:21 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 17:33:21 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 17:33:21 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 17:33:21 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 17:33:21 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 17:33:22 INFO - Downloading docopt-0.6.1.tar.gz 17:33:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:33:22 INFO - Installing collected packages: blobuploader, requests, docopt 17:33:22 INFO - Running setup.py install for blobuploader 17:33:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:33:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:33:22 INFO - Running setup.py install for requests 17:33:22 INFO - Running setup.py install for docopt 17:33:23 INFO - Successfully installed blobuploader requests docopt 17:33:23 INFO - Cleaning up... 17:33:23 INFO - Return code: 0 17:33:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:33: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')]} 17:33:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:33:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:33: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')]} 17:33:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:33:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:33:23 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 0x106db2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d28570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb70a456460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106bc6d30>, '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 0x1069ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106bbeb20>, '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.3cd8s1dQZr/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.mKaAI98gkB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 17:33:23 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 17:33:23 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 17:33:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3cd8s1dQZr/Render', 17:33:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:33:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:33:23 INFO - 'HOME': '/Users/cltbld', 17:33:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:33:23 INFO - 'LOGNAME': 'cltbld', 17:33:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:33:23 INFO - 'MOZ_NO_REMOTE': '1', 17:33:23 INFO - 'NO_EM_RESTART': '1', 17:33:23 INFO - 'PAGER': '/bin/cat', 17:33:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:33:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:33:23 INFO - 'PWD': '/builds/slave/test', 17:33:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:33:23 INFO - 'SHELL': '/bin/bash', 17:33:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mKaAI98gkB/Listeners', 17:33:23 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 17:33:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:33:23 INFO - 'USER': 'cltbld', 17:33:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:33:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:33:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:33:23 INFO - 'XPC_FLAGS': '0x0', 17:33:23 INFO - 'XPC_SERVICE_NAME': '0', 17:33:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:33:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:33:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:33:23 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-4E4r0O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:33:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:33:23 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-9lHPSQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:33:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:33:23 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-A7LSPP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:33:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:33:23 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-ZnqVVA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:33:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:33:23 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-0LSYkT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:33:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:33:23 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-SRQDDP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:33:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:33:24 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-7Xci0l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:33:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:33:24 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-KZUFkX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:33:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:33:24 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-gAz5GP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:33:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:33:24 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-tEPUTv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:33:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:33:24 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-oaZpiL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:33:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:33:24 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-jZ1rna-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:33:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:33:24 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-pUan8d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:33:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:33:24 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-yroNcf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:33:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:33:24 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-3N4jrs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:33:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:33:24 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-kwwMch-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:33:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:33:25 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-hEWxi3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:33:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:33:25 INFO - Running setup.py install for manifestparser 17:33:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:33:25 INFO - Running setup.py install for mozcrash 17:33:25 INFO - Running setup.py install for mozdebug 17:33:25 INFO - Running setup.py install for mozdevice 17:33:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:33:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:33:25 INFO - Running setup.py install for mozfile 17:33:25 INFO - Running setup.py install for mozhttpd 17:33:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:33:26 INFO - Running setup.py install for mozinfo 17:33:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:33:26 INFO - Running setup.py install for mozInstall 17:33:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:33:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:33:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:33:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:33:26 INFO - Running setup.py install for mozleak 17:33:26 INFO - Running setup.py install for mozlog 17:33:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:33:26 INFO - Running setup.py install for moznetwork 17:33:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:33:26 INFO - Running setup.py install for mozprocess 17:33:26 INFO - Running setup.py install for mozprofile 17:33:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:33:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:33:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:33:27 INFO - Running setup.py install for mozrunner 17:33:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:33:27 INFO - Running setup.py install for mozscreenshot 17:33:27 INFO - Running setup.py install for moztest 17:33:27 INFO - Running setup.py install for mozversion 17:33:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:33:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:33:27 INFO - Cleaning up... 17:33:27 INFO - Return code: 0 17:33:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:33:27 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')]} 17:33:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:33:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:33:27 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')]} 17:33:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:33:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:33:27 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 0x106db2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d28570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb70a456460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106bc6d30>, '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 0x1069ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106bbeb20>, '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.3cd8s1dQZr/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.mKaAI98gkB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 17:33:27 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 17:33:27 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 17:33:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3cd8s1dQZr/Render', 17:33:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:33:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:33:27 INFO - 'HOME': '/Users/cltbld', 17:33:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:33:27 INFO - 'LOGNAME': 'cltbld', 17:33:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:33:27 INFO - 'MOZ_NO_REMOTE': '1', 17:33:27 INFO - 'NO_EM_RESTART': '1', 17:33:27 INFO - 'PAGER': '/bin/cat', 17:33:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:33:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:33:27 INFO - 'PWD': '/builds/slave/test', 17:33:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:33:27 INFO - 'SHELL': '/bin/bash', 17:33:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mKaAI98gkB/Listeners', 17:33:27 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 17:33:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:33:27 INFO - 'USER': 'cltbld', 17:33:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:33:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:33:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:33:27 INFO - 'XPC_FLAGS': '0x0', 17:33:27 INFO - 'XPC_SERVICE_NAME': '0', 17:33:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:33:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:33:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:33:28 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-jHQcyF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:33:28 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)) 17:33:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:33:28 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-6LSjqp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:33:28 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)) 17:33:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:33:28 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-hfcwjf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:33:28 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)) 17:33:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:33:28 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-p59vM3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:33:28 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)) 17:33:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:33:28 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-MxCT9f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:33:28 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)) 17:33:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:33:28 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-NuK5Vf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:33:28 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)) 17:33:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:33:28 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-b2VAJY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:33:28 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)) 17:33:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:33:28 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-MaWDHB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:33:28 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)) 17:33:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:33:28 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-oOtCP1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:33:29 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)) 17:33:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:33:29 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-1n3HBU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:33:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:33:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:33:29 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-Nd9jJN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:33:29 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)) 17:33:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:33:29 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-9NzzxF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:33:29 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)) 17:33:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:33:29 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-qyC6ak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:33:29 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)) 17:33:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:33:29 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-xbz6Tc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:33:29 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)) 17:33:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:33:29 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-nFjyLH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:33:29 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)) 17:33:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:33:29 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-nRRqvT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:33:29 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)) 17:33:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:33:29 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-i8RmFQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:33:29 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)) 17:33:29 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)) 17:33:29 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)) 17:33:29 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)) 17:33:29 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)) 17:33:29 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)) 17:33:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:33:29 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 17:33:29 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 17:33:29 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 17:33:29 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 17:33:29 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 17:33:29 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 17:33:32 INFO - Downloading blessings-1.6.tar.gz 17:33:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:33:32 INFO - Installing collected packages: blessings 17:33:32 INFO - Running setup.py install for blessings 17:33:32 INFO - Successfully installed blessings 17:33:32 INFO - Cleaning up... 17:33:32 INFO - Return code: 0 17:33:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:33:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:33:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:33:32 INFO - Reading from file tmpfile_stdout 17:33:32 INFO - Current package versions: 17:33:32 INFO - blessings == 1.6 17:33:32 INFO - blobuploader == 1.2.4 17:33:32 INFO - docopt == 0.6.1 17:33:32 INFO - functools32 == 3.2.3-2 17:33:32 INFO - jsonschema == 2.5.1 17:33:32 INFO - manifestparser == 1.1 17:33:32 INFO - mozInstall == 1.12 17:33:32 INFO - mozcrash == 0.17 17:33:32 INFO - mozdebug == 0.1 17:33:32 INFO - mozdevice == 0.48 17:33:32 INFO - mozfile == 1.2 17:33:32 INFO - mozhttpd == 0.7 17:33:32 INFO - mozinfo == 0.9 17:33:32 INFO - mozleak == 0.1 17:33:32 INFO - mozlog == 3.4 17:33:32 INFO - moznetwork == 0.27 17:33:32 INFO - mozprocess == 0.23 17:33:32 INFO - mozprofile == 0.28 17:33:32 INFO - mozrunner == 6.12 17:33:32 INFO - mozscreenshot == 0.1 17:33:32 INFO - mozsystemmonitor == 0.3 17:33:32 INFO - moztest == 0.7 17:33:32 INFO - mozversion == 1.4 17:33:32 INFO - psutil == 3.1.1 17:33:32 INFO - requests == 1.2.3 17:33:32 INFO - wsgiref == 0.1.2 17:33:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:33:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:33:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:33:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:33:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:33:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:33:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:33:32 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 0x106db2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d28570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb70a456460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106bc6d30>, '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 0x1069ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106bbeb20>, '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.3cd8s1dQZr/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.mKaAI98gkB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 17:33:32 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 17:33:32 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 17:33:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3cd8s1dQZr/Render', 17:33:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:33:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:33:32 INFO - 'HOME': '/Users/cltbld', 17:33:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:33:32 INFO - 'LOGNAME': 'cltbld', 17:33:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:33:32 INFO - 'MOZ_NO_REMOTE': '1', 17:33:32 INFO - 'NO_EM_RESTART': '1', 17:33:32 INFO - 'PAGER': '/bin/cat', 17:33:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:33:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:33:32 INFO - 'PWD': '/builds/slave/test', 17:33:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:33:32 INFO - 'SHELL': '/bin/bash', 17:33:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mKaAI98gkB/Listeners', 17:33:32 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 17:33:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:33:32 INFO - 'USER': 'cltbld', 17:33:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:33:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:33:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:33:32 INFO - 'XPC_FLAGS': '0x0', 17:33:32 INFO - 'XPC_SERVICE_NAME': '0', 17:33:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:33:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:33:33 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)) 17:33:33 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)) 17:33:33 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)) 17:33:33 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)) 17:33:33 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)) 17:33:33 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)) 17:33:33 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)) 17:33:33 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)) 17:33:33 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)) 17:33:33 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)) 17:33:33 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)) 17:33:33 INFO - Cleaning up... 17:33:33 INFO - Return code: 0 17:33:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:33: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')]} 17:33:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:33:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:33: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')]} 17:33:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:33:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:33:33 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 0x106db2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d28570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb70a456460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106bc6d30>, '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 0x1069ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106bbeb20>, '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.3cd8s1dQZr/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.mKaAI98gkB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 17:33:33 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 17:33:33 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 17:33:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3cd8s1dQZr/Render', 17:33:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:33:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:33:33 INFO - 'HOME': '/Users/cltbld', 17:33:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:33:33 INFO - 'LOGNAME': 'cltbld', 17:33:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:33:33 INFO - 'MOZ_NO_REMOTE': '1', 17:33:33 INFO - 'NO_EM_RESTART': '1', 17:33:33 INFO - 'PAGER': '/bin/cat', 17:33:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:33:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:33:33 INFO - 'PWD': '/builds/slave/test', 17:33:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:33:33 INFO - 'SHELL': '/bin/bash', 17:33:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mKaAI98gkB/Listeners', 17:33:33 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 17:33:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:33:33 INFO - 'USER': 'cltbld', 17:33:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:33:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:33:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:33:33 INFO - 'XPC_FLAGS': '0x0', 17:33:33 INFO - 'XPC_SERVICE_NAME': '0', 17:33:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:33:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:33:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:33:33 INFO - Cleaning up... 17:33:33 INFO - Return code: 0 17:33:33 INFO - Running post-action listener: _resource_record_post_action 17:33:33 INFO - Running post-action listener: _start_resource_monitoring 17:33:33 INFO - Starting resource monitoring. 17:33:33 INFO - [mozharness: 2016-09-29 00:33:33.541578Z] Finished create-virtualenv step (success) 17:33:33 INFO - [mozharness: 2016-09-29 00:33:33.542608Z] Running install step. 17:33:33 INFO - Running pre-action listener: _resource_record_pre_action 17:33:33 INFO - Running main action method: install 17:33:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:33:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:33:33 INFO - Reading from file tmpfile_stdout 17:33:33 INFO - Detecting whether we're running mozinstall >=1.0... 17:33:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:33:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:33:33 INFO - Reading from file tmpfile_stdout 17:33:33 INFO - Output received: 17:33:33 INFO - Usage: mozinstall [options] installer 17:33:33 INFO - Options: 17:33:33 INFO - -h, --help show this help message and exit 17:33:33 INFO - -d DEST, --destination=DEST 17:33:33 INFO - Directory to install application into. [default: 17:33:33 INFO - "/builds/slave/test"] 17:33:33 INFO - --app=APP Application being installed. [default: firefox] 17:33:33 INFO - mkdir: /builds/slave/test/build/application 17:33:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 17:33:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 17:34:08 INFO - Reading from file tmpfile_stdout 17:34:08 INFO - Output received: 17:34:08 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:34:08 INFO - Running post-action listener: _resource_record_post_action 17:34:08 INFO - [mozharness: 2016-09-29 00:34:08.348496Z] Finished install step (success) 17:34:08 INFO - [mozharness: 2016-09-29 00:34:08.348666Z] Running run-tests step. 17:34:08 INFO - Running pre-action listener: _resource_record_pre_action 17:34:08 INFO - Running main action method: run_tests 17:34:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:34:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:34:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:34:08 INFO - 2016-09-28 17:34:08.363 screenresolution[1925:13272] starting screenresolution argv=screenresolution get 17:34:08 INFO - 2016-09-28 17:34:08.366 screenresolution[1925:13272] Display 0: 1600x1200x32@60 17:34:08 INFO - 2016-09-28 17:34:08.371 screenresolution[1926:13274] starting screenresolution argv=screenresolution list 17:34:08 INFO - Available Modes on Display 0 17:34:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:34:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:34:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:34:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:34:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:34:08 INFO - Intel Iris: 17:34:08 INFO - Chipset Model: Intel Iris 17:34:08 INFO - Type: GPU 17:34:08 INFO - Bus: Built-In 17:34:08 INFO - VRAM (Dynamic, Max): 1536 MB 17:34:08 INFO - Vendor: Intel (0x8086) 17:34:08 INFO - Device ID: 0x0a2e 17:34:08 INFO - Revision ID: 0x0009 17:34:08 INFO - Displays: 17:34:08 INFO - Display: 17:34:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:34:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:34:08 INFO - Display Serial Number: 1600x1200 60 17:34:08 INFO - Main Display: Yes 17:34:08 INFO - Mirror: Off 17:34:08 INFO - Online: Yes 17:34:08 INFO - Rotation: Supported 17:34:08 INFO - Return code: 0 17:34:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:34:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:34:08 INFO - Python 2.7.3 17:34:08 INFO - Return code: 0 17:34:08 INFO - grabbing minidump binary from tooltool 17:34:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:34:08 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 0x106bc6d30>, '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 0x1069ecdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106bbeb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:34:08 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 17:34:08 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 17:34:08 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 17:34:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:34:08 INFO - Return code: 0 17:34:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:34:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:34:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:34:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:34:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:34:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HvFaEWqHS_WwFtLBw-HF0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0332', '--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 17:34:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HvFaEWqHS_WwFtLBw-HF0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0332 --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 17:34:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3cd8s1dQZr/Render', 17:34:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:34:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:34:08 INFO - 'HOME': '/Users/cltbld', 17:34:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:34:08 INFO - 'LOGNAME': 'cltbld', 17:34:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:34:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:34:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:34:08 INFO - 'MOZ_NO_REMOTE': '1', 17:34:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:34:08 INFO - 'NO_EM_RESTART': '1', 17:34:08 INFO - 'PAGER': '/bin/cat', 17:34:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:34:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:34:08 INFO - 'PWD': '/builds/slave/test', 17:34:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:34:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:34:08 INFO - 'SHELL': '/bin/bash', 17:34:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mKaAI98gkB/Listeners', 17:34:08 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 17:34:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:34:08 INFO - 'USER': 'cltbld', 17:34:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:34:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:34:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:34:08 INFO - 'XPC_FLAGS': '0x0', 17:34:08 INFO - 'XPC_SERVICE_NAME': '0', 17:34:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:34:08 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HvFaEWqHS_WwFtLBw-HF0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0332', '--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 17:34:08 INFO - mozversion application_buildid: 20160928141419 17:34:08 INFO - mozversion application_changeset: 9df68a52f11882ad658e8a49fb37c325641556ed 17:34:08 INFO - mozversion application_display_name: Nightly 17:34:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:34:08 INFO - mozversion application_name: Firefox 17:34:08 INFO - mozversion application_remotingname: firefox 17:34:08 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 17:34:08 INFO - mozversion application_vendor: Mozilla 17:34:08 INFO - mozversion application_version: 52.0a1 17:34:08 INFO - mozversion platform_buildid: 20160928141419 17:34:08 INFO - mozversion platform_changeset: 9df68a52f11882ad658e8a49fb37c325641556ed 17:34:08 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 17:34:08 INFO - mozversion platform_version: 52.0a1 17:34:08 INFO - using testdate: 1475109248 17:34:08 INFO - actual date: 1475109248 17:34:08 INFO - starting webserver on 'localhost:51559' 17:34:08 INFO - SUITE-START | Running 2 tests 17:34:08 INFO - TEST-START | dromaeo_css 17:34:08 INFO - operating with platform_type : mac_ 17:34:08 INFO - Initialising browser for dromaeo_css test... 17:34:08 INFO - TEST-INFO | started process 1936 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/tmpzXK02t/profile http://localhost:51559/getInfo.html) 17:34:11 INFO - PROCESS | 1936 | __metrics Screen width/height:1600/1200 17:34:11 INFO - PROCESS | 1936 | colorDepth:24 17:34:11 INFO - PROCESS | 1936 | Browser inner width/height: 1024/689 17:34:11 INFO - PROCESS | 1936 | __metrics 17:34:11 INFO - PROCESS | 1936 | 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" 17:34:11 INFO - PROCESS | 1936 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:34:12 INFO - TEST-INFO | 1936: exit 0 17:34:12 INFO - Browser initialized. 17:34:12 INFO - Running cycle 1/1 for dromaeo_css test... 17:34:12 INFO - TEST-INFO | started process 1938 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/tmpzXK02t/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:34:23 INFO - PROCESS | 1938 | RSS: Main: 236023808 17:34:23 INFO - PROCESS | 1938 | 17:35:15 INFO - PROCESS | 1938 | 1475109315841 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] 17:37:26 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51559/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51559/tests/dromaeo/cssquery-ext.html) 17:37:28 INFO - PROCESS | 1938 | RSS: Main: 447799296 17:37:28 INFO - PROCESS | 1938 | 17:40:31 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51559/tests/dromaeo/cssquery-ext.html (next: http://localhost:51559/tests/dromaeo/cssquery-jquery.html) 17:40:32 INFO - PROCESS | 1938 | RSS: Main: 371314688 17:40:32 INFO - PROCESS | 1938 | 17:43:34 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51559/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51559/tests/dromaeo/cssquery-mootools.html) 17:43:35 INFO - PROCESS | 1938 | RSS: Main: 338415616 17:43:35 INFO - PROCESS | 1938 | 17:46:37 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51559/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51559/tests/dromaeo/cssquery-prototype.html) 17:46:38 INFO - PROCESS | 1938 | RSS: Main: 349208576 17:46:38 INFO - PROCESS | 1938 | 17:49:41 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51559/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51559/tests/dromaeo/cssquery-yui.html) 17:49:42 INFO - PROCESS | 1938 | RSS: Main: 333320192 17:49:42 INFO - PROCESS | 1938 | 17:52:45 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:51559/tests/dromaeo/cssquery-yui.html (next: http://localhost:51559/tests/dromaeo/cssquery-dojo.html) 17:52:45 INFO - PROCESS | 1938 | RSS: Main: 491184128 17:52:45 INFO - PROCESS | 1938 | 17:52:45 INFO - PROCESS | 1938 | __start_tp_report 17:52:45 INFO - PROCESS | 1938 | _x_x_mozilla_page_load 17:52:45 INFO - PROCESS | 1938 | _x_x_mozilla_page_load_details 17:52:45 INFO - PROCESS | 1938 | |i|pagename|runs| 17:52:45 INFO - PROCESS | 1938 | |0;dojo.html;3325.7006869381753;3332.5667663203785;3352.145202973243;3377.9324413511745;3406.727461803056;5606.103023516238;5687.573431992597;5721.256236689224;5749.942500574993;5769.682667453294;5687.203791469197;5691.373948865621;5828.271466066742;5831.620944638596;5837.328707198673;5084.745762711868;5152.716600586966;5187.870303242411;5210.973945130288;5231.712255825947;5285.418603953562;5321.414644389114;5324.813631522898;5335.813246536372;5340.62615616907;5260.710660913648;5267.236250743659;5307.973460132714;5312.389075256357;5357.624966252364;5085.389753229636;5142.151544995071;5150.381954165487;5171.3535808024;5228.4510126436935;5121.897562048738;5166.689998600058;5198.610104242197;5217.982493413786;5250.9212361814225;5697.9715101424645;5703.828885133453;5715.428457154293;5725.169850371714;5749.568782341341;6120.569035656853;6121.632702037848;6135.3857825960895;6250.156228909084;6263.968680156616;6207;6232.781852635136;6250.281217659982;6271.435570798604;6283.528735344822;5795.3045634523705;5801.216835727208;5805.941940580625;5818;5884.146798714162;6182.381761823782;6193.907091393633;6196.721147548371;6208.317085120633;6247.375262473716;6160.9383906160365;6174.9073763893575;6179.351168127372;6186.505079593621;6188.9690551546955;6221.346758590374;6240.375962403723;6248.75004999804;6254.21822272216;6256.530760192913;6030.065339872327;6112.929053818819;6224.159738435344;6226.315105338409;6244.906326405107;6138.3577446079025;6148.508119350441;6210.254769427697;6238.688065596702;6245.18812554365;4926.5073492650445;4927.482614325444;4930.038642464685;4938.703677779345;4970.577500912391;3819.5034645495907;4481.551844815493;4483.3947417098925;4484.529124441887;4513.187626227311;4887.608991280689;5643.604947653625;5662.292213473316;5731.168980497804;5764.5676574257095;6226.501879849601;6232.56372053961;6238.719257633417;6255.843603909939;6256.593321434092;5823.2429784128635;5830.4461076197695;5833.416658334218;5834.649921004753;5847.853803654943;5277.997180535686;5303.708296043677;5334.146536554133;5396.865078373072;5403.2165336026055;6186.536009799193;6205.162303089025;6212.06818977157;6232.5948813328805;6244.937550624436;6238.750449982132;6239.688015599111;6255.093011513396;6292.433680968735;6296.33888441707;6251.374862513712;6266.091416744455;6296.716647750954;6306.779262725782;6336.746530138743;6064.999275119732;6115.960286751174;6116.816495505142;6152.953997820292;6226.40849119333;6165.506759459143;6186.1339412483185;6209.472194863487;6210.068489726396;6255.499560035095;5973.970130149321;5994.730237139252;6150.292716337678;6159.907601385893;6169.84575385619;5957.6127551709;5969.850753731192;6124.203853499017;6143.877122457615;6166.229221347332;5802.506786923159;5853.94146058551;5862.944669959277;5914.112883067574;6018.307894592178;6094.878102438015;6095.725692343766;6101.267847858286;6106.419890110433;6106.542009349406;4987.376577927759;5852.385499522489;5908.291005079418;5920.674362909909;5932.970335148297 17:52:45 INFO - PROCESS | 1938 | |1;ext.html;481.407868321964;501.60874517876044;510.6604108267997;524.9868753281168;538.2034588808564;39823.99999999996;41117;41184.38223426651;42904.785476072655;43400.91497712559;42557.361639575356;42959.14081718372;43071.78464107675;43073;43162.568374316244;8678.522681252529;9665.66170184042;9818.852717209247;9820.134174506857;9842.409455432697;8711.085336039689;11912.106592005592;12175;12221.20562163461;12340.321282329469;11789.764204715944;11846.526138954432;11858.644240672793;11862.881371186264;11865.228760130607;8768.78078048054;9003.324750643727;12141.089418293619;12204.999999999956;12240.571579994747;11484.598039068676;11953.804619538063;11956.880431195663;12105.636830895088;12126.696832579168;39330.803345983084;39595.80202099;39624;39756.60243397558;39788.80105599482;3372.718367020541;3388.3731509670542;3398.3882901077795;3483.930321393583;3849.6727778138893;30879.536806947915;31488.685113148804;31656.208594785083;32176.034718958468;32208.71165153391;3198.2643991881764;3259.6251431085493;3262.9347413051605;3280.9343813123605;3386.5936087669393;384725;386600;387096.9999999972;387861;388720;60587.39412605906;60721.39278607157;60814.695926520086;60816.69591652013;60936;61948.38051619426;62945.370546294864;62985.74028519496;63057.73884522284;63085.05372419417;15998.9200053999;16104.919475402548;16303;16345.509634710977;16366.345346186257;11461.426928653535;11634.883651163549;12407.627771166879;12458.937705311597;12735.044871634664;18708.719369209473;19003.334883279018;19021;19037;19040.904795475933;7107.538010029331;7692.192349792285;7876.448648594544;7982.9600851995365;8072.475289106307;869.1829680100685;923.3628796130782;931.9207867331353;983.3362480325751;1042.519622136568;29663.96176133868;29779;30150.547741783892;30558.69441305603;30740.846295768824;1052.1740433759462;1057.6615483045352;1087.1628845788698;1089.3137323486312;1135.2223726747204;15462.690746185013;15495.767563486557;15623.531294061197;15636.609084772972;15855.048697078213;653677;664131;666451;668400.6579967069;669358;57652;57989.81056041958;58015.41984580184;58056.548586284836;58077.70961145252;1218.7440637466054;1266.341502418737;1346.9528566500126;1374.924379159136;1381.8687224713635;25886.22341329763;26982;28831.99088031909;31233.687663123077;32104.43164546784;49097.281595144;49707.77483913908;49713.502864970884;49752.25371619356;49856.501434985184;504316;505597;510366;511707;512449;26499.735002649726;27172;27181;27966;28053.316800992005;46518.53481465142;46519.53480465152;46760.53239467562;47067;48225.79435514;38711.03222419462;38927.41608875926;39028.804855976676;39568.60431395649;39790.60209397869;38240;38636.03409914775;38859.71418189474;39609.60390396059;39721.40417893676;31995.68004319927;36577.63422365742;38437;38551.807240963615;38569.807150964065;33393;34131.48802767911;34635.13412164716;34644.133896652784;34778.652213477544;23088.769112308662;25165;25504.234872953843;25669.871650641628;25724.614130788428 17:52:45 INFO - PROCESS | 1938 | |2;jquery.html;351.2115301148921;370.4128955605365;372.21276999146806;379.1885365318218;402.44060755549793;13174.802377964326;15527.913046086778;16481.50555483336;20577.27979520717;21299.57400851986;19413.514662133417;20011.699824502644;20489;20640.967951602397;20826.271080512186;5111.08000559899;5776.855578610537;6042.425969532887;6043.788467403641;6044.455998960093;5381.596380271495;5906;5999.100134979766;6004.219451471303;6008.158857759917;5643.68959707216;5824.330202026757;5936.614120082181;5937.495312898409;5954.106883967418;5818.301803783552;5872.265966754156;5879.412058794129;5881.323647780475;5886.323072846634;5122.564582010534;5125.538701516882;5183.870403239949;5413.945860541384;5486.149646804712;12911.418986145645;12992.025598080087;13124.409401576951;13143.60569182926;13148.342582870913;2098.6642137257963;2249.538844536882;2339.4736184358553;2476.0343466048257;2893.86977586007;9696.75758106046;9698.36960597559;9713.805723885553;10235.641752538626;10261.692149235534;1485.2796393749022;1860.4418674397762;1870.3079860451585;1871.9064046797605;1917.7411049508416;110651.89348106497;114382.99999999917;114936;122808.38595806964;123919;10939.507722152523;11093.500792464358;11363.602273920373;11386.94306528462;11597.76804463906;14355;14406;14411.927940360441;14419.279036048156;14432.42270309176;7057.329553692391;8174.141715119946;8381.580920953927;8409.495430274203;8533.615987280587;8324.875126873101;8326.209010144026;8356.039055508572;8482.660693572188;8532.23209911111;3680.245549662313;3718.925621487609;4422.977885110554;4463.085067561142;4576.016156526296;1731.1430841733422;1783.0995347349506;1824.1758241758241;1825.7949753162848;1858.5911099558075;867.7873920889297;893.9910600894038;903.6159632156307;946.1437399153776;954.61815273891;20989.265375712082;21271.72484117189;22380.216692415688;22568.210112646306;22764.634121952735;850.3197442046338;883.9513826739591;969.9515024248759;971.5919313888043;1035.440861934562;10497.842532362021;10721.517531711092;10908.181886358554;11328.603498877499;11340.829887551694;496453;503522;503525;513096;609097;107214.46392768143;111486;123332;134031.32984335016;136772;971.5288085278635;996.9750756231007;1014.9086582207636;1017.5166795772097;1017.5703137092397;18534.351297704514;19423.3201837935;19436.70844937299;19507.60984780268;19518.804811951697;44667;53909;54344;54472;55073;77356;80050.59974700089;80463.19536804558;81093;81174;6926.307369263033;7976.846251511944;8018.278354947849;8035.638396272298;8225.095239523527;10418.635347763095;12731.299778512272;13060.673483162996;13153.671158221121;13154.868451315364;10855.620053298097;10898.891011090105;11159.609413670483;11345.319280843176;11482.483288252215;10713.839292410465;11097.778044439228;11284.32294062359;11355.943220283845;11379.943100284445;10215.284930054824;10612.612162263738;11132.88867111351;11544.88455115438;11691.415429228335;6660.83347916306;6958.512904096665;7100;7246.96376518114;7363.410927125924;9320.766980825534;10874.347539147677;11182.105431565293;11235.88764112381;11390.886091138982 17:52:45 INFO - PROCESS | 1938 | |3;mootools.html;1530.8086489188854;1552.037736603306;1768.5932235585815;1904.2763749775083;1915.8850468971855;8406.537640429773;8890.86663700045;8897.15477029682;8908.930928288615;8910.198082172612;7741.458097933147;8048.993875765529;8051.718189863342;8422.663093476269;8674.26268767154;2345.1557439321873;2380.0836677879056;2380.2621187431864;2380.976190238093;2386.486905315357;2341.566810140121;2342.203650758742;2376.1327115602767;2377.5720370333333;2383.8688872112025;2349.3186975777003;2358.174638876397;2374.9287521374386;2375.1449478187837;2375.5367703297866;1958.4712127725577;1969.6454638165123;2203.3389983005036;2329.301209637111;2336.4859730859184;2173.434906924211;2347.2371479269136;2349.2952114365794;2353.5881220786464;2359.0210062823908;8797.29621630268;8803.867941980876;8883.600237989305;8907.10928907104;8934.151255630704;5143.3570803649545;5302.337207849019;5309.707966061865;5317.574594032468;5318.335208098988;3411;3487.9651203487892;3568.8572457101686;3614.981925090384;3689.7232707546773;1041.5279688725198;1078.14826287233;1078.2560033576806;1096.6929259807266;1098.9175811814066;11471.311721296666;11520.193586448951;11543.94228028863;11577.594784182598;11601.825972610503;4463.598276155161;4535.727856328631;4712.31671407644;4724.834630787906;4744.952550474451;9786;9786.608535658635;9787.168090712226;9795.412275263507;9805.950970245103;5295.470452954673;5301.3373328333955;5301.602379821452;5305.4164041955355;5307.28351672527;4356.28121359972;4392.538783427758;4524.23088075028;4685.672002959828;4880.829170978998;3762.454444105644;3770.792606406674;3917.6278253565865;3919.3729003359326;3952.5256969163884;1257.264500267354;1278.1244847279643;1281.6667666406615;1284.653143651209;1290.1549485087282;892.9910700893038;911.3074063711626;922.1930810540777;939.4081728511131;939.7697564096703;5681;5898.640419556017;5913.733881975321;5968.279845126912;6070.662794824025;416.9291220492523;421.5320993696994;432.08613781851784;444.9132419178294;448.25365765999896;3924.7252692311263;3996.9200615987515;4010.0150425539546;4012.077222238843;4014.4781178446615;10280.126189273846;10295;10677.850034239918;10682;10717.678469645924;8459.407841451162;10633.202509811796;10637.521311540999;10638.73403164927;10694.786104278026;7689.307962283421;7979.521228726179;8151.673933042612;8157.347412207009;8198.59007049657;5895.823125306248;5957.702114894325;5985.850353741192;6036.33600303975;6071.453569178795;9688.70933871985;10013.39919604826;10044.196464282693;10049.899501004897;10103.24225683062;9715;10138.442385668859;10253.436061016715;10316.43259620728;10327.741806454898;6878.69045892946;7122.786316410516;7474.514156579805;7610.9238907612435;7626.54240745557;7837.098733645704;8011.078725946591;8185.836283274421;8311.168883111883;8403.957980210304;7384.630768461663;7451.3666338361845;7461.738839140604;7535.058117735283;7624.085109786861;7104.715811367488;7250.74622388235;7263.382612477999;7333.1200255969625;7361.705531778883;6872.278410766798;6873.1064961554675;6972.965135174291;6982.930170698228;6994.650267486503;4846.667166529232;4903.656744028027;4921.50784921505;4958.231474121517;5019.899602008012;4998.4251811042195;5018.247262910592;5098.2862399263395;5147;5169.508896654812 17:52:45 INFO - PROCESS | 1938 | |4;prototype.html;264.1612879108829;300.1999670877113;308.53103283009824;313.140429520965;316.7323611548241;5132.381670922;5503.20203570483;5557.416471270519;5566.582506312033;5577.8047768328115;4937.851864444064;5174.197999310103;5352.608321836321;5403.891922161554;5488.451154884509;3978.641922226999;4156.376543518481;4188.518320393156;4188.958110418903;4193.014641559231;3576.5886923003795;4109.157622687357;4175.665946724254;4216.810243539048;4249.660027197817;3300.2409445827475;3986.2625414298304;4091.8158682859194;4145.647619952308;4150.3566947123245;3638.854445822164;4113.958860411388;4120.793960301973;4129.306984136505;4140.751554906685;3263.543103965447;3391.4404123319623;3584.426491761332;3585.5697316322207;3603.4234522476277;4176.812043458021;4783.617310615143;5275.525202731734;5275.920861187047;5309.3894202166475;4727.312696097049;4765.880852978668;4799.832005879777;4799.872030072965;4820.927686084711;5735;5771.220885180489;5778.219940308089;5814.575429019875;5815.476607105381;3631.4371272452813;3875.5155605549307;4013.1171142348635;4023;4034.7579145251084;5272.288241087443;5599.236240584244;5999.340072592011;6051.788187413419;6106.786262480792;3629.491871138043;3695.519582454264;3974.3044967130636;3977.985613668496;3978.9654689780286;3863.942040869389;4011.8796436106963;4025.376066709665;4030.838766449368;4036.192761447722;4144.3990621359735;4638.121560767894;4668.136394766979;4684.929726054112;4693.718376897397;4623.352730617717;5170.3278573785165;5398.541124004425;5616.66300022;5841.620294680833;1197.0363857095122;1285.0875878126449;1322.98130439561;1366.9179849209079;1371.1087792934673;659.6932426421737;747.9813004674927;809.7692157735017;833.8082241084463;843.5196230615231;4061.3704875744306;4348.412829317276;4546.886327841764;4594.379758732595;4635.837745678885;4355.956440435619;4778.546038126442;4941.258811178352;4951.356323677971;5044.319016932741;3793.563740169916;4035.3139966205226;4291.592593332423;4424.062584299975;4504.909901801946;2528.9241322760345;2618.245775330533;2635.3806855388966;2648.973510264911;2649.403884126095;4445.822167113344;4722.748471655014;5090.182363227579;5099.77051032697;5150.845474635767;4137.809429581804;4380.474343078788;5021.899562008739;5080.49195080489;5087.389513258433;5084.661055914321;5093.312402825572;5120.897582048338;5128.717920514333;5159.194282001269;4069.758723589334;4403.071957148461;4434.534373890824;4749.862561847113;4926.8768280793265;3883.990162557699;4403.691741578059;4904.400667646153;4944.206523314316;4944.431390390151;4456.844010459618;4530.731395209346;4766.594839438548;4985.850424487271;5113.488651134857;3375.4599264117624;3394.507796369562;3397.2865698203655;3413.2661477782394;3435.209901722568;4981.825636102719;5004.849854504225;5017.799288028438;5039.974800125976;5579.581531385081;4990.975045124751;5828.562857785599;5832.279477554047;5849.894038146179;5864.44287792659;5823.272090988627;5851.736671849691;5852;5879.794207202727;5892.94107058924;4721.598664113589;5450.945490545044;5531.8893622128135;5692.857678558069;5706.714664266687;5650.434956504317;5712.742926568398;5725.770969161187;5793.768249269982;5942.851428714143;4854.975725121351;5210.27056212123;5682.931217142862;5788.334341550775;5805.709714514342 17:52:45 INFO - PROCESS | 1938 | |5;yui.html;716.1765509294898;727.4871215792863;779.6842278877054;782.6086956521739;796.1202870827739;12489.812652810204;15826.604334891634;15908.84091159088;15965.281562329694;15965.600859978478;14356.856431435683;14420.278986050707;14434.20611866347;14477.348519316629;14479.782803257933;677.2888467109515;694.1704662927804;697.4943166204512;841.5035129273728;843.6035063520136;854.0520022774715;856.87146927961;864.3171894203572;865.1824026295153;866.9436486628381;931.9347645664806;932.3007744191856;932.6968735161066;933.5752232734089;934.7055677461611;917.8347897378469;920.9815803683922;922.395830730869;923.072312326108;926.7266156483853;533.1975377057543;547.3787251469571;659.6602749583968;669.8760729265101;683.2962049089446;15111.093334399971;15121.243937803067;15135.470258540898;15153.924230378778;15230;13726.519571814939;13899.444022239099;14132.50536231227;14191.148531088167;14518.128912265192;11828.704282392922;12249.326287054208;12619.990400767916;12641;12879.291638959854;3787.3561494545993;3910.7262491625597;4462;4513.7743112844555;4531.818727250906;117134;117391;118233;118704.40647796792;118872;10761.139108871272;11256.549738010552;11299.661010169708;11662.708432289091;11740.58907938218;22505.987230574337;22611.321660350215;22629.321120366414;22633.886830566073;22771.203007894645;8081;8095.83808323842;8124.065732440845;8165.918340816635;8270.917290827014;4525.5700708433305;4539.364488971547;4614.976925115353;4697.671163018625;4739.620830333565;3518.138056176202;3641.8543258269374;3642.162302670348;3646.2889736501656;3650.799206043693;652.7943697735199;654.0875478707177;657.1260223902199;674.8920172772431;684.5447777228087;489.82349463727695;522.4096770649143;607.7963882099547;619.4301242856583;625.7997440818986;8871.955640221757;9121;9318;9343.392679475812;9344.859827102599;516.3210378352454;591.1192323438046;595.3897255313252;625.0717684238992;633.2749002392235;4461.420015397977;4524.9547504524535;4528.298113792367;4552.682315994509;4582.783435842566;139498;158487;160314.1984290071;160382;164319;118725;129984.05023924648;141019.29490352687;141245;142319;86209.56895215483;110214;111946;113139;115243.84756152499;8235.29999950011;8596.269317108116;8609.526476043628;8843.380963332505;8937.329700272376;60491;61317.386826132955;62285.68857155685;62406.687966559875;62614.6869265669;82139;84881.7267740972;89838.75403114974;91335.54332228296;99523;9202.953985230031;11487.712807179887;12883.871161288642;13015;13958.58124256274;12950.287734174712;15094.698106038037;15322.387104515694;15338.693226135638;15440.30518626642;11425.771484570429;13745;13791.724165516433;13990.650233744238;14642.926785366006;13748.450061997408;13884.930575347058;13935.30323483842;14193;14200.857991419953;13414.731705365642;13673.111247768864;13784;14156.78764818508;14203.715925681221;9737.610495580097;11260.94369528147;11415.942920285346;11581.420928953687;11702.531898723955;10001.949990250003;11435.428228588704;11548.380981836612;11618.419079045845;11700.70748231267 17:52:45 INFO - PROCESS | 1938 | __end_tp_report 17:52:45 INFO - PROCESS | 1938 | __start_cc_report 17:52:45 INFO - PROCESS | 1938 | _x_x_mozilla_cycle_collect,2207 17:52:45 INFO - PROCESS | 1938 | __end_cc_report 17:52:45 INFO - PROCESS | 1938 | __startTimestamp1475110365533__endTimestamp 17:52:45 INFO - PROCESS | 1938 | 17:52:45 INFO - PROCESS | 1938 | ------- Summary: start ------- 17:52:45 INFO - PROCESS | 1938 | Number of tests: 6 17:52:45 INFO - PROCESS | 1938 | 17:52:45 INFO - PROCESS | 1938 | [#0] dojo.html Cycles:165 Average:5732.78 Median:5920.67 stddev:640.88 (10.8%) stddev-sans-first:614.40 17:52:45 INFO - PROCESS | 1938 | Values: 3325.7 3332.6 3352.1 3377.9 3406.7 5606.1 5687.6 5721.3 5749.9 5769.7 5687.2 5691.4 5828.3 5831.6 5837.3 5084.7 5152.7 5187.9 5211.0 5231.7 5285.4 5321.4 5324.8 5335.8 5340.6 5260.7 5267.2 5308.0 5312.4 5357.6 5085.4 5142.2 5150.4 5171.4 5228.5 5121.9 5166.7 5198.6 5218.0 5250.9 5698.0 5703.8 5715.4 5725.2 5749.6 6120.6 6121.6 6135.4 6250.2 6264.0 6207.0 6232.8 6250.3 6271.4 6283.5 5795.3 5801.2 5805.9 5818.0 5884.1 6182.4 6193.9 6196.7 6208.3 6247.4 6160.9 6174.9 6179.4 6186.5 6189.0 6221.3 6240.4 6248.8 6254.2 6256.5 6030.1 6112.9 6224.2 6226.3 6244.9 6138.4 6148.5 6210.3 6238.7 6245.2 4926.5 4927.5 4930.0 4938.7 4970.6 3819.5 4481.6 4483.4 4484.5 4513.2 4887.6 5643.6 5662.3 5731.2 5764.6 6226.5 6232.6 6238.7 6255.8 6256.6 5823.2 5830.4 5833.4 5834.6 5847.9 5278.0 5303.7 5334.1 5396.9 5403.2 6186.5 6205.2 6212.1 6232.6 6244.9 6238.8 6239.7 6255.1 6292.4 6296.3 6251.4 6266.1 6296.7 6306.8 6336.7 6065.0 6116.0 6116.8 6153.0 6226.4 6165.5 6186.1 6209.5 6210.1 6255.5 5974.0 5994.7 6150.3 6159.9 6169.8 5957.6 5969.9 6124.2 6143.9 6166.2 5802.5 5853.9 5862.9 5914.1 6018.3 6094.9 6095.7 6101.3 6106.4 6106.5 4987.4 5852.4 5908.3 5920.7 5933.0 17:52:45 INFO - PROCESS | 1938 | 17:52:45 INFO - PROCESS | 1938 | [#1] ext.html Cycles:180 Average:66455.08 Median:211581.00 stddev:142162.28 (67.2%) stddev-sans-first:142474.78 17:52:45 INFO - PROCESS | 1938 | Values: 481.4 501.6 510.7 525.0 538.2 39824.0 41117.0 41184.4 42904.8 43400.9 42557.4 42959.1 43071.8 43073.0 43162.6 8678.5 9665.7 9818.9 9820.1 9842.4 8711.1 11912.1 12175.0 12221.2 12340.3 11789.8 11846.5 11858.6 11862.9 11865.2 8768.8 9003.3 12141.1 12205.0 12240.6 11484.6 11953.8 11956.9 12105.6 12126.7 39330.8 39595.8 39624.0 39756.6 39788.8 3372.7 3388.4 3398.4 3483.9 3849.7 30879.5 31488.7 31656.2 32176.0 32208.7 3198.3 3259.6 3262.9 3280.9 3386.6 384725.0 386600.0 387097.0 387861.0 388720.0 60587.4 60721.4 60814.7 60816.7 60936.0 61948.4 62945.4 62985.7 63057.7 63085.1 15998.9 16104.9 16303.0 16345.5 16366.3 11461.4 11634.9 12407.6 12458.9 12735.0 18708.7 19003.3 19021.0 19037.0 19040.9 7107.5 7692.2 7876.4 7983.0 8072.5 869.2 923.4 931.9 983.3 1042.5 29664.0 29779.0 30150.5 30558.7 30740.8 1052.2 1057.7 1087.2 1089.3 1135.2 15462.7 15495.8 15623.5 15636.6 15855.0 653677.0 664131.0 666451.0 668400.7 669358.0 57652.0 57989.8 58015.4 58056.5 58077.7 1218.7 1266.3 1347.0 1374.9 1381.9 25886.2 26982.0 28832.0 31233.7 32104.4 49097.3 49707.8 49713.5 49752.3 49856.5 504316.0 505597.0 510366.0 511707.0 512449.0 26499.7 27172.0 27181.0 27966.0 28053.3 46518.5 46519.5 46760.5 47067.0 48225.8 38711.0 38927.4 39028.8 39568.6 39790.6 38240.0 38636.0 38859.7 39609.6 39721.4 31995.7 36577.6 38437.0 38551.8 38569.8 33393.0 34131.5 34635.1 34644.1 34778.7 23088.8 25165.0 25504.2 25669.9 25724.6 17:52:45 INFO - PROCESS | 1938 | 17:52:45 INFO - PROCESS | 1938 | [#2] jquery.html Cycles:180 Average:32397.68 Median:22666.42 stddev:88643.54 (391.1%) stddev-sans-first:88859.55 17:52:45 INFO - PROCESS | 1938 | Values: 351.2 370.4 372.2 379.2 402.4 13174.8 15527.9 16481.5 20577.3 21299.6 19413.5 20011.7 20489.0 20641.0 20826.3 5111.1 5776.9 6042.4 6043.8 6044.5 5381.6 5906.0 5999.1 6004.2 6008.2 5643.7 5824.3 5936.6 5937.5 5954.1 5818.3 5872.3 5879.4 5881.3 5886.3 5122.6 5125.5 5183.9 5413.9 5486.1 12911.4 12992.0 13124.4 13143.6 13148.3 2098.7 2249.5 2339.5 2476.0 2893.9 9696.8 9698.4 9713.8 10235.6 10261.7 1485.3 1860.4 1870.3 1871.9 1917.7 110651.9 114383.0 114936.0 122808.4 123919.0 10939.5 11093.5 11363.6 11386.9 11597.8 14355.0 14406.0 14411.9 14419.3 14432.4 7057.3 8174.1 8381.6 8409.5 8533.6 8324.9 8326.2 8356.0 8482.7 8532.2 3680.2 3718.9 4423.0 4463.1 4576.0 1731.1 1783.1 1824.2 1825.8 1858.6 867.8 894.0 903.6 946.1 954.6 20989.3 21271.7 22380.2 22568.2 22764.6 850.3 884.0 970.0 971.6 1035.4 10497.8 10721.5 10908.2 11328.6 11340.8 496453.0 503522.0 503525.0 513096.0 609097.0 107214.5 111486.0 123332.0 134031.3 136772.0 971.5 997.0 1014.9 1017.5 1017.6 18534.4 19423.3 19436.7 19507.6 19518.8 44667.0 53909.0 54344.0 54472.0 55073.0 77356.0 80050.6 80463.2 81093.0 81174.0 6926.3 7976.8 8018.3 8035.6 8225.1 10418.6 12731.3 13060.7 13153.7 13154.9 10855.6 10898.9 11159.6 11345.3 11482.5 10713.8 11097.8 11284.3 11355.9 11379.9 10215.3 10612.6 11132.9 11544.9 11691.4 6660.8 6958.5 7100.0 7247.0 7363.4 9320.8 10874.3 11182.1 11235.9 11390.9 17:52:45 INFO - PROCESS | 1938 | 17:52:45 INFO - PROCESS | 1938 | [#3] mootools.html Cycles:180 Average:5650.55 Median:4795.81 stddev:3172.87 (66.2%) stddev-sans-first:3166.67 17:52:45 INFO - PROCESS | 1938 | Values: 1530.8 1552.0 1768.6 1904.3 1915.9 8406.5 8890.9 8897.2 8908.9 8910.2 7741.5 8049.0 8051.7 8422.7 8674.3 2345.2 2380.1 2380.3 2381.0 2386.5 2341.6 2342.2 2376.1 2377.6 2383.9 2349.3 2358.2 2374.9 2375.1 2375.5 1958.5 1969.6 2203.3 2329.3 2336.5 2173.4 2347.2 2349.3 2353.6 2359.0 8797.3 8803.9 8883.6 8907.1 8934.2 5143.4 5302.3 5309.7 5317.6 5318.3 3411.0 3488.0 3568.9 3615.0 3689.7 1041.5 1078.1 1078.3 1096.7 1098.9 11471.3 11520.2 11543.9 11577.6 11601.8 4463.6 4535.7 4712.3 4724.8 4745.0 9786.0 9786.6 9787.2 9795.4 9806.0 5295.5 5301.3 5301.6 5305.4 5307.3 4356.3 4392.5 4524.2 4685.7 4880.8 3762.5 3770.8 3917.6 3919.4 3952.5 1257.3 1278.1 1281.7 1284.7 1290.2 893.0 911.3 922.2 939.4 939.8 5681.0 5898.6 5913.7 5968.3 6070.7 416.9 421.5 432.1 444.9 448.3 3924.7 3996.9 4010.0 4012.1 4014.5 10280.1 10295.0 10677.9 10682.0 10717.7 8459.4 10633.2 10637.5 10638.7 10694.8 7689.3 7979.5 8151.7 8157.3 8198.6 5895.8 5957.7 5985.9 6036.3 6071.5 9688.7 10013.4 10044.2 10049.9 10103.2 9715.0 10138.4 10253.4 10316.4 10327.7 6878.7 7122.8 7474.5 7610.9 7626.5 7837.1 8011.1 8185.8 8311.2 8404.0 7384.6 7451.4 7461.7 7535.1 7624.1 7104.7 7250.7 7263.4 7333.1 7361.7 6872.3 6873.1 6973.0 6982.9 6994.7 4846.7 4903.7 4921.5 4958.2 5019.9 4998.4 5018.2 5098.3 5147.0 5169.5 17:52:45 INFO - PROCESS | 1938 | 17:52:45 INFO - PROCESS | 1938 | [#4] prototype.html Cycles:180 Average:4367.58 Median:4738.59 stddev:1342.38 (28.3%) stddev-sans-first:1310.34 17:52:45 INFO - PROCESS | 1938 | Values: 264.2 300.2 308.5 313.1 316.7 5132.4 5503.2 5557.4 5566.6 5577.8 4937.9 5174.2 5352.6 5403.9 5488.5 3978.6 4156.4 4188.5 4189.0 4193.0 3576.6 4109.2 4175.7 4216.8 4249.7 3300.2 3986.3 4091.8 4145.6 4150.4 3638.9 4114.0 4120.8 4129.3 4140.8 3263.5 3391.4 3584.4 3585.6 3603.4 4176.8 4783.6 5275.5 5275.9 5309.4 4727.3 4765.9 4799.8 4799.9 4820.9 5735.0 5771.2 5778.2 5814.6 5815.5 3631.4 3875.5 4013.1 4023.0 4034.8 5272.3 5599.2 5999.3 6051.8 6106.8 3629.5 3695.5 3974.3 3978.0 3979.0 3863.9 4011.9 4025.4 4030.8 4036.2 4144.4 4638.1 4668.1 4684.9 4693.7 4623.4 5170.3 5398.5 5616.7 5841.6 1197.0 1285.1 1323.0 1366.9 1371.1 659.7 748.0 809.8 833.8 843.5 4061.4 4348.4 4546.9 4594.4 4635.8 4356.0 4778.5 4941.3 4951.4 5044.3 3793.6 4035.3 4291.6 4424.1 4504.9 2528.9 2618.2 2635.4 2649.0 2649.4 4445.8 4722.7 5090.2 5099.8 5150.8 4137.8 4380.5 5021.9 5080.5 5087.4 5084.7 5093.3 5120.9 5128.7 5159.2 4069.8 4403.1 4434.5 4749.9 4926.9 3884.0 4403.7 4904.4 4944.2 4944.4 4456.8 4530.7 4766.6 4985.9 5113.5 3375.5 3394.5 3397.3 3413.3 3435.2 4981.8 5004.8 5017.8 5040.0 5579.6 4991.0 5828.6 5832.3 5849.9 5864.4 5823.3 5851.7 5852.0 5879.8 5892.9 4721.6 5450.9 5531.9 5692.9 5706.7 5650.4 5712.7 5725.8 5793.8 5942.9 4855.0 5210.3 5682.9 5788.3 5805.7 17:52:45 INFO - PROCESS | 1938 | 17:52:45 INFO - PROCESS | 1938 | [#5] yui.html Cycles:180 Average:25381.95 Median:3648.54 stddev:41021.75 (1124.3%) stddev-sans-first:41095.02 17:52:45 INFO - PROCESS | 1938 | Values: 716.2 727.5 779.7 782.6 796.1 12489.8 15826.6 15908.8 15965.3 15965.6 14356.9 14420.3 14434.2 14477.3 14479.8 677.3 694.2 697.5 841.5 843.6 854.1 856.9 864.3 865.2 866.9 931.9 932.3 932.7 933.6 934.7 917.8 921.0 922.4 923.1 926.7 533.2 547.4 659.7 669.9 683.3 15111.1 15121.2 15135.5 15153.9 15230.0 13726.5 13899.4 14132.5 14191.1 14518.1 11828.7 12249.3 12620.0 12641.0 12879.3 3787.4 3910.7 4462.0 4513.8 4531.8 117134.0 117391.0 118233.0 118704.4 118872.0 10761.1 11256.5 11299.7 11662.7 11740.6 22506.0 22611.3 22629.3 22633.9 22771.2 8081.0 8095.8 8124.1 8165.9 8270.9 4525.6 4539.4 4615.0 4697.7 4739.6 3518.1 3641.9 3642.2 3646.3 3650.8 652.8 654.1 657.1 674.9 684.5 489.8 522.4 607.8 619.4 625.8 8872.0 9121.0 9318.0 9343.4 9344.9 516.3 591.1 595.4 625.1 633.3 4461.4 4525.0 4528.3 4552.7 4582.8 139498.0 158487.0 160314.2 160382.0 164319.0 118725.0 129984.1 141019.3 141245.0 142319.0 86209.6 110214.0 111946.0 113139.0 115243.8 8235.3 8596.3 8609.5 8843.4 8937.3 60491.0 61317.4 62285.7 62406.7 62614.7 82139.0 84881.7 89838.8 91335.5 99523.0 9203.0 11487.7 12883.9 13015.0 13958.6 12950.3 15094.7 15322.4 15338.7 15440.3 11425.8 13745.0 13791.7 13990.7 14642.9 13748.5 13884.9 13935.3 14193.0 14200.9 13414.7 13673.1 13784.0 14156.8 14203.7 9737.6 11260.9 11415.9 11581.4 11702.5 10001.9 11435.4 11548.4 11618.4 11700.7 17:52:45 INFO - PROCESS | 1938 | -------- Summary: end -------- 17:52:45 INFO - PROCESS | 1938 | 17:52:46 INFO - TEST-INFO | 1938: exit 0 17:52:46 INFO - TEST-OK | dromaeo_css | took 1117190ms 17:52:46 INFO - TEST-START | kraken 17:52:46 INFO - operating with platform_type : mac_ 17:52:46 INFO - Initialising browser for kraken test... 17:52:46 INFO - TEST-INFO | started process 1942 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/tmpP0ef4d/profile http://localhost:51559/getInfo.html) 17:52:48 INFO - PROCESS | 1942 | __metrics Screen width/height:1600/1200 17:52:48 INFO - PROCESS | 1942 | colorDepth:24 17:52:48 INFO - PROCESS | 1942 | Browser inner width/height: 1024/689 17:52:48 INFO - PROCESS | 1942 | __metrics 17:52:48 INFO - PROCESS | 1942 | 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" 17:52:48 INFO - PROCESS | 1942 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:52:49 INFO - TEST-INFO | 1942: exit 0 17:52:49 INFO - Browser initialized. 17:52:49 INFO - Running cycle 1/1 for kraken test... 17:52:49 INFO - TEST-INFO | started process 1943 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/tmpP0ef4d/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:53:01 INFO - PROCESS | 1943 | RSS: Main: 236318720 17:53:01 INFO - PROCESS | 1943 | 17:53:04 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:51559/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51559/tests/kraken/driver.html?testName=audio-beat-detection) 17:53:05 INFO - PROCESS | 1943 | RSS: Main: 294670336 17:53:05 INFO - PROCESS | 1943 | 17:53:07 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:51559/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51559/tests/kraken/driver.html?testName=audio-dft) 17:53:08 INFO - PROCESS | 1943 | RSS: Main: 293695488 17:53:08 INFO - PROCESS | 1943 | 17:53:12 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:51559/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51559/tests/kraken/driver.html?testName=audio-fft) 17:53:12 INFO - PROCESS | 1943 | RSS: Main: 383049728 17:53:12 INFO - PROCESS | 1943 | 17:53:14 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:51559/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51559/tests/kraken/driver.html?testName=audio-oscillator) 17:53:15 INFO - PROCESS | 1943 | RSS: Main: 285126656 17:53:15 INFO - PROCESS | 1943 | 17:53:17 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:51559/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51559/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:53:18 INFO - PROCESS | 1943 | RSS: Main: 287985664 17:53:18 INFO - PROCESS | 1943 | 17:53:20 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:51559/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51559/tests/kraken/driver.html?testName=imaging-darkroom) 17:53:21 INFO - PROCESS | 1943 | RSS: Main: 326508544 17:53:21 INFO - PROCESS | 1943 | 17:53:24 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:51559/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51559/tests/kraken/driver.html?testName=imaging-desaturate) 17:53:25 INFO - PROCESS | 1943 | RSS: Main: 327655424 17:53:25 INFO - PROCESS | 1943 | 17:53:27 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:51559/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51559/tests/kraken/driver.html?testName=json-parse-financial) 17:53:28 INFO - PROCESS | 1943 | RSS: Main: 325242880 17:53:28 INFO - PROCESS | 1943 | 17:53:30 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:51559/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51559/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:53:30 INFO - PROCESS | 1943 | RSS: Main: 305213440 17:53:30 INFO - PROCESS | 1943 | 17:53:32 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:51559/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51559/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:53:33 INFO - PROCESS | 1943 | RSS: Main: 305049600 17:53:33 INFO - PROCESS | 1943 | 17:53:35 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:51559/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51559/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:53:36 INFO - PROCESS | 1943 | RSS: Main: 309813248 17:53:36 INFO - PROCESS | 1943 | 17:53:39 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:51559/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51559/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:53:39 INFO - PROCESS | 1943 | RSS: Main: 317091840 17:53:39 INFO - PROCESS | 1943 | 17:53:42 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:51559/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51559/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:53:43 INFO - PROCESS | 1943 | RSS: Main: 319324160 17:53:43 INFO - PROCESS | 1943 | 17:53:45 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:51559/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51559/tests/kraken/driver.html?testName=ai-astar) 17:53:45 INFO - PROCESS | 1943 | RSS: Main: 363184128 17:53:45 INFO - PROCESS | 1943 | 17:53:45 INFO - PROCESS | 1943 | __start_tp_report 17:53:45 INFO - PROCESS | 1943 | _x_x_mozilla_page_load 17:53:45 INFO - PROCESS | 1943 | _x_x_mozilla_page_load_details 17:53:45 INFO - PROCESS | 1943 | |i|pagename|runs| 17:53:45 INFO - PROCESS | 1943 | |0;ai-astar;113;112;122;163;147;111;112;112;113;113 17:53:45 INFO - PROCESS | 1943 | |1;audio-beat-detection;147;110;109;160;105;131;90;128;175;140 17:53:45 INFO - PROCESS | 1943 | |2;audio-dft;183;181;184;198;261;168;249;179;184;181 17:53:45 INFO - PROCESS | 1943 | |3;audio-fft;81;59;60;89;62;62;60;58;80;58 17:53:45 INFO - PROCESS | 1943 | |4;audio-oscillator;91;70;92;70;70;92;125;96;69;90 17:53:45 INFO - PROCESS | 1943 | |5;imaging-gaussian-blur;88;85;88;96;115;85;88;85;88;84 17:53:45 INFO - PROCESS | 1943 | |6;imaging-darkroom;94;94;94;95;93;94;94;95;93;105 17:53:45 INFO - PROCESS | 1943 | |7;imaging-desaturate;86;84;86;88;84;85;87;85;96;132 17:53:45 INFO - PROCESS | 1943 | |8;json-parse-financial;55;55;55;54;57;54;54;55;55;55 17:53:45 INFO - PROCESS | 1943 | |9;json-stringify-tinderbox;65;73;70;76;73;70;71;70;70;70 17:53:45 INFO - PROCESS | 1943 | |10;stanford-crypto-aes;86;89;85;87;85;85;87;85;92;104 17:53:45 INFO - PROCESS | 1943 | |11;stanford-crypto-ccm;156;128;148;154;143;150;154;155;229;137 17:53:45 INFO - PROCESS | 1943 | |12;stanford-crypto-pbkdf2;170;169;167;166;169;166;219;178;200;200 17:53:45 INFO - PROCESS | 1943 | |13;stanford-crypto-sha256-iterative;68;69;68;67;68;66;67;69;67;65 17:53:45 INFO - PROCESS | 1943 | __end_tp_report 17:53:45 INFO - PROCESS | 1943 | __start_cc_report 17:53:45 INFO - PROCESS | 1943 | _x_x_mozilla_cycle_collect,2057 17:53:45 INFO - PROCESS | 1943 | __end_cc_report 17:53:45 INFO - PROCESS | 1943 | __startTimestamp1475110425436__endTimestamp 17:53:45 INFO - PROCESS | 1943 | 17:53:45 INFO - PROCESS | 1943 | ------- Summary: start ------- 17:53:45 INFO - PROCESS | 1943 | Number of tests: 14 17:53:45 INFO - PROCESS | 1943 | 17:53:45 INFO - PROCESS | 1943 | [#0] ai-astar Cycles:10 Average:121.80 Median:113.00 stddev:18.16 (16.1%) stddev-sans-first:18.99 17:53:45 INFO - PROCESS | 1943 | Values: 113.0 112.0 122.0 163.0 147.0 111.0 112.0 112.0 113.0 113.0 17:53:45 INFO - PROCESS | 1943 | 17:53:45 INFO - PROCESS | 1943 | [#1] audio-beat-detection Cycles:10 Average:129.50 Median:143.50 stddev:26.63 (18.6%) stddev-sans-first:27.48 17:53:45 INFO - PROCESS | 1943 | Values: 147.0 110.0 109.0 160.0 105.0 131.0 90.0 128.0 175.0 140.0 17:53:45 INFO - PROCESS | 1943 | 17:53:45 INFO - PROCESS | 1943 | [#2] audio-dft Cycles:10 Average:196.80 Median:184.00 stddev:31.64 (17.2%) stddev-sans-first:33.17 17:53:45 INFO - PROCESS | 1943 | Values: 183.0 181.0 184.0 198.0 261.0 168.0 249.0 179.0 184.0 181.0 17:53:45 INFO - PROCESS | 1943 | 17:53:45 INFO - PROCESS | 1943 | [#3] audio-fft Cycles:10 Average:66.90 Median:62.00 stddev:11.66 (18.8%) stddev-sans-first:11.19 17:53:45 INFO - PROCESS | 1943 | Values: 81.0 59.0 60.0 89.0 62.0 62.0 60.0 58.0 80.0 58.0 17:53:45 INFO - PROCESS | 1943 | 17:53:45 INFO - PROCESS | 1943 | [#4] audio-oscillator Cycles:10 Average:86.50 Median:90.50 stddev:17.60 (19.4%) stddev-sans-first:18.59 17:53:45 INFO - PROCESS | 1943 | Values: 91.0 70.0 92.0 70.0 70.0 92.0 125.0 96.0 69.0 90.0 17:53:45 INFO - PROCESS | 1943 | 17:53:45 INFO - PROCESS | 1943 | [#5] imaging-gaussian-blur Cycles:10 Average:90.20 Median:88.00 stddev:9.35 (10.6%) stddev-sans-first:9.89 17:53:45 INFO - PROCESS | 1943 | Values: 88.0 85.0 88.0 96.0 115.0 85.0 88.0 85.0 88.0 84.0 17:53:45 INFO - PROCESS | 1943 | 17:53:45 INFO - PROCESS | 1943 | [#6] imaging-darkroom Cycles:10 Average:95.10 Median:94.00 stddev:3.54 (3.8%) stddev-sans-first:3.73 17:53:45 INFO - PROCESS | 1943 | Values: 94.0 94.0 94.0 95.0 93.0 94.0 94.0 95.0 93.0 105.0 17:53:45 INFO - PROCESS | 1943 | 17:53:45 INFO - PROCESS | 1943 | [#7] imaging-desaturate Cycles:10 Average:91.30 Median:86.00 stddev:14.72 (17.1%) stddev-sans-first:15.49 17:53:45 INFO - PROCESS | 1943 | Values: 86.0 84.0 86.0 88.0 84.0 85.0 87.0 85.0 96.0 132.0 17:53:45 INFO - PROCESS | 1943 | 17:53:45 INFO - PROCESS | 1943 | [#8] json-parse-financial Cycles:10 Average:54.90 Median:55.00 stddev:0.88 (1.6%) stddev-sans-first:0.93 17:53:45 INFO - PROCESS | 1943 | Values: 55.0 55.0 55.0 54.0 57.0 54.0 54.0 55.0 55.0 55.0 17:53:45 INFO - PROCESS | 1943 | 17:53:45 INFO - PROCESS | 1943 | [#9] json-stringify-tinderbox Cycles:10 Average:70.80 Median:70.50 stddev:2.86 (4.1%) stddev-sans-first:2.13 17:53:45 INFO - PROCESS | 1943 | Values: 65.0 73.0 70.0 76.0 73.0 70.0 71.0 70.0 70.0 70.0 17:53:45 INFO - PROCESS | 1943 | 17:53:45 INFO - PROCESS | 1943 | [#10] stanford-crypto-aes Cycles:10 Average:88.50 Median:86.50 stddev:5.89 (6.8%) stddev-sans-first:6.18 17:53:45 INFO - PROCESS | 1943 | Values: 86.0 89.0 85.0 87.0 85.0 85.0 87.0 85.0 92.0 104.0 17:53:45 INFO - PROCESS | 1943 | 17:53:45 INFO - PROCESS | 1943 | [#11] stanford-crypto-ccm Cycles:10 Average:155.40 Median:154.00 stddev:27.38 (17.8%) stddev-sans-first:29.04 17:53:45 INFO - PROCESS | 1943 | Values: 156.0 128.0 148.0 154.0 143.0 150.0 154.0 155.0 229.0 137.0 17:53:45 INFO - PROCESS | 1943 | 17:53:45 INFO - PROCESS | 1943 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.40 Median:174.00 stddev:18.93 (10.9%) stddev-sans-first:19.70 17:53:45 INFO - PROCESS | 1943 | Values: 170.0 169.0 167.0 166.0 169.0 166.0 219.0 178.0 200.0 200.0 17:53:45 INFO - PROCESS | 1943 | 17:53:45 INFO - PROCESS | 1943 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:68.00 stddev:1.26 (1.9%) stddev-sans-first:1.32 17:53:45 INFO - PROCESS | 1943 | Values: 68.0 69.0 68.0 67.0 68.0 66.0 67.0 69.0 67.0 65.0 17:53:45 INFO - PROCESS | 1943 | -------- Summary: end -------- 17:53:45 INFO - PROCESS | 1943 | 17:53:46 INFO - TEST-INFO | 1943: exit 0 17:53:46 INFO - TEST-OK | kraken | took 60193ms 17:53:46 INFO - SUITE-END | took 1177s 17:53:46 INFO - Completed test suite (00:19:37) 17:53:46 INFO - javascript benchmark 17:53:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3325.7006869381753, 3332.5667663203785, 3352.145202973243, 3377.9324413511745, 3406.727461803056, 5606.103023516238, 5687.573431992597, 5721.256236689224, 5749.942500574993, 5769.682667453294, 5687.203791469197, 5691.373948865621, 5828.271466066742, 5831.620944638596, 5837.328707198673, 5084.745762711868, 5152.716600586966, 5187.870303242411, 5210.973945130288, 5231.712255825947, 5285.418603953562, 5321.414644389114, 5324.813631522898, 5335.813246536372, 5340.62615616907, 5260.710660913648, 5267.236250743659, 5307.973460132714, 5312.389075256357, 5357.624966252364, 5085.389753229636, 5142.151544995071, 5150.381954165487, 5171.3535808024, 5228.4510126436935, 5121.897562048738, 5166.689998600058, 5198.610104242197, 5217.982493413786, 5250.9212361814225, 5697.9715101424645, 5703.828885133453, 5715.428457154293, 5725.169850371714, 5749.568782341341, 6120.569035656853, 6121.632702037848, 6135.3857825960895, 6250.156228909084, 6263.968680156616, 6207.0, 6232.781852635136, 6250.281217659982, 6271.435570798604, 6283.528735344822, 5795.3045634523705, 5801.216835727208, 5805.941940580625, 5818.0, 5884.146798714162, 6182.381761823782, 6193.907091393633, 6196.721147548371, 6208.317085120633, 6247.375262473716, 6160.9383906160365, 6174.9073763893575, 6179.351168127372, 6186.505079593621, 6188.9690551546955, 6221.346758590374, 6240.375962403723, 6248.75004999804, 6254.21822272216, 6256.530760192913, 6030.065339872327, 6112.929053818819, 6224.159738435344, 6226.315105338409, 6244.906326405107, 6138.3577446079025, 6148.508119350441, 6210.254769427697, 6238.688065596702, 6245.18812554365, 4926.5073492650445, 4927.482614325444, 4930.038642464685, 4938.703677779345, 4970.577500912391, 3819.5034645495907, 4481.551844815493, 4483.3947417098925, 4484.529124441887, 4513.187626227311, 4887.608991280689, 5643.604947653625, 5662.292213473316, 5731.168980497804, 5764.5676574257095, 6226.501879849601, 6232.56372053961, 6238.719257633417, 6255.843603909939, 6256.593321434092, 5823.2429784128635, 5830.4461076197695, 5833.416658334218, 5834.649921004753, 5847.853803654943, 5277.997180535686, 5303.708296043677, 5334.146536554133, 5396.865078373072, 5403.2165336026055, 6186.536009799193, 6205.162303089025, 6212.06818977157, 6232.5948813328805, 6244.937550624436, 6238.750449982132, 6239.688015599111, 6255.093011513396, 6292.433680968735, 6296.33888441707, 6251.374862513712, 6266.091416744455, 6296.716647750954, 6306.779262725782, 6336.746530138743, 6064.999275119732, 6115.960286751174, 6116.816495505142, 6152.953997820292, 6226.40849119333, 6165.506759459143, 6186.1339412483185, 6209.472194863487, 6210.068489726396, 6255.499560035095, 5973.970130149321, 5994.730237139252, 6150.292716337678, 6159.907601385893, 6169.84575385619, 5957.6127551709, 5969.850753731192, 6124.203853499017, 6143.877122457615, 6166.229221347332, 5802.506786923159, 5853.94146058551, 5862.944669959277, 5914.112883067574, 6018.307894592178, 6094.878102438015, 6095.725692343766, 6101.267847858286, 6106.419890110433, 6106.542009349406, 4987.376577927759, 5852.385499522489, 5908.291005079418, 5920.674362909909, 5932.970335148297], "value": 5691.367332494998, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [481.407868321964, 501.60874517876044, 510.6604108267997, 524.9868753281168, 538.2034588808564, 39823.99999999996, 41117.0, 41184.38223426651, 42904.785476072655, 43400.91497712559, 42557.361639575356, 42959.14081718372, 43071.78464107675, 43073.0, 43162.568374316244, 8678.522681252529, 9665.66170184042, 9818.852717209247, 9820.134174506857, 9842.409455432697, 8711.085336039689, 11912.106592005592, 12175.0, 12221.20562163461, 12340.321282329469, 11789.764204715944, 11846.526138954432, 11858.644240672793, 11862.881371186264, 11865.228760130607, 8768.78078048054, 9003.324750643727, 12141.089418293619, 12204.999999999956, 12240.571579994747, 11484.598039068676, 11953.804619538063, 11956.880431195663, 12105.636830895088, 12126.696832579168, 39330.803345983084, 39595.80202099, 39624.0, 39756.60243397558, 39788.80105599482, 3372.718367020541, 3388.3731509670542, 3398.3882901077795, 3483.930321393583, 3849.6727778138893, 30879.536806947915, 31488.685113148804, 31656.208594785083, 32176.034718958468, 32208.71165153391, 3198.2643991881764, 3259.6251431085493, 3262.9347413051605, 3280.9343813123605, 3386.5936087669393, 384725.0, 386600.0, 387096.9999999972, 387861.0, 388720.0, 60587.39412605906, 60721.39278607157, 60814.695926520086, 60816.69591652013, 60936.0, 61948.38051619426, 62945.370546294864, 62985.74028519496, 63057.73884522284, 63085.05372419417, 15998.9200053999, 16104.919475402548, 16303.0, 16345.509634710977, 16366.345346186257, 11461.426928653535, 11634.883651163549, 12407.627771166879, 12458.937705311597, 12735.044871634664, 18708.719369209473, 19003.334883279018, 19021.0, 19037.0, 19040.904795475933, 7107.538010029331, 7692.192349792285, 7876.448648594544, 7982.9600851995365, 8072.475289106307, 869.1829680100685, 923.3628796130782, 931.9207867331353, 983.3362480325751, 1042.519622136568, 29663.96176133868, 29779.0, 30150.547741783892, 30558.69441305603, 30740.846295768824, 1052.1740433759462, 1057.6615483045352, 1087.1628845788698, 1089.3137323486312, 1135.2223726747204, 15462.690746185013, 15495.767563486557, 15623.531294061197, 15636.609084772972, 15855.048697078213, 653677.0, 664131.0, 666451.0, 668400.6579967069, 669358.0, 57652.0, 57989.81056041958, 58015.41984580184, 58056.548586284836, 58077.70961145252, 1218.7440637466054, 1266.341502418737, 1346.9528566500126, 1374.924379159136, 1381.8687224713635, 25886.22341329763, 26982.0, 28831.99088031909, 31233.687663123077, 32104.43164546784, 49097.281595144, 49707.77483913908, 49713.502864970884, 49752.25371619356, 49856.501434985184, 504316.0, 505597.0, 510366.0, 511707.0, 512449.0, 26499.735002649726, 27172.0, 27181.0, 27966.0, 28053.316800992005, 46518.53481465142, 46519.53480465152, 46760.53239467562, 47067.0, 48225.79435514, 38711.03222419462, 38927.41608875926, 39028.804855976676, 39568.60431395649, 39790.60209397869, 38240.0, 38636.03409914775, 38859.71418189474, 39609.60390396059, 39721.40417893676, 31995.68004319927, 36577.63422365742, 38437.0, 38551.807240963615, 38569.807150964065, 33393.0, 34131.48802767911, 34635.13412164716, 34644.133896652784, 34778.652213477544, 23088.769112308662, 25165.0, 25504.234872953843, 25669.871650641628, 25724.614130788428], "value": 20248.521992011854, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.2115301148921, 370.4128955605365, 372.21276999146806, 379.1885365318218, 402.44060755549793, 13174.802377964326, 15527.913046086778, 16481.50555483336, 20577.27979520717, 21299.57400851986, 19413.514662133417, 20011.699824502644, 20489.0, 20640.967951602397, 20826.271080512186, 5111.08000559899, 5776.855578610537, 6042.425969532887, 6043.788467403641, 6044.455998960093, 5381.596380271495, 5906.0, 5999.100134979766, 6004.219451471303, 6008.158857759917, 5643.68959707216, 5824.330202026757, 5936.614120082181, 5937.495312898409, 5954.106883967418, 5818.301803783552, 5872.265966754156, 5879.412058794129, 5881.323647780475, 5886.323072846634, 5122.564582010534, 5125.538701516882, 5183.870403239949, 5413.945860541384, 5486.149646804712, 12911.418986145645, 12992.025598080087, 13124.409401576951, 13143.60569182926, 13148.342582870913, 2098.6642137257963, 2249.538844536882, 2339.4736184358553, 2476.0343466048257, 2893.86977586007, 9696.75758106046, 9698.36960597559, 9713.805723885553, 10235.641752538626, 10261.692149235534, 1485.2796393749022, 1860.4418674397762, 1870.3079860451585, 1871.9064046797605, 1917.7411049508416, 110651.89348106497, 114382.99999999917, 114936.0, 122808.38595806964, 123919.0, 10939.507722152523, 11093.500792464358, 11363.602273920373, 11386.94306528462, 11597.76804463906, 14355.0, 14406.0, 14411.927940360441, 14419.279036048156, 14432.42270309176, 7057.329553692391, 8174.141715119946, 8381.580920953927, 8409.495430274203, 8533.615987280587, 8324.875126873101, 8326.209010144026, 8356.039055508572, 8482.660693572188, 8532.23209911111, 3680.245549662313, 3718.925621487609, 4422.977885110554, 4463.085067561142, 4576.016156526296, 1731.1430841733422, 1783.0995347349506, 1824.1758241758241, 1825.7949753162848, 1858.5911099558075, 867.7873920889297, 893.9910600894038, 903.6159632156307, 946.1437399153776, 954.61815273891, 20989.265375712082, 21271.72484117189, 22380.216692415688, 22568.210112646306, 22764.634121952735, 850.3197442046338, 883.9513826739591, 969.9515024248759, 971.5919313888043, 1035.440861934562, 10497.842532362021, 10721.517531711092, 10908.181886358554, 11328.603498877499, 11340.829887551694, 496453.0, 503522.0, 503525.0, 513096.0, 609097.0, 107214.46392768143, 111486.0, 123332.0, 134031.32984335016, 136772.0, 971.5288085278635, 996.9750756231007, 1014.9086582207636, 1017.5166795772097, 1017.5703137092397, 18534.351297704514, 19423.3201837935, 19436.70844937299, 19507.60984780268, 19518.804811951697, 44667.0, 53909.0, 54344.0, 54472.0, 55073.0, 77356.0, 80050.59974700089, 80463.19536804558, 81093.0, 81174.0, 6926.307369263033, 7976.846251511944, 8018.278354947849, 8035.638396272298, 8225.095239523527, 10418.635347763095, 12731.299778512272, 13060.673483162996, 13153.671158221121, 13154.868451315364, 10855.620053298097, 10898.891011090105, 11159.609413670483, 11345.319280843176, 11482.483288252215, 10713.839292410465, 11097.778044439228, 11284.32294062359, 11355.943220283845, 11379.943100284445, 10215.284930054824, 10612.612162263738, 11132.88867111351, 11544.88455115438, 11691.415429228335, 6660.83347916306, 6958.512904096665, 7100.0, 7246.96376518114, 7363.410927125924, 9320.766980825534, 10874.347539147677, 11182.105431565293, 11235.88764112381, 11390.886091138982], "value": 9203.615124750744, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1530.8086489188854, 1552.037736603306, 1768.5932235585815, 1904.2763749775083, 1915.8850468971855, 8406.537640429773, 8890.86663700045, 8897.15477029682, 8908.930928288615, 8910.198082172612, 7741.458097933147, 8048.993875765529, 8051.718189863342, 8422.663093476269, 8674.26268767154, 2345.1557439321873, 2380.0836677879056, 2380.2621187431864, 2380.976190238093, 2386.486905315357, 2341.566810140121, 2342.203650758742, 2376.1327115602767, 2377.5720370333333, 2383.8688872112025, 2349.3186975777003, 2358.174638876397, 2374.9287521374386, 2375.1449478187837, 2375.5367703297866, 1958.4712127725577, 1969.6454638165123, 2203.3389983005036, 2329.301209637111, 2336.4859730859184, 2173.434906924211, 2347.2371479269136, 2349.2952114365794, 2353.5881220786464, 2359.0210062823908, 8797.29621630268, 8803.867941980876, 8883.600237989305, 8907.10928907104, 8934.151255630704, 5143.3570803649545, 5302.337207849019, 5309.707966061865, 5317.574594032468, 5318.335208098988, 3411.0, 3487.9651203487892, 3568.8572457101686, 3614.981925090384, 3689.7232707546773, 1041.5279688725198, 1078.14826287233, 1078.2560033576806, 1096.6929259807266, 1098.9175811814066, 11471.311721296666, 11520.193586448951, 11543.94228028863, 11577.594784182598, 11601.825972610503, 4463.598276155161, 4535.727856328631, 4712.31671407644, 4724.834630787906, 4744.952550474451, 9786.0, 9786.608535658635, 9787.168090712226, 9795.412275263507, 9805.950970245103, 5295.470452954673, 5301.3373328333955, 5301.602379821452, 5305.4164041955355, 5307.28351672527, 4356.28121359972, 4392.538783427758, 4524.23088075028, 4685.672002959828, 4880.829170978998, 3762.454444105644, 3770.792606406674, 3917.6278253565865, 3919.3729003359326, 3952.5256969163884, 1257.264500267354, 1278.1244847279643, 1281.6667666406615, 1284.653143651209, 1290.1549485087282, 892.9910700893038, 911.3074063711626, 922.1930810540777, 939.4081728511131, 939.7697564096703, 5681.0, 5898.640419556017, 5913.733881975321, 5968.279845126912, 6070.662794824025, 416.9291220492523, 421.5320993696994, 432.08613781851784, 444.9132419178294, 448.25365765999896, 3924.7252692311263, 3996.9200615987515, 4010.0150425539546, 4012.077222238843, 4014.4781178446615, 10280.126189273846, 10295.0, 10677.850034239918, 10682.0, 10717.678469645924, 8459.407841451162, 10633.202509811796, 10637.521311540999, 10638.73403164927, 10694.786104278026, 7689.307962283421, 7979.521228726179, 8151.673933042612, 8157.347412207009, 8198.59007049657, 5895.823125306248, 5957.702114894325, 5985.850353741192, 6036.33600303975, 6071.453569178795, 9688.70933871985, 10013.39919604826, 10044.196464282693, 10049.899501004897, 10103.24225683062, 9715.0, 10138.442385668859, 10253.436061016715, 10316.43259620728, 10327.741806454898, 6878.69045892946, 7122.786316410516, 7474.514156579805, 7610.9238907612435, 7626.54240745557, 7837.098733645704, 8011.078725946591, 8185.836283274421, 8311.168883111883, 8403.957980210304, 7384.630768461663, 7451.3666338361845, 7461.738839140604, 7535.058117735283, 7624.085109786861, 7104.715811367488, 7250.74622388235, 7263.382612477999, 7333.1200255969625, 7361.705531778883, 6872.278410766798, 6873.1064961554675, 6972.965135174291, 6982.930170698228, 6994.650267486503, 4846.667166529232, 4903.656744028027, 4921.50784921505, 4958.231474121517, 5019.899602008012, 4998.4251811042195, 5018.247262910592, 5098.2862399263395, 5147.0, 5169.508896654812], "value": 4472.033382804739, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [264.1612879108829, 300.1999670877113, 308.53103283009824, 313.140429520965, 316.7323611548241, 5132.381670922, 5503.20203570483, 5557.416471270519, 5566.582506312033, 5577.8047768328115, 4937.851864444064, 5174.197999310103, 5352.608321836321, 5403.891922161554, 5488.451154884509, 3978.641922226999, 4156.376543518481, 4188.518320393156, 4188.958110418903, 4193.014641559231, 3576.5886923003795, 4109.157622687357, 4175.665946724254, 4216.810243539048, 4249.660027197817, 3300.2409445827475, 3986.2625414298304, 4091.8158682859194, 4145.647619952308, 4150.3566947123245, 3638.854445822164, 4113.958860411388, 4120.793960301973, 4129.306984136505, 4140.751554906685, 3263.543103965447, 3391.4404123319623, 3584.426491761332, 3585.5697316322207, 3603.4234522476277, 4176.812043458021, 4783.617310615143, 5275.525202731734, 5275.920861187047, 5309.3894202166475, 4727.312696097049, 4765.880852978668, 4799.832005879777, 4799.872030072965, 4820.927686084711, 5735.0, 5771.220885180489, 5778.219940308089, 5814.575429019875, 5815.476607105381, 3631.4371272452813, 3875.5155605549307, 4013.1171142348635, 4023.0, 4034.7579145251084, 5272.288241087443, 5599.236240584244, 5999.340072592011, 6051.788187413419, 6106.786262480792, 3629.491871138043, 3695.519582454264, 3974.3044967130636, 3977.985613668496, 3978.9654689780286, 3863.942040869389, 4011.8796436106963, 4025.376066709665, 4030.838766449368, 4036.192761447722, 4144.3990621359735, 4638.121560767894, 4668.136394766979, 4684.929726054112, 4693.718376897397, 4623.352730617717, 5170.3278573785165, 5398.541124004425, 5616.66300022, 5841.620294680833, 1197.0363857095122, 1285.0875878126449, 1322.98130439561, 1366.9179849209079, 1371.1087792934673, 659.6932426421737, 747.9813004674927, 809.7692157735017, 833.8082241084463, 843.5196230615231, 4061.3704875744306, 4348.412829317276, 4546.886327841764, 4594.379758732595, 4635.837745678885, 4355.956440435619, 4778.546038126442, 4941.258811178352, 4951.356323677971, 5044.319016932741, 3793.563740169916, 4035.3139966205226, 4291.592593332423, 4424.062584299975, 4504.909901801946, 2528.9241322760345, 2618.245775330533, 2635.3806855388966, 2648.973510264911, 2649.403884126095, 4445.822167113344, 4722.748471655014, 5090.182363227579, 5099.77051032697, 5150.845474635767, 4137.809429581804, 4380.474343078788, 5021.899562008739, 5080.49195080489, 5087.389513258433, 5084.661055914321, 5093.312402825572, 5120.897582048338, 5128.717920514333, 5159.194282001269, 4069.758723589334, 4403.071957148461, 4434.534373890824, 4749.862561847113, 4926.8768280793265, 3883.990162557699, 4403.691741578059, 4904.400667646153, 4944.206523314316, 4944.431390390151, 4456.844010459618, 4530.731395209346, 4766.594839438548, 4985.850424487271, 5113.488651134857, 3375.4599264117624, 3394.507796369562, 3397.2865698203655, 3413.2661477782394, 3435.209901722568, 4981.825636102719, 5004.849854504225, 5017.799288028438, 5039.974800125976, 5579.581531385081, 4990.975045124751, 5828.562857785599, 5832.279477554047, 5849.894038146179, 5864.44287792659, 5823.272090988627, 5851.736671849691, 5852.0, 5879.794207202727, 5892.94107058924, 4721.598664113589, 5450.945490545044, 5531.8893622128135, 5692.857678558069, 5706.714664266687, 5650.434956504317, 5712.742926568398, 5725.770969161187, 5793.768249269982, 5942.851428714143, 4854.975725121351, 5210.27056212123, 5682.931217142862, 5788.334341550775, 5805.709714514342], "value": 3936.5872048612796, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [716.1765509294898, 727.4871215792863, 779.6842278877054, 782.6086956521739, 796.1202870827739, 12489.812652810204, 15826.604334891634, 15908.84091159088, 15965.281562329694, 15965.600859978478, 14356.856431435683, 14420.278986050707, 14434.20611866347, 14477.348519316629, 14479.782803257933, 677.2888467109515, 694.1704662927804, 697.4943166204512, 841.5035129273728, 843.6035063520136, 854.0520022774715, 856.87146927961, 864.3171894203572, 865.1824026295153, 866.9436486628381, 931.9347645664806, 932.3007744191856, 932.6968735161066, 933.5752232734089, 934.7055677461611, 917.8347897378469, 920.9815803683922, 922.395830730869, 923.072312326108, 926.7266156483853, 533.1975377057543, 547.3787251469571, 659.6602749583968, 669.8760729265101, 683.2962049089446, 15111.093334399971, 15121.243937803067, 15135.470258540898, 15153.924230378778, 15230.0, 13726.519571814939, 13899.444022239099, 14132.50536231227, 14191.148531088167, 14518.128912265192, 11828.704282392922, 12249.326287054208, 12619.990400767916, 12641.0, 12879.291638959854, 3787.3561494545993, 3910.7262491625597, 4462.0, 4513.7743112844555, 4531.818727250906, 117134.0, 117391.0, 118233.0, 118704.40647796792, 118872.0, 10761.139108871272, 11256.549738010552, 11299.661010169708, 11662.708432289091, 11740.58907938218, 22505.987230574337, 22611.321660350215, 22629.321120366414, 22633.886830566073, 22771.203007894645, 8081.0, 8095.83808323842, 8124.065732440845, 8165.918340816635, 8270.917290827014, 4525.5700708433305, 4539.364488971547, 4614.976925115353, 4697.671163018625, 4739.620830333565, 3518.138056176202, 3641.8543258269374, 3642.162302670348, 3646.2889736501656, 3650.799206043693, 652.7943697735199, 654.0875478707177, 657.1260223902199, 674.8920172772431, 684.5447777228087, 489.82349463727695, 522.4096770649143, 607.7963882099547, 619.4301242856583, 625.7997440818986, 8871.955640221757, 9121.0, 9318.0, 9343.392679475812, 9344.859827102599, 516.3210378352454, 591.1192323438046, 595.3897255313252, 625.0717684238992, 633.2749002392235, 4461.420015397977, 4524.9547504524535, 4528.298113792367, 4552.682315994509, 4582.783435842566, 139498.0, 158487.0, 160314.1984290071, 160382.0, 164319.0, 118725.0, 129984.05023924648, 141019.29490352687, 141245.0, 142319.0, 86209.56895215483, 110214.0, 111946.0, 113139.0, 115243.84756152499, 8235.29999950011, 8596.269317108116, 8609.526476043628, 8843.380963332505, 8937.329700272376, 60491.0, 61317.386826132955, 62285.68857155685, 62406.687966559875, 62614.6869265669, 82139.0, 84881.7267740972, 89838.75403114974, 91335.54332228296, 99523.0, 9202.953985230031, 11487.712807179887, 12883.871161288642, 13015.0, 13958.58124256274, 12950.287734174712, 15094.698106038037, 15322.387104515694, 15338.693226135638, 15440.30518626642, 11425.771484570429, 13745.0, 13791.724165516433, 13990.650233744238, 14642.926785366006, 13748.450061997408, 13884.930575347058, 13935.30323483842, 14193.0, 14200.857991419953, 13414.731705365642, 13673.111247768864, 13784.0, 14156.78764818508, 14203.715925681221, 9737.610495580097, 11260.94369528147, 11415.942920285346, 11581.420928953687, 11702.531898723955, 10001.949990250003, 11435.428228588704, 11548.380981836612, 11618.419079045845, 11700.70748231267], "value": 7848.126618843388, "unit": "score"}], "name": "dromaeo_css", "value": 7261.082267922877, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 122.0, 163.0, 147.0, 111.0, 112.0, 112.0, 113.0, 113.0], "value": 121.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 110.0, 109.0, 160.0, 105.0, 131.0, 90.0, 128.0, 175.0, 140.0], "value": 129.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 181.0, 184.0, 198.0, 261.0, 168.0, 249.0, 179.0, 184.0, 181.0], "value": 196.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 60.0, 89.0, 62.0, 62.0, 60.0, 58.0, 80.0, 58.0], "value": 66.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 70.0, 92.0, 70.0, 70.0, 92.0, 125.0, 96.0, 69.0, 90.0], "value": 86.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 96.0, 115.0, 85.0, 88.0, 85.0, 88.0, 84.0], "value": 90.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 93.0, 94.0, 94.0, 95.0, 93.0, 105.0], "value": 95.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 86.0, 88.0, 84.0, 85.0, 87.0, 85.0, 96.0, 132.0], "value": 91.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 55.0, 54.0, 57.0, 54.0, 54.0, 55.0, 55.0, 55.0], "value": 54.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 73.0, 70.0, 76.0, 73.0, 70.0, 71.0, 70.0, 70.0, 70.0], "value": 70.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 89.0, 85.0, 87.0, 85.0, 85.0, 87.0, 85.0, 92.0, 104.0], "value": 88.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 128.0, 148.0, 154.0, 143.0, 150.0, 154.0, 155.0, 229.0, 137.0], "value": 155.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 169.0, 167.0, 166.0, 169.0, 166.0, 219.0, 178.0, 200.0, 200.0], "value": 180.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 68.0, 67.0, 68.0, 66.0, 67.0, 69.0, 67.0, 65.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1495.5000000000002, "alertThreshold": 2.0}]} 17:53:46 INFO - Return code: 0 17:53:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:53:46 INFO - # TBPL SUCCESS # 17:53:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:53:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:53:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:53:46 INFO - 2016-09-28 17:53:46.733 screenresolution[1947:15394] starting screenresolution argv=screenresolution get 17:53:46 INFO - 2016-09-28 17:53:46.736 screenresolution[1947:15394] Display 0: 1600x1200x32@60 17:53:46 INFO - 2016-09-28 17:53:46.742 screenresolution[1948:15397] starting screenresolution argv=screenresolution list 17:53:46 INFO - Available Modes on Display 0 17:53:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:53:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:53:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:53:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:53:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:53:46 INFO - Intel Iris: 17:53:46 INFO - Chipset Model: Intel Iris 17:53:46 INFO - Type: GPU 17:53:46 INFO - Bus: Built-In 17:53:46 INFO - VRAM (Dynamic, Max): 1536 MB 17:53:46 INFO - Vendor: Intel (0x8086) 17:53:46 INFO - Device ID: 0x0a2e 17:53:46 INFO - Revision ID: 0x0009 17:53:46 INFO - Displays: 17:53:46 INFO - Display: 17:53:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:53:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:53:46 INFO - Display Serial Number: 1600x1200 60 17:53:46 INFO - Main Display: Yes 17:53:46 INFO - Mirror: Off 17:53:46 INFO - Online: Yes 17:53:46 INFO - Rotation: Supported 17:53:46 INFO - Return code: 0 17:53:46 INFO - Running post-action listener: _resource_record_post_action 17:53:46 INFO - [mozharness: 2016-09-29 00:53:46.985064Z] Finished run-tests step (success) 17:53:46 INFO - Running post-run listener: _resource_record_post_run 17:53:47 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 765952; Write bytes: 703430656; Read time: 9; Write time: 1896 17:53:47 INFO - TinderboxPrint: CPU usage
53.7% 17:53:47 INFO - TinderboxPrint: I/O read bytes / time
765,952 / 9 17:53:47 INFO - TinderboxPrint: I/O write bytes / time
703,430,656 / 1,896 17:53:47 INFO - TinderboxPrint: CPU idle
2,243.9 (46.3%) 17:53:47 INFO - TinderboxPrint: CPU system
969.8 (20.0%) 17:53:47 INFO - TinderboxPrint: CPU user
1,635.9 (33.7%) 17:53:47 INFO - TinderboxPrint: Swap in / out
408,342,528 / 0 17:53:47 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 391266816; Write bytes: 386639360; Read time: 29283; Write time: 750 17:53:47 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 765952; Write bytes: 292534784; Read time: 9; Write time: 1088 17:53:47 INFO - Running post-run listener: _upload_blobber_files 17:53:47 INFO - Blob upload gear active. 17:53:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:53:47 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 17:53:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:53:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:53:47 INFO - (blobuploader) - INFO - Open directory for files ... 17:53:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:53:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:53:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:53:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:53:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:53:48 INFO - (blobuploader) - INFO - Done attempting. 17:53:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:53:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:53:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:53:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:53:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:53:49 INFO - (blobuploader) - INFO - Done attempting. 17:53:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:53:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:53:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:53:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:53:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:53:51 INFO - (blobuploader) - INFO - Done attempting. 17:53:51 INFO - (blobuploader) - INFO - Iteration through files over. 17:53:51 INFO - Return code: 0 17:53:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:53:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:53:51 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8056127c30786fb6c736200f7504eaa612ce1af978a87ca3fd943e99800d5c67763d5d13df9c03e2351a36cc95e70e5213d9b08cc18946f8681dc13882e6c6e9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/aef0d1bf2b5cbcdac674d8d20a4e712515a6601b5b66054a1bc2813cd9efca6542e2a440c1e1244fe60caa1e5caea630323617ac1f790595bda7db0c1906a36d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5962859d32eb23956f1c61c14141affb6b3e664d8873452e2e30ab50edaf3b38aa73d413ca77efa3addcb5b99539eedf340444183b8720b962ca3339a762297a"} 17:53:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:53:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:53:51 INFO - Contents: 17:53:51 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8056127c30786fb6c736200f7504eaa612ce1af978a87ca3fd943e99800d5c67763d5d13df9c03e2351a36cc95e70e5213d9b08cc18946f8681dc13882e6c6e9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/aef0d1bf2b5cbcdac674d8d20a4e712515a6601b5b66054a1bc2813cd9efca6542e2a440c1e1244fe60caa1e5caea630323617ac1f790595bda7db0c1906a36d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5962859d32eb23956f1c61c14141affb6b3e664d8873452e2e30ab50edaf3b38aa73d413ca77efa3addcb5b99539eedf340444183b8720b962ca3339a762297a"} 17:53:51 INFO - Running post-run listener: copy_logs_to_upload_dir 17:53:51 INFO - Copying logs to upload dir... 17:53:51 INFO - mkdir: /builds/slave/test/build/upload/logs 17:53:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.415476 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-09-28 17:53:51.918423) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-28 17:53:51.922414) ========= 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.3cd8s1dQZr/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.mKaAI98gkB/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8056127c30786fb6c736200f7504eaa612ce1af978a87ca3fd943e99800d5c67763d5d13df9c03e2351a36cc95e70e5213d9b08cc18946f8681dc13882e6c6e9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/aef0d1bf2b5cbcdac674d8d20a4e712515a6601b5b66054a1bc2813cd9efca6542e2a440c1e1244fe60caa1e5caea630323617ac1f790595bda7db0c1906a36d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5962859d32eb23956f1c61c14141affb6b3e664d8873452e2e30ab50edaf3b38aa73d413ca77efa3addcb5b99539eedf340444183b8720b962ca3339a762297a"} build_url:https://queue.taskcluster.net/v1/task/HvFaEWqHS_WwFtLBw-HF0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011108 build_url: 'https://queue.taskcluster.net/v1/task/HvFaEWqHS_WwFtLBw-HF0Q/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8056127c30786fb6c736200f7504eaa612ce1af978a87ca3fd943e99800d5c67763d5d13df9c03e2351a36cc95e70e5213d9b08cc18946f8681dc13882e6c6e9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/aef0d1bf2b5cbcdac674d8d20a4e712515a6601b5b66054a1bc2813cd9efca6542e2a440c1e1244fe60caa1e5caea630323617ac1f790595bda7db0c1906a36d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5962859d32eb23956f1c61c14141affb6b3e664d8873452e2e30ab50edaf3b38aa73d413ca77efa3addcb5b99539eedf340444183b8720b962ca3339a762297a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-28 17:53:51.956286) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 17:53:51.956708) ========= 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.3cd8s1dQZr/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.mKaAI98gkB/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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.005023 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 17:53:52.008387) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-28 17:53:52.009307) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-28 17:53:52.010003) ========= ========= Total master_lag: 0.19 =========