builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0127 starttime: 1475179561.8 results: success (0) buildid: 20160929110720 builduid: 6da30fe286554d119d8c1b974f87d66b revision: 4b69e32e3a837f70fcfb9c8a8b2c8b4af4a280de ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-29 13:06:01.803535) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-29 13:06:01.803998) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-29 13:06:01.804304) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-29 13:06:01.829385) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 13:06:01.829870) ========= 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.IORhYYDkcy/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.fO6Fo21tn1/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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-29 13:06:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.07M=0.001s 2016-09-29 13:06:01 (8.07 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.113029 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 13:06:01.966487) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 13:06:01.966852) ========= 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.IORhYYDkcy/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.fO6Fo21tn1/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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.021735 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 13:06:02.019699) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-29 13:06:02.020391) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 4b69e32e3a837f70fcfb9c8a8b2c8b4af4a280de --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 4b69e32e3a837f70fcfb9c8a8b2c8b4af4a280de --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.IORhYYDkcy/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.fO6Fo21tn1/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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-29 13:06:02,090 truncating revision to first 12 chars 2016-09-29 13:06:02,090 Setting DEBUG logging. 2016-09-29 13:06:02,090 attempt 1/10 2016-09-29 13:06:02,090 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/4b69e32e3a83?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-29 13:06:03,632 unpacking tar archive at: autoland-4b69e32e3a83/testing/mozharness/ program finished with exit code 0 elapsedTime=1.789361 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-29 13:06:03.835201) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-29 13:06:03.835798) ========= script_repo_revision: 4b69e32e3a837f70fcfb9c8a8b2c8b4af4a280de ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-29 13:06:03.836467) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-29 13:06:03.836971) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-29 13:06:03.857219) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-09-29 13:06:03.857885) ========= /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.IORhYYDkcy/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.fO6Fo21tn1/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 13:06:04 INFO - MultiFileLogger online at 20160929 13:06:04 in /builds/slave/test 13:06:04 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 13:06:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:06:04 INFO - {'append_to_log': False, 13:06:04 INFO - 'base_work_dir': '/builds/slave/test', 13:06:04 INFO - 'blob_upload_branch': 'Autoland', 13:06:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:06:04 INFO - 'branch': 'Autoland', 13:06:04 INFO - 'buildbot_json_path': 'buildprops.json', 13:06:04 INFO - 'config_files': ('talos/mac_config.py',), 13:06:04 INFO - 'default_actions': ('clobber', 13:06:04 INFO - 'read-buildbot-config', 13:06:04 INFO - 'download-and-extract', 13:06:04 INFO - 'populate-webroot', 13:06:04 INFO - 'create-virtualenv', 13:06:04 INFO - 'install', 13:06:04 INFO - 'run-tests'), 13:06:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:06:04 INFO - 'download_minidump_stackwalk': True, 13:06:04 INFO - 'download_symbols': 'ondemand', 13:06:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:06:04 INFO - 'tooltool.py': '/tools/tooltool.py', 13:06:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:06:04 INFO - '/tools/misc-python/virtualenv.py')}, 13:06:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:06:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:06:04 INFO - 'installer_path': 'installer.exe', 13:06:04 INFO - 'log_level': 'info', 13:06:04 INFO - 'log_name': 'talos', 13:06:04 INFO - 'log_to_console': True, 13:06:04 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:06:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:06:04 INFO - 'opt_config_files': (), 13:06:04 INFO - 'pip_index': False, 13:06:04 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:06:04 INFO - 'cmd': ('bash', 13:06:04 INFO - '-c', 13:06:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:06:04 INFO - 'enabled': True, 13:06:04 INFO - 'halt_on_failure': False, 13:06:04 INFO - 'name': 'check_screen_resolution'},), 13:06:04 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:06:04 INFO - 'cmd': ('bash', 13:06:04 INFO - '-c', 13:06:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:06:04 INFO - 'enabled': True, 13:06:04 INFO - 'halt_on_failure': False, 13:06:04 INFO - 'name': 'check_screen_resolution'},), 13:06:04 INFO - 'run_cmd_checks_enabled': True, 13:06:04 INFO - 'sps_profile': False, 13:06:04 INFO - 'sps_profile_interval': 0, 13:06:04 INFO - 'suite': 'dromaeojs', 13:06:04 INFO - 'system_bits': '32', 13:06:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:06:04 INFO - 'title': 't-yosemite-r7-0127', 13:06:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:06:04 INFO - 'use_talos_json': True, 13:06:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:06:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:06:04 INFO - 'work_dir': 'build'} 13:06:04 INFO - [mozharness: 2016-09-29 20:06:04.057338Z] Running clobber step. 13:06:04 INFO - Running pre-action listener: _resource_record_pre_action 13:06:04 INFO - Running main action method: clobber 13:06:04 INFO - rmtree: /builds/slave/test/build 13:06:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:06:05 INFO - Running post-action listener: _resource_record_post_action 13:06:05 INFO - [mozharness: 2016-09-29 20:06:05.557707Z] Finished clobber step (success) 13:06:05 INFO - [mozharness: 2016-09-29 20:06:05.557934Z] Running read-buildbot-config step. 13:06:05 INFO - Running pre-action listener: _resource_record_pre_action 13:06:05 INFO - Running main action method: read_buildbot_config 13:06:05 INFO - Using buildbot properties: 13:06:05 INFO - { 13:06:05 INFO - "project": "", 13:06:05 INFO - "product": "firefox", 13:06:05 INFO - "script_repo_revision": "production", 13:06:05 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 13:06:05 INFO - "repository": "", 13:06:05 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 13:06:05 INFO - "buildid": "20160929110720", 13:06:05 INFO - "pgo_build": "False", 13:06:05 INFO - "basedir": "/builds/slave/test", 13:06:05 INFO - "buildnumber": 204, 13:06:05 INFO - "slavename": "t-yosemite-r7-0127", 13:06:05 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 13:06:05 INFO - "platform": "yosemite_r7", 13:06:05 INFO - "scheduler": "tests-autoland-macosx64-talos", 13:06:05 INFO - "branch": "autoland", 13:06:05 INFO - "repo_path": "integration/autoland", 13:06:05 INFO - "revision": "4b69e32e3a837f70fcfb9c8a8b2c8b4af4a280de", 13:06:05 INFO - "stage_platform": "macosx64", 13:06:05 INFO - "builduid": "6da30fe286554d119d8c1b974f87d66b", 13:06:05 INFO - "slavebuilddir": "test" 13:06:05 INFO - } 13:06:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/Fd-B-8j9RTmdBhRyqqyY5w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 13:06:05 INFO - Running post-action listener: _resource_record_post_action 13:06:05 INFO - [mozharness: 2016-09-29 20:06:05.561655Z] Finished read-buildbot-config step (success) 13:06:05 INFO - [mozharness: 2016-09-29 20:06:05.561816Z] Running download-and-extract step. 13:06:05 INFO - Running pre-action listener: _resource_record_pre_action 13:06:05 INFO - Running main action method: download_and_extract 13:06:05 INFO - mkdir: /builds/slave/test/build/tests 13:06:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:06:05 INFO - trying https://queue.taskcluster.net/v1/task/Fd-B-8j9RTmdBhRyqqyY5w/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 13:06:05 INFO - Downloading https://queue.taskcluster.net/v1/task/Fd-B-8j9RTmdBhRyqqyY5w/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 13:06:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Fd-B-8j9RTmdBhRyqqyY5w/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 13:06:07 INFO - Downloaded 1199 bytes. 13:06:07 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 13:06:07 INFO - Using the following test package requirements: 13:06:07 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 13:06:07 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:06:07 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 13:06:07 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:06:07 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 13:06:07 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:06:07 INFO - u'jsshell-mac.zip'], 13:06:07 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:06:07 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 13:06:07 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 13:06:07 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:06:07 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 13:06:07 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:06:07 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 13:06:07 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:06:07 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 13:06:07 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 13:06:07 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 13:06:07 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 13:06:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Fd-B-8j9RTmdBhRyqqyY5w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 13:06:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Fd-B-8j9RTmdBhRyqqyY5w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:06:07 INFO - Fetch https://queue.taskcluster.net/v1/task/Fd-B-8j9RTmdBhRyqqyY5w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 13:06:07 INFO - Http code: 200 13:06:07 INFO - Content-Encoding: None 13:06:07 INFO - Content-Type: application/zip 13:06:07 INFO - via: 1.1 5c2ff4ca1e447265402af29264e83497.cloudfront.net (CloudFront) 13:06:07 INFO - x-amz-cf-id: oIb8-doJ__S_XVLGxBUhO31Mlwn3KbgQtnknIaEdU1rgFgy9phK42Q== 13:06:07 INFO - x-amz-version-id: sfTTr_0IbvlaKeSK9OvwZLANxt9uWWfW 13:06:07 INFO - x-cache: Hit from cloudfront 13:06:07 INFO - Expected file size: 16473305 13:06:07 INFO - Obtained file size: 16473305 13:06:14 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 13:06:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Fd-B-8j9RTmdBhRyqqyY5w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 13:06:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Fd-B-8j9RTmdBhRyqqyY5w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:06:14 INFO - Fetch https://queue.taskcluster.net/v1/task/Fd-B-8j9RTmdBhRyqqyY5w/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 13:06:15 INFO - Http code: 200 13:06:15 INFO - Content-Encoding: None 13:06:15 INFO - Content-Type: application/zip 13:06:15 INFO - via: 1.1 455cf6ccbccc261c46d02110de1c0237.cloudfront.net (CloudFront) 13:06:15 INFO - x-amz-cf-id: tSvYD5lrO7dEN70Q9Cspd0ies_34cgDP496HjpxfpBE7EpN6xHcK3A== 13:06:15 INFO - x-amz-version-id: sfTTr_0IbvlaKeSK9OvwZLANxt9uWWfW 13:06:15 INFO - x-cache: Hit from cloudfront 13:06:15 INFO - Expected file size: 16473305 13:06:15 INFO - Obtained file size: 16473305 13:06:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Fd-B-8j9RTmdBhRyqqyY5w/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 13:06:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Fd-B-8j9RTmdBhRyqqyY5w/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 13:06:21 INFO - Fetch https://queue.taskcluster.net/v1/task/Fd-B-8j9RTmdBhRyqqyY5w/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 13:06:22 INFO - Http code: 200 13:06:22 INFO - Content-Encoding: None 13:06:22 INFO - Content-Type: application/zip 13:06:22 INFO - via: 1.1 66ed69e8b47ad05050331602c798132f.cloudfront.net (CloudFront) 13:06:22 INFO - x-amz-cf-id: hbuH3AGcFdGikV2txTOQhYC0tVsRahFhVUMpgi4aQ_CWQbQys7UVjQ== 13:06:22 INFO - x-amz-version-id: Xpth.e6CPFxfuy3qNhWZS3DR4HAPpU8R 13:06:22 INFO - x-cache: Hit from cloudfront 13:06:22 INFO - Expected file size: 14414067 13:06:22 INFO - Obtained file size: 14414067 13:06: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')]} 13:06:23 INFO - trying https://queue.taskcluster.net/v1/task/Fd-B-8j9RTmdBhRyqqyY5w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 13:06:23 INFO - Downloading https://queue.taskcluster.net/v1/task/Fd-B-8j9RTmdBhRyqqyY5w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 13:06:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Fd-B-8j9RTmdBhRyqqyY5w/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 13:06:26 INFO - Downloaded 111796407 bytes. 13:06:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Fd-B-8j9RTmdBhRyqqyY5w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 13:06:26 INFO - mkdir: /builds/slave/test/properties 13:06:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:06:26 INFO - Writing to file /builds/slave/test/properties/build_url 13:06:26 INFO - Contents: 13:06:26 INFO - build_url:https://queue.taskcluster.net/v1/task/Fd-B-8j9RTmdBhRyqqyY5w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 13:06:26 INFO - Running post-action listener: _resource_record_post_action 13:06:26 INFO - Running post-action listener: set_extra_try_arguments 13:06:26 INFO - [mozharness: 2016-09-29 20:06:26.822714Z] Finished download-and-extract step (success) 13:06:26 INFO - [mozharness: 2016-09-29 20:06:26.822821Z] Running populate-webroot step. 13:06:26 INFO - Running pre-action listener: _resource_record_pre_action 13:06:26 INFO - Running main action method: populate_webroot 13:06:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:06:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:06:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:06:26 INFO - '--tppagecycles', 13:06:26 INFO - '7'], 13:06:26 INFO - 'tests': ['tsvgm']}}, 13:06:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:06:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:06:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:06:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:06:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:06:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:06:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:06:26 INFO - 'talos_options': ['--disable-e10s'], 13:06:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:06:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:06:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:06:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:06:26 INFO - 'talos_options': ['--disable-e10s'], 13:06:26 INFO - 'tests': ['damp', 'tps']}, 13:06:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:06:26 INFO - 'tests': ['damp', 'tps']}, 13:06:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:06:26 INFO - 'tests': ['dromaeo_dom']}, 13:06:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:06:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:06:26 INFO - 'tests': ['basic_compositor_video']}, 13:06:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:06:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:06:26 INFO - 'tests': ['a11yr', 13:06:26 INFO - 'ts_paint', 13:06:26 INFO - 'tpaint', 13:06:26 INFO - 'sessionrestore', 13:06:26 INFO - 'sessionrestore_no_auto_restore', 13:06:26 INFO - 'tabpaint']}, 13:06:26 INFO - 'other-e10s': {'tests': ['a11yr', 13:06:26 INFO - 'ts_paint', 13:06:26 INFO - 'tpaint', 13:06:26 INFO - 'sessionrestore', 13:06:26 INFO - 'sessionrestore_no_auto_restore', 13:06:26 INFO - 'tabpaint']}, 13:06:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:06:26 INFO - 'ts_paint', 13:06:26 INFO - 'tpaint', 13:06:26 INFO - 'sessionrestore', 13:06:26 INFO - 'sessionrestore_no_auto_restore', 13:06:26 INFO - 'tabpaint']}, 13:06:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:06:26 INFO - 'ts_paint', 13:06:26 INFO - 'tpaint', 13:06:26 INFO - 'sessionrestore', 13:06:26 INFO - 'sessionrestore_no_auto_restore', 13:06:26 INFO - 'tabpaint']}, 13:06:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:06:26 INFO - 'tests': ['a11yr', 13:06:26 INFO - 'ts_paint', 13:06:26 INFO - 'tpaint', 13:06:26 INFO - 'sessionrestore', 13:06:26 INFO - 'sessionrestore_no_auto_restore', 13:06:26 INFO - 'tabpaint']}, 13:06:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:06:26 INFO - 'tests': ['a11yr', 13:06:26 INFO - 'ts_paint', 13:06:26 INFO - 'tpaint', 13:06:26 INFO - 'sessionrestore', 13:06:26 INFO - 'sessionrestore_no_auto_restore', 13:06:26 INFO - 'tabpaint']}, 13:06:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:06:26 INFO - 'tests': ['tsvgx', 13:06:26 INFO - 'tsvgr_opacity', 13:06:26 INFO - 'tart', 13:06:26 INFO - 'tscrollx', 13:06:26 INFO - 'cart']}, 13:06:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:06:26 INFO - 'tsvgr_opacity', 13:06:26 INFO - 'tart', 13:06:26 INFO - 'tscrollx', 13:06:26 INFO - 'cart']}, 13:06:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:06:26 INFO - 'talos_options': ['--disable-e10s'], 13:06:26 INFO - 'tests': ['tp5o']}, 13:06:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:06:26 INFO - 'tests': ['tp5o']}, 13:06:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:06:26 INFO - 'talos_options': ['--disable-e10s', 13:06:26 INFO - '--xperf_path', 13:06:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:06:26 INFO - 'tests': ['tp5n']}, 13:06:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:06:26 INFO - 'talos_options': ['--xperf_path', 13:06:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:06:26 INFO - 'tests': ['tp5n']}}, 13:06:26 INFO - 'talos.zip': {'path': '', 13:06:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:06:26 INFO - Running post-action listener: _resource_record_post_action 13:06:26 INFO - [mozharness: 2016-09-29 20:06:26.841992Z] Finished populate-webroot step (success) 13:06:26 INFO - [mozharness: 2016-09-29 20:06:26.842130Z] Running create-virtualenv step. 13:06:26 INFO - Running pre-action listener: _resource_record_pre_action 13:06:26 INFO - Running main action method: create_virtualenv 13:06:26 INFO - Creating virtualenv /builds/slave/test/build/venv 13:06:26 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 13:06:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:06:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:06:26 INFO - Using real prefix '/tools/python27' 13:06:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:06:27 INFO - Installing distribute.............................................................................................................................................................................................done. 13:06:30 INFO - Installing pip.................done. 13:06:30 INFO - Return code: 0 13:06:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:06:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:06:30 INFO - Reading from file tmpfile_stdout 13:06:30 INFO - Output received: 13:06:30 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) 13:06:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:06:30 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')]} 13:06:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:06:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:30 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')]} 13:06:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:06:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:30 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 0x102268490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021de570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff43b477350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10207cd30>, '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 0x101ea2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102074b20>, '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.IORhYYDkcy/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.fO6Fo21tn1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 13:06:30 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 13:06:30 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 13:06:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IORhYYDkcy/Render', 13:06:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:06:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:06:30 INFO - 'HOME': '/Users/cltbld', 13:06:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:06:30 INFO - 'LOGNAME': 'cltbld', 13:06:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:30 INFO - 'MOZ_NO_REMOTE': '1', 13:06:30 INFO - 'NO_EM_RESTART': '1', 13:06:30 INFO - 'PAGER': '/bin/cat', 13:06:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:06:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:30 INFO - 'PWD': '/builds/slave/test', 13:06:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:06:30 INFO - 'SHELL': '/bin/bash', 13:06:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fO6Fo21tn1/Listeners', 13:06:30 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 13:06:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:06:30 INFO - 'USER': 'cltbld', 13:06:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:06:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:06:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:30 INFO - 'XPC_FLAGS': '0x0', 13:06:30 INFO - 'XPC_SERVICE_NAME': '0', 13:06:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:06:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:06:31 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 13:06:31 INFO - Cleaning up... 13:06:31 INFO - Return code: 0 13:06:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:06:31 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')]} 13:06:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:06:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:31 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')]} 13:06:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:06:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:31 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 0x102268490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021de570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff43b477350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10207cd30>, '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 0x101ea2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102074b20>, '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.IORhYYDkcy/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.fO6Fo21tn1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 13:06:31 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 13:06:31 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 13:06:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IORhYYDkcy/Render', 13:06:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:06:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:06:31 INFO - 'HOME': '/Users/cltbld', 13:06:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:06:31 INFO - 'LOGNAME': 'cltbld', 13:06:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:31 INFO - 'MOZ_NO_REMOTE': '1', 13:06:31 INFO - 'NO_EM_RESTART': '1', 13:06:31 INFO - 'PAGER': '/bin/cat', 13:06:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:06:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:31 INFO - 'PWD': '/builds/slave/test', 13:06:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:06:31 INFO - 'SHELL': '/bin/bash', 13:06:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fO6Fo21tn1/Listeners', 13:06:31 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 13:06:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:06:31 INFO - 'USER': 'cltbld', 13:06:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:06:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:06:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:31 INFO - 'XPC_FLAGS': '0x0', 13:06:31 INFO - 'XPC_SERVICE_NAME': '0', 13:06:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:06:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:06:31 INFO - Downloading/unpacking psutil>=3.1.1 13:06:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:06:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:06:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:06:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:06:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:06:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:06:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:06:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:06:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:06:34 INFO - Installing collected packages: psutil 13:06:34 INFO - Running setup.py install for psutil 13:06:34 INFO - building 'psutil._psutil_osx' extension 13:06:34 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 13:06:35 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 13:06:35 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 13:06:35 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 13:06:35 INFO - building 'psutil._psutil_posix' extension 13:06:35 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 13:06:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:06:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:06:35 INFO - ^ 13:06:35 INFO - 1 warning generated. 13:06:35 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 13:06:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:06:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:06:35 INFO - Successfully installed psutil 13:06:35 INFO - Cleaning up... 13:06:35 INFO - Return code: 0 13:06:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:06:35 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')]} 13:06:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:06:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:35 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')]} 13:06:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:06:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:35 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 0x102268490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021de570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff43b477350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10207cd30>, '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 0x101ea2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102074b20>, '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.IORhYYDkcy/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.fO6Fo21tn1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 13:06:35 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 13:06:35 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 13:06:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IORhYYDkcy/Render', 13:06:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:06:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:06:35 INFO - 'HOME': '/Users/cltbld', 13:06:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:06:35 INFO - 'LOGNAME': 'cltbld', 13:06:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:35 INFO - 'MOZ_NO_REMOTE': '1', 13:06:35 INFO - 'NO_EM_RESTART': '1', 13:06:35 INFO - 'PAGER': '/bin/cat', 13:06:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:06:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:35 INFO - 'PWD': '/builds/slave/test', 13:06:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:06:35 INFO - 'SHELL': '/bin/bash', 13:06:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fO6Fo21tn1/Listeners', 13:06:35 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 13:06:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:06:35 INFO - 'USER': 'cltbld', 13:06:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:06:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:06:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:35 INFO - 'XPC_FLAGS': '0x0', 13:06:35 INFO - 'XPC_SERVICE_NAME': '0', 13:06:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:06:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:06:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:06:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:06:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:06:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:06:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:06:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:06:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:06:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:06:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:06:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:06:38 INFO - Installing collected packages: mozsystemmonitor 13:06:38 INFO - Running setup.py install for mozsystemmonitor 13:06:38 INFO - Successfully installed mozsystemmonitor 13:06:38 INFO - Cleaning up... 13:06:38 INFO - Return code: 0 13:06:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:06:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:06:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:06:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:06:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:06:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:38 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 0x102268490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021de570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff43b477350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10207cd30>, '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 0x101ea2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102074b20>, '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.IORhYYDkcy/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.fO6Fo21tn1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 13:06:38 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 13:06:38 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 13:06:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IORhYYDkcy/Render', 13:06:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:06:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:06:38 INFO - 'HOME': '/Users/cltbld', 13:06:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:06:38 INFO - 'LOGNAME': 'cltbld', 13:06:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:38 INFO - 'MOZ_NO_REMOTE': '1', 13:06:38 INFO - 'NO_EM_RESTART': '1', 13:06:38 INFO - 'PAGER': '/bin/cat', 13:06:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:06:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:38 INFO - 'PWD': '/builds/slave/test', 13:06:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:06:38 INFO - 'SHELL': '/bin/bash', 13:06:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fO6Fo21tn1/Listeners', 13:06:38 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 13:06:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:06:38 INFO - 'USER': 'cltbld', 13:06:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:06:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:06:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:38 INFO - 'XPC_FLAGS': '0x0', 13:06:38 INFO - 'XPC_SERVICE_NAME': '0', 13:06:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:06:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:06:38 INFO - Downloading/unpacking jsonschema==2.5.1 13:06:38 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 13:06:38 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 13:06:38 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 13:06:38 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 13:06:38 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 13:06:38 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 13:06:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:06:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:06:41 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 13:06:41 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 13:06:41 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 13:06:41 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 13:06:41 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 13:06:41 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 13:06:41 INFO - Downloading functools32-3.2.3-2.tar.gz 13:06:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:06:41 INFO - warning: no files found matching '*.txt' 13:06:41 INFO - no previously-included directories found matching 'build' 13:06:41 INFO - no previously-included directories found matching 'dist' 13:06:41 INFO - no previously-included directories found matching '.git*' 13:06:41 INFO - Installing collected packages: jsonschema, functools32 13:06:41 INFO - Running setup.py install for functools32 13:06:42 INFO - warning: no files found matching '*.txt' 13:06:42 INFO - no previously-included directories found matching 'build' 13:06:42 INFO - no previously-included directories found matching 'dist' 13:06:42 INFO - no previously-included directories found matching '.git*' 13:06:42 INFO - Successfully installed jsonschema functools32 13:06:42 INFO - Cleaning up... 13:06:42 INFO - Return code: 0 13:06:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:06:42 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')]} 13:06:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:06:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:42 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')]} 13:06:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:06:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:42 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 0x102268490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021de570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff43b477350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10207cd30>, '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 0x101ea2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102074b20>, '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.IORhYYDkcy/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.fO6Fo21tn1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 13:06:42 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 13:06:42 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 13:06:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IORhYYDkcy/Render', 13:06:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:06:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:06:42 INFO - 'HOME': '/Users/cltbld', 13:06:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:06:42 INFO - 'LOGNAME': 'cltbld', 13:06:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:42 INFO - 'MOZ_NO_REMOTE': '1', 13:06:42 INFO - 'NO_EM_RESTART': '1', 13:06:42 INFO - 'PAGER': '/bin/cat', 13:06:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:06:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:42 INFO - 'PWD': '/builds/slave/test', 13:06:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:06:42 INFO - 'SHELL': '/bin/bash', 13:06:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fO6Fo21tn1/Listeners', 13:06:42 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 13:06:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:06:42 INFO - 'USER': 'cltbld', 13:06:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:06:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:06:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:42 INFO - 'XPC_FLAGS': '0x0', 13:06:42 INFO - 'XPC_SERVICE_NAME': '0', 13:06:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:06:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:06:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:06:42 INFO - Cleaning up... 13:06:42 INFO - Return code: 0 13:06:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:06:42 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')]} 13:06:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:06:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:42 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')]} 13:06:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:06:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:42 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 0x102268490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021de570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff43b477350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10207cd30>, '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 0x101ea2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102074b20>, '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.IORhYYDkcy/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.fO6Fo21tn1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 13:06:42 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 13:06:42 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 13:06:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IORhYYDkcy/Render', 13:06:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:06:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:06:42 INFO - 'HOME': '/Users/cltbld', 13:06:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:06:42 INFO - 'LOGNAME': 'cltbld', 13:06:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:42 INFO - 'MOZ_NO_REMOTE': '1', 13:06:42 INFO - 'NO_EM_RESTART': '1', 13:06:42 INFO - 'PAGER': '/bin/cat', 13:06:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:06:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:42 INFO - 'PWD': '/builds/slave/test', 13:06:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:06:42 INFO - 'SHELL': '/bin/bash', 13:06:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fO6Fo21tn1/Listeners', 13:06:42 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 13:06:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:06:42 INFO - 'USER': 'cltbld', 13:06:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:06:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:06:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:42 INFO - 'XPC_FLAGS': '0x0', 13:06:42 INFO - 'XPC_SERVICE_NAME': '0', 13:06:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:06:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:06:42 INFO - Downloading/unpacking blobuploader==1.2.4 13:06:42 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 13:06:42 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 13:06:42 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 13:06:42 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 13:06:42 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 13:06:42 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 13:06:45 INFO - Downloading blobuploader-1.2.4.tar.gz 13:06:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:06:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:06:45 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 13:06:45 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 13:06:45 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 13:06:45 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 13:06:45 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 13:06:45 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 13:06:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:06:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:06:45 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 13:06:45 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 13:06:45 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 13:06:45 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 13:06:45 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 13:06:45 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 13:06:46 INFO - Downloading docopt-0.6.1.tar.gz 13:06:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:06:46 INFO - Installing collected packages: blobuploader, requests, docopt 13:06:46 INFO - Running setup.py install for blobuploader 13:06:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:06:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:06:46 INFO - Running setup.py install for requests 13:06:47 INFO - Running setup.py install for docopt 13:06:47 INFO - Successfully installed blobuploader requests docopt 13:06:47 INFO - Cleaning up... 13:06:47 INFO - Return code: 0 13:06:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:06:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:06:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:06:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:06:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:06:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:47 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 0x102268490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021de570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff43b477350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10207cd30>, '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 0x101ea2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102074b20>, '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.IORhYYDkcy/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.fO6Fo21tn1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 13:06:47 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 13:06:47 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 13:06:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IORhYYDkcy/Render', 13:06:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:06:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:06:47 INFO - 'HOME': '/Users/cltbld', 13:06:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:06:47 INFO - 'LOGNAME': 'cltbld', 13:06:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:47 INFO - 'MOZ_NO_REMOTE': '1', 13:06:47 INFO - 'NO_EM_RESTART': '1', 13:06:47 INFO - 'PAGER': '/bin/cat', 13:06:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:06:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:47 INFO - 'PWD': '/builds/slave/test', 13:06:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:06:47 INFO - 'SHELL': '/bin/bash', 13:06:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fO6Fo21tn1/Listeners', 13:06:47 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 13:06:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:06:47 INFO - 'USER': 'cltbld', 13:06:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:06:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:06:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:47 INFO - 'XPC_FLAGS': '0x0', 13:06:47 INFO - 'XPC_SERVICE_NAME': '0', 13:06:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:06:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:06:47 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-HvowPQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:06:47 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-Mqsygl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:06:47 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-z38gG2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:06:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:06:47 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-DAOPAl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:06:48 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-EuKqxj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:06:48 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-HL2R8H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:06:48 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-mssYRK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:06:48 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-PAXYVH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:06:48 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-cqG5uL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:06:48 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-Yi84wR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:06:48 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-le7XSb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:06:48 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-yPUHEo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:06:48 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-0TeKZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:06:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:06:48 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-qmzVot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:06:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:06:49 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-f_CqKv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:06:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:06:49 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-gIP53z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:06:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:06:49 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-AjT9DN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:06:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:06:49 INFO - Running setup.py install for manifestparser 13:06:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:06:49 INFO - Running setup.py install for mozcrash 13:06:49 INFO - Running setup.py install for mozdebug 13:06:49 INFO - Running setup.py install for mozdevice 13:06:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:06:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:06:49 INFO - Running setup.py install for mozfile 13:06:50 INFO - Running setup.py install for mozhttpd 13:06:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:06:50 INFO - Running setup.py install for mozinfo 13:06:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:06:50 INFO - Running setup.py install for mozInstall 13:06:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:06:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:06:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:06:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:06:50 INFO - Running setup.py install for mozleak 13:06:50 INFO - Running setup.py install for mozlog 13:06:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:06:50 INFO - Running setup.py install for moznetwork 13:06:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:06:51 INFO - Running setup.py install for mozprocess 13:06:51 INFO - Running setup.py install for mozprofile 13:06:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:06:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:06:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:06:51 INFO - Running setup.py install for mozrunner 13:06:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:06:51 INFO - Running setup.py install for mozscreenshot 13:06:51 INFO - Running setup.py install for moztest 13:06:51 INFO - Running setup.py install for mozversion 13:06:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:06:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:06:51 INFO - Cleaning up... 13:06:52 INFO - Return code: 0 13:06:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:06:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:06:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:06:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:06:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:06:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:52 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 0x102268490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021de570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff43b477350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10207cd30>, '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 0x101ea2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102074b20>, '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.IORhYYDkcy/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.fO6Fo21tn1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 13:06:52 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 13:06:52 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 13:06:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IORhYYDkcy/Render', 13:06:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:06:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:06:52 INFO - 'HOME': '/Users/cltbld', 13:06:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:06:52 INFO - 'LOGNAME': 'cltbld', 13:06:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:52 INFO - 'MOZ_NO_REMOTE': '1', 13:06:52 INFO - 'NO_EM_RESTART': '1', 13:06:52 INFO - 'PAGER': '/bin/cat', 13:06:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:06:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:52 INFO - 'PWD': '/builds/slave/test', 13:06:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:06:52 INFO - 'SHELL': '/bin/bash', 13:06:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fO6Fo21tn1/Listeners', 13:06:52 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 13:06:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:06:52 INFO - 'USER': 'cltbld', 13:06:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:06:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:06:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:52 INFO - 'XPC_FLAGS': '0x0', 13:06:52 INFO - 'XPC_SERVICE_NAME': '0', 13:06:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:06:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:06:52 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-ZpMh3c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:06:52 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)) 13:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:06:52 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-2uzuxn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:06:52 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)) 13:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:06:52 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-gzzMWb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:06:52 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)) 13:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:06:52 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-7woo79-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:06:52 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)) 13:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:06:52 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-X8jgP7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:06:52 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)) 13:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:06:52 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-oApvZj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:06:52 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)) 13:06:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:06:52 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-4zKqj1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:06:53 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)) 13:06:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:06:53 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-Bm4OrN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:06:53 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)) 13:06:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:06:53 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-whEAKa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:06:53 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)) 13:06:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:06:53 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-Kotg52-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:06:53 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)) 13:06:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:06:53 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-NufEPS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:06:53 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)) 13:06:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:06:53 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-rw6Dhz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:06:53 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)) 13:06:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:06:53 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-mgw2A6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:06:53 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)) 13:06:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:06:53 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-GGcAGT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:06:53 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)) 13:06:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:06:53 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-JfxDi5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:06:53 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)) 13:06:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:06:53 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-f2nXWb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:06:53 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)) 13:06:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:06:53 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-C5abAH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:06:54 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)) 13:06:54 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)) 13:06:54 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)) 13:06:54 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)) 13:06:54 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)) 13:06:54 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)) 13:06:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:06:54 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 13:06:54 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 13:06:54 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 13:06:54 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 13:06:54 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 13:06:54 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 13:06:56 INFO - Downloading blessings-1.6.tar.gz 13:06:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:06:56 INFO - Installing collected packages: blessings 13:06:56 INFO - Running setup.py install for blessings 13:06:56 INFO - Successfully installed blessings 13:06:56 INFO - Cleaning up... 13:06:57 INFO - Return code: 0 13:06:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:06:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:06:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:06:57 INFO - Reading from file tmpfile_stdout 13:06:57 INFO - Current package versions: 13:06:57 INFO - blessings == 1.6 13:06:57 INFO - blobuploader == 1.2.4 13:06:57 INFO - docopt == 0.6.1 13:06:57 INFO - functools32 == 3.2.3-2 13:06:57 INFO - jsonschema == 2.5.1 13:06:57 INFO - manifestparser == 1.1 13:06:57 INFO - mozInstall == 1.12 13:06:57 INFO - mozcrash == 0.17 13:06:57 INFO - mozdebug == 0.1 13:06:57 INFO - mozdevice == 0.48 13:06:57 INFO - mozfile == 1.2 13:06:57 INFO - mozhttpd == 0.7 13:06:57 INFO - mozinfo == 0.9 13:06:57 INFO - mozleak == 0.1 13:06:57 INFO - mozlog == 3.4 13:06:57 INFO - moznetwork == 0.27 13:06:57 INFO - mozprocess == 0.23 13:06:57 INFO - mozprofile == 0.28 13:06:57 INFO - mozrunner == 6.12 13:06:57 INFO - mozscreenshot == 0.1 13:06:57 INFO - mozsystemmonitor == 0.3 13:06:57 INFO - moztest == 0.7 13:06:57 INFO - mozversion == 1.4 13:06:57 INFO - psutil == 3.1.1 13:06:57 INFO - requests == 1.2.3 13:06:57 INFO - wsgiref == 0.1.2 13:06:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:06:57 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')]} 13:06:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:06:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:57 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')]} 13:06:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:06:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:57 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 0x102268490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021de570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff43b477350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10207cd30>, '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 0x101ea2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102074b20>, '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.IORhYYDkcy/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.fO6Fo21tn1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 13:06:57 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 13:06:57 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 13:06:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IORhYYDkcy/Render', 13:06:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:06:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:06:57 INFO - 'HOME': '/Users/cltbld', 13:06:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:06:57 INFO - 'LOGNAME': 'cltbld', 13:06:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:57 INFO - 'MOZ_NO_REMOTE': '1', 13:06:57 INFO - 'NO_EM_RESTART': '1', 13:06:57 INFO - 'PAGER': '/bin/cat', 13:06:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:06:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:57 INFO - 'PWD': '/builds/slave/test', 13:06:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:06:57 INFO - 'SHELL': '/bin/bash', 13:06:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fO6Fo21tn1/Listeners', 13:06:57 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 13:06:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:06:57 INFO - 'USER': 'cltbld', 13:06:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:06:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:06:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:57 INFO - 'XPC_FLAGS': '0x0', 13:06:57 INFO - 'XPC_SERVICE_NAME': '0', 13:06:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:06:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:06:57 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)) 13:06:57 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)) 13:06:57 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)) 13:06:57 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)) 13:06:57 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)) 13:06:57 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)) 13:06:57 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)) 13:06:57 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)) 13:06:57 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)) 13:06:57 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)) 13:06:57 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)) 13:06:57 INFO - Cleaning up... 13:06:57 INFO - Return code: 0 13:06:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:06:57 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')]} 13:06:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:06:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:57 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')]} 13:06:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:06:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:06:57 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 0x102268490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021de570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff43b477350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10207cd30>, '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 0x101ea2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102074b20>, '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.IORhYYDkcy/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.fO6Fo21tn1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 13:06:57 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 13:06:57 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 13:06:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IORhYYDkcy/Render', 13:06:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:06:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:06:57 INFO - 'HOME': '/Users/cltbld', 13:06:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:06:57 INFO - 'LOGNAME': 'cltbld', 13:06:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:06:57 INFO - 'MOZ_NO_REMOTE': '1', 13:06:57 INFO - 'NO_EM_RESTART': '1', 13:06:57 INFO - 'PAGER': '/bin/cat', 13:06:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:06:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:06:57 INFO - 'PWD': '/builds/slave/test', 13:06:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:06:57 INFO - 'SHELL': '/bin/bash', 13:06:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fO6Fo21tn1/Listeners', 13:06:57 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 13:06:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:06:57 INFO - 'USER': 'cltbld', 13:06:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:06:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:06:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:06:57 INFO - 'XPC_FLAGS': '0x0', 13:06:57 INFO - 'XPC_SERVICE_NAME': '0', 13:06:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:06:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:06:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:06:57 INFO - Cleaning up... 13:06:57 INFO - Return code: 0 13:06:57 INFO - Running post-action listener: _resource_record_post_action 13:06:57 INFO - Running post-action listener: _start_resource_monitoring 13:06:57 INFO - Starting resource monitoring. 13:06:57 INFO - [mozharness: 2016-09-29 20:06:57.853521Z] Finished create-virtualenv step (success) 13:06:57 INFO - [mozharness: 2016-09-29 20:06:57.854529Z] Running install step. 13:06:57 INFO - Running pre-action listener: _resource_record_pre_action 13:06:57 INFO - Running main action method: install 13:06:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:06:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:06:58 INFO - Reading from file tmpfile_stdout 13:06:58 INFO - Detecting whether we're running mozinstall >=1.0... 13:06:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:06:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:06:58 INFO - Reading from file tmpfile_stdout 13:06:58 INFO - Output received: 13:06:58 INFO - Usage: mozinstall [options] installer 13:06:58 INFO - Options: 13:06:58 INFO - -h, --help show this help message and exit 13:06:58 INFO - -d DEST, --destination=DEST 13:06:58 INFO - Directory to install application into. [default: 13:06:58 INFO - "/builds/slave/test"] 13:06:58 INFO - --app=APP Application being installed. [default: firefox] 13:06:58 INFO - mkdir: /builds/slave/test/build/application 13:06:58 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'] 13:06:58 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 13:07:32 INFO - Reading from file tmpfile_stdout 13:07:32 INFO - Output received: 13:07:32 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 13:07:32 INFO - Running post-action listener: _resource_record_post_action 13:07:32 INFO - [mozharness: 2016-09-29 20:07:32.455565Z] Finished install step (success) 13:07:32 INFO - [mozharness: 2016-09-29 20:07:32.455712Z] Running run-tests step. 13:07:32 INFO - Running pre-action listener: _resource_record_pre_action 13:07:32 INFO - Running main action method: run_tests 13:07:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:07:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:07:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:07:32 INFO - 2016-09-29 13:07:32.470 screenresolution[1908:12567] starting screenresolution argv=screenresolution get 13:07:32 INFO - 2016-09-29 13:07:32.472 screenresolution[1908:12567] Display 0: 1600x1200x32@60 13:07:32 INFO - 2016-09-29 13:07:32.478 screenresolution[1909:12569] starting screenresolution argv=screenresolution list 13:07:32 INFO - Available Modes on Display 0 13:07:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:07:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:07:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:07:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:07:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:07:32 INFO - Intel Iris: 13:07:32 INFO - Chipset Model: Intel Iris 13:07:32 INFO - Type: GPU 13:07:32 INFO - Bus: Built-In 13:07:32 INFO - VRAM (Dynamic, Max): 1536 MB 13:07:32 INFO - Vendor: Intel (0x8086) 13:07:32 INFO - Device ID: 0x0a2e 13:07:32 INFO - Revision ID: 0x0009 13:07:32 INFO - Displays: 13:07:32 INFO - Display: 13:07:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:07:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:07:32 INFO - Display Serial Number: 1600x1200 60 13:07:32 INFO - Main Display: Yes 13:07:32 INFO - Mirror: Off 13:07:32 INFO - Online: Yes 13:07:32 INFO - Rotation: Supported 13:07:32 INFO - Return code: 0 13:07:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:07:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:07:32 INFO - Python 2.7.3 13:07:32 INFO - Return code: 0 13:07:32 INFO - grabbing minidump binary from tooltool 13:07: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')]} 13:07:32 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 0x10207cd30>, '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 0x101ea2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102074b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:07:32 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 13:07:32 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 13:07:32 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 13:07:32 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:07:32 INFO - Return code: 0 13:07:32 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:07:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:07:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:07:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:07:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:07:32 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/Fd-B-8j9RTmdBhRyqqyY5w/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0127', '--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 13:07:32 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/Fd-B-8j9RTmdBhRyqqyY5w/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0127 --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 13:07:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IORhYYDkcy/Render', 13:07:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:07:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:07:32 INFO - 'HOME': '/Users/cltbld', 13:07:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:07:32 INFO - 'LOGNAME': 'cltbld', 13:07:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:07:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:07:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:07:32 INFO - 'MOZ_NO_REMOTE': '1', 13:07:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:07:32 INFO - 'NO_EM_RESTART': '1', 13:07:32 INFO - 'PAGER': '/bin/cat', 13:07:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:07:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:07:32 INFO - 'PWD': '/builds/slave/test', 13:07:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:07:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:07:32 INFO - 'SHELL': '/bin/bash', 13:07:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fO6Fo21tn1/Listeners', 13:07:32 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 13:07:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:07:32 INFO - 'USER': 'cltbld', 13:07:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:07:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:07:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:07:32 INFO - 'XPC_FLAGS': '0x0', 13:07:32 INFO - 'XPC_SERVICE_NAME': '0', 13:07:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:07:32 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/Fd-B-8j9RTmdBhRyqqyY5w/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0127', '--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 13:07:33 INFO - mozversion application_buildid: 20160929110720 13:07:33 INFO - mozversion application_changeset: 4b69e32e3a837f70fcfb9c8a8b2c8b4af4a280de 13:07:33 INFO - mozversion application_display_name: Nightly 13:07:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:07:33 INFO - mozversion application_name: Firefox 13:07:33 INFO - mozversion application_remotingname: firefox 13:07:33 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 13:07:33 INFO - mozversion application_vendor: Mozilla 13:07:33 INFO - mozversion application_version: 52.0a1 13:07:33 INFO - mozversion platform_buildid: 20160929110720 13:07:33 INFO - mozversion platform_changeset: 4b69e32e3a837f70fcfb9c8a8b2c8b4af4a280de 13:07:33 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 13:07:33 INFO - mozversion platform_version: 52.0a1 13:07:33 INFO - using testdate: 1475179653 13:07:33 INFO - actual date: 1475179653 13:07:33 INFO - starting webserver on 'localhost:51428' 13:07:33 INFO - SUITE-START | Running 2 tests 13:07:33 INFO - TEST-START | dromaeo_css 13:07:33 INFO - operating with platform_type : mac_ 13:07:33 INFO - Initialising browser for dromaeo_css test... 13:07:33 INFO - TEST-INFO | started process 1919 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmpo4O4ho/profile http://localhost:51428/getInfo.html) 13:07:35 INFO - PROCESS | 1919 | __metrics Screen width/height:1600/1200 13:07:35 INFO - PROCESS | 1919 | colorDepth:24 13:07:35 INFO - PROCESS | 1919 | Browser inner width/height: 1024/689 13:07:35 INFO - PROCESS | 1919 | __metrics 13:07:35 INFO - PROCESS | 1919 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:07:35 INFO - PROCESS | 1919 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:07:36 INFO - TEST-INFO | 1919: exit 0 13:07:36 INFO - Browser initialized. 13:07:36 INFO - Running cycle 1/1 for dromaeo_css test... 13:07:36 INFO - TEST-INFO | started process 1921 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmpo4O4ho/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:07:48 INFO - PROCESS | 1921 | RSS: Main: 235954176 13:07:48 INFO - PROCESS | 1921 | 13:08:40 INFO - PROCESS | 1921 | 1475179720194 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] 13:10:51 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:51428/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51428/tests/dromaeo/cssquery-ext.html) 13:10:53 INFO - PROCESS | 1921 | RSS: Main: 389754880 13:10:53 INFO - PROCESS | 1921 | 13:13:56 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:51428/tests/dromaeo/cssquery-ext.html (next: http://localhost:51428/tests/dromaeo/cssquery-jquery.html) 13:13:56 INFO - PROCESS | 1921 | RSS: Main: 372781056 13:13:56 INFO - PROCESS | 1921 | 13:16:59 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:51428/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51428/tests/dromaeo/cssquery-mootools.html) 13:16:59 INFO - PROCESS | 1921 | RSS: Main: 337551360 13:16:59 INFO - PROCESS | 1921 | 13:20:02 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:51428/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51428/tests/dromaeo/cssquery-prototype.html) 13:20:02 INFO - PROCESS | 1921 | RSS: Main: 357453824 13:20:02 INFO - PROCESS | 1921 | 13:23:05 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:51428/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51428/tests/dromaeo/cssquery-yui.html) 13:23:06 INFO - PROCESS | 1921 | RSS: Main: 329461760 13:23:06 INFO - PROCESS | 1921 | 13:26:09 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:51428/tests/dromaeo/cssquery-yui.html (next: http://localhost:51428/tests/dromaeo/cssquery-dojo.html) 13:26:09 INFO - PROCESS | 1921 | RSS: Main: 516530176 13:26:09 INFO - PROCESS | 1921 | 13:26:09 INFO - PROCESS | 1921 | __start_tp_report 13:26:09 INFO - PROCESS | 1921 | _x_x_mozilla_page_load 13:26:09 INFO - PROCESS | 1921 | _x_x_mozilla_page_load_details 13:26:09 INFO - PROCESS | 1921 | |i|pagename|runs| 13:26:09 INFO - PROCESS | 1921 | |0;dojo.html;3101.9763478052237;3261.168402057475;3315.950260746089;3319.4522903720886;3343.0973637117995;5546.084895992157;5609;5645.661260324383;5676.914846277309;5703.77184912604;5638.548916086714;5647.519960803336;5729.3411257705375;5746.310442746866;5761.539076873851;5051.494850514956;5103;5117.590592752571;5122.462141475147;5183.844484665466;5179.741012949356;5282.23407605897;5339;5345.7594408251525;5357.678539287635;5251.10731175701;5253.816116435925;5272.23552584875;5280.445553216915;5333.733313334318;5105.566026887719;5144.742762861842;5170.586353091744;5181.35233095863;5185.196294574309;4264.808083636244;5072.34059572257;5140.974295128538;5143.614228932845;5147.124988751922;5575.916361254584;5648.830535083954;5670.177824215507;5673.26247587815;5708.286464191976;6110.114033465167;6168.784092556739;6185.103160041769;6190.0405437157315;6191.752329906798;6131.080337949299;6184.257889053298;6247.843803904939;6268.623882567061;6269.185005949197;5661.575381846378;5695.316562012585;5704.800331988402;5747.25285712863;5767.538596912237;6244.0321750128805;6252.531060170505;6259.436650701458;6267.467265282411;6271.27880293763;6093.482054025458;6108.633481991006;6111.388861113942;6117.541184411187;6121.969390153021;6087.756489740449;6099.370313949828;6100.540239930047;6138.631682099089;6239;6102.549845102896;6145.324014358506;6148.524354154971;6176.629402235881;6179.134921110958;6045.881511920309;6179.783707570304;6181.474574661204;6198.597091189058;6222.62664240147;4804.639652026112;4874.439439464436;4895.388076490439;4910.656254062182;4962.826301079445;4375.824967001348;4428.512863585003;4442.666799990014;4462.0183559616835;4479.820807167677;5830.47525722687;5831.125331200354;5835.183074369592;5878.706064696748;5889.793857214977;6216.378362163748;6244.594101383395;6247.156633854463;6287.371262873676;6297.464715499143;5635.971820140873;5704.201411802351;5709.371969083398;5744.368119506851;5821.7380217890295;5321;5368.32895888014;5369.5972802040005;5387.838364849061;5419.701916394557;6274.968625156845;6286.15136956505;6293.056041593798;6314.842128946813;6324.956382196886;6104.053871649901;6203.100550420254;6256.343083976117;6260.530460215411;6266.843328916813;6116.092308610808;6209.285932117684;6214.130021796862;6241.157443745218;6244.094606282155;6098.855844323317;6144;6144.969275153596;6150.630962142286;6155.353687862889;6138;6175.166352542349;6247.781327653511;6252.124702541557;6260.624362538262;4700.6239500839165;5902.2622172228475;5961.225040744675;5998.25021872266;6071.7571297147615;5257.843274479608;5979.1928089707335;6046.09308603713;6078.270607527125;6103.42017508336;5676.545876329967;5880.000399932107;5886.205362276038;5887.116932460165;5891.1457838612305;5899.17411562373;5970.9701451492465;6015.699215039318;6045.365236650088;6063.454289114001;5809.8547536311935;5813.709314534171;5831.883362332815;5946.13781001761;5948.851278717893 13:26:09 INFO - PROCESS | 1921 | |1;ext.html;469.38979326875057;485.72799232429844;500.91551790373836;533.2028617217261;543.3751186135945;31281.374372512564;41199.7640070798;42398;42443;44407;35640.82179589098;43081.569184308144;43206;43515;43552.78223608877;9721.319507634451;9794.314397992144;9839.212862970946;9943.651972180975;9954.253430992669;7570.583617901013;8710.651573937035;8898.822023559525;9026.413283136608;9029.819403611924;12113.21264117834;12380.380980950917;12394.318312492855;12493.812592811115;12511.499540018389;12235.08236882233;12340.01279897606;12354.876451235463;12356;12361.567345142877;12086.576969806014;12127.151099423045;12146.392680365947;12161.817572736416;12169.878301217052;41116;41309.380359294635;41351.1729765403;41380.79309603433;41512.58487415148;3143.6384815746023;3161.2254997525524;3165.9208519786957;3175.491921292582;3254.1539199807985;31427.84286078578;31710.841445792856;31726.682733172835;32346.838265808754;32870.01389958305;2788.4144329690585;3242.8540715667614;3270.591176102987;3271.8854840080485;3360.31113621707;479033;479238;480506;482501.58749206027;482728;61587;61885.38114618796;61937.69031154906;61997.380026199164;62155;35341;35815.28369432597;35983.82008089995;37461.25077498435;37565.62434375676;16237.269322880262;16299.348026079062;16350.918245408697;16384.0169589825;16387.918060409622;12411.317377544141;12476.064295177897;12489.875101249054;12497.312647804458;12530;16870;17207.053612051208;17219.311227551007;18995.525111872037;19266.223730700076;6759.628220447923;7700.9614951926005;7897.24976127267;7987.480813747088;8000.79998000043;842.3513894301353;884.4251236696199;948.4546385828177;974.0503009565758;1052.0584077250817;28857.56713649297;29749;29870.25324366908;30613;30652.773889044634;1034.5086084109987;1077.8275475923972;1105.5798796457295;1126.3298337489182;1148.2937993134162;13557.864421355858;15051;15724.921375393049;15839.921292316487;15919.363225471083;658201;661680;662884;667000;667662;57569.71215143897;58275;58284.54288642818;58342.4165758337;58399.70800145972;1347.2455424962052;1373.4300265389752;1382.495389182921;1382.8409732880646;1392.095138160204;27246.72753272442;29318.41363172682;29478.705212947596;30303.545446817876;30460;48621.75689121673;51650.48349516457;52160.95678086493;52186.73906630594;52344.69138271573;566895;569335;569592;570885;571918;25917.870410647825;26073.348166295236;26271.868640656674;26487.867560662846;26496.337591560365;37798.62201377951;38581.8425447237;45652.315215271134;48202.27696584624;48504.51495485;38947.2210555793;39037.8048109769;39047.80476097601;39199.60800392074;40718;39178.2164356717;39339.409908850816;40183.59816401799;40645.79677101596;40804;32099.839500802347;36873.63126368702;37518.87443376703;38350;38918.41622375723;32616;33263.168420789676;34041.31917361688;34229.81180922075;34422.48366274458;24853.25440236796;25081;25129.497410051332;25646.487070258863;26025.479490409707 13:26:09 INFO - PROCESS | 1921 | |2;jquery.html;365.9634036596339;371.50404210379145;375.68067142928504;386.1157948298397;396.35790020167315;19519.804801951996;21002.26492072774;21441;21760.564788704218;21927.451813704665;20497.795022049773;20923;21251.25620603279;21405.785942140574;21491.6776248356;5841.094630332305;5920.822375328747;6041.939580604204;6100.81697549072;6103;4201.264778663737;4416.779161041951;4423.889402764925;5984.072468767348;6109.938900610981;6458.128152699396;6769.390754832064;6799.388055075042;6810.3189681032;6810.761623343184;6500.382463665944;6691;6752.054712340277;6753.561018533779;6763.492738044617;5728.198052272685;5755.510781583571;5828.941710582924;5835.533157347402;5850.444207800252;26046.86976565124;26105.608415873776;26281.605775913376;26287.868560657073;26527.33681657974;2014.4158194123686;2201.5816994771103;2346.4016675747575;2467.7655622715815;2795.161451564543;10026;10559.577616895314;10587.84118238227;12748;15342.769858452131;1887.9150438230313;1934.6904495280828;1938.8158124978104;1940.9126589303514;1965.7542807149107;97286.51356743241;118916;120430.79569204371;121820.78179218184;122895.3855230727;11386.94306528462;11408;11557.479913403917;11588.304701717923;11744.17790754639;14525.854741452662;14529.78205326921;14542.563723088324;14546.709065818624;14588.489402870848;8192.54940978252;8459.788505287293;8486.108958559385;8596.097409772008;8616.956915215384;7264.273572642694;8338;8382.83234335322;8390.916090839135;8538;3847.942280865789;4370.781460926941;4508.954910450919;4528.818847246139;4598.747068911242;1574.7795308656798;1766.381766381756;1804.3504338438152;1871.822176893193;1954.7263383126374;782.4796510320687;815.3110621524802;954.4416516337881;1000.3947611694965;1024.293237666008;19969.900150499154;21276.893615531822;21309.46726331823;21402.357929262147;21404.678929815753;894.4722613657973;901.6032945503957;926.420986883198;946.4888959961681;962.6823148361024;10443.738906527398;11201.327920324806;11237.438128093563;11258.43707814606;11280.774384512264;550342;552633;554330.2283488556;572433;577303;125571.60499382477;131138.34430827975;135729;136187;137732.622673774;948.4498990585444;960.9807803843884;966.3815158298695;967.2407160379069;968.733598260484;18303.633927321644;18463.353782617545;18665.34671286553;18733.437996860117;18846.52883677919;39476.80261598789;54065.459345406045;54174.00318425574;54554.454455454936;55093.89812203814;75534.24465755271;76112;77348.2265177341;79071;82665.17334826816;5375.919361209507;7829.765107046797;8057.798555036171;8261;8306.54314012735;11216.270942388719;12907.290099044643;12994.80507792365;13317.66705832362;13411.262380569162;5280.973595132;5311.601629877698;8625.266852317623;11112.721892011956;11563.42182890869;10481.947590262;11105.389203593557;11243.269187502787;11445.599404020819;11523.596674116367;9713.757156070873;11345.773084538429;11572.18994670365;11830.940845295718;11864;8194.057683366489;8306.62620182081;8321.377331420315;8399.580020999048;8421.284190843848;9517.058926298561;11615.419229038345;11708.180427370344;11774.646760597196;11835.763284734428 13:26:09 INFO - PROCESS | 1921 | |3;mootools.html;1407.8099456573364;1462.4881291547958;1532.5632194824477;1638.3774165816988;1919.846412287017;8441.198086181817;8927.910720892789;8937.285017198625;8942.865857012142;8977.640894364225;7825.295723384908;8033.47782394144;8044.3966702497255;8475.660973561065;8715.172058654418;2309.734380546238;2354.7409784923643;2356.6818479505305;2358.162852187466;2359.103540654549;2316.513532158249;2348.1077190667525;2352.741198468167;2361.114582031738;2366.9763302367014;2335.1710142899196;2349.0955981946904;2358.2099996501192;2360.9881950590225;2364.574376612209;2320.582295186866;2333.2067097186955;2336.731275903259;2340.5201933603657;2340.590396680574;2330.347502699247;2330.6249312905466;2332.8017118544776;2333.101755824003;2339.6841426407386;8790.692325768567;8992.77518062047;8996.145366190201;8999.19007289347;9008.279337652972;5209.479052094798;5356.062689029443;5360.142377219665;5363.168708850135;5373.5163835254625;3094.5048792193365;3474.687278144954;3563.055790215595;3679.521662183899;3705.6479634434686;919.3564504846568;924.8890133184061;1072.5441687282873;1073.9677809665645;1094.7919895219882;11240.718982025433;11607.245529040585;11608.187426880122;11664.883351166465;11761.941190293994;4357.694961352739;4433.689641725113;4600.7469589172915;4615.515370886076;4625.95374046262;9129.950055743542;9133.497657628894;9148.130927561871;9153.67962121336;9160.908390916005;5228.790848366023;5232.529072383428;5239.240310155083;5240.0567897778765;5244.187150991603;4415.315626077963;4568.725876447424;4645.790939407735;4917.139500587383;4918.2376731606655;3653.5615726113037;3669.889903302905;3787.450819631193;3795.8102094895144;3800.1449673823167;1127.1490025031048;1145.9484323205309;1153.26191237608;1154.359330571524;1158.1603337580186;832.8673004713585;842.0737189092072;882.7793051737066;907.2243232036645;931.4923366765129;5556.666600004012;5759.424057594207;5820.068788993741;5834.212381328466;6013.458788709037;782.2529484342439;871.5293741379711;871.7776966873407;898.2723993565103;932.8554074118523;3791.526059242595;3794.658480736747;3863.5750067492554;3890.922181556353;3933.331333673282;10524.36853788775;10944.56221751137;10960.397178155119;10983.505742241618;10994.34033957965;10943.507542160622;10944.835827462595;10959.616413425492;10963.83554246687;10981.39602321864;6708.932910670928;8206.917930820735;8283.875741863756;8333.208345207195;8664.393492455467;5463.754131064191;5610.775568977195;5777.0179069557435;5777.884442311046;5833.620814647034;9912.256580756617;9969.451680157661;9987.101160895263;10000.699979000641;10087.89912100899;8270.2556769891;10033.498325083863;10289.125424338716;10322.2258330624;10390.688279351632;6532.510061745293;6756.932430675631;7022.683979221049;7172.856542869218;7173.713051477883;7884;8121;8287.875681864656;8448.450850694686;8516.489010659381;7430.554166750012;7502.024736784183;7540.811479713051;7610.12483564375;7694.461387702807;7306.744263950736;7354.77935661931;7373.262673732798;7375.262473752797;7504.512206706547;6901.344372284625;6918.5502942308585;6991.720331186899;6995.300469952964;6995.300469952964;4958.355413796184;4998.750062496933;5014.222795466709;5017.096922553976;5114.590832733298;4838.854834354975;4950.232713929347;5041;5182;5211.869703257449 13:26:09 INFO - PROCESS | 1921 | |4;prototype.html;287.41367610075446;292.27807315929647;298.99999999999994;304.46414310812975;316.0376653091337;4565.246734288842;4926.236433352827;5274.894502109955;5322.680639161653;5342.439043900388;4495.595396414322;5185.714785686786;5237.580993520519;5239.633225674204;5258.6318957672975;3851.4993050903413;4061.715679902408;4079;4080.9387859182134;4093.672506199497;4093.774842383668;4095.5699651536606;4099.651529619986;4109.623276202461;4114.6091121343425;3927.3323534998976;3970.2655008823317;4032.8588499402526;4075.1238483725992;4076.32740597801;4016.9598304016877;4039.3537034074407;4055.858044968427;4056.898577535556;4060.7969601520103;3528.6118526962014;3533.5406397168204;3536.840842162109;3544.8227588620466;3557.2707594942976;4255.425517555122;4831.9033619327765;5158.97420512895;5182.585393168538;5188.870278243036;4521.852120242891;4566.88582785433;4572.919321708648;4587.24310488769;4599.586037256663;4854.441739199967;5576.525995290405;5579.497845193952;5597.320803758856;5602.887942241132;3222.4038552867796;3227.128265605943;3481.415955740132;3503.3693935091674;3816.3893776995824;5487.64330318524;5510.476504732044;5553.527950124244;5791.307042225385;5846.3861294563885;3357.78174418662;3734.7572407793405;3817.503724515795;3855.652991230803;3887.688984881203;3885;3888.3972984187494;3901.258760835432;3905.824237909301;3917.4515567820526;3609.4946707460977;4301.827926882889;4423.4912985006495;4544.318602117238;4552.884543286849;5199.726067113581;5270.789168433296;5421.756020979065;5458.4814442627885;5515.25544051556;1183.6153250193722;1303.6219496346143;1321.623337348851;1324.5562736483193;1326.8275124233787;755.7166062726477;765.3303359560384;797.5812698333422;818.7748369198519;827.664795757719;4109.856155034619;4388.5830846069575;4410.580994805488;4425.3140763181755;4461.286194208911;4108.712390132722;4421.137878113737;4479.216137175981;4556.658250631216;4599.4710608280475;3846.9038274043373;4072.7352722073565;4146.460960075171;4245.8513952011535;4351.49704487884;2486.627005949122;2691.4751623433635;3001.1446737680094;3006.5189569668746;3024.1532370936175;5944;5963.568743501529;5964.314103878023;5965.940340596625;6005.6997150142315;5185.747686896709;5472.753726082336;5844.12338149281;5905.46850783423;5945.494632956247;5084.9745751271;5384.488473595002;5462.317210348707;5706.514946229534;5722.799702010409;5143.2285157225215;5182.6113041521285;5272.920906186334;5305.65513241638;5772.6536407815665;5461.1226029519485;5476.2333273342565;5560.387487628543;5630.324361076533;5643.379228284967;5260.842174734764;5539.833804985857;5600.215969764155;5807.593468457337;5969.970150149222;3347.727863411888;3885.68914486846;3990.201959608148;3990.9201815964097;3996.741026576619;5681.2330335404195;5693.544516438592;5701.372848986691;5738.196652468741;5749.9712501437225;5198.4541623130535;5497.092979658471;5517.503424691797;5562.749676264497;5562.749676264497;4608.953910460987;5525.143602741581;5533.320341972543;5536;5550;4569.977150114361;5364.719994002611;5415.781449173905;5420.940042783717;5461.017016936989;4473.686841921034;4681.712529054537;5265.814182419734;5604.271444712162;5700.572457065654;4618.944569666601;5586.944130558642;5592.888142237051;5593.580481463825;5614.719264036864 13:26:09 INFO - PROCESS | 1921 | |5;yui.html;686.3322127230828;719.9532030418019;765.1659690936877;766.7584710816093;816.7427260412969;15128.394864205433;15767.60580985476;15789.684206315867;15793.763093553605;15807.84192158078;14113.082649627768;14249.71500569988;14262.928685356559;14278.2860856957;14298.213598252092;687.924328323884;712.3659942651045;715.39906478558;845.2012847858776;852.2457625001872;881.4446898453965;881.6385282034367;886.352962337494;887.6981826179098;890.0565400675305;911.2573252798948;927.7866090799121;929.4005366538579;929.7303781903274;930.2325581395343;920.9999999999967;923.7090316550332;925.6852670092134;925.9629614815399;929.7024952015357;533.8265063854263;553.1177771454524;653.8986457099157;691.1015679616529;692.1693967239333;14449.78325325121;14910.925445372812;14914.70170596582;14939.477118300807;14970.775438368433;13145.112932240674;13321.866781332159;13466.326683665777;13523.39144738489;13974.510892118727;9974.650887218913;12081.516739330416;12228.327441990687;12509.249445033327;12892.033097517724;4429.867103986886;4460;4491.46102467706;4522.66080043998;4679.88300292492;109438;110742.44628776805;111737.44131279373;112450.43774781155;112564;9664.613415463444;10771.6229931952;10826.837597436044;10959.83560246597;11033;21046.473838153863;21063.368098957057;21090.472738181667;21093.472663183544;21144.89427552883;7695;7979.601019948979;8101.716439924692;8105.31104856082;8110.756677299691;4446.155230506258;4473.373727678128;4563.338315944169;4565.977170114194;4657.46439159501;3439.3465241604017;3554.733394995386;3555.626659200747;3557.857685692595;3561.6972557332397;664.1598378051768;671.4427025568766;672.0188524753817;672.2638710611873;674.6694119881223;617.6109051297653;620.3021600699213;621.5027977617978;631.8736252749469;638.2946843737695;7938.126806051329;8802.60388282516;8908.153725396078;8944.821103578022;9128.49793261377;533.6211289984077;595.2648479128273;607.5807692692028;636.1062706896818;638.2022471910112;2975.9256018599276;3661.8718344857803;3685.594584595692;3759.154190307159;3853.537575490959;89651.55174224087;92157;130027.34986325009;144874;160770;125315.3734231323;126677;126958.36520817522;127378;130246.34876825556;106923;110716.33925491125;111626;111858;114521;8059.677612895419;8394.580270986304;8452.131914872434;8555.657773688983;8746.30029597618;56501.152482713616;58535;63175;64597;64732.029019565685;77246.84129737946;92270.53864730634;93861;94091.52954235185;95705.04294956962;10411.375317480975;10554.525046373084;10591.788164236827;10615.575376985144;10963;11205.103591712481;11502.02232810187;11606.251746576803;11621.070314374661;12120.60613029513;10957.287776294515;11265.986061254527;11267.830982535104;11370.94314528422;11498.770024599295;11014.614488492864;11213.775724485628;11314.886851131712;11572.016378607912;11685;10569.154467642416;10832.783344332911;10864.619738309122;10876.836847447137;11030.393328367018;9240.168384845396;9385.718428447157;9432.858507122535;9515.191208747057;9899.50502474859;7235.63821808918;8975;8977.596008179778;9337.71986840396;9346.672866449642 13:26:09 INFO - PROCESS | 1921 | __end_tp_report 13:26:09 INFO - PROCESS | 1921 | __start_cc_report 13:26:09 INFO - PROCESS | 1921 | _x_x_mozilla_cycle_collect,1458 13:26:09 INFO - PROCESS | 1921 | __end_cc_report 13:26:09 INFO - PROCESS | 1921 | __startTimestamp1475180769868__endTimestamp 13:26:09 INFO - PROCESS | 1921 | 13:26:09 INFO - PROCESS | 1921 | ------- Summary: start ------- 13:26:09 INFO - PROCESS | 1921 | Number of tests: 6 13:26:09 INFO - PROCESS | 1921 | 13:26:09 INFO - PROCESS | 1921 | [#0] dojo.html Cycles:165 Average:5695.09 Median:5887.12 stddev:648.52 (11.0%) stddev-sans-first:617.78 13:26:09 INFO - PROCESS | 1921 | Values: 3102.0 3261.2 3316.0 3319.5 3343.1 5546.1 5609.0 5645.7 5676.9 5703.8 5638.5 5647.5 5729.3 5746.3 5761.5 5051.5 5103.0 5117.6 5122.5 5183.8 5179.7 5282.2 5339.0 5345.8 5357.7 5251.1 5253.8 5272.2 5280.4 5333.7 5105.6 5144.7 5170.6 5181.4 5185.2 4264.8 5072.3 5141.0 5143.6 5147.1 5575.9 5648.8 5670.2 5673.3 5708.3 6110.1 6168.8 6185.1 6190.0 6191.8 6131.1 6184.3 6247.8 6268.6 6269.2 5661.6 5695.3 5704.8 5747.3 5767.5 6244.0 6252.5 6259.4 6267.5 6271.3 6093.5 6108.6 6111.4 6117.5 6122.0 6087.8 6099.4 6100.5 6138.6 6239.0 6102.5 6145.3 6148.5 6176.6 6179.1 6045.9 6179.8 6181.5 6198.6 6222.6 4804.6 4874.4 4895.4 4910.7 4962.8 4375.8 4428.5 4442.7 4462.0 4479.8 5830.5 5831.1 5835.2 5878.7 5889.8 6216.4 6244.6 6247.2 6287.4 6297.5 5636.0 5704.2 5709.4 5744.4 5821.7 5321.0 5368.3 5369.6 5387.8 5419.7 6275.0 6286.2 6293.1 6314.8 6325.0 6104.1 6203.1 6256.3 6260.5 6266.8 6116.1 6209.3 6214.1 6241.2 6244.1 6098.9 6144.0 6145.0 6150.6 6155.4 6138.0 6175.2 6247.8 6252.1 6260.6 4700.6 5902.3 5961.2 5998.3 6071.8 5257.8 5979.2 6046.1 6078.3 6103.4 5676.5 5880.0 5886.2 5887.1 5891.1 5899.2 5971.0 6015.7 6045.4 6063.5 5809.9 5813.7 5831.9 5946.1 5948.9 13:26:09 INFO - PROCESS | 1921 | 13:26:09 INFO - PROCESS | 1921 | [#1] ext.html Cycles:180 Average:69922.40 Median:35899.55 stddev:154057.64 (429.1%) stddev-sans-first:154401.56 13:26:09 INFO - PROCESS | 1921 | Values: 469.4 485.7 500.9 533.2 543.4 31281.4 41199.8 42398.0 42443.0 44407.0 35640.8 43081.6 43206.0 43515.0 43552.8 9721.3 9794.3 9839.2 9943.7 9954.3 7570.6 8710.7 8898.8 9026.4 9029.8 12113.2 12380.4 12394.3 12493.8 12511.5 12235.1 12340.0 12354.9 12356.0 12361.6 12086.6 12127.2 12146.4 12161.8 12169.9 41116.0 41309.4 41351.2 41380.8 41512.6 3143.6 3161.2 3165.9 3175.5 3254.2 31427.8 31710.8 31726.7 32346.8 32870.0 2788.4 3242.9 3270.6 3271.9 3360.3 479033.0 479238.0 480506.0 482501.6 482728.0 61587.0 61885.4 61937.7 61997.4 62155.0 35341.0 35815.3 35983.8 37461.3 37565.6 16237.3 16299.3 16350.9 16384.0 16387.9 12411.3 12476.1 12489.9 12497.3 12530.0 16870.0 17207.1 17219.3 18995.5 19266.2 6759.6 7701.0 7897.2 7987.5 8000.8 842.4 884.4 948.5 974.1 1052.1 28857.6 29749.0 29870.3 30613.0 30652.8 1034.5 1077.8 1105.6 1126.3 1148.3 13557.9 15051.0 15724.9 15839.9 15919.4 658201.0 661680.0 662884.0 667000.0 667662.0 57569.7 58275.0 58284.5 58342.4 58399.7 1347.2 1373.4 1382.5 1382.8 1392.1 27246.7 29318.4 29478.7 30303.5 30460.0 48621.8 51650.5 52161.0 52186.7 52344.7 566895.0 569335.0 569592.0 570885.0 571918.0 25917.9 26073.3 26271.9 26487.9 26496.3 37798.6 38581.8 45652.3 48202.3 48504.5 38947.2 39037.8 39047.8 39199.6 40718.0 39178.2 39339.4 40183.6 40645.8 40804.0 32099.8 36873.6 37518.9 38350.0 38918.4 32616.0 33263.2 34041.3 34229.8 34422.5 24853.3 25081.0 25129.5 25646.5 26025.5 13:26:09 INFO - PROCESS | 1921 | 13:26:09 INFO - PROCESS | 1921 | [#2] jquery.html Cycles:180 Average:34140.78 Median:1580.56 stddev:94192.66 (5959.4%) stddev-sans-first:94422.76 13:26:09 INFO - PROCESS | 1921 | Values: 366.0 371.5 375.7 386.1 396.4 19519.8 21002.3 21441.0 21760.6 21927.5 20497.8 20923.0 21251.3 21405.8 21491.7 5841.1 5920.8 6041.9 6100.8 6103.0 4201.3 4416.8 4423.9 5984.1 6109.9 6458.1 6769.4 6799.4 6810.3 6810.8 6500.4 6691.0 6752.1 6753.6 6763.5 5728.2 5755.5 5828.9 5835.5 5850.4 26046.9 26105.6 26281.6 26287.9 26527.3 2014.4 2201.6 2346.4 2467.8 2795.2 10026.0 10559.6 10587.8 12748.0 15342.8 1887.9 1934.7 1938.8 1940.9 1965.8 97286.5 118916.0 120430.8 121820.8 122895.4 11386.9 11408.0 11557.5 11588.3 11744.2 14525.9 14529.8 14542.6 14546.7 14588.5 8192.5 8459.8 8486.1 8596.1 8617.0 7264.3 8338.0 8382.8 8390.9 8538.0 3847.9 4370.8 4509.0 4528.8 4598.7 1574.8 1766.4 1804.4 1871.8 1954.7 782.5 815.3 954.4 1000.4 1024.3 19969.9 21276.9 21309.5 21402.4 21404.7 894.5 901.6 926.4 946.5 962.7 10443.7 11201.3 11237.4 11258.4 11280.8 550342.0 552633.0 554330.2 572433.0 577303.0 125571.6 131138.3 135729.0 136187.0 137732.6 948.4 961.0 966.4 967.2 968.7 18303.6 18463.4 18665.3 18733.4 18846.5 39476.8 54065.5 54174.0 54554.5 55093.9 75534.2 76112.0 77348.2 79071.0 82665.2 5375.9 7829.8 8057.8 8261.0 8306.5 11216.3 12907.3 12994.8 13317.7 13411.3 5281.0 5311.6 8625.3 11112.7 11563.4 10481.9 11105.4 11243.3 11445.6 11523.6 9713.8 11345.8 11572.2 11830.9 11864.0 8194.1 8306.6 8321.4 8399.6 8421.3 9517.1 11615.4 11708.2 11774.6 11835.8 13:26:09 INFO - PROCESS | 1921 | 13:26:09 INFO - PROCESS | 1921 | [#3] mootools.html Cycles:180 Average:5645.20 Median:5077.80 stddev:3191.03 (62.8%) stddev-sans-first:3184.09 13:26:09 INFO - PROCESS | 1921 | Values: 1407.8 1462.5 1532.6 1638.4 1919.8 8441.2 8927.9 8937.3 8942.9 8977.6 7825.3 8033.5 8044.4 8475.7 8715.2 2309.7 2354.7 2356.7 2358.2 2359.1 2316.5 2348.1 2352.7 2361.1 2367.0 2335.2 2349.1 2358.2 2361.0 2364.6 2320.6 2333.2 2336.7 2340.5 2340.6 2330.3 2330.6 2332.8 2333.1 2339.7 8790.7 8992.8 8996.1 8999.2 9008.3 5209.5 5356.1 5360.1 5363.2 5373.5 3094.5 3474.7 3563.1 3679.5 3705.6 919.4 924.9 1072.5 1074.0 1094.8 11240.7 11607.2 11608.2 11664.9 11761.9 4357.7 4433.7 4600.7 4615.5 4626.0 9130.0 9133.5 9148.1 9153.7 9160.9 5228.8 5232.5 5239.2 5240.1 5244.2 4415.3 4568.7 4645.8 4917.1 4918.2 3653.6 3669.9 3787.5 3795.8 3800.1 1127.1 1145.9 1153.3 1154.4 1158.2 832.9 842.1 882.8 907.2 931.5 5556.7 5759.4 5820.1 5834.2 6013.5 782.3 871.5 871.8 898.3 932.9 3791.5 3794.7 3863.6 3890.9 3933.3 10524.4 10944.6 10960.4 10983.5 10994.3 10943.5 10944.8 10959.6 10963.8 10981.4 6708.9 8206.9 8283.9 8333.2 8664.4 5463.8 5610.8 5777.0 5777.9 5833.6 9912.3 9969.5 9987.1 10000.7 10087.9 8270.3 10033.5 10289.1 10322.2 10390.7 6532.5 6756.9 7022.7 7172.9 7173.7 7884.0 8121.0 8287.9 8448.5 8516.5 7430.6 7502.0 7540.8 7610.1 7694.5 7306.7 7354.8 7373.3 7375.3 7504.5 6901.3 6918.6 6991.7 6995.3 6995.3 4958.4 4998.8 5014.2 5017.1 5114.6 4838.9 4950.2 5041.0 5182.0 5211.9 13:26:09 INFO - PROCESS | 1921 | 13:26:09 INFO - PROCESS | 1921 | [#4] prototype.html Cycles:180 Average:4421.58 Median:4613.95 stddev:1364.94 (29.6%) stddev-sans-first:1333.03 13:26:09 INFO - PROCESS | 1921 | Values: 287.4 292.3 299.0 304.5 316.0 4565.2 4926.2 5274.9 5322.7 5342.4 4495.6 5185.7 5237.6 5239.6 5258.6 3851.5 4061.7 4079.0 4080.9 4093.7 4093.8 4095.6 4099.7 4109.6 4114.6 3927.3 3970.3 4032.9 4075.1 4076.3 4017.0 4039.4 4055.9 4056.9 4060.8 3528.6 3533.5 3536.8 3544.8 3557.3 4255.4 4831.9 5159.0 5182.6 5188.9 4521.9 4566.9 4572.9 4587.2 4599.6 4854.4 5576.5 5579.5 5597.3 5602.9 3222.4 3227.1 3481.4 3503.4 3816.4 5487.6 5510.5 5553.5 5791.3 5846.4 3357.8 3734.8 3817.5 3855.7 3887.7 3885.0 3888.4 3901.3 3905.8 3917.5 3609.5 4301.8 4423.5 4544.3 4552.9 5199.7 5270.8 5421.8 5458.5 5515.3 1183.6 1303.6 1321.6 1324.6 1326.8 755.7 765.3 797.6 818.8 827.7 4109.9 4388.6 4410.6 4425.3 4461.3 4108.7 4421.1 4479.2 4556.7 4599.5 3846.9 4072.7 4146.5 4245.9 4351.5 2486.6 2691.5 3001.1 3006.5 3024.2 5944.0 5963.6 5964.3 5965.9 6005.7 5185.7 5472.8 5844.1 5905.5 5945.5 5085.0 5384.5 5462.3 5706.5 5722.8 5143.2 5182.6 5272.9 5305.7 5772.7 5461.1 5476.2 5560.4 5630.3 5643.4 5260.8 5539.8 5600.2 5807.6 5970.0 3347.7 3885.7 3990.2 3990.9 3996.7 5681.2 5693.5 5701.4 5738.2 5750.0 5198.5 5497.1 5517.5 5562.7 5562.7 4609.0 5525.1 5533.3 5536.0 5550.0 4570.0 5364.7 5415.8 5420.9 5461.0 4473.7 4681.7 5265.8 5604.3 5700.6 4618.9 5586.9 5592.9 5593.6 5614.7 13:26:09 INFO - PROCESS | 1921 | 13:26:09 INFO - PROCESS | 1921 | [#5] yui.html Cycles:180 Average:23596.63 Median:4507.06 stddev:37989.19 (842.9%) stddev-sans-first:38056.82 13:26:09 INFO - PROCESS | 1921 | Values: 686.3 720.0 765.2 766.8 816.7 15128.4 15767.6 15789.7 15793.8 15807.8 14113.1 14249.7 14262.9 14278.3 14298.2 687.9 712.4 715.4 845.2 852.2 881.4 881.6 886.4 887.7 890.1 911.3 927.8 929.4 929.7 930.2 921.0 923.7 925.7 926.0 929.7 533.8 553.1 653.9 691.1 692.2 14449.8 14910.9 14914.7 14939.5 14970.8 13145.1 13321.9 13466.3 13523.4 13974.5 9974.7 12081.5 12228.3 12509.2 12892.0 4429.9 4460.0 4491.5 4522.7 4679.9 109438.0 110742.4 111737.4 112450.4 112564.0 9664.6 10771.6 10826.8 10959.8 11033.0 21046.5 21063.4 21090.5 21093.5 21144.9 7695.0 7979.6 8101.7 8105.3 8110.8 4446.2 4473.4 4563.3 4566.0 4657.5 3439.3 3554.7 3555.6 3557.9 3561.7 664.2 671.4 672.0 672.3 674.7 617.6 620.3 621.5 631.9 638.3 7938.1 8802.6 8908.2 8944.8 9128.5 533.6 595.3 607.6 636.1 638.2 2975.9 3661.9 3685.6 3759.2 3853.5 89651.6 92157.0 130027.3 144874.0 160770.0 125315.4 126677.0 126958.4 127378.0 130246.3 106923.0 110716.3 111626.0 111858.0 114521.0 8059.7 8394.6 8452.1 8555.7 8746.3 56501.2 58535.0 63175.0 64597.0 64732.0 77246.8 92270.5 93861.0 94091.5 95705.0 10411.4 10554.5 10591.8 10615.6 10963.0 11205.1 11502.0 11606.3 11621.1 12120.6 10957.3 11266.0 11267.8 11370.9 11498.8 11014.6 11213.8 11314.9 11572.0 11685.0 10569.2 10832.8 10864.6 10876.8 11030.4 9240.2 9385.7 9432.9 9515.2 9899.5 7235.6 8975.0 8977.6 9337.7 9346.7 13:26:09 INFO - PROCESS | 1921 | -------- Summary: end -------- 13:26:09 INFO - PROCESS | 1921 | 13:26:10 INFO - TEST-INFO | 1921: exit 0 13:26:10 INFO - TEST-OK | dromaeo_css | took 1117479ms 13:26:10 INFO - TEST-START | kraken 13:26:10 INFO - operating with platform_type : mac_ 13:26:10 INFO - Initialising browser for kraken test... 13:26:10 INFO - TEST-INFO | started process 1925 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmp20X1Dk/profile http://localhost:51428/getInfo.html) 13:26:12 INFO - PROCESS | 1925 | __metrics Screen width/height:1600/1200 13:26:12 INFO - PROCESS | 1925 | colorDepth:24 13:26:12 INFO - PROCESS | 1925 | Browser inner width/height: 1024/689 13:26:12 INFO - PROCESS | 1925 | __metrics 13:26:12 INFO - PROCESS | 1925 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:26:12 INFO - PROCESS | 1925 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:26:13 INFO - TEST-INFO | 1925: exit 0 13:26:13 INFO - Browser initialized. 13:26:13 INFO - Running cycle 1/1 for kraken test... 13:26:13 INFO - TEST-INFO | started process 1926 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmp20X1Dk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:26:25 INFO - PROCESS | 1926 | RSS: Main: 244248576 13:26:25 INFO - PROCESS | 1926 | 13:26:28 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:51428/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51428/tests/kraken/driver.html?testName=audio-beat-detection) 13:26:29 INFO - PROCESS | 1926 | RSS: Main: 294752256 13:26:29 INFO - PROCESS | 1926 | 13:26:32 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:51428/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51428/tests/kraken/driver.html?testName=audio-dft) 13:26:32 INFO - PROCESS | 1926 | RSS: Main: 293306368 13:26:32 INFO - PROCESS | 1926 | 13:26:36 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:51428/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51428/tests/kraken/driver.html?testName=audio-fft) 13:26:36 INFO - PROCESS | 1926 | RSS: Main: 383905792 13:26:36 INFO - PROCESS | 1926 | 13:26:38 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:51428/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51428/tests/kraken/driver.html?testName=audio-oscillator) 13:26:39 INFO - PROCESS | 1926 | RSS: Main: 286785536 13:26:39 INFO - PROCESS | 1926 | 13:26:41 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:51428/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51428/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:26:42 INFO - PROCESS | 1926 | RSS: Main: 291532800 13:26:42 INFO - PROCESS | 1926 | 13:26:44 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:51428/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51428/tests/kraken/driver.html?testName=imaging-darkroom) 13:26:45 INFO - PROCESS | 1926 | RSS: Main: 328409088 13:26:45 INFO - PROCESS | 1926 | 13:26:48 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:51428/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51428/tests/kraken/driver.html?testName=imaging-desaturate) 13:26:49 INFO - PROCESS | 1926 | RSS: Main: 329940992 13:26:49 INFO - PROCESS | 1926 | 13:26:51 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:51428/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51428/tests/kraken/driver.html?testName=json-parse-financial) 13:26:52 INFO - PROCESS | 1926 | RSS: Main: 329998336 13:26:52 INFO - PROCESS | 1926 | 13:26:53 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:51428/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51428/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:26:54 INFO - PROCESS | 1926 | RSS: Main: 305045504 13:26:54 INFO - PROCESS | 1926 | 13:26:56 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:51428/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51428/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:26:57 INFO - PROCESS | 1926 | RSS: Main: 306442240 13:26:57 INFO - PROCESS | 1926 | 13:26:59 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:51428/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51428/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:27:00 INFO - PROCESS | 1926 | RSS: Main: 305954816 13:27:00 INFO - PROCESS | 1926 | 13:27:02 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:51428/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51428/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:27:03 INFO - PROCESS | 1926 | RSS: Main: 316915712 13:27:03 INFO - PROCESS | 1926 | 13:27:06 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:51428/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51428/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:27:07 INFO - PROCESS | 1926 | RSS: Main: 318754816 13:27:07 INFO - PROCESS | 1926 | 13:27:08 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:51428/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51428/tests/kraken/driver.html?testName=ai-astar) 13:27:09 INFO - PROCESS | 1926 | RSS: Main: 362651648 13:27:09 INFO - PROCESS | 1926 | 13:27:09 INFO - PROCESS | 1926 | __start_tp_report 13:27:09 INFO - PROCESS | 1926 | _x_x_mozilla_page_load 13:27:09 INFO - PROCESS | 1926 | _x_x_mozilla_page_load_details 13:27:09 INFO - PROCESS | 1926 | |i|pagename|runs| 13:27:09 INFO - PROCESS | 1926 | |0;ai-astar;112;114;113;112;111;114;112;113;113;113 13:27:09 INFO - PROCESS | 1926 | |1;audio-beat-detection;145;106;105;143;108;108;108;121;169;133 13:27:09 INFO - PROCESS | 1926 | |2;audio-dft;184;183;183;186;181;183;180;182;182;182 13:27:09 INFO - PROCESS | 1926 | |3;audio-fft;78;59;58;89;62;59;59;59;83;58 13:27:09 INFO - PROCESS | 1926 | |4;audio-oscillator;84;72;117;98;77;69;91;68;68;89 13:27:09 INFO - PROCESS | 1926 | |5;imaging-gaussian-blur;87;85;87;85;87;85;88;85;87;84 13:27:09 INFO - PROCESS | 1926 | |6;imaging-darkroom;93;92;93;94;94;94;93;93;94;108 13:27:09 INFO - PROCESS | 1926 | |7;imaging-desaturate;84;85;84;84;83;84;83;84;84;84 13:27:09 INFO - PROCESS | 1926 | |8;json-parse-financial;56;56;53;54;54;54;56;55;55;54 13:27:09 INFO - PROCESS | 1926 | |9;json-stringify-tinderbox;69;74;79;72;70;64;69;70;68;70 13:27:09 INFO - PROCESS | 1926 | |10;stanford-crypto-aes;94;90;105;104;84;85;83;87;87;90 13:27:09 INFO - PROCESS | 1926 | |11;stanford-crypto-ccm;139;139;136;126;170;150;153;139;169;137 13:27:09 INFO - PROCESS | 1926 | |12;stanford-crypto-pbkdf2;171;168;165;167;168;170;168;168;166;180 13:27:09 INFO - PROCESS | 1926 | |13;stanford-crypto-sha256-iterative;68;68;69;68;69;66;67;67;68;67 13:27:09 INFO - PROCESS | 1926 | __end_tp_report 13:27:09 INFO - PROCESS | 1926 | __start_cc_report 13:27:09 INFO - PROCESS | 1926 | _x_x_mozilla_cycle_collect,1953 13:27:09 INFO - PROCESS | 1926 | __end_cc_report 13:27:09 INFO - PROCESS | 1926 | __startTimestamp1475180829089__endTimestamp 13:27:09 INFO - PROCESS | 1926 | 13:27:09 INFO - PROCESS | 1926 | ------- Summary: start ------- 13:27:09 INFO - PROCESS | 1926 | Number of tests: 14 13:27:09 INFO - PROCESS | 1926 | 13:27:09 INFO - PROCESS | 1926 | [#0] ai-astar Cycles:10 Average:112.70 Median:113.00 stddev:0.95 (0.8%) stddev-sans-first:0.97 13:27:09 INFO - PROCESS | 1926 | Values: 112.0 114.0 113.0 112.0 111.0 114.0 112.0 113.0 113.0 113.0 13:27:09 INFO - PROCESS | 1926 | 13:27:09 INFO - PROCESS | 1926 | [#1] audio-beat-detection Cycles:10 Average:124.60 Median:127.00 stddev:22.03 (17.3%) stddev-sans-first:22.09 13:27:09 INFO - PROCESS | 1926 | Values: 145.0 106.0 105.0 143.0 108.0 108.0 108.0 121.0 169.0 133.0 13:27:09 INFO - PROCESS | 1926 | 13:27:09 INFO - PROCESS | 1926 | [#2] audio-dft Cycles:10 Average:182.60 Median:183.00 stddev:1.65 (0.9%) stddev-sans-first:1.67 13:27:09 INFO - PROCESS | 1926 | Values: 184.0 183.0 183.0 186.0 181.0 183.0 180.0 182.0 182.0 182.0 13:27:09 INFO - PROCESS | 1926 | 13:27:09 INFO - PROCESS | 1926 | [#3] audio-fft Cycles:10 Average:66.40 Median:60.50 stddev:12.02 (19.9%) stddev-sans-first:11.99 13:27:09 INFO - PROCESS | 1926 | Values: 78.0 59.0 58.0 89.0 62.0 59.0 59.0 59.0 83.0 58.0 13:27:09 INFO - PROCESS | 1926 | 13:27:09 INFO - PROCESS | 1926 | [#4] audio-oscillator Cycles:10 Average:83.30 Median:80.50 stddev:15.93 (19.8%) stddev-sans-first:16.90 13:27:09 INFO - PROCESS | 1926 | Values: 84.0 72.0 117.0 98.0 77.0 69.0 91.0 68.0 68.0 89.0 13:27:09 INFO - PROCESS | 1926 | 13:27:09 INFO - PROCESS | 1926 | [#5] imaging-gaussian-blur Cycles:10 Average:86.00 Median:87.00 stddev:1.33 (1.5%) stddev-sans-first:1.36 13:27:09 INFO - PROCESS | 1926 | Values: 87.0 85.0 87.0 85.0 87.0 85.0 88.0 85.0 87.0 84.0 13:27:09 INFO - PROCESS | 1926 | 13:27:09 INFO - PROCESS | 1926 | [#6] imaging-darkroom Cycles:10 Average:94.80 Median:93.50 stddev:4.69 (5.0%) stddev-sans-first:4.92 13:27:09 INFO - PROCESS | 1926 | Values: 93.0 92.0 93.0 94.0 94.0 94.0 93.0 93.0 94.0 108.0 13:27:09 INFO - PROCESS | 1926 | 13:27:09 INFO - PROCESS | 1926 | [#7] imaging-desaturate Cycles:10 Average:83.90 Median:84.00 stddev:0.57 (0.7%) stddev-sans-first:0.60 13:27:09 INFO - PROCESS | 1926 | Values: 84.0 85.0 84.0 84.0 83.0 84.0 83.0 84.0 84.0 84.0 13:27:09 INFO - PROCESS | 1926 | 13:27:09 INFO - PROCESS | 1926 | [#8] json-parse-financial Cycles:10 Average:54.70 Median:55.00 stddev:1.06 (1.9%) stddev-sans-first:1.01 13:27:09 INFO - PROCESS | 1926 | Values: 56.0 56.0 53.0 54.0 54.0 54.0 56.0 55.0 55.0 54.0 13:27:09 INFO - PROCESS | 1926 | 13:27:09 INFO - PROCESS | 1926 | [#9] json-stringify-tinderbox Cycles:10 Average:70.50 Median:70.00 stddev:3.95 (5.6%) stddev-sans-first:4.15 13:27:09 INFO - PROCESS | 1926 | Values: 69.0 74.0 79.0 72.0 70.0 64.0 69.0 70.0 68.0 70.0 13:27:09 INFO - PROCESS | 1926 | 13:27:09 INFO - PROCESS | 1926 | [#10] stanford-crypto-aes Cycles:10 Average:90.90 Median:87.00 stddev:7.87 (9.0%) stddev-sans-first:8.26 13:27:09 INFO - PROCESS | 1926 | Values: 94.0 90.0 105.0 104.0 84.0 85.0 83.0 87.0 87.0 90.0 13:27:09 INFO - PROCESS | 1926 | 13:27:09 INFO - PROCESS | 1926 | [#11] stanford-crypto-ccm Cycles:10 Average:145.80 Median:144.50 stddev:14.52 (10.0%) stddev-sans-first:15.19 13:27:09 INFO - PROCESS | 1926 | Values: 139.0 139.0 136.0 126.0 170.0 150.0 153.0 139.0 169.0 137.0 13:27:09 INFO - PROCESS | 1926 | 13:27:09 INFO - PROCESS | 1926 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.10 Median:168.00 stddev:4.20 (2.5%) stddev-sans-first:4.40 13:27:09 INFO - PROCESS | 1926 | Values: 171.0 168.0 165.0 167.0 168.0 170.0 168.0 168.0 166.0 180.0 13:27:09 INFO - PROCESS | 1926 | 13:27:09 INFO - PROCESS | 1926 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.70 Median:68.00 stddev:0.95 (1.4%) stddev-sans-first:1.00 13:27:09 INFO - PROCESS | 1926 | Values: 68.0 68.0 69.0 68.0 69.0 66.0 67.0 67.0 68.0 67.0 13:27:09 INFO - PROCESS | 1926 | -------- Summary: end -------- 13:27:09 INFO - PROCESS | 1926 | 13:27:09 INFO - TEST-INFO | 1926: exit 0 13:27:09 INFO - TEST-OK | kraken | took 59407ms 13:27:09 INFO - SUITE-END | took 1176s 13:27:10 INFO - Completed test suite (00:19:37) 13:27:10 INFO - javascript benchmark 13:27:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3101.9763478052237, 3261.168402057475, 3315.950260746089, 3319.4522903720886, 3343.0973637117995, 5546.084895992157, 5609.0, 5645.661260324383, 5676.914846277309, 5703.77184912604, 5638.548916086714, 5647.519960803336, 5729.3411257705375, 5746.310442746866, 5761.539076873851, 5051.494850514956, 5103.0, 5117.590592752571, 5122.462141475147, 5183.844484665466, 5179.741012949356, 5282.23407605897, 5339.0, 5345.7594408251525, 5357.678539287635, 5251.10731175701, 5253.816116435925, 5272.23552584875, 5280.445553216915, 5333.733313334318, 5105.566026887719, 5144.742762861842, 5170.586353091744, 5181.35233095863, 5185.196294574309, 4264.808083636244, 5072.34059572257, 5140.974295128538, 5143.614228932845, 5147.124988751922, 5575.916361254584, 5648.830535083954, 5670.177824215507, 5673.26247587815, 5708.286464191976, 6110.114033465167, 6168.784092556739, 6185.103160041769, 6190.0405437157315, 6191.752329906798, 6131.080337949299, 6184.257889053298, 6247.843803904939, 6268.623882567061, 6269.185005949197, 5661.575381846378, 5695.316562012585, 5704.800331988402, 5747.25285712863, 5767.538596912237, 6244.0321750128805, 6252.531060170505, 6259.436650701458, 6267.467265282411, 6271.27880293763, 6093.482054025458, 6108.633481991006, 6111.388861113942, 6117.541184411187, 6121.969390153021, 6087.756489740449, 6099.370313949828, 6100.540239930047, 6138.631682099089, 6239.0, 6102.549845102896, 6145.324014358506, 6148.524354154971, 6176.629402235881, 6179.134921110958, 6045.881511920309, 6179.783707570304, 6181.474574661204, 6198.597091189058, 6222.62664240147, 4804.639652026112, 4874.439439464436, 4895.388076490439, 4910.656254062182, 4962.826301079445, 4375.824967001348, 4428.512863585003, 4442.666799990014, 4462.0183559616835, 4479.820807167677, 5830.47525722687, 5831.125331200354, 5835.183074369592, 5878.706064696748, 5889.793857214977, 6216.378362163748, 6244.594101383395, 6247.156633854463, 6287.371262873676, 6297.464715499143, 5635.971820140873, 5704.201411802351, 5709.371969083398, 5744.368119506851, 5821.7380217890295, 5321.0, 5368.32895888014, 5369.5972802040005, 5387.838364849061, 5419.701916394557, 6274.968625156845, 6286.15136956505, 6293.056041593798, 6314.842128946813, 6324.956382196886, 6104.053871649901, 6203.100550420254, 6256.343083976117, 6260.530460215411, 6266.843328916813, 6116.092308610808, 6209.285932117684, 6214.130021796862, 6241.157443745218, 6244.094606282155, 6098.855844323317, 6144.0, 6144.969275153596, 6150.630962142286, 6155.353687862889, 6138.0, 6175.166352542349, 6247.781327653511, 6252.124702541557, 6260.624362538262, 4700.6239500839165, 5902.2622172228475, 5961.225040744675, 5998.25021872266, 6071.7571297147615, 5257.843274479608, 5979.1928089707335, 6046.09308603713, 6078.270607527125, 6103.42017508336, 5676.545876329967, 5880.000399932107, 5886.205362276038, 5887.116932460165, 5891.1457838612305, 5899.17411562373, 5970.9701451492465, 6015.699215039318, 6045.365236650088, 6063.454289114001, 5809.8547536311935, 5813.709314534171, 5831.883362332815, 5946.13781001761, 5948.851278717893], "value": 5652.676521062214, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [469.38979326875057, 485.72799232429844, 500.91551790373836, 533.2028617217261, 543.3751186135945, 31281.374372512564, 41199.7640070798, 42398.0, 42443.0, 44407.0, 35640.82179589098, 43081.569184308144, 43206.0, 43515.0, 43552.78223608877, 9721.319507634451, 9794.314397992144, 9839.212862970946, 9943.651972180975, 9954.253430992669, 7570.583617901013, 8710.651573937035, 8898.822023559525, 9026.413283136608, 9029.819403611924, 12113.21264117834, 12380.380980950917, 12394.318312492855, 12493.812592811115, 12511.499540018389, 12235.08236882233, 12340.01279897606, 12354.876451235463, 12356.0, 12361.567345142877, 12086.576969806014, 12127.151099423045, 12146.392680365947, 12161.817572736416, 12169.878301217052, 41116.0, 41309.380359294635, 41351.1729765403, 41380.79309603433, 41512.58487415148, 3143.6384815746023, 3161.2254997525524, 3165.9208519786957, 3175.491921292582, 3254.1539199807985, 31427.84286078578, 31710.841445792856, 31726.682733172835, 32346.838265808754, 32870.01389958305, 2788.4144329690585, 3242.8540715667614, 3270.591176102987, 3271.8854840080485, 3360.31113621707, 479033.0, 479238.0, 480506.0, 482501.58749206027, 482728.0, 61587.0, 61885.38114618796, 61937.69031154906, 61997.380026199164, 62155.0, 35341.0, 35815.28369432597, 35983.82008089995, 37461.25077498435, 37565.62434375676, 16237.269322880262, 16299.348026079062, 16350.918245408697, 16384.0169589825, 16387.918060409622, 12411.317377544141, 12476.064295177897, 12489.875101249054, 12497.312647804458, 12530.0, 16870.0, 17207.053612051208, 17219.311227551007, 18995.525111872037, 19266.223730700076, 6759.628220447923, 7700.9614951926005, 7897.24976127267, 7987.480813747088, 8000.79998000043, 842.3513894301353, 884.4251236696199, 948.4546385828177, 974.0503009565758, 1052.0584077250817, 28857.56713649297, 29749.0, 29870.25324366908, 30613.0, 30652.773889044634, 1034.5086084109987, 1077.8275475923972, 1105.5798796457295, 1126.3298337489182, 1148.2937993134162, 13557.864421355858, 15051.0, 15724.921375393049, 15839.921292316487, 15919.363225471083, 658201.0, 661680.0, 662884.0, 667000.0, 667662.0, 57569.71215143897, 58275.0, 58284.54288642818, 58342.4165758337, 58399.70800145972, 1347.2455424962052, 1373.4300265389752, 1382.495389182921, 1382.8409732880646, 1392.095138160204, 27246.72753272442, 29318.41363172682, 29478.705212947596, 30303.545446817876, 30460.0, 48621.75689121673, 51650.48349516457, 52160.95678086493, 52186.73906630594, 52344.69138271573, 566895.0, 569335.0, 569592.0, 570885.0, 571918.0, 25917.870410647825, 26073.348166295236, 26271.868640656674, 26487.867560662846, 26496.337591560365, 37798.62201377951, 38581.8425447237, 45652.315215271134, 48202.27696584624, 48504.51495485, 38947.2210555793, 39037.8048109769, 39047.80476097601, 39199.60800392074, 40718.0, 39178.2164356717, 39339.409908850816, 40183.59816401799, 40645.79677101596, 40804.0, 32099.839500802347, 36873.63126368702, 37518.87443376703, 38350.0, 38918.41622375723, 32616.0, 33263.168420789676, 34041.31917361688, 34229.81180922075, 34422.48366274458, 24853.25440236796, 25081.0, 25129.497410051332, 25646.487070258863, 26025.479490409707], "value": 19989.359172223012, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.9634036596339, 371.50404210379145, 375.68067142928504, 386.1157948298397, 396.35790020167315, 19519.804801951996, 21002.26492072774, 21441.0, 21760.564788704218, 21927.451813704665, 20497.795022049773, 20923.0, 21251.25620603279, 21405.785942140574, 21491.6776248356, 5841.094630332305, 5920.822375328747, 6041.939580604204, 6100.81697549072, 6103.0, 4201.264778663737, 4416.779161041951, 4423.889402764925, 5984.072468767348, 6109.938900610981, 6458.128152699396, 6769.390754832064, 6799.388055075042, 6810.3189681032, 6810.761623343184, 6500.382463665944, 6691.0, 6752.054712340277, 6753.561018533779, 6763.492738044617, 5728.198052272685, 5755.510781583571, 5828.941710582924, 5835.533157347402, 5850.444207800252, 26046.86976565124, 26105.608415873776, 26281.605775913376, 26287.868560657073, 26527.33681657974, 2014.4158194123686, 2201.5816994771103, 2346.4016675747575, 2467.7655622715815, 2795.161451564543, 10026.0, 10559.577616895314, 10587.84118238227, 12748.0, 15342.769858452131, 1887.9150438230313, 1934.6904495280828, 1938.8158124978104, 1940.9126589303514, 1965.7542807149107, 97286.51356743241, 118916.0, 120430.79569204371, 121820.78179218184, 122895.3855230727, 11386.94306528462, 11408.0, 11557.479913403917, 11588.304701717923, 11744.17790754639, 14525.854741452662, 14529.78205326921, 14542.563723088324, 14546.709065818624, 14588.489402870848, 8192.54940978252, 8459.788505287293, 8486.108958559385, 8596.097409772008, 8616.956915215384, 7264.273572642694, 8338.0, 8382.83234335322, 8390.916090839135, 8538.0, 3847.942280865789, 4370.781460926941, 4508.954910450919, 4528.818847246139, 4598.747068911242, 1574.7795308656798, 1766.381766381756, 1804.3504338438152, 1871.822176893193, 1954.7263383126374, 782.4796510320687, 815.3110621524802, 954.4416516337881, 1000.3947611694965, 1024.293237666008, 19969.900150499154, 21276.893615531822, 21309.46726331823, 21402.357929262147, 21404.678929815753, 894.4722613657973, 901.6032945503957, 926.420986883198, 946.4888959961681, 962.6823148361024, 10443.738906527398, 11201.327920324806, 11237.438128093563, 11258.43707814606, 11280.774384512264, 550342.0, 552633.0, 554330.2283488556, 572433.0, 577303.0, 125571.60499382477, 131138.34430827975, 135729.0, 136187.0, 137732.622673774, 948.4498990585444, 960.9807803843884, 966.3815158298695, 967.2407160379069, 968.733598260484, 18303.633927321644, 18463.353782617545, 18665.34671286553, 18733.437996860117, 18846.52883677919, 39476.80261598789, 54065.459345406045, 54174.00318425574, 54554.454455454936, 55093.89812203814, 75534.24465755271, 76112.0, 77348.2265177341, 79071.0, 82665.17334826816, 5375.919361209507, 7829.765107046797, 8057.798555036171, 8261.0, 8306.54314012735, 11216.270942388719, 12907.290099044643, 12994.80507792365, 13317.66705832362, 13411.262380569162, 5280.973595132, 5311.601629877698, 8625.266852317623, 11112.721892011956, 11563.42182890869, 10481.947590262, 11105.389203593557, 11243.269187502787, 11445.599404020819, 11523.596674116367, 9713.757156070873, 11345.773084538429, 11572.18994670365, 11830.940845295718, 11864.0, 8194.057683366489, 8306.62620182081, 8321.377331420315, 8399.580020999048, 8421.284190843848, 9517.058926298561, 11615.419229038345, 11708.180427370344, 11774.646760597196, 11835.763284734428], "value": 9571.583928502801, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1407.8099456573364, 1462.4881291547958, 1532.5632194824477, 1638.3774165816988, 1919.846412287017, 8441.198086181817, 8927.910720892789, 8937.285017198625, 8942.865857012142, 8977.640894364225, 7825.295723384908, 8033.47782394144, 8044.3966702497255, 8475.660973561065, 8715.172058654418, 2309.734380546238, 2354.7409784923643, 2356.6818479505305, 2358.162852187466, 2359.103540654549, 2316.513532158249, 2348.1077190667525, 2352.741198468167, 2361.114582031738, 2366.9763302367014, 2335.1710142899196, 2349.0955981946904, 2358.2099996501192, 2360.9881950590225, 2364.574376612209, 2320.582295186866, 2333.2067097186955, 2336.731275903259, 2340.5201933603657, 2340.590396680574, 2330.347502699247, 2330.6249312905466, 2332.8017118544776, 2333.101755824003, 2339.6841426407386, 8790.692325768567, 8992.77518062047, 8996.145366190201, 8999.19007289347, 9008.279337652972, 5209.479052094798, 5356.062689029443, 5360.142377219665, 5363.168708850135, 5373.5163835254625, 3094.5048792193365, 3474.687278144954, 3563.055790215595, 3679.521662183899, 3705.6479634434686, 919.3564504846568, 924.8890133184061, 1072.5441687282873, 1073.9677809665645, 1094.7919895219882, 11240.718982025433, 11607.245529040585, 11608.187426880122, 11664.883351166465, 11761.941190293994, 4357.694961352739, 4433.689641725113, 4600.7469589172915, 4615.515370886076, 4625.95374046262, 9129.950055743542, 9133.497657628894, 9148.130927561871, 9153.67962121336, 9160.908390916005, 5228.790848366023, 5232.529072383428, 5239.240310155083, 5240.0567897778765, 5244.187150991603, 4415.315626077963, 4568.725876447424, 4645.790939407735, 4917.139500587383, 4918.2376731606655, 3653.5615726113037, 3669.889903302905, 3787.450819631193, 3795.8102094895144, 3800.1449673823167, 1127.1490025031048, 1145.9484323205309, 1153.26191237608, 1154.359330571524, 1158.1603337580186, 832.8673004713585, 842.0737189092072, 882.7793051737066, 907.2243232036645, 931.4923366765129, 5556.666600004012, 5759.424057594207, 5820.068788993741, 5834.212381328466, 6013.458788709037, 782.2529484342439, 871.5293741379711, 871.7776966873407, 898.2723993565103, 932.8554074118523, 3791.526059242595, 3794.658480736747, 3863.5750067492554, 3890.922181556353, 3933.331333673282, 10524.36853788775, 10944.56221751137, 10960.397178155119, 10983.505742241618, 10994.34033957965, 10943.507542160622, 10944.835827462595, 10959.616413425492, 10963.83554246687, 10981.39602321864, 6708.932910670928, 8206.917930820735, 8283.875741863756, 8333.208345207195, 8664.393492455467, 5463.754131064191, 5610.775568977195, 5777.0179069557435, 5777.884442311046, 5833.620814647034, 9912.256580756617, 9969.451680157661, 9987.101160895263, 10000.699979000641, 10087.89912100899, 8270.2556769891, 10033.498325083863, 10289.125424338716, 10322.2258330624, 10390.688279351632, 6532.510061745293, 6756.932430675631, 7022.683979221049, 7172.856542869218, 7173.713051477883, 7884.0, 8121.0, 8287.875681864656, 8448.450850694686, 8516.489010659381, 7430.554166750012, 7502.024736784183, 7540.811479713051, 7610.12483564375, 7694.461387702807, 7306.744263950736, 7354.77935661931, 7373.262673732798, 7375.262473752797, 7504.512206706547, 6901.344372284625, 6918.5502942308585, 6991.720331186899, 6995.300469952964, 6995.300469952964, 4958.355413796184, 4998.750062496933, 5014.222795466709, 5017.096922553976, 5114.590832733298, 4838.854834354975, 4950.232713929347, 5041.0, 5182.0, 5211.869703257449], "value": 4513.753747852543, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [287.41367610075446, 292.27807315929647, 298.99999999999994, 304.46414310812975, 316.0376653091337, 4565.246734288842, 4926.236433352827, 5274.894502109955, 5322.680639161653, 5342.439043900388, 4495.595396414322, 5185.714785686786, 5237.580993520519, 5239.633225674204, 5258.6318957672975, 3851.4993050903413, 4061.715679902408, 4079.0, 4080.9387859182134, 4093.672506199497, 4093.774842383668, 4095.5699651536606, 4099.651529619986, 4109.623276202461, 4114.6091121343425, 3927.3323534998976, 3970.2655008823317, 4032.8588499402526, 4075.1238483725992, 4076.32740597801, 4016.9598304016877, 4039.3537034074407, 4055.858044968427, 4056.898577535556, 4060.7969601520103, 3528.6118526962014, 3533.5406397168204, 3536.840842162109, 3544.8227588620466, 3557.2707594942976, 4255.425517555122, 4831.9033619327765, 5158.97420512895, 5182.585393168538, 5188.870278243036, 4521.852120242891, 4566.88582785433, 4572.919321708648, 4587.24310488769, 4599.586037256663, 4854.441739199967, 5576.525995290405, 5579.497845193952, 5597.320803758856, 5602.887942241132, 3222.4038552867796, 3227.128265605943, 3481.415955740132, 3503.3693935091674, 3816.3893776995824, 5487.64330318524, 5510.476504732044, 5553.527950124244, 5791.307042225385, 5846.3861294563885, 3357.78174418662, 3734.7572407793405, 3817.503724515795, 3855.652991230803, 3887.688984881203, 3885.0, 3888.3972984187494, 3901.258760835432, 3905.824237909301, 3917.4515567820526, 3609.4946707460977, 4301.827926882889, 4423.4912985006495, 4544.318602117238, 4552.884543286849, 5199.726067113581, 5270.789168433296, 5421.756020979065, 5458.4814442627885, 5515.25544051556, 1183.6153250193722, 1303.6219496346143, 1321.623337348851, 1324.5562736483193, 1326.8275124233787, 755.7166062726477, 765.3303359560384, 797.5812698333422, 818.7748369198519, 827.664795757719, 4109.856155034619, 4388.5830846069575, 4410.580994805488, 4425.3140763181755, 4461.286194208911, 4108.712390132722, 4421.137878113737, 4479.216137175981, 4556.658250631216, 4599.4710608280475, 3846.9038274043373, 4072.7352722073565, 4146.460960075171, 4245.8513952011535, 4351.49704487884, 2486.627005949122, 2691.4751623433635, 3001.1446737680094, 3006.5189569668746, 3024.1532370936175, 5944.0, 5963.568743501529, 5964.314103878023, 5965.940340596625, 6005.6997150142315, 5185.747686896709, 5472.753726082336, 5844.12338149281, 5905.46850783423, 5945.494632956247, 5084.9745751271, 5384.488473595002, 5462.317210348707, 5706.514946229534, 5722.799702010409, 5143.2285157225215, 5182.6113041521285, 5272.920906186334, 5305.65513241638, 5772.6536407815665, 5461.1226029519485, 5476.2333273342565, 5560.387487628543, 5630.324361076533, 5643.379228284967, 5260.842174734764, 5539.833804985857, 5600.215969764155, 5807.593468457337, 5969.970150149222, 3347.727863411888, 3885.68914486846, 3990.201959608148, 3990.9201815964097, 3996.741026576619, 5681.2330335404195, 5693.544516438592, 5701.372848986691, 5738.196652468741, 5749.9712501437225, 5198.4541623130535, 5497.092979658471, 5517.503424691797, 5562.749676264497, 5562.749676264497, 4608.953910460987, 5525.143602741581, 5533.320341972543, 5536.0, 5550.0, 4569.977150114361, 5364.719994002611, 5415.781449173905, 5420.940042783717, 5461.017016936989, 4473.686841921034, 4681.712529054537, 5265.814182419734, 5604.271444712162, 5700.572457065654, 4618.944569666601, 5586.944130558642, 5592.888142237051, 5593.580481463825, 5614.719264036864], "value": 3981.0662656218246, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [686.3322127230828, 719.9532030418019, 765.1659690936877, 766.7584710816093, 816.7427260412969, 15128.394864205433, 15767.60580985476, 15789.684206315867, 15793.763093553605, 15807.84192158078, 14113.082649627768, 14249.71500569988, 14262.928685356559, 14278.2860856957, 14298.213598252092, 687.924328323884, 712.3659942651045, 715.39906478558, 845.2012847858776, 852.2457625001872, 881.4446898453965, 881.6385282034367, 886.352962337494, 887.6981826179098, 890.0565400675305, 911.2573252798948, 927.7866090799121, 929.4005366538579, 929.7303781903274, 930.2325581395343, 920.9999999999967, 923.7090316550332, 925.6852670092134, 925.9629614815399, 929.7024952015357, 533.8265063854263, 553.1177771454524, 653.8986457099157, 691.1015679616529, 692.1693967239333, 14449.78325325121, 14910.925445372812, 14914.70170596582, 14939.477118300807, 14970.775438368433, 13145.112932240674, 13321.866781332159, 13466.326683665777, 13523.39144738489, 13974.510892118727, 9974.650887218913, 12081.516739330416, 12228.327441990687, 12509.249445033327, 12892.033097517724, 4429.867103986886, 4460.0, 4491.46102467706, 4522.66080043998, 4679.88300292492, 109438.0, 110742.44628776805, 111737.44131279373, 112450.43774781155, 112564.0, 9664.613415463444, 10771.6229931952, 10826.837597436044, 10959.83560246597, 11033.0, 21046.473838153863, 21063.368098957057, 21090.472738181667, 21093.472663183544, 21144.89427552883, 7695.0, 7979.601019948979, 8101.716439924692, 8105.31104856082, 8110.756677299691, 4446.155230506258, 4473.373727678128, 4563.338315944169, 4565.977170114194, 4657.46439159501, 3439.3465241604017, 3554.733394995386, 3555.626659200747, 3557.857685692595, 3561.6972557332397, 664.1598378051768, 671.4427025568766, 672.0188524753817, 672.2638710611873, 674.6694119881223, 617.6109051297653, 620.3021600699213, 621.5027977617978, 631.8736252749469, 638.2946843737695, 7938.126806051329, 8802.60388282516, 8908.153725396078, 8944.821103578022, 9128.49793261377, 533.6211289984077, 595.2648479128273, 607.5807692692028, 636.1062706896818, 638.2022471910112, 2975.9256018599276, 3661.8718344857803, 3685.594584595692, 3759.154190307159, 3853.537575490959, 89651.55174224087, 92157.0, 130027.34986325009, 144874.0, 160770.0, 125315.3734231323, 126677.0, 126958.36520817522, 127378.0, 130246.34876825556, 106923.0, 110716.33925491125, 111626.0, 111858.0, 114521.0, 8059.677612895419, 8394.580270986304, 8452.131914872434, 8555.657773688983, 8746.30029597618, 56501.152482713616, 58535.0, 63175.0, 64597.0, 64732.029019565685, 77246.84129737946, 92270.53864730634, 93861.0, 94091.52954235185, 95705.04294956962, 10411.375317480975, 10554.525046373084, 10591.788164236827, 10615.575376985144, 10963.0, 11205.103591712481, 11502.02232810187, 11606.251746576803, 11621.070314374661, 12120.60613029513, 10957.287776294515, 11265.986061254527, 11267.830982535104, 11370.94314528422, 11498.770024599295, 11014.614488492864, 11213.775724485628, 11314.886851131712, 11572.016378607912, 11685.0, 10569.154467642416, 10832.783344332911, 10864.619738309122, 10876.836847447137, 11030.393328367018, 9240.168384845396, 9385.718428447157, 9432.858507122535, 9515.191208747057, 9899.50502474859, 7235.63821808918, 8975.0, 8977.596008179778, 9337.71986840396, 9346.672866449642], "value": 7400.9804062318035, "unit": "score"}], "name": "dromaeo_css", "value": 7238.554962357222, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 114.0, 113.0, 112.0, 111.0, 114.0, 112.0, 113.0, 113.0, 113.0], "value": 112.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 106.0, 105.0, 143.0, 108.0, 108.0, 108.0, 121.0, 169.0, 133.0], "value": 124.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 183.0, 186.0, 181.0, 183.0, 180.0, 182.0, 182.0, 182.0], "value": 182.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 59.0, 58.0, 89.0, 62.0, 59.0, 59.0, 59.0, 83.0, 58.0], "value": 66.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 72.0, 117.0, 98.0, 77.0, 69.0, 91.0, 68.0, 68.0, 89.0], "value": 83.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 87.0, 85.0, 87.0, 85.0, 88.0, 85.0, 87.0, 84.0], "value": 86.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 93.0, 94.0, 94.0, 94.0, 93.0, 93.0, 94.0, 108.0], "value": 94.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 84.0, 84.0, 83.0, 84.0, 83.0, 84.0, 84.0, 84.0], "value": 83.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 53.0, 54.0, 54.0, 54.0, 56.0, 55.0, 55.0, 54.0], "value": 54.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 74.0, 79.0, 72.0, 70.0, 64.0, 69.0, 70.0, 68.0, 70.0], "value": 70.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 90.0, 105.0, 104.0, 84.0, 85.0, 83.0, 87.0, 87.0, 90.0], "value": 90.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 139.0, 136.0, 126.0, 170.0, 150.0, 153.0, 139.0, 169.0, 137.0], "value": 145.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 168.0, 165.0, 167.0, 168.0, 170.0, 168.0, 168.0, 166.0, 180.0], "value": 169.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 69.0, 68.0, 69.0, 66.0, 67.0, 67.0, 68.0, 67.0], "value": 67.7, "unit": "score"}], "name": "kraken", "value": 1432.9999999999998, "alertThreshold": 2.0}]} 13:27:10 INFO - Return code: 0 13:27:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:27:10 INFO - # TBPL SUCCESS # 13:27:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:27:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:27:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:27:10 INFO - 2016-09-29 13:27:10.436 screenresolution[1930:14531] starting screenresolution argv=screenresolution get 13:27:10 INFO - 2016-09-29 13:27:10.438 screenresolution[1930:14531] Display 0: 1600x1200x32@60 13:27:10 INFO - 2016-09-29 13:27:10.444 screenresolution[1931:14534] starting screenresolution argv=screenresolution list 13:27:10 INFO - Available Modes on Display 0 13:27:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:27:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:27:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:27:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:27:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:27:10 INFO - Intel Iris: 13:27:10 INFO - Chipset Model: Intel Iris 13:27:10 INFO - Type: GPU 13:27:10 INFO - Bus: Built-In 13:27:10 INFO - VRAM (Dynamic, Max): 1536 MB 13:27:10 INFO - Vendor: Intel (0x8086) 13:27:10 INFO - Device ID: 0x0a2e 13:27:10 INFO - Revision ID: 0x0009 13:27:10 INFO - Displays: 13:27:10 INFO - Display: 13:27:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:27:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:27:10 INFO - Display Serial Number: 1600x1200 60 13:27:10 INFO - Main Display: Yes 13:27:10 INFO - Mirror: Off 13:27:10 INFO - Online: Yes 13:27:10 INFO - Rotation: Supported 13:27:10 INFO - Return code: 0 13:27:10 INFO - Running post-action listener: _resource_record_post_action 13:27:10 INFO - [mozharness: 2016-09-29 20:27:10.680736Z] Finished run-tests step (success) 13:27:10 INFO - Running post-run listener: _resource_record_post_run 13:27:10 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 761856; Write bytes: 706750976; Read time: 13; Write time: 1891 13:27:10 INFO - TinderboxPrint: CPU usage
53.6% 13:27:10 INFO - TinderboxPrint: I/O read bytes / time
761,856 / 13 13:27:10 INFO - TinderboxPrint: I/O write bytes / time
706,750,976 / 1,891 13:27:10 INFO - TinderboxPrint: CPU idle
2,248.0 (46.4%) 13:27:10 INFO - TinderboxPrint: CPU system
968.1 (20.0%) 13:27:10 INFO - TinderboxPrint: CPU user
1,633.5 (33.7%) 13:27:10 INFO - TinderboxPrint: Swap in / out
408,207,360 / 0 13:27:10 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 395440640; Write bytes: 390649344; Read time: 29616; Write time: 716 13:27:10 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 737280; Write bytes: 295175168; Read time: 11; Write time: 1132 13:27:11 INFO - Running post-run listener: _upload_blobber_files 13:27:11 INFO - Blob upload gear active. 13:27:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:27:11 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 13:27:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:27:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:27:11 INFO - (blobuploader) - INFO - Open directory for files ... 13:27:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:27:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:27:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:27:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:27:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:27:12 INFO - (blobuploader) - INFO - Done attempting. 13:27:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:27:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:27:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:27:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:27:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:27:13 INFO - (blobuploader) - INFO - Done attempting. 13:27:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:27:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:27:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:27:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:27:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:27:16 INFO - (blobuploader) - INFO - Done attempting. 13:27:16 INFO - (blobuploader) - INFO - Iteration through files over. 13:27:16 INFO - Return code: 0 13:27:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:27:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:27:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2d0e9e788c53794baf8c625502df54d87dc6c6d83b95d47c1f36dc9dcf788bc4a17667558e8d2c8ad45a10ebe65cc50fd75ab3b15f3fda2efcd195151d7283c6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/bd2d8beddf61118e9feb99b8ccd20232073f14ee51a663acf8000017d35c841ea5d042e85b07cf1c11c6ba5634e0c934f43d5a55fbe53293251624b20f09a549", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2c580eaef2c52bc4b7f5cc03126b9cfa4895b66cbc379927a95218230fe3ad2a551d86116d8d5e20b6e17be7a21e6e71aa7200215f025ed5a60795a7aa16d2f4"} 13:27:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:27:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:27:16 INFO - Contents: 13:27:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2d0e9e788c53794baf8c625502df54d87dc6c6d83b95d47c1f36dc9dcf788bc4a17667558e8d2c8ad45a10ebe65cc50fd75ab3b15f3fda2efcd195151d7283c6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/bd2d8beddf61118e9feb99b8ccd20232073f14ee51a663acf8000017d35c841ea5d042e85b07cf1c11c6ba5634e0c934f43d5a55fbe53293251624b20f09a549", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2c580eaef2c52bc4b7f5cc03126b9cfa4895b66cbc379927a95218230fe3ad2a551d86116d8d5e20b6e17be7a21e6e71aa7200215f025ed5a60795a7aa16d2f4"} 13:27:16 INFO - Running post-run listener: copy_logs_to_upload_dir 13:27:16 INFO - Copying logs to upload dir... 13:27:16 INFO - mkdir: /builds/slave/test/build/upload/logs 13:27:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.624376 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-09-29 13:27:16.539541) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-29 13:27:16.544235) ========= 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.IORhYYDkcy/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.fO6Fo21tn1/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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/2d0e9e788c53794baf8c625502df54d87dc6c6d83b95d47c1f36dc9dcf788bc4a17667558e8d2c8ad45a10ebe65cc50fd75ab3b15f3fda2efcd195151d7283c6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/bd2d8beddf61118e9feb99b8ccd20232073f14ee51a663acf8000017d35c841ea5d042e85b07cf1c11c6ba5634e0c934f43d5a55fbe53293251624b20f09a549", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2c580eaef2c52bc4b7f5cc03126b9cfa4895b66cbc379927a95218230fe3ad2a551d86116d8d5e20b6e17be7a21e6e71aa7200215f025ed5a60795a7aa16d2f4"} build_url:https://queue.taskcluster.net/v1/task/Fd-B-8j9RTmdBhRyqqyY5w/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011114 build_url: 'https://queue.taskcluster.net/v1/task/Fd-B-8j9RTmdBhRyqqyY5w/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/2d0e9e788c53794baf8c625502df54d87dc6c6d83b95d47c1f36dc9dcf788bc4a17667558e8d2c8ad45a10ebe65cc50fd75ab3b15f3fda2efcd195151d7283c6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/bd2d8beddf61118e9feb99b8ccd20232073f14ee51a663acf8000017d35c841ea5d042e85b07cf1c11c6ba5634e0c934f43d5a55fbe53293251624b20f09a549", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2c580eaef2c52bc4b7f5cc03126b9cfa4895b66cbc379927a95218230fe3ad2a551d86116d8d5e20b6e17be7a21e6e71aa7200215f025ed5a60795a7aa16d2f4"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-29 13:27:16.580503) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 13:27:16.580934) ========= 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.IORhYYDkcy/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.fO6Fo21tn1/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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.005265 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 13:27:16.630187) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-29 13:27:16.630525) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-29 13:27:16.630887) ========= ========= Total master_lag: 0.21 =========