builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0231 starttime: 1476096051.5 results: success (0) buildid: 20161010023217 builduid: 2962ba92fbf54c629a99eb85ea905abf revision: 515e6d07c0c23ff4e8ad1530f5562f582b952f77 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-10 03:40:51.500851) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-10 03:40:51.501526) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-10 03:40:51.501995) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-10 03:40:51.527508) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 03:40:51.528007) ========= 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.hVhqwT6oBa/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.tN32UEEISn/Listeners TMPDIR=/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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-10-10 03:40:51-- 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% 34.0M=0s 2016-10-10 03:40:51 (34.0 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.100243 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 03:40:51.660457) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 03:40:51.661033) ========= 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.hVhqwT6oBa/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.tN32UEEISn/Listeners TMPDIR=/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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.021034 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 03:40:51.710960) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-10 03:40:51.711302) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 515e6d07c0c23ff4e8ad1530f5562f582b952f77 --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 515e6d07c0c23ff4e8ad1530f5562f582b952f77 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hVhqwT6oBa/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.tN32UEEISn/Listeners TMPDIR=/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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-10-10 03:40:51,794 truncating revision to first 12 chars 2016-10-10 03:40:51,794 Setting DEBUG logging. 2016-10-10 03:40:51,794 attempt 1/10 2016-10-10 03:40:51,794 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/515e6d07c0c2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-10 03:40:53,763 unpacking tar archive at: autoland-515e6d07c0c2/testing/mozharness/ program finished with exit code 0 elapsedTime=2.230538 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-10 03:40:53.963641) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-10 03:40:53.963977) ========= script_repo_revision: 515e6d07c0c23ff4e8ad1530f5562f582b952f77 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-10 03:40:53.964425) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-10 03:40:53.964693) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-10 03:40:53.982682) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-10 03:40:53.982972) ========= /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.hVhqwT6oBa/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.tN32UEEISn/Listeners TMPDIR=/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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 03:40:54 INFO - MultiFileLogger online at 20161010 03:40:54 in /builds/slave/test 03:40:54 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 03:40:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:40:54 INFO - {'append_to_log': False, 03:40:54 INFO - 'base_work_dir': '/builds/slave/test', 03:40:54 INFO - 'blob_upload_branch': 'Autoland', 03:40:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:40:54 INFO - 'branch': 'Autoland', 03:40:54 INFO - 'buildbot_json_path': 'buildprops.json', 03:40:54 INFO - 'config_files': ('talos/mac_config.py',), 03:40:54 INFO - 'default_actions': ('clobber', 03:40:54 INFO - 'read-buildbot-config', 03:40:54 INFO - 'download-and-extract', 03:40:54 INFO - 'populate-webroot', 03:40:54 INFO - 'create-virtualenv', 03:40:54 INFO - 'install', 03:40:54 INFO - 'run-tests'), 03:40:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:40:54 INFO - 'download_minidump_stackwalk': True, 03:40:54 INFO - 'download_symbols': 'ondemand', 03:40:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:40:54 INFO - 'tooltool.py': '/tools/tooltool.py', 03:40:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:40:54 INFO - '/tools/misc-python/virtualenv.py')}, 03:40:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:40:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:40:54 INFO - 'installer_path': 'installer.exe', 03:40:54 INFO - 'log_level': 'info', 03:40:54 INFO - 'log_name': 'talos', 03:40:54 INFO - 'log_to_console': True, 03:40:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:40:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:40:54 INFO - 'opt_config_files': (), 03:40:54 INFO - 'pip_index': False, 03:40:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:40:54 INFO - 'cmd': ('bash', 03:40:54 INFO - '-c', 03:40:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:40:54 INFO - 'enabled': True, 03:40:54 INFO - 'halt_on_failure': False, 03:40:54 INFO - 'name': 'check_screen_resolution'},), 03:40:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:40:54 INFO - 'cmd': ('bash', 03:40:54 INFO - '-c', 03:40:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:40:54 INFO - 'enabled': True, 03:40:54 INFO - 'halt_on_failure': False, 03:40:54 INFO - 'name': 'check_screen_resolution'},), 03:40:54 INFO - 'run_cmd_checks_enabled': True, 03:40:54 INFO - 'sps_profile': False, 03:40:54 INFO - 'sps_profile_interval': 0, 03:40:54 INFO - 'suite': 'dromaeojs', 03:40:54 INFO - 'system_bits': '32', 03:40:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:40:54 INFO - 'title': 't-yosemite-r7-0231', 03:40:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:40:54 INFO - 'use_talos_json': True, 03:40:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:40:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:40:54 INFO - 'work_dir': 'build'} 03:40:54 INFO - [mozharness: 2016-10-10 10:40:54.185620Z] Running clobber step. 03:40:54 INFO - Running pre-action listener: _resource_record_pre_action 03:40:54 INFO - Running main action method: clobber 03:40:54 INFO - rmtree: /builds/slave/test/build 03:40:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:40:55 INFO - Running post-action listener: _resource_record_post_action 03:40:55 INFO - [mozharness: 2016-10-10 10:40:55.203831Z] Finished clobber step (success) 03:40:55 INFO - [mozharness: 2016-10-10 10:40:55.204034Z] Running read-buildbot-config step. 03:40:55 INFO - Running pre-action listener: _resource_record_pre_action 03:40:55 INFO - Running main action method: read_buildbot_config 03:40:55 INFO - Using buildbot properties: 03:40:55 INFO - { 03:40:55 INFO - "project": "", 03:40:55 INFO - "product": "firefox", 03:40:55 INFO - "script_repo_revision": "production", 03:40:55 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 03:40:55 INFO - "repository": "", 03:40:55 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 03:40:55 INFO - "buildid": "20161010023217", 03:40:55 INFO - "pgo_build": "False", 03:40:55 INFO - "basedir": "/builds/slave/test", 03:40:55 INFO - "buildnumber": 224, 03:40:55 INFO - "slavename": "t-yosemite-r7-0231", 03:40:55 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 03:40:55 INFO - "platform": "yosemite_r7", 03:40:55 INFO - "scheduler": "tests-autoland-macosx64-talos", 03:40:55 INFO - "branch": "autoland", 03:40:55 INFO - "repo_path": "integration/autoland", 03:40:55 INFO - "revision": "515e6d07c0c23ff4e8ad1530f5562f582b952f77", 03:40:55 INFO - "stage_platform": "macosx64", 03:40:55 INFO - "builduid": "2962ba92fbf54c629a99eb85ea905abf", 03:40:55 INFO - "slavebuilddir": "test" 03:40:55 INFO - } 03:40:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/R-KV5ixeQk65N1KTVzbwZw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 03:40:55 INFO - Running post-action listener: _resource_record_post_action 03:40:55 INFO - [mozharness: 2016-10-10 10:40:55.206855Z] Finished read-buildbot-config step (success) 03:40:55 INFO - [mozharness: 2016-10-10 10:40:55.206955Z] Running download-and-extract step. 03:40:55 INFO - Running pre-action listener: _resource_record_pre_action 03:40:55 INFO - Running main action method: download_and_extract 03:40:55 INFO - mkdir: /builds/slave/test/build/tests 03:40:55 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')]} 03:40:55 INFO - trying https://queue.taskcluster.net/v1/task/R-KV5ixeQk65N1KTVzbwZw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 03:40:55 INFO - Downloading https://queue.taskcluster.net/v1/task/R-KV5ixeQk65N1KTVzbwZw/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 03:40:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/R-KV5ixeQk65N1KTVzbwZw/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 03:40:56 INFO - Downloaded 1199 bytes. 03:40:56 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 03:40:56 INFO - Using the following test package requirements: 03:40:56 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 03:40:56 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 03:40:56 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 03:40:56 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 03:40:56 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 03:40:56 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 03:40:56 INFO - u'jsshell-mac.zip'], 03:40:56 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 03:40:56 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 03:40:56 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 03:40:56 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 03:40:56 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 03:40:56 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 03:40:56 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 03:40:56 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 03:40:56 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 03:40:56 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 03:40:56 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 03:40:56 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:40:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/R-KV5ixeQk65N1KTVzbwZw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 03:40:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/R-KV5ixeQk65N1KTVzbwZw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:40:56 INFO - Fetch https://queue.taskcluster.net/v1/task/R-KV5ixeQk65N1KTVzbwZw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 03:40:56 INFO - Http code: 200 03:40:56 INFO - via: 1.1 a75342fa94ff4a9431476624fe74f02e.cloudfront.net (CloudFront) 03:40:56 INFO - x-amz-cf-id: o4U6rcXPFgHByJsA4Mm_R-d-p-Mq_kMXQlxKCObnzF0Umbq6N2LMAw== 03:40:56 INFO - x-amz-version-id: WYRTVQmp2HN6_yexrG1M0kg6ApnI2m6g 03:40:57 INFO - Expected file size: 16496378 03:40:57 INFO - Obtained file size: 16496378 03:41:03 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 03:41:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/R-KV5ixeQk65N1KTVzbwZw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 03:41:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/R-KV5ixeQk65N1KTVzbwZw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:41:03 INFO - Fetch https://queue.taskcluster.net/v1/task/R-KV5ixeQk65N1KTVzbwZw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 03:41:04 INFO - Http code: 200 03:41:04 INFO - via: 1.1 d8f42fc9558e3e49ebfdf8834baeb756.cloudfront.net (CloudFront) 03:41:04 INFO - x-amz-cf-id: _VAXrMJoiZIosgQ4r4pROylCpxQQNPEL1v0gmEQBq-2slWlsNAS7Dw== 03:41:04 INFO - x-amz-version-id: WYRTVQmp2HN6_yexrG1M0kg6ApnI2m6g 03:41:04 INFO - Expected file size: 16496378 03:41:04 INFO - Obtained file size: 16496378 03:41:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/R-KV5ixeQk65N1KTVzbwZw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 03:41:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/R-KV5ixeQk65N1KTVzbwZw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 03:41:10 INFO - Fetch https://queue.taskcluster.net/v1/task/R-KV5ixeQk65N1KTVzbwZw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 03:41:11 INFO - Http code: 200 03:41:11 INFO - via: 1.1 bb8a7ff26b131d51953dc2d3e19a2751.cloudfront.net (CloudFront) 03:41:11 INFO - x-amz-cf-id: acEut8HclhedYu88yyhoGF97b8qCxuHEkOTahBU2Pb0Cx9SxfTwVvQ== 03:41:11 INFO - x-amz-version-id: vcEqijCGh_ZAMLha1Iq.umumP08BmX6w 03:41:12 INFO - Expected file size: 14409006 03:41:12 INFO - Obtained file size: 14409006 03:41:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:41:12 INFO - trying https://queue.taskcluster.net/v1/task/R-KV5ixeQk65N1KTVzbwZw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 03:41:12 INFO - Downloading https://queue.taskcluster.net/v1/task/R-KV5ixeQk65N1KTVzbwZw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 03:41:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/R-KV5ixeQk65N1KTVzbwZw/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 03:41:15 INFO - Downloaded 111714299 bytes. 03:41:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/R-KV5ixeQk65N1KTVzbwZw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 03:41:15 INFO - mkdir: /builds/slave/test/properties 03:41:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:41:15 INFO - Writing to file /builds/slave/test/properties/build_url 03:41:15 INFO - Contents: 03:41:15 INFO - build_url:https://queue.taskcluster.net/v1/task/R-KV5ixeQk65N1KTVzbwZw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 03:41:16 INFO - Running post-action listener: _resource_record_post_action 03:41:16 INFO - Running post-action listener: set_extra_try_arguments 03:41:16 INFO - [mozharness: 2016-10-10 10:41:16.646267Z] Finished download-and-extract step (success) 03:41:16 INFO - [mozharness: 2016-10-10 10:41:16.646417Z] Running populate-webroot step. 03:41:16 INFO - Running pre-action listener: _resource_record_pre_action 03:41:16 INFO - Running main action method: populate_webroot 03:41:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:41:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:41:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:41:16 INFO - '--tppagecycles', 03:41:16 INFO - '7'], 03:41:16 INFO - 'tests': ['tsvgm']}}, 03:41:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:41:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:41:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:41:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:41:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:41:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:41:16 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:41:16 INFO - 'talos_options': ['--disable-e10s'], 03:41:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:41:16 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:41:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:41:16 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:41:16 INFO - 'talos_options': ['--disable-e10s'], 03:41:16 INFO - 'tests': ['damp', 'tps']}, 03:41:16 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:41:16 INFO - 'tests': ['damp', 'tps']}, 03:41:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:41:16 INFO - 'tests': ['dromaeo_dom']}, 03:41:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:41:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:41:16 INFO - 'tests': ['basic_compositor_video']}, 03:41:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:41:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:41:16 INFO - 'tests': ['a11yr', 03:41:16 INFO - 'ts_paint', 03:41:16 INFO - 'tpaint', 03:41:16 INFO - 'sessionrestore', 03:41:16 INFO - 'sessionrestore_no_auto_restore', 03:41:16 INFO - 'tabpaint']}, 03:41:16 INFO - 'other-e10s': {'tests': ['a11yr', 03:41:16 INFO - 'ts_paint', 03:41:16 INFO - 'tpaint', 03:41:16 INFO - 'sessionrestore', 03:41:16 INFO - 'sessionrestore_no_auto_restore', 03:41:16 INFO - 'tabpaint']}, 03:41:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:41:16 INFO - 'ts_paint', 03:41:16 INFO - 'tpaint', 03:41:16 INFO - 'sessionrestore', 03:41:16 INFO - 'sessionrestore_no_auto_restore', 03:41:16 INFO - 'tabpaint']}, 03:41:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:41:16 INFO - 'ts_paint', 03:41:16 INFO - 'tpaint', 03:41:16 INFO - 'sessionrestore', 03:41:16 INFO - 'sessionrestore_no_auto_restore', 03:41:16 INFO - 'tabpaint']}, 03:41:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:41:16 INFO - 'tests': ['a11yr', 03:41:16 INFO - 'ts_paint', 03:41:16 INFO - 'tpaint', 03:41:16 INFO - 'sessionrestore', 03:41:16 INFO - 'sessionrestore_no_auto_restore', 03:41:16 INFO - 'tabpaint']}, 03:41:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:41:16 INFO - 'tests': ['a11yr', 03:41:16 INFO - 'ts_paint', 03:41:16 INFO - 'tpaint', 03:41:16 INFO - 'sessionrestore', 03:41:16 INFO - 'sessionrestore_no_auto_restore', 03:41:16 INFO - 'tabpaint']}, 03:41:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:41:16 INFO - 'tests': ['tsvgx', 03:41:16 INFO - 'tsvgr_opacity', 03:41:16 INFO - 'tart', 03:41:16 INFO - 'tscrollx', 03:41:16 INFO - 'cart']}, 03:41:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:41:16 INFO - 'tsvgr_opacity', 03:41:16 INFO - 'tart', 03:41:16 INFO - 'tscrollx', 03:41:16 INFO - 'cart']}, 03:41:16 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:41:16 INFO - 'talos_options': ['--disable-e10s'], 03:41:16 INFO - 'tests': ['tp5o']}, 03:41:16 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:41:16 INFO - 'tests': ['tp5o']}, 03:41:16 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:41:16 INFO - 'talos_options': ['--disable-e10s', 03:41:16 INFO - '--xperf_path', 03:41:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:41:16 INFO - 'tests': ['tp5n']}, 03:41:16 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:41:16 INFO - 'talos_options': ['--xperf_path', 03:41:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:41:16 INFO - 'tests': ['tp5n']}}, 03:41:16 INFO - 'talos.zip': {'path': '', 03:41:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:41:16 INFO - Running post-action listener: _resource_record_post_action 03:41:16 INFO - [mozharness: 2016-10-10 10:41:16.664996Z] Finished populate-webroot step (success) 03:41:16 INFO - [mozharness: 2016-10-10 10:41:16.665092Z] Running create-virtualenv step. 03:41:16 INFO - Running pre-action listener: _resource_record_pre_action 03:41:16 INFO - Running main action method: create_virtualenv 03:41:16 INFO - Creating virtualenv /builds/slave/test/build/venv 03:41:16 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 03:41:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:41:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:41:16 INFO - Using real prefix '/tools/python27' 03:41:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:41:17 INFO - Installing distribute.............................................................................................................................................................................................done. 03:41:20 INFO - Installing pip.................done. 03:41:20 INFO - Return code: 0 03:41:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:41:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:41:20 INFO - Reading from file tmpfile_stdout 03:41:20 INFO - Output received: 03:41:20 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) 03:41:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:41:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:41:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:41:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:41:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:41:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fc88490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbfe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8babc5b9b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa9cd30>, '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 0x10f8c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa94b20>, '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.hVhqwT6oBa/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.tN32UEEISn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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 03:41:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:41:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:41:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hVhqwT6oBa/Render', 03:41:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:41:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:41:20 INFO - 'HOME': '/Users/cltbld', 03:41:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:41:20 INFO - 'LOGNAME': 'cltbld', 03:41:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:20 INFO - 'MOZ_NO_REMOTE': '1', 03:41:20 INFO - 'NO_EM_RESTART': '1', 03:41:20 INFO - 'PAGER': '/bin/cat', 03:41:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:41:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:20 INFO - 'PWD': '/builds/slave/test', 03:41:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:41:20 INFO - 'SHELL': '/bin/bash', 03:41:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tN32UEEISn/Listeners', 03:41:20 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 03:41:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:41:20 INFO - 'USER': 'cltbld', 03:41:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:41:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:41:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:20 INFO - 'XPC_FLAGS': '0x0', 03:41:20 INFO - 'XPC_SERVICE_NAME': '0', 03:41:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:41:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:41:20 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 03:41:20 INFO - Cleaning up... 03:41:20 INFO - Return code: 0 03:41:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:41:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:41:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:41:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:41:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:41:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10fc88490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbfe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8babc5b9b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa9cd30>, '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 0x10f8c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa94b20>, '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.hVhqwT6oBa/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.tN32UEEISn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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 03:41:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:41:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:41:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hVhqwT6oBa/Render', 03:41:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:41:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:41:20 INFO - 'HOME': '/Users/cltbld', 03:41:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:41:20 INFO - 'LOGNAME': 'cltbld', 03:41:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:20 INFO - 'MOZ_NO_REMOTE': '1', 03:41:20 INFO - 'NO_EM_RESTART': '1', 03:41:20 INFO - 'PAGER': '/bin/cat', 03:41:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:41:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:20 INFO - 'PWD': '/builds/slave/test', 03:41:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:41:20 INFO - 'SHELL': '/bin/bash', 03:41:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tN32UEEISn/Listeners', 03:41:20 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 03:41:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:41:20 INFO - 'USER': 'cltbld', 03:41:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:41:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:41:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:20 INFO - 'XPC_FLAGS': '0x0', 03:41:20 INFO - 'XPC_SERVICE_NAME': '0', 03:41:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:41:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:41:21 INFO - Downloading/unpacking psutil>=3.1.1 03:41:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:41:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:41:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:41:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:41:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:41:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:41:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:41:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:41:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:41:24 INFO - Installing collected packages: psutil 03:41:24 INFO - Running setup.py install for psutil 03:41:24 INFO - building 'psutil._psutil_osx' extension 03:41:24 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 03:41:25 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 03:41:25 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 03:41:25 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 03:41:25 INFO - building 'psutil._psutil_posix' extension 03:41:25 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 03:41:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:41:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:41:25 INFO - ^ 03:41:25 INFO - 1 warning generated. 03:41:25 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 03:41:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:41:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:41:25 INFO - Successfully installed psutil 03:41:25 INFO - Cleaning up... 03:41:25 INFO - Return code: 0 03:41:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:41:25 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')]} 03:41:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:41:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:25 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')]} 03:41:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:41:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:25 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 0x10fc88490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbfe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8babc5b9b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa9cd30>, '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 0x10f8c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa94b20>, '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.hVhqwT6oBa/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.tN32UEEISn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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 03:41:25 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 03:41:25 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 03:41:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hVhqwT6oBa/Render', 03:41:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:41:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:41:25 INFO - 'HOME': '/Users/cltbld', 03:41:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:41:25 INFO - 'LOGNAME': 'cltbld', 03:41:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:25 INFO - 'MOZ_NO_REMOTE': '1', 03:41:25 INFO - 'NO_EM_RESTART': '1', 03:41:25 INFO - 'PAGER': '/bin/cat', 03:41:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:41:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:25 INFO - 'PWD': '/builds/slave/test', 03:41:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:41:25 INFO - 'SHELL': '/bin/bash', 03:41:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tN32UEEISn/Listeners', 03:41:25 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 03:41:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:41:25 INFO - 'USER': 'cltbld', 03:41:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:41:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:41:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:25 INFO - 'XPC_FLAGS': '0x0', 03:41:25 INFO - 'XPC_SERVICE_NAME': '0', 03:41:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:41:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:41:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:41:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:41:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:41:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:41:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:41:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:41:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:41:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:41:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:41:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:41:28 INFO - Installing collected packages: mozsystemmonitor 03:41:28 INFO - Running setup.py install for mozsystemmonitor 03:41:28 INFO - Successfully installed mozsystemmonitor 03:41:28 INFO - Cleaning up... 03:41:28 INFO - Return code: 0 03:41:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:41:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:41:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:41:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:29 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')]} 03:41:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:41:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:29 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 0x10fc88490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbfe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8babc5b9b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa9cd30>, '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 0x10f8c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa94b20>, '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.hVhqwT6oBa/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.tN32UEEISn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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 03:41:29 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 03:41:29 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 03:41:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hVhqwT6oBa/Render', 03:41:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:41:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:41:29 INFO - 'HOME': '/Users/cltbld', 03:41:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:41:29 INFO - 'LOGNAME': 'cltbld', 03:41:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:29 INFO - 'MOZ_NO_REMOTE': '1', 03:41:29 INFO - 'NO_EM_RESTART': '1', 03:41:29 INFO - 'PAGER': '/bin/cat', 03:41:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:41:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:29 INFO - 'PWD': '/builds/slave/test', 03:41:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:41:29 INFO - 'SHELL': '/bin/bash', 03:41:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tN32UEEISn/Listeners', 03:41:29 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 03:41:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:41:29 INFO - 'USER': 'cltbld', 03:41:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:41:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:41:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:29 INFO - 'XPC_FLAGS': '0x0', 03:41:29 INFO - 'XPC_SERVICE_NAME': '0', 03:41:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:41:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:41:29 INFO - Downloading/unpacking jsonschema==2.5.1 03:41:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:41:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:41:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:41:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:41:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:41:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:41:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:41:32 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:41:32 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 03:41:32 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 03:41:32 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 03:41:32 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 03:41:32 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 03:41:32 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 03:41:32 INFO - Downloading functools32-3.2.3-2.tar.gz 03:41:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:41:32 INFO - warning: no files found matching '*.txt' 03:41:32 INFO - no previously-included directories found matching 'build' 03:41:32 INFO - no previously-included directories found matching 'dist' 03:41:32 INFO - no previously-included directories found matching '.git*' 03:41:32 INFO - Installing collected packages: jsonschema, functools32 03:41:32 INFO - Running setup.py install for functools32 03:41:32 INFO - warning: no files found matching '*.txt' 03:41:32 INFO - no previously-included directories found matching 'build' 03:41:32 INFO - no previously-included directories found matching 'dist' 03:41:32 INFO - no previously-included directories found matching '.git*' 03:41:32 INFO - Successfully installed jsonschema functools32 03:41:32 INFO - Cleaning up... 03:41:32 INFO - Return code: 0 03:41:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:41: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')]} 03:41:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:41:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41: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')]} 03:41:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:41:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10fc88490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbfe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8babc5b9b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa9cd30>, '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 0x10f8c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa94b20>, '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.hVhqwT6oBa/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.tN32UEEISn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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 03:41:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:41:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:41:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hVhqwT6oBa/Render', 03:41:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:41:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:41:32 INFO - 'HOME': '/Users/cltbld', 03:41:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:41:32 INFO - 'LOGNAME': 'cltbld', 03:41:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:32 INFO - 'MOZ_NO_REMOTE': '1', 03:41:32 INFO - 'NO_EM_RESTART': '1', 03:41:32 INFO - 'PAGER': '/bin/cat', 03:41:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:41:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:32 INFO - 'PWD': '/builds/slave/test', 03:41:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:41:32 INFO - 'SHELL': '/bin/bash', 03:41:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tN32UEEISn/Listeners', 03:41:32 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 03:41:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:41:32 INFO - 'USER': 'cltbld', 03:41:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:41:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:41:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:32 INFO - 'XPC_FLAGS': '0x0', 03:41:32 INFO - 'XPC_SERVICE_NAME': '0', 03:41:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:41:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:41:33 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:41:33 INFO - Cleaning up... 03:41:33 INFO - Return code: 0 03:41:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:41:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:41:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:41:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:41:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:41:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10fc88490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbfe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8babc5b9b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa9cd30>, '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 0x10f8c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa94b20>, '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.hVhqwT6oBa/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.tN32UEEISn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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 03:41:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:41:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:41:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hVhqwT6oBa/Render', 03:41:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:41:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:41:33 INFO - 'HOME': '/Users/cltbld', 03:41:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:41:33 INFO - 'LOGNAME': 'cltbld', 03:41:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:33 INFO - 'MOZ_NO_REMOTE': '1', 03:41:33 INFO - 'NO_EM_RESTART': '1', 03:41:33 INFO - 'PAGER': '/bin/cat', 03:41:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:41:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:33 INFO - 'PWD': '/builds/slave/test', 03:41:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:41:33 INFO - 'SHELL': '/bin/bash', 03:41:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tN32UEEISn/Listeners', 03:41:33 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 03:41:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:41:33 INFO - 'USER': 'cltbld', 03:41:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:41:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:41:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:33 INFO - 'XPC_FLAGS': '0x0', 03:41:33 INFO - 'XPC_SERVICE_NAME': '0', 03:41:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:41:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:41:33 INFO - Downloading/unpacking blobuploader==1.2.4 03:41:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:41:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:41:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:41:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:41:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:41:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:41:35 INFO - Downloading blobuploader-1.2.4.tar.gz 03:41:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:41:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:41: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 03:41: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 03:41: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 03:41: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 03:41: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 03:41: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 03:41:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:41:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:41:36 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 03:41:36 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 03:41:36 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 03:41:36 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 03:41:36 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 03:41:36 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 03:41:37 INFO - Downloading docopt-0.6.1.tar.gz 03:41:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:41:37 INFO - Installing collected packages: blobuploader, requests, docopt 03:41:37 INFO - Running setup.py install for blobuploader 03:41:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:41:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:41:37 INFO - Running setup.py install for requests 03:41:37 INFO - Running setup.py install for docopt 03:41:38 INFO - Successfully installed blobuploader requests docopt 03:41:38 INFO - Cleaning up... 03:41:38 INFO - Return code: 0 03:41:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:41: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')]} 03:41:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:41:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41: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')]} 03:41:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:41:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:38 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 0x10fc88490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbfe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8babc5b9b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa9cd30>, '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 0x10f8c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa94b20>, '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.hVhqwT6oBa/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.tN32UEEISn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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 03:41:38 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 03:41:38 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 03:41:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hVhqwT6oBa/Render', 03:41:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:41:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:41:38 INFO - 'HOME': '/Users/cltbld', 03:41:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:41:38 INFO - 'LOGNAME': 'cltbld', 03:41:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:38 INFO - 'MOZ_NO_REMOTE': '1', 03:41:38 INFO - 'NO_EM_RESTART': '1', 03:41:38 INFO - 'PAGER': '/bin/cat', 03:41:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:41:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:38 INFO - 'PWD': '/builds/slave/test', 03:41:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:41:38 INFO - 'SHELL': '/bin/bash', 03:41:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tN32UEEISn/Listeners', 03:41:38 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 03:41:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:41:38 INFO - 'USER': 'cltbld', 03:41:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:41:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:41:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:38 INFO - 'XPC_FLAGS': '0x0', 03:41:38 INFO - 'XPC_SERVICE_NAME': '0', 03:41:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:41:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:41:38 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-sB8lMC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:41:38 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-iFb2XD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:41:38 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-om4Mvd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:41:38 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-zCjDRX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:41:38 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-rqsaSu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:41:38 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-js2v_p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:41:39 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-q0y8ky-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:41:39 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-9IQT25-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:41:39 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-Oqzg0b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:41:39 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-RvnBqE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:41:39 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-OpbZBm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:41:39 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-Ox0Pab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:41:39 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-qWPdEO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:41:39 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-qpejzC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:41:39 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-H7ZPjO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:41:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:41:40 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-pl0yQ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:41:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:41:40 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-auQNdt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:41:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:41:40 INFO - Running setup.py install for manifestparser 03:41:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:41:40 INFO - Running setup.py install for mozcrash 03:41:40 INFO - Running setup.py install for mozdebug 03:41:40 INFO - Running setup.py install for mozdevice 03:41:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:41:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:41:40 INFO - Running setup.py install for mozfile 03:41:40 INFO - Running setup.py install for mozhttpd 03:41:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:41:41 INFO - Running setup.py install for mozinfo 03:41:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:41:41 INFO - Running setup.py install for mozInstall 03:41:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:41:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:41:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:41:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:41:41 INFO - Running setup.py install for mozleak 03:41:41 INFO - Running setup.py install for mozlog 03:41:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:41:41 INFO - Running setup.py install for moznetwork 03:41:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:41:41 INFO - Running setup.py install for mozprocess 03:41:42 INFO - Running setup.py install for mozprofile 03:41:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:41:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:41:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:41:42 INFO - Running setup.py install for mozrunner 03:41:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:41:42 INFO - Running setup.py install for mozscreenshot 03:41:42 INFO - Running setup.py install for moztest 03:41:42 INFO - Running setup.py install for mozversion 03:41:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:41:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:41:42 INFO - Cleaning up... 03:41:42 INFO - Return code: 0 03:41:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:41: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')]} 03:41:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:41:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41: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')]} 03:41:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:41:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:42 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 0x10fc88490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbfe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8babc5b9b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa9cd30>, '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 0x10f8c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa94b20>, '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.hVhqwT6oBa/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.tN32UEEISn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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 03:41:42 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 03:41:42 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 03:41:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hVhqwT6oBa/Render', 03:41:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:41:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:41:42 INFO - 'HOME': '/Users/cltbld', 03:41:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:41:42 INFO - 'LOGNAME': 'cltbld', 03:41:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:42 INFO - 'MOZ_NO_REMOTE': '1', 03:41:42 INFO - 'NO_EM_RESTART': '1', 03:41:42 INFO - 'PAGER': '/bin/cat', 03:41:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:41:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:42 INFO - 'PWD': '/builds/slave/test', 03:41:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:41:42 INFO - 'SHELL': '/bin/bash', 03:41:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tN32UEEISn/Listeners', 03:41:42 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 03:41:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:41:42 INFO - 'USER': 'cltbld', 03:41:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:41:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:41:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:42 INFO - 'XPC_FLAGS': '0x0', 03:41:42 INFO - 'XPC_SERVICE_NAME': '0', 03:41:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:41:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:41:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:41:43 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-Ce0c2w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:41:43 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)) 03:41:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:41:43 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-qSsP2K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:41:43 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)) 03:41:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:41:43 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-MplFvn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:41:43 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)) 03:41:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:41:43 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-AN5_4r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:41:43 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)) 03:41:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:41:43 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-cmqRvb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:41:43 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)) 03:41:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:41:43 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-328JtK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:41:43 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)) 03:41:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:41:43 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-uolIXq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:41:43 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)) 03:41:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:41:43 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-fZsG6b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:41:43 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)) 03:41:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:41:43 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-aVRhAS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:41:44 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)) 03:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:41:44 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-DGIcQt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:41:44 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)) 03:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:41:44 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-SuK1W0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:41:44 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)) 03:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:41:44 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-E7n1MD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:41:44 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)) 03:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:41:44 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-F8YCoz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:41:44 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)) 03:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:41:44 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-kxTDm_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:41:44 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)) 03:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:41:44 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-AGSnu2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:41:44 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)) 03:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:41:44 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-o3LPev-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:41:44 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)) 03:41:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:41:44 INFO - Running setup.py (path:/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/pip-VdWIBm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:41:44 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)) 03:41:44 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)) 03:41:44 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)) 03:41:44 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)) 03:41:44 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)) 03:41:44 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)) 03:41:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:41:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:41:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:41:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:41:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:41:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:41:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:41:47 INFO - Downloading blessings-1.6.tar.gz 03:41:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:41:47 INFO - Installing collected packages: blessings 03:41:47 INFO - Running setup.py install for blessings 03:41:47 INFO - Successfully installed blessings 03:41:47 INFO - Cleaning up... 03:41:47 INFO - Return code: 0 03:41:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:41:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:41:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:41:48 INFO - Reading from file tmpfile_stdout 03:41:48 INFO - Current package versions: 03:41:48 INFO - blessings == 1.6 03:41:48 INFO - blobuploader == 1.2.4 03:41:48 INFO - docopt == 0.6.1 03:41:48 INFO - functools32 == 3.2.3-2 03:41:48 INFO - jsonschema == 2.5.1 03:41:48 INFO - manifestparser == 1.1 03:41:48 INFO - mozInstall == 1.12 03:41:48 INFO - mozcrash == 0.17 03:41:48 INFO - mozdebug == 0.1 03:41:48 INFO - mozdevice == 0.48 03:41:48 INFO - mozfile == 1.2 03:41:48 INFO - mozhttpd == 0.7 03:41:48 INFO - mozinfo == 0.9 03:41:48 INFO - mozleak == 0.1 03:41:48 INFO - mozlog == 3.4 03:41:48 INFO - moznetwork == 0.27 03:41:48 INFO - mozprocess == 0.23 03:41:48 INFO - mozprofile == 0.28 03:41:48 INFO - mozrunner == 6.12 03:41:48 INFO - mozscreenshot == 0.1 03:41:48 INFO - mozsystemmonitor == 0.3 03:41:48 INFO - moztest == 0.7 03:41:48 INFO - mozversion == 1.4 03:41:48 INFO - psutil == 3.1.1 03:41:48 INFO - requests == 1.2.3 03:41:48 INFO - wsgiref == 0.1.2 03:41:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:41:48 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')]} 03:41:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:41:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:48 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')]} 03:41:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:41:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:48 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 0x10fc88490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbfe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8babc5b9b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa9cd30>, '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 0x10f8c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa94b20>, '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.hVhqwT6oBa/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.tN32UEEISn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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 03:41:48 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 03:41:48 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 03:41:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hVhqwT6oBa/Render', 03:41:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:41:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:41:48 INFO - 'HOME': '/Users/cltbld', 03:41:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:41:48 INFO - 'LOGNAME': 'cltbld', 03:41:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:48 INFO - 'MOZ_NO_REMOTE': '1', 03:41:48 INFO - 'NO_EM_RESTART': '1', 03:41:48 INFO - 'PAGER': '/bin/cat', 03:41:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:41:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:48 INFO - 'PWD': '/builds/slave/test', 03:41:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:41:48 INFO - 'SHELL': '/bin/bash', 03:41:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tN32UEEISn/Listeners', 03:41:48 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 03:41:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:41:48 INFO - 'USER': 'cltbld', 03:41:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:41:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:41:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:48 INFO - 'XPC_FLAGS': '0x0', 03:41:48 INFO - 'XPC_SERVICE_NAME': '0', 03:41:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:41:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:41:48 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)) 03:41:48 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)) 03:41:48 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)) 03:41:48 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)) 03:41:48 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)) 03:41:48 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)) 03:41:48 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)) 03:41:48 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)) 03:41:48 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)) 03:41:48 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)) 03:41:48 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)) 03:41:48 INFO - Cleaning up... 03:41:48 INFO - Return code: 0 03:41:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:41:48 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')]} 03:41:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:41:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:48 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')]} 03:41:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:41:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:48 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 0x10fc88490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fbfe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8babc5b9b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa9cd30>, '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 0x10f8c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa94b20>, '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.hVhqwT6oBa/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.tN32UEEISn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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 03:41:48 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 03:41:48 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 03:41:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hVhqwT6oBa/Render', 03:41:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:41:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:41:48 INFO - 'HOME': '/Users/cltbld', 03:41:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:41:48 INFO - 'LOGNAME': 'cltbld', 03:41:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:48 INFO - 'MOZ_NO_REMOTE': '1', 03:41:48 INFO - 'NO_EM_RESTART': '1', 03:41:48 INFO - 'PAGER': '/bin/cat', 03:41:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:41:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:48 INFO - 'PWD': '/builds/slave/test', 03:41:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:41:48 INFO - 'SHELL': '/bin/bash', 03:41:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tN32UEEISn/Listeners', 03:41:48 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 03:41:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:41:48 INFO - 'USER': 'cltbld', 03:41:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:41:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:41:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:48 INFO - 'XPC_FLAGS': '0x0', 03:41:48 INFO - 'XPC_SERVICE_NAME': '0', 03:41:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:41:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:41:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:41:48 INFO - Cleaning up... 03:41:48 INFO - Return code: 0 03:41:48 INFO - Running post-action listener: _resource_record_post_action 03:41:48 INFO - Running post-action listener: _start_resource_monitoring 03:41:48 INFO - Starting resource monitoring. 03:41:48 INFO - [mozharness: 2016-10-10 10:41:48.643737Z] Finished create-virtualenv step (success) 03:41:48 INFO - [mozharness: 2016-10-10 10:41:48.644783Z] Running install step. 03:41:48 INFO - Running pre-action listener: _resource_record_pre_action 03:41:48 INFO - Running main action method: install 03:41:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:41:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:41:48 INFO - Reading from file tmpfile_stdout 03:41:48 INFO - Detecting whether we're running mozinstall >=1.0... 03:41:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:41:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:41:49 INFO - Reading from file tmpfile_stdout 03:41:49 INFO - Output received: 03:41:49 INFO - Usage: mozinstall [options] installer 03:41:49 INFO - Options: 03:41:49 INFO - -h, --help show this help message and exit 03:41:49 INFO - -d DEST, --destination=DEST 03:41:49 INFO - Directory to install application into. [default: 03:41:49 INFO - "/builds/slave/test"] 03:41:49 INFO - --app=APP Application being installed. [default: firefox] 03:41:49 INFO - mkdir: /builds/slave/test/build/application 03:41:49 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'] 03:41:49 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 03:42:23 INFO - Reading from file tmpfile_stdout 03:42:23 INFO - Output received: 03:42:23 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:42:23 INFO - Running post-action listener: _resource_record_post_action 03:42:23 INFO - [mozharness: 2016-10-10 10:42:23.497841Z] Finished install step (success) 03:42:23 INFO - [mozharness: 2016-10-10 10:42:23.497982Z] Running run-tests step. 03:42:23 INFO - Running pre-action listener: _resource_record_pre_action 03:42:23 INFO - Running main action method: run_tests 03:42:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:42:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:42:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:42:23 INFO - 2016-10-10 03:42:23.512 screenresolution[2532:22740] starting screenresolution argv=screenresolution get 03:42:23 INFO - 2016-10-10 03:42:23.515 screenresolution[2532:22740] Display 0: 1600x1200x32@60 03:42:23 INFO - 2016-10-10 03:42:23.521 screenresolution[2533:22742] starting screenresolution argv=screenresolution list 03:42:23 INFO - Available Modes on Display 0 03:42:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:42:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:42:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:42:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:42:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:42:23 INFO - Intel Iris: 03:42:23 INFO - Chipset Model: Intel Iris 03:42:23 INFO - Type: GPU 03:42:23 INFO - Bus: Built-In 03:42:23 INFO - VRAM (Dynamic, Max): 1536 MB 03:42:23 INFO - Vendor: Intel (0x8086) 03:42:23 INFO - Device ID: 0x0a2e 03:42:23 INFO - Revision ID: 0x0009 03:42:23 INFO - Displays: 03:42:23 INFO - Display: 03:42:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:42:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:42:23 INFO - Display Serial Number: 1600x1200 60 03:42:23 INFO - Main Display: Yes 03:42:23 INFO - Mirror: Off 03:42:23 INFO - Online: Yes 03:42:23 INFO - Rotation: Supported 03:42:23 INFO - Return code: 0 03:42:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:42:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:42:23 INFO - Python 2.7.3 03:42:23 INFO - Return code: 0 03:42:23 INFO - grabbing minidump binary from tooltool 03:42: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')]} 03:42:23 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 0x10fa9cd30>, '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 0x10f8c2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa94b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:42:23 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 03:42:23 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 03:42:23 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 03:42:23 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:42:23 INFO - Return code: 0 03:42:23 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:42:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:42:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:42:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:42:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:42:23 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/R-KV5ixeQk65N1KTVzbwZw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0231', '--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 03:42:23 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/R-KV5ixeQk65N1KTVzbwZw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0231 --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 03:42:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hVhqwT6oBa/Render', 03:42:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:42:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:42:23 INFO - 'HOME': '/Users/cltbld', 03:42:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:42:23 INFO - 'LOGNAME': 'cltbld', 03:42:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:42:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:42:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:42:23 INFO - 'MOZ_NO_REMOTE': '1', 03:42:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:42:23 INFO - 'NO_EM_RESTART': '1', 03:42:23 INFO - 'PAGER': '/bin/cat', 03:42:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:42:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:42:23 INFO - 'PWD': '/builds/slave/test', 03:42:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:42:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:42:23 INFO - 'SHELL': '/bin/bash', 03:42:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tN32UEEISn/Listeners', 03:42:23 INFO - 'TMPDIR': '/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/', 03:42:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:42:23 INFO - 'USER': 'cltbld', 03:42:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:42:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:42:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:42:23 INFO - 'XPC_FLAGS': '0x0', 03:42:23 INFO - 'XPC_SERVICE_NAME': '0', 03:42:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:42:23 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/R-KV5ixeQk65N1KTVzbwZw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0231', '--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 03:42:24 INFO - mozversion application_buildid: 20161010023217 03:42:24 INFO - mozversion application_changeset: 515e6d07c0c23ff4e8ad1530f5562f582b952f77 03:42:24 INFO - mozversion application_display_name: Nightly 03:42:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:42:24 INFO - mozversion application_name: Firefox 03:42:24 INFO - mozversion application_remotingname: firefox 03:42:24 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 03:42:24 INFO - mozversion application_vendor: Mozilla 03:42:24 INFO - mozversion application_version: 52.0a1 03:42:24 INFO - mozversion platform_buildid: 20161010023217 03:42:24 INFO - mozversion platform_changeset: 515e6d07c0c23ff4e8ad1530f5562f582b952f77 03:42:24 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 03:42:24 INFO - mozversion platform_version: 52.0a1 03:42:24 INFO - using testdate: 1476096144 03:42:24 INFO - actual date: 1476096144 03:42:24 INFO - starting webserver on 'localhost:56593' 03:42:24 INFO - SUITE-START | Running 2 tests 03:42:24 INFO - TEST-START | dromaeo_css 03:42:24 INFO - operating with platform_type : mac_ 03:42:24 INFO - Initialising browser for dromaeo_css test... 03:42:24 INFO - TEST-INFO | started process 2543 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/tmpWBYTFs/profile http://localhost:56593/getInfo.html) 03:42:26 INFO - PROCESS | 2543 | __metrics Screen width/height:1600/1200 03:42:26 INFO - PROCESS | 2543 | colorDepth:24 03:42:26 INFO - PROCESS | 2543 | Browser inner width/height: 1024/689 03:42:26 INFO - PROCESS | 2543 | __metrics 03:42:26 INFO - PROCESS | 2543 | 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" 03:42:26 INFO - PROCESS | 2543 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:42:27 INFO - TEST-INFO | 2543: exit 0 03:42:27 INFO - Browser initialized. 03:42:27 INFO - Running cycle 1/1 for dromaeo_css test... 03:42:27 INFO - TEST-INFO | started process 2545 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/tmpWBYTFs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:42:39 INFO - PROCESS | 2545 | RSS: Main: 256851968 03:42:39 INFO - PROCESS | 2545 | 03:43:30 INFO - PROCESS | 2545 | 1476096210324 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] 03:45:43 INFO - PROCESS | 2545 | Cycle 1(1): loaded http://localhost:56593/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56593/tests/dromaeo/cssquery-ext.html) 03:45:45 INFO - PROCESS | 2545 | RSS: Main: 369717248 03:45:45 INFO - PROCESS | 2545 | 03:48:47 INFO - PROCESS | 2545 | Cycle 1(1): loaded http://localhost:56593/tests/dromaeo/cssquery-ext.html (next: http://localhost:56593/tests/dromaeo/cssquery-jquery.html) 03:48:48 INFO - PROCESS | 2545 | RSS: Main: 384876544 03:48:48 INFO - PROCESS | 2545 | 03:51:50 INFO - PROCESS | 2545 | Cycle 1(1): loaded http://localhost:56593/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56593/tests/dromaeo/cssquery-mootools.html) 03:51:51 INFO - PROCESS | 2545 | RSS: Main: 347275264 03:51:51 INFO - PROCESS | 2545 | 03:54:53 INFO - PROCESS | 2545 | Cycle 1(1): loaded http://localhost:56593/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56593/tests/dromaeo/cssquery-prototype.html) 03:54:54 INFO - PROCESS | 2545 | RSS: Main: 366821376 03:54:54 INFO - PROCESS | 2545 | 03:57:58 INFO - PROCESS | 2545 | Cycle 1(1): loaded http://localhost:56593/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56593/tests/dromaeo/cssquery-yui.html) 03:57:58 INFO - PROCESS | 2545 | RSS: Main: 345575424 03:57:58 INFO - PROCESS | 2545 | 04:01:02 INFO - PROCESS | 2545 | Cycle 1(1): loaded http://localhost:56593/tests/dromaeo/cssquery-yui.html (next: http://localhost:56593/tests/dromaeo/cssquery-dojo.html) 04:01:02 INFO - PROCESS | 2545 | RSS: Main: 431906816 04:01:02 INFO - PROCESS | 2545 | 04:01:02 INFO - PROCESS | 2545 | __start_tp_report 04:01:02 INFO - PROCESS | 2545 | _x_x_mozilla_page_load 04:01:02 INFO - PROCESS | 2545 | _x_x_mozilla_page_load_details 04:01:02 INFO - PROCESS | 2545 | |i|pagename|runs| 04:01:02 INFO - PROCESS | 2545 | |0;dojo.html;3306.7189288910445;3313.867445302188;3317.4360358739027;3340.1482621931405;3379.7127244184244;5540.667559946406;5652.802151924683;5658.179563963233;5694.971525142374;5745.253117094782;4739;5553.194786755918;5710.571707121972;5739.799107031255;5795.855103622412;5169.40551836538;5233.555147812422;5244.737763111848;5278.683279003272;5292.947070529303;5175.197844334134;5285.312909321802;5290.656107353029;5310.4424035476295;5311.123664595337;5308.362996440433;5334.03987282289;5341.65279256849;5360.785568577272;5371.301730774994;5117.385913690344;5279.313689220415;5287.524122828964;5300;5323.866903327409;5140.049090918192;5147.073526765181;5200.5059519345605;5207.791688332462;5210.322658054467;5539.9169012464845;5615.241942337773;5615.691136987464;5625.80309689163;5634.802781902655;6170.382961703794;6196.256449226076;6206.534509911775;6206.968965155145;6216.813495595139;6085.300190478063;6106.210084242431;6148.077788331741;6183.227096612924;6308.369163083701;5751.683657398841;5767.653940763567;5770.365259821417;5781.248437703156;5796.449337312949;6332.715027823667;6353.078803573456;6368.395002474757;6378.075179099004;6383.2978372379;4982.8754281143265;6021.3677563856045;6050.304215015241;6129.938700612936;6143.38566143391;6128.503123582198;6277.96861015692;6286.339934306923;6300.30696623371;6309.022101574264;6085.939140608626;6187.443130118311;6222.12890195373;6232.719527621177;6241.906371404432;6040.456358927718;6254.249490061222;6260.436560709558;6270.404311590391;6286.119943207955;4863.5622793948005;4866.635002374836;4874.293227481995;4902.632302577321;4934.8766280843265;4445.911081778347;4454.866354009385;4455.064436468378;4479.059397526556;4515.525869783691;5806.21616081832;5816.796412125555;5822.155787410802;5822.301323841081;5828.912566311508;6060.878782424327;6163.506919446433;6168.013117901204;6179.474744646755;6268.404501572434;5752.338481074647;5769.624974376652;5824.359320474744;5835.824925252249;5850.824475265749;5341.4925582069645;5359.598030147755;5361.544268737201;5363.383210930715;5363.839084827461;6272.717727702356;6288.591241569284;6312.779052733133;6316.494680425647;6317.210348706411;6168.599041062316;6209.720562574605;6253.062040693932;6255.40573645503;6307.400796924285;6304.684765761602;6309.463695585927;6314.210723659543;6314.715837787218;6331.778387756406;5961.225040744675;6149.169862068564;6172.876542469216;6184.319724830175;6188.504919606331;6121.143039974496;6172.5679202457195;6189.319174890668;6218.689065546795;6305.684715764101;6121.387861213843;6135.754569817157;6140.3245642978345;6147.200863887666;6152.753889844356;6061.571072443225;6089.786857459968;6093.289542879333;6126.387361263838;6150.876982460415;5773.451522105393;5829.679367634821;5889.617174883619;5900.380460051632;5905.409459054232;6093.878122437438;6141.170941922962;6143.600665956698;6143.784967526115;6144.109104179958;5777.8555536111935;5834.241548598655;5938.020226662552;5964.463198311999;6002.489788367863 04:01:02 INFO - PROCESS | 2545 | |1;ext.html;421.4415898933912;472.4000519340436;475.6955548448992;490.9787641705253;521.8956208758244;30651.693483065163;40179.196416071696;41362;41422.58577414229;41468.75593732193;42168.36747448791;42508.36237456441;42628.14743705124;42656.78671606637;42684.786576067156;9562.665306714249;9601.1358977692;9735.70792876215;9771.316007879452;9786.168175705074;9614.855777163348;9691.370060946052;9693.612255509772;9713.514324283757;9715;9631.133198012178;9643.951780241088;9720.222382209442;9730.756731081708;9736.51317434129;9655.372400793925;9690.32167748258;9691.757706057335;9700.223982081416;9713.98003209667;9566.665166719202;9673.951630241874;9674.22606191503;9676.95161524195;9685.32202745808;39853.00367490807;40210.79894600537;40228.59771402278;40261.99345016369;40334;3313.950290745641;3315.4860996545576;3347.983260083684;3382.7970321780526;3406.233597440581;24141;30581.541276880864;30723.539146912815;30954.845225773726;31232.843835780677;3154.3848949454864;3207.294395233045;3211.2132527530903;3255.739540836751;3319.40250754864;355076;357019;357812;359434;359942.2002889995;57354.56613584694;57629.423705762405;57665.71167144137;57666;57746.422535774946;34890.65109348925;35607.82196089055;35613.46579801305;60689.08966365508;60812.087818682754;16011.439599613957;16382.426615068654;16427.91786041062;16431.1784410779;16431.589210269838;12640.367981600883;12735.745285094432;12738.172018818932;12749.68125796844;12772.361381930867;14943.775843362359;18265.726014109798;18631.906840465712;18664;18670.719939200924;6675.332466753286;7853.568053756984;7862.488938218998;7904.288614024766;7954.125046244909;847.4618617178136;955.6177528988321;1000.3747657713928;1006.7483129217695;1070.7483741320784;27971.44057118846;28076.01733939343;29021.98423055225;29716.462378276745;30196.24509387283;808.9069756977905;1004.5278719002204;1034.896510348974;1042.6142327338932;1054.878688950765;15201.087934723952;15310.157941313111;15326.310316035582;15338.769918451233;15371;338938;345777.27111364785;346996;347443;349016;55031.44968550263;55500;55509.16736248959;55539.72230138904;55678.72160639252;1340.8458027326787;1356.525216174331;1367.1523655333558;1374.0793668242302;1375.2229990055587;23621.881890590437;26280.73719262783;30055.248618784706;30517.694823051486;30573.388532228786;54930.72534637461;55783;55787;55898;56045.43954560402;494250;580191;584301;590702;590996;40910.5681301153;41644.375334369404;42191.73424797261;42498.575014249465;42534;46714.532854672376;46720.53279467298;46994;47297.7635111836;48299.517004829504;38316.80841595774;39026;39183;39751.80124099477;39866;38875.80562097171;39096.41355379728;39919.80040099781;40017.59982400139;40341.39487907625;37110.8144459276;37118.62881371152;37585.81207093947;38287.61712382841;38750.80624596859;27350.179494615193;33594;33638.83180584081;34472.82763586166;34484.310313794085;25019.624705629067;25157.86789594436;25161.874190629664;25466;25709.3572660685 04:01:02 INFO - PROCESS | 2545 | |2;jquery.html;348.8255872063968;365.22936603766055;367.5809577082127;380.0877893056664;393.9369700847863;14703.852961470382;14934.626634334134;15630.609234769136;19505.707414388795;20385.796142038576;19547.706784398244;20177.697334539957;20758.85826279554;20875.791242087573;21197.682034769452;5536.363318218396;5922.940770592282;6238.844028899269;6244.56288059836;6244.875102497947;5960.850978725545;6159.599626024294;6237;6240.625562466266;6247.281562620288;5834.124881267822;6056.757729690807;6195.845103872394;6200.658963756991;6235.158253635769;6059.697015149225;6105.084237364386;6106.908396374036;6123.265208175025;6131.662758548278;5031.798728050873;5272.630915835893;5299.496547827989;5311.840644780624;5315.069862773999;25506.617400739004;25553.361165970815;25568.48863022748;25649.35876603081;25944.61083083755;2408.8193385496156;2439.7194322652945;2510.447701505666;2523.866022627428;2915.125462361283;14337.28313584327;14584.489542866055;15351.4626988056;15906.681866362607;16121.435749748702;1829.6432195721839;1843.6865732825452;1908.5133291010843;1925.258775371492;1943.24213556713;123094;128639.35680321632;131103;131533;132541;8894;11025.779484410266;11501.654950351503;11538.250013749079;11587.710307242218;12998.285094319714;14456.566303010926;14479.565613031415;14490.985631005728;14491.492797752238;8254.752357429286;8565.443246188977;8593.398462107718;8724.389292749574;8744.562771861381;6972;8476.110008449148;8481.363897707697;8624.482531048157;8646.52444115567;4447.288433850568;4527.207738645724;4534.523874993144;4610.907781844411;4612.477882298834;1891.8391936685537;1945;1973.3290681168185;1978.169168949016;1981.5343394302326;898.8786513820551;909.5588639509832;971.5433746139303;981.6711401680374;1010.2069875147973;20268.695969560173;20712;21123.577528449347;21135.894320528605;21266.680999785014;921.5484412637893;928.475411392561;931.5300505616524;937.6530683647094;946.654471118037;10131.949340253399;10772.946135269274;10800.135989120852;10858.185636077325;10889.782204356026;585590.072049637;606365;614155;615506;622093;93464.53267733617;112248.16277558432;124625;134292.32853835865;145916;950.2065775077888;955.7180631713522;1014.7158795537262;1025.7025462616057;1028.0541901450683;18578.44264672062;18798.3420580279;18822.905885470485;18848.6230275391;18957.620847582697;52578.21132682936;52925.38432695913;53033.73483132559;53263.934721306134;54193.72903135459;77497;77680.61159694166;79259;79277;80780;7871.094824095073;7962.761117166494;8034.397420193626;8152.388570857091;8174.754757357289;10337.74155646115;10733.711954565502;12474;12927.418266177856;13003.284819335027;10890.433136566227;10965.61620343284;11023.708564949862;11089.445527723743;11335.489902954552;10534.947325263325;10634.73413164677;10844;10863.130949524177;11178.329300242012;10714.51784669676;11170.88829111731;11242.60650877248;11361.715957101138;11473.311601303949;6875.1749790025515;7316.304951029643;7937.6031198438695;8100.432969692065;8255.215754503313;8934.463932164092;9875.456849873326;11237.157213208879;11368.602098926498;11491 04:01:02 INFO - PROCESS | 2545 | |3;mootools.html;1476.0848274070074;1494.4395851555666;1571.797574855236;1661.5347702643257;1809.8099699531558;8432.915670843297;8913.974892887329;8921.063288354726;8924.955375223122;8926.41978271412;7805.6487458064385;8031.879521807163;8066.395020373481;8516.74449766508;8625.439346442477;2322.4542232575373;2367.147826782357;2369.4668699542635;2371.2530552875874;2373.050779688121;2348.835581509295;2355.316958082154;2367.2069856597973;2387.236084452976;2393.4734358441115;2340.5201933603657;2367.230650038736;2373.252425485975;2377.9286621401384;2379.381360846175;2325.4418939454577;2354.1878052072007;2356.7643235676383;2358.3160883343808;2364.3143488388346;2324.953500929972;2332.3935776698013;2336.3691803213037;2337.298810356886;2338;7359.963200184018;8809.911900880974;8872.334574906843;8877.866831997526;8899.599518021705;4324.805383757707;5347.491988261109;5347.545458635982;5349.7057661828585;5355.946440535584;3428.7257019438384;3505.1587618971516;3656.7988760618155;3663.0476076220107;3667.8716244931566;1050.3697781331216;1058.449606204777;1062.1184416933927;1074.333912973961;1083.3337497439018;11471.369074700888;11620.302781833032;11647.76704465906;11648.0681545477;11651.475683594259;4534.161180181677;4567.310095264774;4780.139574876486;4782.689125206851;4785.306130611042;9843;9871.901280987242;9883.258755593359;9887.961764014819;9892.505374731234;5353.437889021597;5354.41101478837;5358.624896257303;5371.328583927009;5375.139977603565;4365.388845561624;4425.734455932655;4844.95155048445;4891.144049791273;4913;3797.7531460455534;3822.541295044557;3895.7662540247675;3907.27715372657;3932.587078356731;1253.254313683357;1260.4769020856425;1262.8673989231181;1269.8666640002853;1274.566647339909;806.8426656801985;870.9651613935372;904.1997831918799;927.707772051815;943.9764005899907;5566.4990150886615;5691.943080569225;5755.424457554295;5832.270966129234;6008.459238668541;928.359431991937;935.4153653966271;937.386012162035;948.1087777489138;979.299800642544;3909.843606255775;4005.4792876926394;4022.1553473770837;4022.896645421712;4167.853840193971;10289.382637041652;10710.839337409945;10792.51433685486;10845.186611004206;10870.130389568816;8863.379563430499;10782.56869725218;10794.406307652998;10794.46027698612;10827.24209305341;7851.568163751049;8175.754727358189;8177.9182208178345;8181.304589109874;8182.549959752271;5790.971045144747;5850.12248162779;5963.254593175853;6014.518838492822;6043.909341359971;9833.5083245836;9866.16137628281;9881.456519891188;9889.159421449258;9902.851457228004;9798.75503112428;10191.94904025475;10193.23550733683;10199.980002000037;10298.794024119326;7518.28576285252;7774.105977812398;7786.338161256356;8038.557879316693;8252.21603947624;7896.052473702963;7943.642536086006;8158.592070396575;8325.084240733635;8473.44922580055;7326.3406293433845;7432.108147022392;7537.397008239437;7545.849083018418;7721.420893432903;7180.712771489082;7204.927950720636;7311.195768465571;7370.5946172961;7470.178280389261;6832.692528836114;7014.649267536704;7028;7028.472864535078;7063.328983746535;4904.92642610354;4966.776495057658;4975.601951843916;5015.122353588181;5080.923786143137;4862.3922009748785;4873.829415970424;5076.619253555924;5100.209467532539;5243.082460569309 04:01:02 INFO - PROCESS | 2545 | |4;prototype.html;290.45104752018676;297.20794083766754;305.4730589732711;311.91266445395286;314.1486571391529;4210.263203939306;4701.2242979908315;5278.996990571798;5321.89356212876;5386.299781028466;4524.276115821469;5148.665336753118;5284.313039304885;5292.470752924715;5321.361436627601;4069.9389509157386;4100.323446631302;4111.321631930743;4130.772807495594;4142.150859073898;3456.602490713575;3868.8065596720053;4038.5961403859674;4095.7747323897174;4116.279651061052;3574.0171452850414;3911.27641386343;4067.6339129478342;4072.2059198456313;4073.97963010186;3337.916552086193;4056.3509838425857;4060.776657283848;4097.606813683362;4117.1765646870745;2963.7036296370406;3252.2682396460846;3323.900282991514;3535.9922422109576;3595.280943811248;4797.160496913061;5010.599152067863;5244.711540865251;5262.053459905072;5263.4473380295285;3918.275119102975;4013.8795836124964;4593.116822102955;4615.284630882219;4698.271767875985;5614.691191984439;5624.55003599711;5642.463965923231;5643.266375371217;5670.68811215383;3336.7163791077787;3601.567811862567;3822.311983842907;3885.6502914737807;3933.6459718625174;5299.682019078868;5458.426865179139;5754.935336962675;5811.767529298823;5816.505597024216;3132.577102091234;3409.863605455754;3595.352836489457;3923.960150560104;4062.471878655756;4071.450354202204;4072.7759973201164;4089.0799570097083;4089.1208390195657;4096.365063415175;4682.882927926829;4694.906101877943;4696.201645720167;4700.059988002345;4706.517447004183;5184.559312458483;5562.749676264578;5574.916376254359;5586.329640443091;5603.767171222325;1231;1260.218664428042;1308.4896890212824;1343.563341913882;1346.5287149497597;676.6278546799241;765.9200527256542;770.8150043989402;799.5927168183922;808.8382323535317;3880.2256386683657;4220.965863363434;4369.388285640013;4370.606645401929;4422.336649502536;4023.215472982799;4698.483166851644;4742.292774601141;4780.976095119502;4888.804447822047;4070.5929407059057;4208.263553878059;4224.51418086924;4312.266914624522;4375.737455752601;2081.718967939339;2750.2024412920027;3035.969640303613;3055.801372910754;3078.2304423894025;5070.2507634865915;5876.324222714357;5992.670403127782;5997.970010149921;6025.849353766103;5325.201219816981;5335;5670.945274129018;5918.112283157561;5971.223740913653;5436.728163591804;5916.47389746844;5935.7147498212635;5990.161377407167;6002.999999999913;5780.190773291659;5793.971030144822;5818.33089194748;5827.562932779973;5848.2689663792025;4650.906981860411;5460.481254280837;5569.415211402745;5621.578381621476;5766.394528574439;5608.327000759935;5761.683107429131;5850;5950.5239580834295;6036.3058248302;3745.3071181831447;3843.5195600549932;3850.9807450962567;3860.8455661773214;3872.496575445174;4861.499720201449;5130.51260130287;5436.157395603688;5864.237649105589;5886.646801191942;4276.679249056271;5302.469753024667;5548.722563871871;5672.262605861211;5697.515711164598;5468.906218756185;5484.670919744828;5591.804286849941;5607.102863541813;5610.465406793694;4414.417565723959;5390.919136213039;5550.833474995757;5558.888222355626;5564.443555644403;4724.740139292372;4949.950500494949;5543.722813859372;5587.273654424899;5648.85877853057;4716;4784.40194975628;4807.831725889577;5246.186841039645;5503.917441238465 04:01:02 INFO - PROCESS | 2545 | |5;yui.html;546.5381752419206;681.9352161544655;687.6321168175027;780.2626517940545;783.2363445640501;12338.44476998535;15614.627904186846;15835.366585336602;16031.919840400797;16061.19694015299;13097.886679632242;13686.657833554165;14278.92860535696;14504.202268875208;14669.779953300682;822.7819627798638;846.8814365988767;848.2535368875381;848.3001523742906;848.6859861851124;671.3219648155374;689.0594338727626;691.8062942376117;696.2828286864512;697.2330436519813;724.7064938699835;877.5787621941472;901.2609660078738;902;914.9908500914972;903.0608167505819;903.2096915199234;903.503073309677;903.6340282185726;903.7108125399875;662.6223052859872;663.0716996205302;663.174347936817;663.8041777675597;664.2328111031766;11828.763424731456;12384.876151238463;14911;15462.149581772997;15518.224088795516;14374.35315410809;14760.907486917104;14848.03487773302;14936.850631493655;15067;11387.772244555146;11709.355985420798;11898.702532436671;12017.158798884082;12119.48961122497;3354.932901341984;3776.3580191367532;3882.6893848492336;3921.156951255465;4011.8796436106672;90087.54956225242;94409.5279523598;95162;103114.48442757738;118586;10671.83992240117;11061;11174.832377514344;11239.381833999209;11712.180147389772;21795.564088718136;21822.127114915227;21825.0178741957;21845.45386365322;21864.562708746053;7873.133955264916;7933.722319718782;7985.880211796828;7989.560574168477;8055.234752698484;4267.807948642257;4270.722403043792;4303.720258183208;4341.196878577473;4427.690061695715;3487.3199726053176;3603.5135256740527;3630.4735813306925;3632.400653892078;3636.3272794533095;659.535027805396;671.2247354305784;671.8857794175002;677.8169894128557;678.3555622158968;562.443181250559;623.6351734235523;651.2282944710438;657.0906913801382;658.7134596450463;9061.456312621132;9249.491277979776;9321.30090243221;9353.532323383804;9414.482203478874;622.5370379607975;642.8907085795332;650.7104338569292;652.4812773844804;656.7701304543467;4316.7547094587735;4557.45310562728;4565.566271204297;4586.072003018294;4631.8378856739855;92327;153744.40828094466;159304;160749;162170.18914905586;133504;139074;144217;148510.25744871207;149546;119944;121615;123780;125107.7489225096;127147.99999999815;7234.421246300389;7851.822226666046;8671.783205419915;8838.381313308008;8950.315218417238;59978.203923294226;60897.39102608917;66333.33666663272;66852;70307.29692703008;65128.06923688351;74688;90235;92333;99788.50105749424;11696.122790790894;11784.920959050072;11990.820137698116;12003.939980300043;12089.335086570323;12746.553870614483;12797.808032879328;12938.288394138413;13111.08569496293;13146.671333216746;11006;11306.265092768943;11452.94273528627;11564.768704626029;11718.23831450987;11816.763664726486;12107.576234832091;12204.506325323893;12566.057545684293;12703.872961270268;10973.780524389307;11958.402079895797;12151.81772273399;12416.81374779397;12617.747645047231;9828.754281143028;10156.949215253877;10157.847632285373;10354.585816567253;10606.575736970435;9732.367396119229;10374.170066394821;10382.844257335995;10383.636572719919;10393.168546516412 04:01:02 INFO - PROCESS | 2545 | __end_tp_report 04:01:02 INFO - PROCESS | 2545 | __start_cc_report 04:01:02 INFO - PROCESS | 2545 | _x_x_mozilla_cycle_collect,1374 04:01:02 INFO - PROCESS | 2545 | __end_cc_report 04:01:02 INFO - PROCESS | 2545 | __startTimestamp1476097262333__endTimestamp 04:01:02 INFO - PROCESS | 2545 | 04:01:02 INFO - PROCESS | 2545 | ------- Summary: start ------- 04:01:02 INFO - PROCESS | 2545 | Number of tests: 6 04:01:02 INFO - PROCESS | 2545 | 04:01:02 INFO - PROCESS | 2545 | [#0] dojo.html Cycles:165 Average:5736.11 Median:5905.41 stddev:639.13 (10.8%) stddev-sans-first:612.01 04:01:02 INFO - PROCESS | 2545 | Values: 3306.7 3313.9 3317.4 3340.1 3379.7 5540.7 5652.8 5658.2 5695.0 5745.3 4739.0 5553.2 5710.6 5739.8 5795.9 5169.4 5233.6 5244.7 5278.7 5292.9 5175.2 5285.3 5290.7 5310.4 5311.1 5308.4 5334.0 5341.7 5360.8 5371.3 5117.4 5279.3 5287.5 5300.0 5323.9 5140.0 5147.1 5200.5 5207.8 5210.3 5539.9 5615.2 5615.7 5625.8 5634.8 6170.4 6196.3 6206.5 6207.0 6216.8 6085.3 6106.2 6148.1 6183.2 6308.4 5751.7 5767.7 5770.4 5781.2 5796.4 6332.7 6353.1 6368.4 6378.1 6383.3 4982.9 6021.4 6050.3 6129.9 6143.4 6128.5 6278.0 6286.3 6300.3 6309.0 6085.9 6187.4 6222.1 6232.7 6241.9 6040.5 6254.2 6260.4 6270.4 6286.1 4863.6 4866.6 4874.3 4902.6 4934.9 4445.9 4454.9 4455.1 4479.1 4515.5 5806.2 5816.8 5822.2 5822.3 5828.9 6060.9 6163.5 6168.0 6179.5 6268.4 5752.3 5769.6 5824.4 5835.8 5850.8 5341.5 5359.6 5361.5 5363.4 5363.8 6272.7 6288.6 6312.8 6316.5 6317.2 6168.6 6209.7 6253.1 6255.4 6307.4 6304.7 6309.5 6314.2 6314.7 6331.8 5961.2 6149.2 6172.9 6184.3 6188.5 6121.1 6172.6 6189.3 6218.7 6305.7 6121.4 6135.8 6140.3 6147.2 6152.8 6061.6 6089.8 6093.3 6126.4 6150.9 5773.5 5829.7 5889.6 5900.4 5905.4 6093.9 6141.2 6143.6 6143.8 6144.1 5777.9 5834.2 5938.0 5964.5 6002.5 04:01:02 INFO - PROCESS | 2545 | 04:01:02 INFO - PROCESS | 2545 | [#1] ext.html Cycles:180 Average:57966.52 Median:39467.40 stddev:115993.54 (293.9%) stddev-sans-first:116238.46 04:01:02 INFO - PROCESS | 2545 | Values: 421.4 472.4 475.7 491.0 521.9 30651.7 40179.2 41362.0 41422.6 41468.8 42168.4 42508.4 42628.1 42656.8 42684.8 9562.7 9601.1 9735.7 9771.3 9786.2 9614.9 9691.4 9693.6 9713.5 9715.0 9631.1 9644.0 9720.2 9730.8 9736.5 9655.4 9690.3 9691.8 9700.2 9714.0 9566.7 9674.0 9674.2 9677.0 9685.3 39853.0 40210.8 40228.6 40262.0 40334.0 3314.0 3315.5 3348.0 3382.8 3406.2 24141.0 30581.5 30723.5 30954.8 31232.8 3154.4 3207.3 3211.2 3255.7 3319.4 355076.0 357019.0 357812.0 359434.0 359942.2 57354.6 57629.4 57665.7 57666.0 57746.4 34890.7 35607.8 35613.5 60689.1 60812.1 16011.4 16382.4 16427.9 16431.2 16431.6 12640.4 12735.7 12738.2 12749.7 12772.4 14943.8 18265.7 18631.9 18664.0 18670.7 6675.3 7853.6 7862.5 7904.3 7954.1 847.5 955.6 1000.4 1006.7 1070.7 27971.4 28076.0 29022.0 29716.5 30196.2 808.9 1004.5 1034.9 1042.6 1054.9 15201.1 15310.2 15326.3 15338.8 15371.0 338938.0 345777.3 346996.0 347443.0 349016.0 55031.4 55500.0 55509.2 55539.7 55678.7 1340.8 1356.5 1367.2 1374.1 1375.2 23621.9 26280.7 30055.2 30517.7 30573.4 54930.7 55783.0 55787.0 55898.0 56045.4 494250.0 580191.0 584301.0 590702.0 590996.0 40910.6 41644.4 42191.7 42498.6 42534.0 46714.5 46720.5 46994.0 47297.8 48299.5 38316.8 39026.0 39183.0 39751.8 39866.0 38875.8 39096.4 39919.8 40017.6 40341.4 37110.8 37118.6 37585.8 38287.6 38750.8 27350.2 33594.0 33638.8 34472.8 34484.3 25019.6 25157.9 25161.9 25466.0 25709.4 04:01:02 INFO - PROCESS | 2545 | 04:01:02 INFO - PROCESS | 2545 | [#2] jquery.html Cycles:180 Average:35506.26 Median:25560.92 stddev:101706.45 (397.9%) stddev-sans-first:101957.51 04:01:02 INFO - PROCESS | 2545 | Values: 348.8 365.2 367.6 380.1 393.9 14703.9 14934.6 15630.6 19505.7 20385.8 19547.7 20177.7 20758.9 20875.8 21197.7 5536.4 5922.9 6238.8 6244.6 6244.9 5960.9 6159.6 6237.0 6240.6 6247.3 5834.1 6056.8 6195.8 6200.7 6235.2 6059.7 6105.1 6106.9 6123.3 6131.7 5031.8 5272.6 5299.5 5311.8 5315.1 25506.6 25553.4 25568.5 25649.4 25944.6 2408.8 2439.7 2510.4 2523.9 2915.1 14337.3 14584.5 15351.5 15906.7 16121.4 1829.6 1843.7 1908.5 1925.3 1943.2 123094.0 128639.4 131103.0 131533.0 132541.0 8894.0 11025.8 11501.7 11538.3 11587.7 12998.3 14456.6 14479.6 14491.0 14491.5 8254.8 8565.4 8593.4 8724.4 8744.6 6972.0 8476.1 8481.4 8624.5 8646.5 4447.3 4527.2 4534.5 4610.9 4612.5 1891.8 1945.0 1973.3 1978.2 1981.5 898.9 909.6 971.5 981.7 1010.2 20268.7 20712.0 21123.6 21135.9 21266.7 921.5 928.5 931.5 937.7 946.7 10131.9 10772.9 10800.1 10858.2 10889.8 585590.1 606365.0 614155.0 615506.0 622093.0 93464.5 112248.2 124625.0 134292.3 145916.0 950.2 955.7 1014.7 1025.7 1028.1 18578.4 18798.3 18822.9 18848.6 18957.6 52578.2 52925.4 53033.7 53263.9 54193.7 77497.0 77680.6 79259.0 79277.0 80780.0 7871.1 7962.8 8034.4 8152.4 8174.8 10337.7 10733.7 12474.0 12927.4 13003.3 10890.4 10965.6 11023.7 11089.4 11335.5 10534.9 10634.7 10844.0 10863.1 11178.3 10714.5 11170.9 11242.6 11361.7 11473.3 6875.2 7316.3 7937.6 8100.4 8255.2 8934.5 9875.5 11237.2 11368.6 11491.0 04:01:02 INFO - PROCESS | 2545 | 04:01:02 INFO - PROCESS | 2545 | [#3] mootools.html Cycles:180 Average:5689.17 Median:5078.77 stddev:3172.87 (62.5%) stddev-sans-first:3165.98 04:01:02 INFO - PROCESS | 2545 | Values: 1476.1 1494.4 1571.8 1661.5 1809.8 8432.9 8914.0 8921.1 8925.0 8926.4 7805.6 8031.9 8066.4 8516.7 8625.4 2322.5 2367.1 2369.5 2371.3 2373.1 2348.8 2355.3 2367.2 2387.2 2393.5 2340.5 2367.2 2373.3 2377.9 2379.4 2325.4 2354.2 2356.8 2358.3 2364.3 2325.0 2332.4 2336.4 2337.3 2338.0 7360.0 8809.9 8872.3 8877.9 8899.6 4324.8 5347.5 5347.5 5349.7 5355.9 3428.7 3505.2 3656.8 3663.0 3667.9 1050.4 1058.4 1062.1 1074.3 1083.3 11471.4 11620.3 11647.8 11648.1 11651.5 4534.2 4567.3 4780.1 4782.7 4785.3 9843.0 9871.9 9883.3 9888.0 9892.5 5353.4 5354.4 5358.6 5371.3 5375.1 4365.4 4425.7 4845.0 4891.1 4913.0 3797.8 3822.5 3895.8 3907.3 3932.6 1253.3 1260.5 1262.9 1269.9 1274.6 806.8 871.0 904.2 927.7 944.0 5566.5 5691.9 5755.4 5832.3 6008.5 928.4 935.4 937.4 948.1 979.3 3909.8 4005.5 4022.2 4022.9 4167.9 10289.4 10710.8 10792.5 10845.2 10870.1 8863.4 10782.6 10794.4 10794.5 10827.2 7851.6 8175.8 8177.9 8181.3 8182.5 5791.0 5850.1 5963.3 6014.5 6043.9 9833.5 9866.2 9881.5 9889.2 9902.9 9798.8 10191.9 10193.2 10200.0 10298.8 7518.3 7774.1 7786.3 8038.6 8252.2 7896.1 7943.6 8158.6 8325.1 8473.4 7326.3 7432.1 7537.4 7545.8 7721.4 7180.7 7204.9 7311.2 7370.6 7470.2 6832.7 7014.6 7028.0 7028.5 7063.3 4904.9 4966.8 4975.6 5015.1 5080.9 4862.4 4873.8 5076.6 5100.2 5243.1 04:01:02 INFO - PROCESS | 2545 | 04:01:02 INFO - PROCESS | 2545 | [#4] prototype.html Cycles:180 Average:4446.37 Median:4761.63 stddev:1395.29 (29.3%) stddev-sans-first:1363.89 04:01:02 INFO - PROCESS | 2545 | Values: 290.5 297.2 305.5 311.9 314.1 4210.3 4701.2 5279.0 5321.9 5386.3 4524.3 5148.7 5284.3 5292.5 5321.4 4069.9 4100.3 4111.3 4130.8 4142.2 3456.6 3868.8 4038.6 4095.8 4116.3 3574.0 3911.3 4067.6 4072.2 4074.0 3337.9 4056.4 4060.8 4097.6 4117.2 2963.7 3252.3 3323.9 3536.0 3595.3 4797.2 5010.6 5244.7 5262.1 5263.4 3918.3 4013.9 4593.1 4615.3 4698.3 5614.7 5624.6 5642.5 5643.3 5670.7 3336.7 3601.6 3822.3 3885.7 3933.6 5299.7 5458.4 5754.9 5811.8 5816.5 3132.6 3409.9 3595.4 3924.0 4062.5 4071.5 4072.8 4089.1 4089.1 4096.4 4682.9 4694.9 4696.2 4700.1 4706.5 5184.6 5562.7 5574.9 5586.3 5603.8 1231.0 1260.2 1308.5 1343.6 1346.5 676.6 765.9 770.8 799.6 808.8 3880.2 4221.0 4369.4 4370.6 4422.3 4023.2 4698.5 4742.3 4781.0 4888.8 4070.6 4208.3 4224.5 4312.3 4375.7 2081.7 2750.2 3036.0 3055.8 3078.2 5070.3 5876.3 5992.7 5998.0 6025.8 5325.2 5335.0 5670.9 5918.1 5971.2 5436.7 5916.5 5935.7 5990.2 6003.0 5780.2 5794.0 5818.3 5827.6 5848.3 4650.9 5460.5 5569.4 5621.6 5766.4 5608.3 5761.7 5850.0 5950.5 6036.3 3745.3 3843.5 3851.0 3860.8 3872.5 4861.5 5130.5 5436.2 5864.2 5886.6 4276.7 5302.5 5548.7 5672.3 5697.5 5468.9 5484.7 5591.8 5607.1 5610.5 4414.4 5390.9 5550.8 5558.9 5564.4 4724.7 4950.0 5543.7 5587.3 5648.9 4716.0 4784.4 4807.8 5246.2 5503.9 04:01:02 INFO - PROCESS | 2545 | 04:01:02 INFO - PROCESS | 2545 | [#5] yui.html Cycles:180 Average:24834.78 Median:3829.52 stddev:40927.25 (1068.7%) stddev-sans-first:41001.43 04:01:02 INFO - PROCESS | 2545 | Values: 546.5 681.9 687.6 780.3 783.2 12338.4 15614.6 15835.4 16031.9 16061.2 13097.9 13686.7 14278.9 14504.2 14669.8 822.8 846.9 848.3 848.3 848.7 671.3 689.1 691.8 696.3 697.2 724.7 877.6 901.3 902.0 915.0 903.1 903.2 903.5 903.6 903.7 662.6 663.1 663.2 663.8 664.2 11828.8 12384.9 14911.0 15462.1 15518.2 14374.4 14760.9 14848.0 14936.9 15067.0 11387.8 11709.4 11898.7 12017.2 12119.5 3354.9 3776.4 3882.7 3921.2 4011.9 90087.5 94409.5 95162.0 103114.5 118586.0 10671.8 11061.0 11174.8 11239.4 11712.2 21795.6 21822.1 21825.0 21845.5 21864.6 7873.1 7933.7 7985.9 7989.6 8055.2 4267.8 4270.7 4303.7 4341.2 4427.7 3487.3 3603.5 3630.5 3632.4 3636.3 659.5 671.2 671.9 677.8 678.4 562.4 623.6 651.2 657.1 658.7 9061.5 9249.5 9321.3 9353.5 9414.5 622.5 642.9 650.7 652.5 656.8 4316.8 4557.5 4565.6 4586.1 4631.8 92327.0 153744.4 159304.0 160749.0 162170.2 133504.0 139074.0 144217.0 148510.3 149546.0 119944.0 121615.0 123780.0 125107.7 127148.0 7234.4 7851.8 8671.8 8838.4 8950.3 59978.2 60897.4 66333.3 66852.0 70307.3 65128.1 74688.0 90235.0 92333.0 99788.5 11696.1 11784.9 11990.8 12003.9 12089.3 12746.6 12797.8 12938.3 13111.1 13146.7 11006.0 11306.3 11452.9 11564.8 11718.2 11816.8 12107.6 12204.5 12566.1 12703.9 10973.8 11958.4 12151.8 12416.8 12617.7 9828.8 10156.9 10157.8 10354.6 10606.6 9732.4 10374.2 10382.8 10383.6 10393.2 04:01:02 INFO - PROCESS | 2545 | -------- Summary: end -------- 04:01:02 INFO - PROCESS | 2545 | 04:01:02 INFO - TEST-INFO | 2545: exit 0 04:01:02 INFO - TEST-OK | dromaeo_css | took 1118889ms 04:01:02 INFO - TEST-START | kraken 04:01:02 INFO - operating with platform_type : mac_ 04:01:02 INFO - Initialising browser for kraken test... 04:01:03 INFO - TEST-INFO | started process 2553 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/tmpBqyly1/profile http://localhost:56593/getInfo.html) 04:01:05 INFO - PROCESS | 2553 | __metrics Screen width/height:1600/1200 04:01:05 INFO - PROCESS | 2553 | colorDepth:24 04:01:05 INFO - PROCESS | 2553 | Browser inner width/height: 1024/689 04:01:05 INFO - PROCESS | 2553 | __metrics 04:01:05 INFO - PROCESS | 2553 | 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" 04:01:05 INFO - PROCESS | 2553 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:01:06 INFO - TEST-INFO | 2553: exit 0 04:01:06 INFO - Browser initialized. 04:01:06 INFO - Running cycle 1/1 for kraken test... 04:01:06 INFO - TEST-INFO | started process 2554 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/T/tmpBqyly1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:01:18 INFO - PROCESS | 2554 | RSS: Main: 248406016 04:01:18 INFO - PROCESS | 2554 | 04:01:20 INFO - PROCESS | 2554 | Cycle 1(1): loaded http://localhost:56593/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56593/tests/kraken/driver.html?testName=audio-beat-detection) 04:01:21 INFO - PROCESS | 2554 | RSS: Main: 304926720 04:01:21 INFO - PROCESS | 2554 | 04:01:24 INFO - PROCESS | 2554 | Cycle 1(1): loaded http://localhost:56593/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56593/tests/kraken/driver.html?testName=audio-dft) 04:01:24 INFO - PROCESS | 2554 | RSS: Main: 305799168 04:01:24 INFO - PROCESS | 2554 | 04:01:28 INFO - PROCESS | 2554 | Cycle 1(1): loaded http://localhost:56593/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56593/tests/kraken/driver.html?testName=audio-fft) 04:01:29 INFO - PROCESS | 2554 | RSS: Main: 392273920 04:01:29 INFO - PROCESS | 2554 | 04:01:31 INFO - PROCESS | 2554 | Cycle 1(1): loaded http://localhost:56593/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56593/tests/kraken/driver.html?testName=audio-oscillator) 04:01:31 INFO - PROCESS | 2554 | RSS: Main: 294723584 04:01:31 INFO - PROCESS | 2554 | 04:01:33 INFO - PROCESS | 2554 | Cycle 1(1): loaded http://localhost:56593/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56593/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:01:34 INFO - PROCESS | 2554 | RSS: Main: 296058880 04:01:34 INFO - PROCESS | 2554 | 04:01:36 INFO - PROCESS | 2554 | Cycle 1(1): loaded http://localhost:56593/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56593/tests/kraken/driver.html?testName=imaging-darkroom) 04:01:37 INFO - PROCESS | 2554 | RSS: Main: 335278080 04:01:37 INFO - PROCESS | 2554 | 04:01:40 INFO - PROCESS | 2554 | Cycle 1(1): loaded http://localhost:56593/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56593/tests/kraken/driver.html?testName=imaging-desaturate) 04:01:41 INFO - PROCESS | 2554 | RSS: Main: 335130624 04:01:41 INFO - PROCESS | 2554 | 04:01:43 INFO - PROCESS | 2554 | Cycle 1(1): loaded http://localhost:56593/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56593/tests/kraken/driver.html?testName=json-parse-financial) 04:01:44 INFO - PROCESS | 2554 | RSS: Main: 332582912 04:01:44 INFO - PROCESS | 2554 | 04:01:45 INFO - PROCESS | 2554 | Cycle 1(1): loaded http://localhost:56593/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56593/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:01:46 INFO - PROCESS | 2554 | RSS: Main: 307716096 04:01:46 INFO - PROCESS | 2554 | 04:01:48 INFO - PROCESS | 2554 | Cycle 1(1): loaded http://localhost:56593/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56593/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:01:49 INFO - PROCESS | 2554 | RSS: Main: 313655296 04:01:49 INFO - PROCESS | 2554 | 04:01:51 INFO - PROCESS | 2554 | Cycle 1(1): loaded http://localhost:56593/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56593/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:01:52 INFO - PROCESS | 2554 | RSS: Main: 318763008 04:01:52 INFO - PROCESS | 2554 | 04:01:55 INFO - PROCESS | 2554 | Cycle 1(1): loaded http://localhost:56593/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56593/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:01:55 INFO - PROCESS | 2554 | RSS: Main: 327327744 04:01:55 INFO - PROCESS | 2554 | 04:01:58 INFO - PROCESS | 2554 | Cycle 1(1): loaded http://localhost:56593/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56593/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:01:59 INFO - PROCESS | 2554 | RSS: Main: 326991872 04:01:59 INFO - PROCESS | 2554 | 04:02:01 INFO - PROCESS | 2554 | Cycle 1(1): loaded http://localhost:56593/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56593/tests/kraken/driver.html?testName=ai-astar) 04:02:01 INFO - PROCESS | 2554 | RSS: Main: 371412992 04:02:01 INFO - PROCESS | 2554 | 04:02:01 INFO - PROCESS | 2554 | __start_tp_report 04:02:01 INFO - PROCESS | 2554 | _x_x_mozilla_page_load 04:02:01 INFO - PROCESS | 2554 | _x_x_mozilla_page_load_details 04:02:01 INFO - PROCESS | 2554 | |i|pagename|runs| 04:02:01 INFO - PROCESS | 2554 | |0;ai-astar;113;112;112;111;112;112;109;107;112;112 04:02:01 INFO - PROCESS | 2554 | |1;audio-beat-detection;152;115;115;151;114;113;112;112;144;114 04:02:01 INFO - PROCESS | 2554 | |2;audio-dft;183;184;186;194;254;187;181;183;184;184 04:02:01 INFO - PROCESS | 2554 | |3;audio-fft;76;59;60;90;64;60;58;58;84;57 04:02:01 INFO - PROCESS | 2554 | |4;audio-oscillator;86;72;96;69;68;68;92;68;68;89 04:02:01 INFO - PROCESS | 2554 | |5;imaging-gaussian-blur;88;85;88;86;88;85;88;85;87;85 04:02:01 INFO - PROCESS | 2554 | |6;imaging-darkroom;94;93;94;95;93;94;94;96;101;93 04:02:01 INFO - PROCESS | 2554 | |7;imaging-desaturate;84;84;87;83;87;84;87;84;84;88 04:02:01 INFO - PROCESS | 2554 | |8;json-parse-financial;57;51;53;53;73;84;54;53;53;53 04:02:01 INFO - PROCESS | 2554 | |9;json-stringify-tinderbox;66;76;69;81;76;74;72;71;73;74 04:02:01 INFO - PROCESS | 2554 | |10;stanford-crypto-aes;87;87;105;85;86;87;86;88;89;92 04:02:01 INFO - PROCESS | 2554 | |11;stanford-crypto-ccm;145;144;148;145;146;144;145;144;207;144 04:02:01 INFO - PROCESS | 2554 | |12;stanford-crypto-pbkdf2;171;172;171;174;171;171;170;171;171;172 04:02:01 INFO - PROCESS | 2554 | |13;stanford-crypto-sha256-iterative;69;69;69;68;68;71;68;68;68;68 04:02:01 INFO - PROCESS | 2554 | __end_tp_report 04:02:01 INFO - PROCESS | 2554 | __start_cc_report 04:02:01 INFO - PROCESS | 2554 | _x_x_mozilla_cycle_collect,1945 04:02:01 INFO - PROCESS | 2554 | __end_cc_report 04:02:01 INFO - PROCESS | 2554 | __startTimestamp1476097321270__endTimestamp 04:02:01 INFO - PROCESS | 2554 | 04:02:01 INFO - PROCESS | 2554 | ------- Summary: start ------- 04:02:01 INFO - PROCESS | 2554 | Number of tests: 14 04:02:01 INFO - PROCESS | 2554 | 04:02:01 INFO - PROCESS | 2554 | [#0] ai-astar Cycles:10 Average:111.20 Median:112.00 stddev:1.81 (1.6%) stddev-sans-first:1.80 04:02:01 INFO - PROCESS | 2554 | Values: 113.0 112.0 112.0 111.0 112.0 112.0 109.0 107.0 112.0 112.0 04:02:01 INFO - PROCESS | 2554 | 04:02:01 INFO - PROCESS | 2554 | [#1] audio-beat-detection Cycles:10 Average:124.20 Median:115.00 stddev:17.27 (15.0%) stddev-sans-first:15.10 04:02:01 INFO - PROCESS | 2554 | Values: 152.0 115.0 115.0 151.0 114.0 113.0 112.0 112.0 144.0 114.0 04:02:01 INFO - PROCESS | 2554 | 04:02:01 INFO - PROCESS | 2554 | [#2] audio-dft Cycles:10 Average:192.00 Median:185.00 stddev:22.07 (11.9%) stddev-sans-first:23.17 04:02:01 INFO - PROCESS | 2554 | Values: 183.0 184.0 186.0 194.0 254.0 187.0 181.0 183.0 184.0 184.0 04:02:01 INFO - PROCESS | 2554 | 04:02:01 INFO - PROCESS | 2554 | [#3] audio-fft Cycles:10 Average:66.60 Median:62.00 stddev:12.16 (19.6%) stddev-sans-first:12.41 04:02:01 INFO - PROCESS | 2554 | Values: 76.0 59.0 60.0 90.0 64.0 60.0 58.0 58.0 84.0 57.0 04:02:01 INFO - PROCESS | 2554 | 04:02:01 INFO - PROCESS | 2554 | [#4] audio-oscillator Cycles:10 Average:77.60 Median:79.00 stddev:11.64 (14.7%) stddev-sans-first:11.95 04:02:01 INFO - PROCESS | 2554 | Values: 86.0 72.0 96.0 69.0 68.0 68.0 92.0 68.0 68.0 89.0 04:02:01 INFO - PROCESS | 2554 | 04:02:01 INFO - PROCESS | 2554 | [#5] imaging-gaussian-blur Cycles:10 Average:86.50 Median:87.50 stddev:1.43 (1.6%) stddev-sans-first:1.41 04:02:01 INFO - PROCESS | 2554 | Values: 88.0 85.0 88.0 86.0 88.0 85.0 88.0 85.0 87.0 85.0 04:02:01 INFO - PROCESS | 2554 | 04:02:01 INFO - PROCESS | 2554 | [#6] imaging-darkroom Cycles:10 Average:94.70 Median:94.00 stddev:2.41 (2.6%) stddev-sans-first:2.54 04:02:01 INFO - PROCESS | 2554 | Values: 94.0 93.0 94.0 95.0 93.0 94.0 94.0 96.0 101.0 93.0 04:02:01 INFO - PROCESS | 2554 | 04:02:01 INFO - PROCESS | 2554 | [#7] imaging-desaturate Cycles:10 Average:85.20 Median:85.50 stddev:1.81 (2.1%) stddev-sans-first:1.87 04:02:01 INFO - PROCESS | 2554 | Values: 84.0 84.0 87.0 83.0 87.0 84.0 87.0 84.0 84.0 88.0 04:02:01 INFO - PROCESS | 2554 | 04:02:01 INFO - PROCESS | 2554 | [#8] json-parse-financial Cycles:10 Average:58.40 Median:53.50 stddev:11.01 (20.6%) stddev-sans-first:11.66 04:02:01 INFO - PROCESS | 2554 | Values: 57.0 51.0 53.0 53.0 73.0 84.0 54.0 53.0 53.0 53.0 04:02:01 INFO - PROCESS | 2554 | 04:02:01 INFO - PROCESS | 2554 | [#9] json-stringify-tinderbox Cycles:10 Average:73.20 Median:74.00 stddev:4.13 (5.6%) stddev-sans-first:3.46 04:02:01 INFO - PROCESS | 2554 | Values: 66.0 76.0 69.0 81.0 76.0 74.0 72.0 71.0 73.0 74.0 04:02:01 INFO - PROCESS | 2554 | 04:02:01 INFO - PROCESS | 2554 | [#10] stanford-crypto-aes Cycles:10 Average:89.20 Median:87.00 stddev:5.88 (6.8%) stddev-sans-first:6.19 04:02:01 INFO - PROCESS | 2554 | Values: 87.0 87.0 105.0 85.0 86.0 87.0 86.0 88.0 89.0 92.0 04:02:01 INFO - PROCESS | 2554 | 04:02:01 INFO - PROCESS | 2554 | [#11] stanford-crypto-ccm Cycles:10 Average:151.20 Median:145.00 stddev:19.65 (13.5%) stddev-sans-first:20.71 04:02:01 INFO - PROCESS | 2554 | Values: 145.0 144.0 148.0 145.0 146.0 144.0 145.0 144.0 207.0 144.0 04:02:01 INFO - PROCESS | 2554 | 04:02:01 INFO - PROCESS | 2554 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.40 Median:171.00 stddev:1.07 (0.6%) stddev-sans-first:1.13 04:02:01 INFO - PROCESS | 2554 | Values: 171.0 172.0 171.0 174.0 171.0 171.0 170.0 171.0 171.0 172.0 04:02:01 INFO - PROCESS | 2554 | 04:02:01 INFO - PROCESS | 2554 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.60 Median:68.50 stddev:0.97 (1.4%) stddev-sans-first:1.01 04:02:01 INFO - PROCESS | 2554 | Values: 69.0 69.0 69.0 68.0 68.0 71.0 68.0 68.0 68.0 68.0 04:02:01 INFO - PROCESS | 2554 | -------- Summary: end -------- 04:02:01 INFO - PROCESS | 2554 | 04:02:02 INFO - TEST-INFO | 2554: exit 0 04:02:02 INFO - TEST-OK | kraken | took 59173ms 04:02:02 INFO - SUITE-END | took 1178s 04:02:02 INFO - Completed test suite (00:19:38) 04:02:02 INFO - javascript benchmark 04:02:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3306.7189288910445, 3313.867445302188, 3317.4360358739027, 3340.1482621931405, 3379.7127244184244, 5540.667559946406, 5652.802151924683, 5658.179563963233, 5694.971525142374, 5745.253117094782, 4739.0, 5553.194786755918, 5710.571707121972, 5739.799107031255, 5795.855103622412, 5169.40551836538, 5233.555147812422, 5244.737763111848, 5278.683279003272, 5292.947070529303, 5175.197844334134, 5285.312909321802, 5290.656107353029, 5310.4424035476295, 5311.123664595337, 5308.362996440433, 5334.03987282289, 5341.65279256849, 5360.785568577272, 5371.301730774994, 5117.385913690344, 5279.313689220415, 5287.524122828964, 5300.0, 5323.866903327409, 5140.049090918192, 5147.073526765181, 5200.5059519345605, 5207.791688332462, 5210.322658054467, 5539.9169012464845, 5615.241942337773, 5615.691136987464, 5625.80309689163, 5634.802781902655, 6170.382961703794, 6196.256449226076, 6206.534509911775, 6206.968965155145, 6216.813495595139, 6085.300190478063, 6106.210084242431, 6148.077788331741, 6183.227096612924, 6308.369163083701, 5751.683657398841, 5767.653940763567, 5770.365259821417, 5781.248437703156, 5796.449337312949, 6332.715027823667, 6353.078803573456, 6368.395002474757, 6378.075179099004, 6383.2978372379, 4982.8754281143265, 6021.3677563856045, 6050.304215015241, 6129.938700612936, 6143.38566143391, 6128.503123582198, 6277.96861015692, 6286.339934306923, 6300.30696623371, 6309.022101574264, 6085.939140608626, 6187.443130118311, 6222.12890195373, 6232.719527621177, 6241.906371404432, 6040.456358927718, 6254.249490061222, 6260.436560709558, 6270.404311590391, 6286.119943207955, 4863.5622793948005, 4866.635002374836, 4874.293227481995, 4902.632302577321, 4934.8766280843265, 4445.911081778347, 4454.866354009385, 4455.064436468378, 4479.059397526556, 4515.525869783691, 5806.21616081832, 5816.796412125555, 5822.155787410802, 5822.301323841081, 5828.912566311508, 6060.878782424327, 6163.506919446433, 6168.013117901204, 6179.474744646755, 6268.404501572434, 5752.338481074647, 5769.624974376652, 5824.359320474744, 5835.824925252249, 5850.824475265749, 5341.4925582069645, 5359.598030147755, 5361.544268737201, 5363.383210930715, 5363.839084827461, 6272.717727702356, 6288.591241569284, 6312.779052733133, 6316.494680425647, 6317.210348706411, 6168.599041062316, 6209.720562574605, 6253.062040693932, 6255.40573645503, 6307.400796924285, 6304.684765761602, 6309.463695585927, 6314.210723659543, 6314.715837787218, 6331.778387756406, 5961.225040744675, 6149.169862068564, 6172.876542469216, 6184.319724830175, 6188.504919606331, 6121.143039974496, 6172.5679202457195, 6189.319174890668, 6218.689065546795, 6305.684715764101, 6121.387861213843, 6135.754569817157, 6140.3245642978345, 6147.200863887666, 6152.753889844356, 6061.571072443225, 6089.786857459968, 6093.289542879333, 6126.387361263838, 6150.876982460415, 5773.451522105393, 5829.679367634821, 5889.617174883619, 5900.380460051632, 5905.409459054232, 6093.878122437438, 6141.170941922962, 6143.600665956698, 6143.784967526115, 6144.109104179958, 5777.8555536111935, 5834.241548598655, 5938.020226662552, 5964.463198311999, 6002.489788367863], "value": 5695.0212483157, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [421.4415898933912, 472.4000519340436, 475.6955548448992, 490.9787641705253, 521.8956208758244, 30651.693483065163, 40179.196416071696, 41362.0, 41422.58577414229, 41468.75593732193, 42168.36747448791, 42508.36237456441, 42628.14743705124, 42656.78671606637, 42684.786576067156, 9562.665306714249, 9601.1358977692, 9735.70792876215, 9771.316007879452, 9786.168175705074, 9614.855777163348, 9691.370060946052, 9693.612255509772, 9713.514324283757, 9715.0, 9631.133198012178, 9643.951780241088, 9720.222382209442, 9730.756731081708, 9736.51317434129, 9655.372400793925, 9690.32167748258, 9691.757706057335, 9700.223982081416, 9713.98003209667, 9566.665166719202, 9673.951630241874, 9674.22606191503, 9676.95161524195, 9685.32202745808, 39853.00367490807, 40210.79894600537, 40228.59771402278, 40261.99345016369, 40334.0, 3313.950290745641, 3315.4860996545576, 3347.983260083684, 3382.7970321780526, 3406.233597440581, 24141.0, 30581.541276880864, 30723.539146912815, 30954.845225773726, 31232.843835780677, 3154.3848949454864, 3207.294395233045, 3211.2132527530903, 3255.739540836751, 3319.40250754864, 355076.0, 357019.0, 357812.0, 359434.0, 359942.2002889995, 57354.56613584694, 57629.423705762405, 57665.71167144137, 57666.0, 57746.422535774946, 34890.65109348925, 35607.82196089055, 35613.46579801305, 60689.08966365508, 60812.087818682754, 16011.439599613957, 16382.426615068654, 16427.91786041062, 16431.1784410779, 16431.589210269838, 12640.367981600883, 12735.745285094432, 12738.172018818932, 12749.68125796844, 12772.361381930867, 14943.775843362359, 18265.726014109798, 18631.906840465712, 18664.0, 18670.719939200924, 6675.332466753286, 7853.568053756984, 7862.488938218998, 7904.288614024766, 7954.125046244909, 847.4618617178136, 955.6177528988321, 1000.3747657713928, 1006.7483129217695, 1070.7483741320784, 27971.44057118846, 28076.01733939343, 29021.98423055225, 29716.462378276745, 30196.24509387283, 808.9069756977905, 1004.5278719002204, 1034.896510348974, 1042.6142327338932, 1054.878688950765, 15201.087934723952, 15310.157941313111, 15326.310316035582, 15338.769918451233, 15371.0, 338938.0, 345777.27111364785, 346996.0, 347443.0, 349016.0, 55031.44968550263, 55500.0, 55509.16736248959, 55539.72230138904, 55678.72160639252, 1340.8458027326787, 1356.525216174331, 1367.1523655333558, 1374.0793668242302, 1375.2229990055587, 23621.881890590437, 26280.73719262783, 30055.248618784706, 30517.694823051486, 30573.388532228786, 54930.72534637461, 55783.0, 55787.0, 55898.0, 56045.43954560402, 494250.0, 580191.0, 584301.0, 590702.0, 590996.0, 40910.5681301153, 41644.375334369404, 42191.73424797261, 42498.575014249465, 42534.0, 46714.532854672376, 46720.53279467298, 46994.0, 47297.7635111836, 48299.517004829504, 38316.80841595774, 39026.0, 39183.0, 39751.80124099477, 39866.0, 38875.80562097171, 39096.41355379728, 39919.80040099781, 40017.59982400139, 40341.39487907625, 37110.8144459276, 37118.62881371152, 37585.81207093947, 38287.61712382841, 38750.80624596859, 27350.179494615193, 33594.0, 33638.83180584081, 34472.82763586166, 34484.310313794085, 25019.624705629067, 25157.86789594436, 25161.874190629664, 25466.0, 25709.3572660685], "value": 19383.532616217377, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.8255872063968, 365.22936603766055, 367.5809577082127, 380.0877893056664, 393.9369700847863, 14703.852961470382, 14934.626634334134, 15630.609234769136, 19505.707414388795, 20385.796142038576, 19547.706784398244, 20177.697334539957, 20758.85826279554, 20875.791242087573, 21197.682034769452, 5536.363318218396, 5922.940770592282, 6238.844028899269, 6244.56288059836, 6244.875102497947, 5960.850978725545, 6159.599626024294, 6237.0, 6240.625562466266, 6247.281562620288, 5834.124881267822, 6056.757729690807, 6195.845103872394, 6200.658963756991, 6235.158253635769, 6059.697015149225, 6105.084237364386, 6106.908396374036, 6123.265208175025, 6131.662758548278, 5031.798728050873, 5272.630915835893, 5299.496547827989, 5311.840644780624, 5315.069862773999, 25506.617400739004, 25553.361165970815, 25568.48863022748, 25649.35876603081, 25944.61083083755, 2408.8193385496156, 2439.7194322652945, 2510.447701505666, 2523.866022627428, 2915.125462361283, 14337.28313584327, 14584.489542866055, 15351.4626988056, 15906.681866362607, 16121.435749748702, 1829.6432195721839, 1843.6865732825452, 1908.5133291010843, 1925.258775371492, 1943.24213556713, 123094.0, 128639.35680321632, 131103.0, 131533.0, 132541.0, 8894.0, 11025.779484410266, 11501.654950351503, 11538.250013749079, 11587.710307242218, 12998.285094319714, 14456.566303010926, 14479.565613031415, 14490.985631005728, 14491.492797752238, 8254.752357429286, 8565.443246188977, 8593.398462107718, 8724.389292749574, 8744.562771861381, 6972.0, 8476.110008449148, 8481.363897707697, 8624.482531048157, 8646.52444115567, 4447.288433850568, 4527.207738645724, 4534.523874993144, 4610.907781844411, 4612.477882298834, 1891.8391936685537, 1945.0, 1973.3290681168185, 1978.169168949016, 1981.5343394302326, 898.8786513820551, 909.5588639509832, 971.5433746139303, 981.6711401680374, 1010.2069875147973, 20268.695969560173, 20712.0, 21123.577528449347, 21135.894320528605, 21266.680999785014, 921.5484412637893, 928.475411392561, 931.5300505616524, 937.6530683647094, 946.654471118037, 10131.949340253399, 10772.946135269274, 10800.135989120852, 10858.185636077325, 10889.782204356026, 585590.072049637, 606365.0, 614155.0, 615506.0, 622093.0, 93464.53267733617, 112248.16277558432, 124625.0, 134292.32853835865, 145916.0, 950.2065775077888, 955.7180631713522, 1014.7158795537262, 1025.7025462616057, 1028.0541901450683, 18578.44264672062, 18798.3420580279, 18822.905885470485, 18848.6230275391, 18957.620847582697, 52578.21132682936, 52925.38432695913, 53033.73483132559, 53263.934721306134, 54193.72903135459, 77497.0, 77680.61159694166, 79259.0, 79277.0, 80780.0, 7871.094824095073, 7962.761117166494, 8034.397420193626, 8152.388570857091, 8174.754757357289, 10337.74155646115, 10733.711954565502, 12474.0, 12927.418266177856, 13003.284819335027, 10890.433136566227, 10965.61620343284, 11023.708564949862, 11089.445527723743, 11335.489902954552, 10534.947325263325, 10634.73413164677, 10844.0, 10863.130949524177, 11178.329300242012, 10714.51784669676, 11170.88829111731, 11242.60650877248, 11361.715957101138, 11473.311601303949, 6875.1749790025515, 7316.304951029643, 7937.6031198438695, 8100.432969692065, 8255.215754503313, 8934.463932164092, 9875.456849873326, 11237.157213208879, 11368.602098926498, 11491.0], "value": 9646.454194427899, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1476.0848274070074, 1494.4395851555666, 1571.797574855236, 1661.5347702643257, 1809.8099699531558, 8432.915670843297, 8913.974892887329, 8921.063288354726, 8924.955375223122, 8926.41978271412, 7805.6487458064385, 8031.879521807163, 8066.395020373481, 8516.74449766508, 8625.439346442477, 2322.4542232575373, 2367.147826782357, 2369.4668699542635, 2371.2530552875874, 2373.050779688121, 2348.835581509295, 2355.316958082154, 2367.2069856597973, 2387.236084452976, 2393.4734358441115, 2340.5201933603657, 2367.230650038736, 2373.252425485975, 2377.9286621401384, 2379.381360846175, 2325.4418939454577, 2354.1878052072007, 2356.7643235676383, 2358.3160883343808, 2364.3143488388346, 2324.953500929972, 2332.3935776698013, 2336.3691803213037, 2337.298810356886, 2338.0, 7359.963200184018, 8809.911900880974, 8872.334574906843, 8877.866831997526, 8899.599518021705, 4324.805383757707, 5347.491988261109, 5347.545458635982, 5349.7057661828585, 5355.946440535584, 3428.7257019438384, 3505.1587618971516, 3656.7988760618155, 3663.0476076220107, 3667.8716244931566, 1050.3697781331216, 1058.449606204777, 1062.1184416933927, 1074.333912973961, 1083.3337497439018, 11471.369074700888, 11620.302781833032, 11647.76704465906, 11648.0681545477, 11651.475683594259, 4534.161180181677, 4567.310095264774, 4780.139574876486, 4782.689125206851, 4785.306130611042, 9843.0, 9871.901280987242, 9883.258755593359, 9887.961764014819, 9892.505374731234, 5353.437889021597, 5354.41101478837, 5358.624896257303, 5371.328583927009, 5375.139977603565, 4365.388845561624, 4425.734455932655, 4844.95155048445, 4891.144049791273, 4913.0, 3797.7531460455534, 3822.541295044557, 3895.7662540247675, 3907.27715372657, 3932.587078356731, 1253.254313683357, 1260.4769020856425, 1262.8673989231181, 1269.8666640002853, 1274.566647339909, 806.8426656801985, 870.9651613935372, 904.1997831918799, 927.707772051815, 943.9764005899907, 5566.4990150886615, 5691.943080569225, 5755.424457554295, 5832.270966129234, 6008.459238668541, 928.359431991937, 935.4153653966271, 937.386012162035, 948.1087777489138, 979.299800642544, 3909.843606255775, 4005.4792876926394, 4022.1553473770837, 4022.896645421712, 4167.853840193971, 10289.382637041652, 10710.839337409945, 10792.51433685486, 10845.186611004206, 10870.130389568816, 8863.379563430499, 10782.56869725218, 10794.406307652998, 10794.46027698612, 10827.24209305341, 7851.568163751049, 8175.754727358189, 8177.9182208178345, 8181.304589109874, 8182.549959752271, 5790.971045144747, 5850.12248162779, 5963.254593175853, 6014.518838492822, 6043.909341359971, 9833.5083245836, 9866.16137628281, 9881.456519891188, 9889.159421449258, 9902.851457228004, 9798.75503112428, 10191.94904025475, 10193.23550733683, 10199.980002000037, 10298.794024119326, 7518.28576285252, 7774.105977812398, 7786.338161256356, 8038.557879316693, 8252.21603947624, 7896.052473702963, 7943.642536086006, 8158.592070396575, 8325.084240733635, 8473.44922580055, 7326.3406293433845, 7432.108147022392, 7537.397008239437, 7545.849083018418, 7721.420893432903, 7180.712771489082, 7204.927950720636, 7311.195768465571, 7370.5946172961, 7470.178280389261, 6832.692528836114, 7014.649267536704, 7028.0, 7028.472864535078, 7063.328983746535, 4904.92642610354, 4966.776495057658, 4975.601951843916, 5015.122353588181, 5080.923786143137, 4862.3922009748785, 4873.829415970424, 5076.619253555924, 5100.209467532539, 5243.082460569309], "value": 4578.945229800395, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [290.45104752018676, 297.20794083766754, 305.4730589732711, 311.91266445395286, 314.1486571391529, 4210.263203939306, 4701.2242979908315, 5278.996990571798, 5321.89356212876, 5386.299781028466, 4524.276115821469, 5148.665336753118, 5284.313039304885, 5292.470752924715, 5321.361436627601, 4069.9389509157386, 4100.323446631302, 4111.321631930743, 4130.772807495594, 4142.150859073898, 3456.602490713575, 3868.8065596720053, 4038.5961403859674, 4095.7747323897174, 4116.279651061052, 3574.0171452850414, 3911.27641386343, 4067.6339129478342, 4072.2059198456313, 4073.97963010186, 3337.916552086193, 4056.3509838425857, 4060.776657283848, 4097.606813683362, 4117.1765646870745, 2963.7036296370406, 3252.2682396460846, 3323.900282991514, 3535.9922422109576, 3595.280943811248, 4797.160496913061, 5010.599152067863, 5244.711540865251, 5262.053459905072, 5263.4473380295285, 3918.275119102975, 4013.8795836124964, 4593.116822102955, 4615.284630882219, 4698.271767875985, 5614.691191984439, 5624.55003599711, 5642.463965923231, 5643.266375371217, 5670.68811215383, 3336.7163791077787, 3601.567811862567, 3822.311983842907, 3885.6502914737807, 3933.6459718625174, 5299.682019078868, 5458.426865179139, 5754.935336962675, 5811.767529298823, 5816.505597024216, 3132.577102091234, 3409.863605455754, 3595.352836489457, 3923.960150560104, 4062.471878655756, 4071.450354202204, 4072.7759973201164, 4089.0799570097083, 4089.1208390195657, 4096.365063415175, 4682.882927926829, 4694.906101877943, 4696.201645720167, 4700.059988002345, 4706.517447004183, 5184.559312458483, 5562.749676264578, 5574.916376254359, 5586.329640443091, 5603.767171222325, 1231.0, 1260.218664428042, 1308.4896890212824, 1343.563341913882, 1346.5287149497597, 676.6278546799241, 765.9200527256542, 770.8150043989402, 799.5927168183922, 808.8382323535317, 3880.2256386683657, 4220.965863363434, 4369.388285640013, 4370.606645401929, 4422.336649502536, 4023.215472982799, 4698.483166851644, 4742.292774601141, 4780.976095119502, 4888.804447822047, 4070.5929407059057, 4208.263553878059, 4224.51418086924, 4312.266914624522, 4375.737455752601, 2081.718967939339, 2750.2024412920027, 3035.969640303613, 3055.801372910754, 3078.2304423894025, 5070.2507634865915, 5876.324222714357, 5992.670403127782, 5997.970010149921, 6025.849353766103, 5325.201219816981, 5335.0, 5670.945274129018, 5918.112283157561, 5971.223740913653, 5436.728163591804, 5916.47389746844, 5935.7147498212635, 5990.161377407167, 6002.999999999913, 5780.190773291659, 5793.971030144822, 5818.33089194748, 5827.562932779973, 5848.2689663792025, 4650.906981860411, 5460.481254280837, 5569.415211402745, 5621.578381621476, 5766.394528574439, 5608.327000759935, 5761.683107429131, 5850.0, 5950.5239580834295, 6036.3058248302, 3745.3071181831447, 3843.5195600549932, 3850.9807450962567, 3860.8455661773214, 3872.496575445174, 4861.499720201449, 5130.51260130287, 5436.157395603688, 5864.237649105589, 5886.646801191942, 4276.679249056271, 5302.469753024667, 5548.722563871871, 5672.262605861211, 5697.515711164598, 5468.906218756185, 5484.670919744828, 5591.804286849941, 5607.102863541813, 5610.465406793694, 4414.417565723959, 5390.919136213039, 5550.833474995757, 5558.888222355626, 5564.443555644403, 4724.740139292372, 4949.950500494949, 5543.722813859372, 5587.273654424899, 5648.85877853057, 4716.0, 4784.40194975628, 4807.831725889577, 5246.186841039645, 5503.917441238465], "value": 3996.4431747505555, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [546.5381752419206, 681.9352161544655, 687.6321168175027, 780.2626517940545, 783.2363445640501, 12338.44476998535, 15614.627904186846, 15835.366585336602, 16031.919840400797, 16061.19694015299, 13097.886679632242, 13686.657833554165, 14278.92860535696, 14504.202268875208, 14669.779953300682, 822.7819627798638, 846.8814365988767, 848.2535368875381, 848.3001523742906, 848.6859861851124, 671.3219648155374, 689.0594338727626, 691.8062942376117, 696.2828286864512, 697.2330436519813, 724.7064938699835, 877.5787621941472, 901.2609660078738, 902.0, 914.9908500914972, 903.0608167505819, 903.2096915199234, 903.503073309677, 903.6340282185726, 903.7108125399875, 662.6223052859872, 663.0716996205302, 663.174347936817, 663.8041777675597, 664.2328111031766, 11828.763424731456, 12384.876151238463, 14911.0, 15462.149581772997, 15518.224088795516, 14374.35315410809, 14760.907486917104, 14848.03487773302, 14936.850631493655, 15067.0, 11387.772244555146, 11709.355985420798, 11898.702532436671, 12017.158798884082, 12119.48961122497, 3354.932901341984, 3776.3580191367532, 3882.6893848492336, 3921.156951255465, 4011.8796436106672, 90087.54956225242, 94409.5279523598, 95162.0, 103114.48442757738, 118586.0, 10671.83992240117, 11061.0, 11174.832377514344, 11239.381833999209, 11712.180147389772, 21795.564088718136, 21822.127114915227, 21825.0178741957, 21845.45386365322, 21864.562708746053, 7873.133955264916, 7933.722319718782, 7985.880211796828, 7989.560574168477, 8055.234752698484, 4267.807948642257, 4270.722403043792, 4303.720258183208, 4341.196878577473, 4427.690061695715, 3487.3199726053176, 3603.5135256740527, 3630.4735813306925, 3632.400653892078, 3636.3272794533095, 659.535027805396, 671.2247354305784, 671.8857794175002, 677.8169894128557, 678.3555622158968, 562.443181250559, 623.6351734235523, 651.2282944710438, 657.0906913801382, 658.7134596450463, 9061.456312621132, 9249.491277979776, 9321.30090243221, 9353.532323383804, 9414.482203478874, 622.5370379607975, 642.8907085795332, 650.7104338569292, 652.4812773844804, 656.7701304543467, 4316.7547094587735, 4557.45310562728, 4565.566271204297, 4586.072003018294, 4631.8378856739855, 92327.0, 153744.40828094466, 159304.0, 160749.0, 162170.18914905586, 133504.0, 139074.0, 144217.0, 148510.25744871207, 149546.0, 119944.0, 121615.0, 123780.0, 125107.7489225096, 127147.99999999815, 7234.421246300389, 7851.822226666046, 8671.783205419915, 8838.381313308008, 8950.315218417238, 59978.203923294226, 60897.39102608917, 66333.33666663272, 66852.0, 70307.29692703008, 65128.06923688351, 74688.0, 90235.0, 92333.0, 99788.50105749424, 11696.122790790894, 11784.920959050072, 11990.820137698116, 12003.939980300043, 12089.335086570323, 12746.553870614483, 12797.808032879328, 12938.288394138413, 13111.08569496293, 13146.671333216746, 11006.0, 11306.265092768943, 11452.94273528627, 11564.768704626029, 11718.23831450987, 11816.763664726486, 12107.576234832091, 12204.506325323893, 12566.057545684293, 12703.872961270268, 10973.780524389307, 11958.402079895797, 12151.81772273399, 12416.81374779397, 12617.747645047231, 9828.754281143028, 10156.949215253877, 10157.847632285373, 10354.585816567253, 10606.575736970435, 9732.367396119229, 10374.170066394821, 10382.844257335995, 10383.636572719919, 10393.168546516412], "value": 7579.9125671165, "unit": "score"}], "name": "dromaeo_css", "value": 7270.66654251703, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 112.0, 111.0, 112.0, 112.0, 109.0, 107.0, 112.0, 112.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 115.0, 115.0, 151.0, 114.0, 113.0, 112.0, 112.0, 144.0, 114.0], "value": 124.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 184.0, 186.0, 194.0, 254.0, 187.0, 181.0, 183.0, 184.0, 184.0], "value": 192.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 59.0, 60.0, 90.0, 64.0, 60.0, 58.0, 58.0, 84.0, 57.0], "value": 66.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 72.0, 96.0, 69.0, 68.0, 68.0, 92.0, 68.0, 68.0, 89.0], "value": 77.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 86.0, 88.0, 85.0, 88.0, 85.0, 87.0, 85.0], "value": 86.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 95.0, 93.0, 94.0, 94.0, 96.0, 101.0, 93.0], "value": 94.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 87.0, 83.0, 87.0, 84.0, 87.0, 84.0, 84.0, 88.0], "value": 85.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 51.0, 53.0, 53.0, 73.0, 84.0, 54.0, 53.0, 53.0, 53.0], "value": 58.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 76.0, 69.0, 81.0, 76.0, 74.0, 72.0, 71.0, 73.0, 74.0], "value": 73.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 105.0, 85.0, 86.0, 87.0, 86.0, 88.0, 89.0, 92.0], "value": 89.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 144.0, 148.0, 145.0, 146.0, 144.0, 145.0, 144.0, 207.0, 144.0], "value": 151.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 172.0, 171.0, 174.0, 171.0, 171.0, 170.0, 171.0, 171.0, 172.0], "value": 171.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 69.0, 68.0, 68.0, 71.0, 68.0, 68.0, 68.0, 68.0], "value": 68.6, "unit": "score"}], "name": "kraken", "value": 1450.0000000000002, "alertThreshold": 2.0}]} 04:02:02 INFO - Return code: 0 04:02:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:02:02 INFO - # TBPL SUCCESS # 04:02:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:02:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:02:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:02:02 INFO - 2016-10-10 04:02:02.585 screenresolution[2558:24764] starting screenresolution argv=screenresolution get 04:02:02 INFO - 2016-10-10 04:02:02.588 screenresolution[2558:24764] Display 0: 1600x1200x32@60 04:02:02 INFO - 2016-10-10 04:02:02.594 screenresolution[2559:24767] starting screenresolution argv=screenresolution list 04:02:02 INFO - Available Modes on Display 0 04:02:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:02:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:02:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:02:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:02:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:02:02 INFO - Intel Iris: 04:02:02 INFO - Chipset Model: Intel Iris 04:02:02 INFO - Type: GPU 04:02:02 INFO - Bus: Built-In 04:02:02 INFO - VRAM (Dynamic, Max): 1536 MB 04:02:02 INFO - Vendor: Intel (0x8086) 04:02:02 INFO - Device ID: 0x0a2e 04:02:02 INFO - Revision ID: 0x0009 04:02:02 INFO - Displays: 04:02:02 INFO - Display: 04:02:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:02:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:02:02 INFO - Display Serial Number: 1600x1200 60 04:02:02 INFO - Main Display: Yes 04:02:02 INFO - Mirror: Off 04:02:02 INFO - Online: Yes 04:02:02 INFO - Rotation: Supported 04:02:02 INFO - Return code: 0 04:02:02 INFO - Running post-action listener: _resource_record_post_action 04:02:02 INFO - [mozharness: 2016-10-10 11:02:02.834092Z] Finished run-tests step (success) 04:02:02 INFO - Running post-run listener: _resource_record_post_run 04:02:02 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 815104; Write bytes: 707047936; Read time: 8; Write time: 1951 04:02:02 INFO - TinderboxPrint: CPU usage
53.5% 04:02:02 INFO - TinderboxPrint: I/O read bytes / time
815,104 / 8 04:02:02 INFO - TinderboxPrint: I/O write bytes / time
707,047,936 / 1,951 04:02:02 INFO - TinderboxPrint: CPU idle
2,254.8 (46.5%) 04:02:02 INFO - TinderboxPrint: CPU system
968.7 (20.0%) 04:02:02 INFO - TinderboxPrint: CPU user
1,630.1 (33.6%) 04:02:02 INFO - TinderboxPrint: Swap in / out
407,666,688 / 0 04:02:02 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 389374464; Write bytes: 385072128; Read time: 29349; Write time: 789 04:02:02 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 815104; Write bytes: 295499264; Read time: 8; Write time: 1095 04:02:03 INFO - Running post-run listener: _upload_blobber_files 04:02:03 INFO - Blob upload gear active. 04:02:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:02:03 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 04:02:03 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'] 04:02:03 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 04:02:03 INFO - (blobuploader) - INFO - Open directory for files ... 04:02:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:02:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:02:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:02:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:02:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:02:05 INFO - (blobuploader) - INFO - Done attempting. 04:02:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:02:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:02:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:02:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:02:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:02:06 INFO - (blobuploader) - INFO - Done attempting. 04:02:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:02:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:02:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:02:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:02:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:02:08 INFO - (blobuploader) - INFO - Done attempting. 04:02:08 INFO - (blobuploader) - INFO - Iteration through files over. 04:02:08 INFO - Return code: 0 04:02:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:02:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:02:08 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1ad43b7d7caf473e299dd725d472008a198199a8931c6419b930894670b4c066ad5dd9cc252e3188d2586381ed43100297e1df90cad7ec84587c6276130d5fd6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/927b1d02cb0eeed4be7ca907fc58b908b432513ea6bad185bb3355b7b6ad7f1944fe28292a8dccb4c977cb721446b844e61a7804ac52be1f441e1c1886fdeb63", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4d376d400844d40086a889d0084de5c850695dbefb21897779f704884d909bd3e2307d58e0c767299b194193402cd5ebb8d4f2076837e341e49c67f03a985160"} 04:02:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:02:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:02:08 INFO - Contents: 04:02:08 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1ad43b7d7caf473e299dd725d472008a198199a8931c6419b930894670b4c066ad5dd9cc252e3188d2586381ed43100297e1df90cad7ec84587c6276130d5fd6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/927b1d02cb0eeed4be7ca907fc58b908b432513ea6bad185bb3355b7b6ad7f1944fe28292a8dccb4c977cb721446b844e61a7804ac52be1f441e1c1886fdeb63", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4d376d400844d40086a889d0084de5c850695dbefb21897779f704884d909bd3e2307d58e0c767299b194193402cd5ebb8d4f2076837e341e49c67f03a985160"} 04:02:08 INFO - Running post-run listener: copy_logs_to_upload_dir 04:02:08 INFO - Copying logs to upload dir... 04:02:08 INFO - mkdir: /builds/slave/test/build/upload/logs 04:02:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.639301 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-10 04:02:08.669714) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-10 04:02:08.673354) ========= 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.hVhqwT6oBa/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.tN32UEEISn/Listeners TMPDIR=/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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/1ad43b7d7caf473e299dd725d472008a198199a8931c6419b930894670b4c066ad5dd9cc252e3188d2586381ed43100297e1df90cad7ec84587c6276130d5fd6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/927b1d02cb0eeed4be7ca907fc58b908b432513ea6bad185bb3355b7b6ad7f1944fe28292a8dccb4c977cb721446b844e61a7804ac52be1f441e1c1886fdeb63", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4d376d400844d40086a889d0084de5c850695dbefb21897779f704884d909bd3e2307d58e0c767299b194193402cd5ebb8d4f2076837e341e49c67f03a985160"} build_url:https://queue.taskcluster.net/v1/task/R-KV5ixeQk65N1KTVzbwZw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011329 build_url: 'https://queue.taskcluster.net/v1/task/R-KV5ixeQk65N1KTVzbwZw/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/1ad43b7d7caf473e299dd725d472008a198199a8931c6419b930894670b4c066ad5dd9cc252e3188d2586381ed43100297e1df90cad7ec84587c6276130d5fd6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/927b1d02cb0eeed4be7ca907fc58b908b432513ea6bad185bb3355b7b6ad7f1944fe28292a8dccb4c977cb721446b844e61a7804ac52be1f441e1c1886fdeb63", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/4d376d400844d40086a889d0084de5c850695dbefb21897779f704884d909bd3e2307d58e0c767299b194193402cd5ebb8d4f2076837e341e49c67f03a985160"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-10 04:02:08.707106) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 04:02:08.707635) ========= 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.hVhqwT6oBa/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.tN32UEEISn/Listeners TMPDIR=/var/folders/9_/bq7njmms6gbgk1ygh6pll6r400000w/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.005266 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 04:02:08.757162) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-10 04:02:08.757492) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-10 04:02:08.757816) ========= ========= Total master_lag: 0.20 =========