builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0003 starttime: 1475689630.6 results: success (0) buildid: 20161005080902 builduid: 1169375398184687a4f7d21c52b799ae revision: 4f41a594f88d17e25bbfd362f2e45e6dc891bfc6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-05 10:47:10.602246) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-05 10:47:10.603272) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-05 10:47:10.603998) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-05 10:47:10.627299) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 10:47:10.627977) ========= 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.RHJPoLaqP5/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.kyDzGqMb6F/Listeners TMPDIR=/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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-05 10:47:10-- 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% 9.62M=0.001s 2016-10-05 10:47:10 (9.62 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.098590 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 10:47:10.754433) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 10:47:10.755270) ========= 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.RHJPoLaqP5/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.kyDzGqMb6F/Listeners TMPDIR=/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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.028088 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 10:47:10.808054) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-05 10:47:10.808729) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 4f41a594f88d17e25bbfd362f2e45e6dc891bfc6 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 4f41a594f88d17e25bbfd362f2e45e6dc891bfc6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RHJPoLaqP5/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.kyDzGqMb6F/Listeners TMPDIR=/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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-05 10:47:10,888 truncating revision to first 12 chars 2016-10-05 10:47:10,888 Setting DEBUG logging. 2016-10-05 10:47:10,888 attempt 1/10 2016-10-05 10:47:10,888 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/4f41a594f88d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-05 10:47:12,008 unpacking tar archive at: fx-team-4f41a594f88d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.378887 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-05 10:47:12.209128) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-05 10:47:12.209671) ========= script_repo_revision: 4f41a594f88d17e25bbfd362f2e45e6dc891bfc6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-05 10:47:12.210421) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-05 10:47:12.211021) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-05 10:47:12.230063) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-10-05 10:47:12.230427) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RHJPoLaqP5/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.kyDzGqMb6F/Listeners TMPDIR=/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 10:47:12 INFO - MultiFileLogger online at 20161005 10:47:12 in /builds/slave/test 10:47:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 10:47:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:47:12 INFO - {'append_to_log': False, 10:47:12 INFO - 'base_work_dir': '/builds/slave/test', 10:47:12 INFO - 'blob_upload_branch': 'Fx-Team', 10:47:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:47:12 INFO - 'branch': 'Fx-Team', 10:47:12 INFO - 'buildbot_json_path': 'buildprops.json', 10:47:12 INFO - 'config_files': ('talos/mac_config.py',), 10:47:12 INFO - 'default_actions': ('clobber', 10:47:12 INFO - 'read-buildbot-config', 10:47:12 INFO - 'download-and-extract', 10:47:12 INFO - 'populate-webroot', 10:47:12 INFO - 'create-virtualenv', 10:47:12 INFO - 'install', 10:47:12 INFO - 'run-tests'), 10:47:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:47:12 INFO - 'download_minidump_stackwalk': True, 10:47:12 INFO - 'download_symbols': 'ondemand', 10:47:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:47:12 INFO - 'tooltool.py': '/tools/tooltool.py', 10:47:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:47:12 INFO - '/tools/misc-python/virtualenv.py')}, 10:47:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:47:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:47:12 INFO - 'installer_path': 'installer.exe', 10:47:12 INFO - 'log_level': 'info', 10:47:12 INFO - 'log_name': 'talos', 10:47:12 INFO - 'log_to_console': True, 10:47:12 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:47:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:47:12 INFO - 'opt_config_files': (), 10:47:12 INFO - 'pip_index': False, 10:47:12 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:47:12 INFO - 'cmd': ('bash', 10:47:12 INFO - '-c', 10:47:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:47:12 INFO - 'enabled': True, 10:47:12 INFO - 'halt_on_failure': False, 10:47:12 INFO - 'name': 'check_screen_resolution'},), 10:47:12 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:47:12 INFO - 'cmd': ('bash', 10:47:12 INFO - '-c', 10:47:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:47:12 INFO - 'enabled': True, 10:47:12 INFO - 'halt_on_failure': False, 10:47:12 INFO - 'name': 'check_screen_resolution'},), 10:47:12 INFO - 'run_cmd_checks_enabled': True, 10:47:12 INFO - 'sps_profile': False, 10:47:12 INFO - 'sps_profile_interval': 0, 10:47:12 INFO - 'suite': 'dromaeojs', 10:47:12 INFO - 'system_bits': '32', 10:47:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:47:12 INFO - 'title': 't-yosemite-r7-0003', 10:47:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:47:12 INFO - 'use_talos_json': True, 10:47:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:47:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:47:12 INFO - 'work_dir': 'build'} 10:47:12 INFO - [mozharness: 2016-10-05 17:47:12.431205Z] Running clobber step. 10:47:12 INFO - Running pre-action listener: _resource_record_pre_action 10:47:12 INFO - Running main action method: clobber 10:47:12 INFO - rmtree: /builds/slave/test/build 10:47:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:47:14 INFO - Running post-action listener: _resource_record_post_action 10:47:14 INFO - [mozharness: 2016-10-05 17:47:14.667420Z] Finished clobber step (success) 10:47:14 INFO - [mozharness: 2016-10-05 17:47:14.667543Z] Running read-buildbot-config step. 10:47:14 INFO - Running pre-action listener: _resource_record_pre_action 10:47:14 INFO - Running main action method: read_buildbot_config 10:47:14 INFO - Using buildbot properties: 10:47:14 INFO - { 10:47:14 INFO - "project": "", 10:47:14 INFO - "product": "firefox", 10:47:14 INFO - "script_repo_revision": "production", 10:47:14 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 10:47:14 INFO - "repository": "", 10:47:14 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 10:47:14 INFO - "buildid": "20161005080902", 10:47:14 INFO - "pgo_build": "False", 10:47:14 INFO - "basedir": "/builds/slave/test", 10:47:14 INFO - "buildnumber": 178, 10:47:14 INFO - "slavename": "t-yosemite-r7-0003", 10:47:14 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 10:47:14 INFO - "platform": "yosemite_r7", 10:47:14 INFO - "scheduler": "tests-fx-team-macosx64-talos", 10:47:14 INFO - "branch": "fx-team", 10:47:14 INFO - "repo_path": "integration/fx-team", 10:47:14 INFO - "revision": "4f41a594f88d17e25bbfd362f2e45e6dc891bfc6", 10:47:14 INFO - "stage_platform": "macosx64", 10:47:14 INFO - "builduid": "1169375398184687a4f7d21c52b799ae", 10:47:14 INFO - "slavebuilddir": "test" 10:47:14 INFO - } 10:47:14 INFO - Found installer url https://queue.taskcluster.net/v1/task/JvRQ073fSE-eXPBlg5w5Jg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 10:47:14 INFO - Running post-action listener: _resource_record_post_action 10:47:14 INFO - [mozharness: 2016-10-05 17:47:14.670845Z] Finished read-buildbot-config step (success) 10:47:14 INFO - [mozharness: 2016-10-05 17:47:14.670946Z] Running download-and-extract step. 10:47:14 INFO - Running pre-action listener: _resource_record_pre_action 10:47:14 INFO - Running main action method: download_and_extract 10:47:14 INFO - mkdir: /builds/slave/test/build/tests 10:47:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:47:14 INFO - trying https://queue.taskcluster.net/v1/task/JvRQ073fSE-eXPBlg5w5Jg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 10:47:14 INFO - Downloading https://queue.taskcluster.net/v1/task/JvRQ073fSE-eXPBlg5w5Jg/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 10:47:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JvRQ073fSE-eXPBlg5w5Jg/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 10:47:15 INFO - Downloaded 1199 bytes. 10:47:15 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 10:47:15 INFO - Using the following test package requirements: 10:47:15 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 10:47:15 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:47:15 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 10:47:15 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:47:15 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 10:47:15 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:47:15 INFO - u'jsshell-mac.zip'], 10:47:15 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:47:15 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 10:47:15 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 10:47:15 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:47:15 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 10:47:15 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:47:15 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 10:47:15 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:47:15 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 10:47:15 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:47:15 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 10:47:15 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:47:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JvRQ073fSE-eXPBlg5w5Jg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 10:47:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JvRQ073fSE-eXPBlg5w5Jg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:47:15 INFO - Fetch https://queue.taskcluster.net/v1/task/JvRQ073fSE-eXPBlg5w5Jg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 10:47:16 INFO - Http code: 200 10:47:16 INFO - via: 1.1 d8f42fc9558e3e49ebfdf8834baeb756.cloudfront.net (CloudFront) 10:47:16 INFO - x-amz-cf-id: vlaeCkByYR04ILHo-uwij3z7NESVrP5weoWaW3qNpZ_nkiihfxZ5Tw== 10:47:16 INFO - x-amz-version-id: 3pp._7M06w7yf1fILMb_kOnc03L1IQwn 10:47:16 INFO - Expected file size: 16477160 10:47:16 INFO - Obtained file size: 16477160 10:47:23 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 10:47:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JvRQ073fSE-eXPBlg5w5Jg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 10:47:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JvRQ073fSE-eXPBlg5w5Jg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:47:23 INFO - Fetch https://queue.taskcluster.net/v1/task/JvRQ073fSE-eXPBlg5w5Jg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 10:47:23 INFO - Http code: 200 10:47:23 INFO - via: 1.1 41b2ee7cbe95749816a7586d9ab4629d.cloudfront.net (CloudFront) 10:47:23 INFO - x-amz-cf-id: U8Xz9CrWobeg8oPtdNTwmUl0kAVxCfmy0-H2h3j5WkZUBNvkjs3kOg== 10:47:23 INFO - x-amz-version-id: 3pp._7M06w7yf1fILMb_kOnc03L1IQwn 10:47:24 INFO - Expected file size: 16477160 10:47:24 INFO - Obtained file size: 16477160 10:47:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JvRQ073fSE-eXPBlg5w5Jg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 10:47:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JvRQ073fSE-eXPBlg5w5Jg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:47:30 INFO - Fetch https://queue.taskcluster.net/v1/task/JvRQ073fSE-eXPBlg5w5Jg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 10:47:31 INFO - Http code: 200 10:47:31 INFO - via: 1.1 bfc29822e21ecc352f71dce83e06ab74.cloudfront.net (CloudFront) 10:47:31 INFO - x-amz-cf-id: G9i4UECD9ZDXPz5FgtVPx9t-J7O2FM2Lr56kC3iqfT0RmSYLW1oMjQ== 10:47:31 INFO - x-amz-version-id: kKakiZG612bcmuNp3.nwQVHDKjjhKjRX 10:47:31 INFO - Expected file size: 14409006 10:47:31 INFO - Obtained file size: 14409006 10:47:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:47:31 INFO - trying https://queue.taskcluster.net/v1/task/JvRQ073fSE-eXPBlg5w5Jg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 10:47:31 INFO - Downloading https://queue.taskcluster.net/v1/task/JvRQ073fSE-eXPBlg5w5Jg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 10:47:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JvRQ073fSE-eXPBlg5w5Jg/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 10:47:34 INFO - Downloaded 111664862 bytes. 10:47:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JvRQ073fSE-eXPBlg5w5Jg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 10:47:34 INFO - mkdir: /builds/slave/test/properties 10:47:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:47:34 INFO - Writing to file /builds/slave/test/properties/build_url 10:47:34 INFO - Contents: 10:47:34 INFO - build_url:https://queue.taskcluster.net/v1/task/JvRQ073fSE-eXPBlg5w5Jg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 10:47:35 INFO - Running post-action listener: _resource_record_post_action 10:47:35 INFO - Running post-action listener: set_extra_try_arguments 10:47:35 INFO - [mozharness: 2016-10-05 17:47:35.304131Z] Finished download-and-extract step (success) 10:47:35 INFO - [mozharness: 2016-10-05 17:47:35.304276Z] Running populate-webroot step. 10:47:35 INFO - Running pre-action listener: _resource_record_pre_action 10:47:35 INFO - Running main action method: populate_webroot 10:47:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:47:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:47:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:47:35 INFO - '--tppagecycles', 10:47:35 INFO - '7'], 10:47:35 INFO - 'tests': ['tsvgm']}}, 10:47:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:47:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:47:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:47:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:47:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:47:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:47:35 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:47:35 INFO - 'talos_options': ['--disable-e10s'], 10:47:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:47:35 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:47:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:47:35 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:47:35 INFO - 'talos_options': ['--disable-e10s'], 10:47:35 INFO - 'tests': ['damp', 'tps']}, 10:47:35 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:47:35 INFO - 'tests': ['damp', 'tps']}, 10:47:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:47:35 INFO - 'tests': ['dromaeo_dom']}, 10:47:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:47:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:47:35 INFO - 'tests': ['basic_compositor_video']}, 10:47:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:47:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:47:35 INFO - 'tests': ['a11yr', 10:47:35 INFO - 'ts_paint', 10:47:35 INFO - 'tpaint', 10:47:35 INFO - 'sessionrestore', 10:47:35 INFO - 'sessionrestore_no_auto_restore', 10:47:35 INFO - 'tabpaint']}, 10:47:35 INFO - 'other-e10s': {'tests': ['a11yr', 10:47:35 INFO - 'ts_paint', 10:47:35 INFO - 'tpaint', 10:47:35 INFO - 'sessionrestore', 10:47:35 INFO - 'sessionrestore_no_auto_restore', 10:47:35 INFO - 'tabpaint']}, 10:47:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:47:35 INFO - 'ts_paint', 10:47:35 INFO - 'tpaint', 10:47:35 INFO - 'sessionrestore', 10:47:35 INFO - 'sessionrestore_no_auto_restore', 10:47:35 INFO - 'tabpaint']}, 10:47:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:47:35 INFO - 'ts_paint', 10:47:35 INFO - 'tpaint', 10:47:35 INFO - 'sessionrestore', 10:47:35 INFO - 'sessionrestore_no_auto_restore', 10:47:35 INFO - 'tabpaint']}, 10:47:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:47:35 INFO - 'tests': ['a11yr', 10:47:35 INFO - 'ts_paint', 10:47:35 INFO - 'tpaint', 10:47:35 INFO - 'sessionrestore', 10:47:35 INFO - 'sessionrestore_no_auto_restore', 10:47:35 INFO - 'tabpaint']}, 10:47:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:47:35 INFO - 'tests': ['a11yr', 10:47:35 INFO - 'ts_paint', 10:47:35 INFO - 'tpaint', 10:47:35 INFO - 'sessionrestore', 10:47:35 INFO - 'sessionrestore_no_auto_restore', 10:47:35 INFO - 'tabpaint']}, 10:47:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:47:35 INFO - 'tests': ['tsvgx', 10:47:35 INFO - 'tsvgr_opacity', 10:47:35 INFO - 'tart', 10:47:35 INFO - 'tscrollx', 10:47:35 INFO - 'cart']}, 10:47:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:47:35 INFO - 'tsvgr_opacity', 10:47:35 INFO - 'tart', 10:47:35 INFO - 'tscrollx', 10:47:35 INFO - 'cart']}, 10:47:35 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:47:35 INFO - 'talos_options': ['--disable-e10s'], 10:47:35 INFO - 'tests': ['tp5o']}, 10:47:35 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:47:35 INFO - 'tests': ['tp5o']}, 10:47:35 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:47:35 INFO - 'talos_options': ['--disable-e10s', 10:47:35 INFO - '--xperf_path', 10:47:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:47:35 INFO - 'tests': ['tp5n']}, 10:47:35 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:47:35 INFO - 'talos_options': ['--xperf_path', 10:47:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:47:35 INFO - 'tests': ['tp5n']}}, 10:47:35 INFO - 'talos.zip': {'path': '', 10:47:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:47:35 INFO - Running post-action listener: _resource_record_post_action 10:47:35 INFO - [mozharness: 2016-10-05 17:47:35.323243Z] Finished populate-webroot step (success) 10:47:35 INFO - [mozharness: 2016-10-05 17:47:35.323374Z] Running create-virtualenv step. 10:47:35 INFO - Running pre-action listener: _resource_record_pre_action 10:47:35 INFO - Running main action method: create_virtualenv 10:47:35 INFO - Creating virtualenv /builds/slave/test/build/venv 10:47:35 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 10:47:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:47:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:47:35 INFO - Using real prefix '/tools/python27' 10:47:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:47:36 INFO - Installing distribute.............................................................................................................................................................................................done. 10:47:39 INFO - Installing pip.................done. 10:47:39 INFO - Return code: 0 10:47:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:47:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:47:39 INFO - Reading from file tmpfile_stdout 10:47:39 INFO - Output received: 10:47:39 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) 10:47:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:47:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:47:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:47:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:47:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:47:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:47:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:47:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10587e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1057f4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0ea49a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105692d30>, '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 0x1054b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10568ab20>, '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.RHJPoLaqP5/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.kyDzGqMb6F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 10:47:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:47:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:47:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RHJPoLaqP5/Render', 10:47:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:47:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:47:39 INFO - 'HOME': '/Users/cltbld', 10:47:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:47:39 INFO - 'LOGNAME': 'cltbld', 10:47:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:47:39 INFO - 'MOZ_NO_REMOTE': '1', 10:47:39 INFO - 'NO_EM_RESTART': '1', 10:47:39 INFO - 'PAGER': '/bin/cat', 10:47:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:47:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:47:39 INFO - 'PWD': '/builds/slave/test', 10:47:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:47:39 INFO - 'SHELL': '/bin/bash', 10:47:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kyDzGqMb6F/Listeners', 10:47:39 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 10:47:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:47:39 INFO - 'USER': 'cltbld', 10:47:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:47:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:47:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:47:39 INFO - 'XPC_FLAGS': '0x0', 10:47:39 INFO - 'XPC_SERVICE_NAME': '0', 10:47:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:47:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:47:39 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 10:47:39 INFO - Cleaning up... 10:47:39 INFO - Return code: 0 10:47:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:47:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:47:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:47:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:47:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:47:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:47:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:47:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10587e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1057f4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0ea49a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105692d30>, '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 0x1054b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10568ab20>, '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.RHJPoLaqP5/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.kyDzGqMb6F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 10:47:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:47:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:47:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RHJPoLaqP5/Render', 10:47:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:47:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:47:39 INFO - 'HOME': '/Users/cltbld', 10:47:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:47:39 INFO - 'LOGNAME': 'cltbld', 10:47:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:47:39 INFO - 'MOZ_NO_REMOTE': '1', 10:47:39 INFO - 'NO_EM_RESTART': '1', 10:47:39 INFO - 'PAGER': '/bin/cat', 10:47:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:47:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:47:39 INFO - 'PWD': '/builds/slave/test', 10:47:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:47:39 INFO - 'SHELL': '/bin/bash', 10:47:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kyDzGqMb6F/Listeners', 10:47:39 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 10:47:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:47:39 INFO - 'USER': 'cltbld', 10:47:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:47:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:47:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:47:39 INFO - 'XPC_FLAGS': '0x0', 10:47:39 INFO - 'XPC_SERVICE_NAME': '0', 10:47:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:47:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:47:39 INFO - Downloading/unpacking psutil>=3.1.1 10:47:39 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 10:47:39 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 10:47:39 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 10:47:39 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 10:47:39 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 10:47:39 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 10:47:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:47:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:47:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:47:43 INFO - Installing collected packages: psutil 10:47:43 INFO - Running setup.py install for psutil 10:47:43 INFO - building 'psutil._psutil_osx' extension 10:47:43 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 10:47:43 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 10:47:43 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 10:47:44 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 10:47:44 INFO - building 'psutil._psutil_posix' extension 10:47:44 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 10:47:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:47:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:47:44 INFO - ^ 10:47:44 INFO - 1 warning generated. 10:47:44 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 10:47:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:47:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:47:44 INFO - Successfully installed psutil 10:47:44 INFO - Cleaning up... 10:47:44 INFO - Return code: 0 10:47:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:47:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:47:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:47:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:47:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:47:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:47:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:47:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10587e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1057f4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0ea49a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105692d30>, '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 0x1054b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10568ab20>, '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.RHJPoLaqP5/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.kyDzGqMb6F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 10:47:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:47:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:47:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RHJPoLaqP5/Render', 10:47:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:47:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:47:44 INFO - 'HOME': '/Users/cltbld', 10:47:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:47:44 INFO - 'LOGNAME': 'cltbld', 10:47:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:47:44 INFO - 'MOZ_NO_REMOTE': '1', 10:47:44 INFO - 'NO_EM_RESTART': '1', 10:47:44 INFO - 'PAGER': '/bin/cat', 10:47:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:47:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:47:44 INFO - 'PWD': '/builds/slave/test', 10:47:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:47:44 INFO - 'SHELL': '/bin/bash', 10:47:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kyDzGqMb6F/Listeners', 10:47:44 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 10:47:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:47:44 INFO - 'USER': 'cltbld', 10:47:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:47:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:47:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:47:44 INFO - 'XPC_FLAGS': '0x0', 10:47:44 INFO - 'XPC_SERVICE_NAME': '0', 10:47:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:47:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:47:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:47: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 10:47: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 10:47: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 10:47: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 10:47: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 10:47: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 10:47:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:47:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:47:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:47:47 INFO - Installing collected packages: mozsystemmonitor 10:47:47 INFO - Running setup.py install for mozsystemmonitor 10:47:47 INFO - Successfully installed mozsystemmonitor 10:47:47 INFO - Cleaning up... 10:47:47 INFO - Return code: 0 10:47:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:47:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:47:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:47:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:47:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:47:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:47:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:47:47 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 0x10587e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1057f4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0ea49a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105692d30>, '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 0x1054b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10568ab20>, '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.RHJPoLaqP5/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.kyDzGqMb6F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 10:47:47 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 10:47:47 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 10:47:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RHJPoLaqP5/Render', 10:47:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:47:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:47:47 INFO - 'HOME': '/Users/cltbld', 10:47:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:47:47 INFO - 'LOGNAME': 'cltbld', 10:47:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:47:47 INFO - 'MOZ_NO_REMOTE': '1', 10:47:47 INFO - 'NO_EM_RESTART': '1', 10:47:47 INFO - 'PAGER': '/bin/cat', 10:47:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:47:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:47:47 INFO - 'PWD': '/builds/slave/test', 10:47:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:47:47 INFO - 'SHELL': '/bin/bash', 10:47:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kyDzGqMb6F/Listeners', 10:47:47 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 10:47:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:47:47 INFO - 'USER': 'cltbld', 10:47:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:47:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:47:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:47:47 INFO - 'XPC_FLAGS': '0x0', 10:47:47 INFO - 'XPC_SERVICE_NAME': '0', 10:47:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:47:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:47:47 INFO - Downloading/unpacking jsonschema==2.5.1 10:47:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:47:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:47:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:47:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:47:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:47:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:47:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:47:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:47:50 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 10:47:50 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 10:47:50 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 10:47:50 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 10:47:50 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 10:47:50 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 10:47:50 INFO - Downloading functools32-3.2.3-2.tar.gz 10:47:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:47:50 INFO - warning: no files found matching '*.txt' 10:47:50 INFO - no previously-included directories found matching 'build' 10:47:50 INFO - no previously-included directories found matching 'dist' 10:47:50 INFO - no previously-included directories found matching '.git*' 10:47:50 INFO - Installing collected packages: jsonschema, functools32 10:47:50 INFO - Running setup.py install for functools32 10:47:50 INFO - warning: no files found matching '*.txt' 10:47:50 INFO - no previously-included directories found matching 'build' 10:47:50 INFO - no previously-included directories found matching 'dist' 10:47:50 INFO - no previously-included directories found matching '.git*' 10:47:50 INFO - Successfully installed jsonschema functools32 10:47:50 INFO - Cleaning up... 10:47:50 INFO - Return code: 0 10:47:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:47:50 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')]} 10:47:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:47:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:47:50 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')]} 10:47:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:47:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:47:50 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 0x10587e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1057f4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0ea49a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105692d30>, '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 0x1054b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10568ab20>, '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.RHJPoLaqP5/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.kyDzGqMb6F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 10:47:50 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 10:47:50 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 10:47:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RHJPoLaqP5/Render', 10:47:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:47:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:47:50 INFO - 'HOME': '/Users/cltbld', 10:47:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:47:50 INFO - 'LOGNAME': 'cltbld', 10:47:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:47:50 INFO - 'MOZ_NO_REMOTE': '1', 10:47:50 INFO - 'NO_EM_RESTART': '1', 10:47:50 INFO - 'PAGER': '/bin/cat', 10:47:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:47:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:47:50 INFO - 'PWD': '/builds/slave/test', 10:47:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:47:50 INFO - 'SHELL': '/bin/bash', 10:47:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kyDzGqMb6F/Listeners', 10:47:50 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 10:47:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:47:50 INFO - 'USER': 'cltbld', 10:47:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:47:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:47:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:47:50 INFO - 'XPC_FLAGS': '0x0', 10:47:50 INFO - 'XPC_SERVICE_NAME': '0', 10:47:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:47:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:47:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:47:51 INFO - Cleaning up... 10:47:51 INFO - Return code: 0 10:47:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:47:51 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')]} 10:47:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:47:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:47:51 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')]} 10:47:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:47:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:47:51 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 0x10587e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1057f4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0ea49a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105692d30>, '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 0x1054b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10568ab20>, '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.RHJPoLaqP5/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.kyDzGqMb6F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 10:47:51 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 10:47:51 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 10:47:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RHJPoLaqP5/Render', 10:47:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:47:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:47:51 INFO - 'HOME': '/Users/cltbld', 10:47:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:47:51 INFO - 'LOGNAME': 'cltbld', 10:47:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:47:51 INFO - 'MOZ_NO_REMOTE': '1', 10:47:51 INFO - 'NO_EM_RESTART': '1', 10:47:51 INFO - 'PAGER': '/bin/cat', 10:47:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:47:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:47:51 INFO - 'PWD': '/builds/slave/test', 10:47:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:47:51 INFO - 'SHELL': '/bin/bash', 10:47:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kyDzGqMb6F/Listeners', 10:47:51 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 10:47:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:47:51 INFO - 'USER': 'cltbld', 10:47:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:47:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:47:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:47:51 INFO - 'XPC_FLAGS': '0x0', 10:47:51 INFO - 'XPC_SERVICE_NAME': '0', 10:47:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:47:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:47:51 INFO - Downloading/unpacking blobuploader==1.2.4 10:47:51 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 10:47:51 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 10:47:51 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 10:47:51 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 10:47:51 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 10:47:51 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 10:47:53 INFO - Downloading blobuploader-1.2.4.tar.gz 10:47:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:47:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:47:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:47:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:47:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:47:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:47:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:47:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:47:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:47:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:47:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:47:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:47:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:47:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:47:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:47:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:47:55 INFO - Downloading docopt-0.6.1.tar.gz 10:47:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:47:55 INFO - Installing collected packages: blobuploader, requests, docopt 10:47:55 INFO - Running setup.py install for blobuploader 10:47:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:47:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:47:55 INFO - Running setup.py install for requests 10:47:55 INFO - Running setup.py install for docopt 10:47:56 INFO - Successfully installed blobuploader requests docopt 10:47:56 INFO - Cleaning up... 10:47:56 INFO - Return code: 0 10:47:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:47:56 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')]} 10:47:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:47:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:47:56 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')]} 10:47:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:47:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:47:56 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 0x10587e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1057f4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0ea49a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105692d30>, '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 0x1054b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10568ab20>, '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.RHJPoLaqP5/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.kyDzGqMb6F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 10:47:56 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 10:47:56 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 10:47:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RHJPoLaqP5/Render', 10:47:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:47:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:47:56 INFO - 'HOME': '/Users/cltbld', 10:47:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:47:56 INFO - 'LOGNAME': 'cltbld', 10:47:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:47:56 INFO - 'MOZ_NO_REMOTE': '1', 10:47:56 INFO - 'NO_EM_RESTART': '1', 10:47:56 INFO - 'PAGER': '/bin/cat', 10:47:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:47:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:47:56 INFO - 'PWD': '/builds/slave/test', 10:47:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:47:56 INFO - 'SHELL': '/bin/bash', 10:47:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kyDzGqMb6F/Listeners', 10:47:56 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 10:47:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:47:56 INFO - 'USER': 'cltbld', 10:47:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:47:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:47:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:47:56 INFO - 'XPC_FLAGS': '0x0', 10:47:56 INFO - 'XPC_SERVICE_NAME': '0', 10:47:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:47:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:47:56 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-Sn_Jt3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:47:56 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-vOAzuc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:47:56 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-E8Uy7u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:47:56 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-xjivZL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:47:56 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-pnlw51-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:47:56 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-gg9xgP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:47:56 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-ceogmu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:47:57 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-nFrnpz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:47:57 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-m4YBYT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:47:57 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-Dr1D1o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:47:57 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-4m5j0M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:47:57 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-26BNZo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:47:57 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-Nah3D_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:47:57 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-Ntw_3s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:47:57 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-bsnEdz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:47:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:47:57 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-l3YN_d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:47:58 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-Ht7m1X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:47:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:47:58 INFO - Running setup.py install for manifestparser 10:47:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:47:58 INFO - Running setup.py install for mozcrash 10:47:58 INFO - Running setup.py install for mozdebug 10:47:58 INFO - Running setup.py install for mozdevice 10:47:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:47:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:47:58 INFO - Running setup.py install for mozfile 10:47:58 INFO - Running setup.py install for mozhttpd 10:47:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:47:59 INFO - Running setup.py install for mozinfo 10:47:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:47:59 INFO - Running setup.py install for mozInstall 10:47:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:47:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:47:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:47:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:47:59 INFO - Running setup.py install for mozleak 10:47:59 INFO - Running setup.py install for mozlog 10:47:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:47:59 INFO - Running setup.py install for moznetwork 10:47:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:47:59 INFO - Running setup.py install for mozprocess 10:47:59 INFO - Running setup.py install for mozprofile 10:48:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:48:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:48:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:48:00 INFO - Running setup.py install for mozrunner 10:48:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:48:00 INFO - Running setup.py install for mozscreenshot 10:48:00 INFO - Running setup.py install for moztest 10:48:00 INFO - Running setup.py install for mozversion 10:48:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:48:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:48:00 INFO - Cleaning up... 10:48:00 INFO - Return code: 0 10:48:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:48:00 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')]} 10:48:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:00 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')]} 10:48:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:00 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 0x10587e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1057f4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0ea49a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105692d30>, '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 0x1054b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10568ab20>, '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.RHJPoLaqP5/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.kyDzGqMb6F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 10:48:00 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 10:48:00 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 10:48:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RHJPoLaqP5/Render', 10:48:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:00 INFO - 'HOME': '/Users/cltbld', 10:48:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:00 INFO - 'LOGNAME': 'cltbld', 10:48:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:00 INFO - 'MOZ_NO_REMOTE': '1', 10:48:00 INFO - 'NO_EM_RESTART': '1', 10:48:00 INFO - 'PAGER': '/bin/cat', 10:48:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:00 INFO - 'PWD': '/builds/slave/test', 10:48:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:00 INFO - 'SHELL': '/bin/bash', 10:48:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kyDzGqMb6F/Listeners', 10:48:00 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 10:48:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:00 INFO - 'USER': 'cltbld', 10:48:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:00 INFO - 'XPC_FLAGS': '0x0', 10:48:00 INFO - 'XPC_SERVICE_NAME': '0', 10:48:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:48:01 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-sD0JJh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:48:01 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)) 10:48:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:48:01 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-4hiFt_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:48:01 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)) 10:48:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:48:01 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-mFSO7l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:48:01 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)) 10:48:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:48:01 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-8sBmf4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:48:01 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)) 10:48:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:48:01 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-ZYDRwj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:48:01 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)) 10:48:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:48:01 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-SjIL7v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:48:01 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)) 10:48:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:48:01 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-Js0bHz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:48:01 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)) 10:48:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:48:01 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-BjYaJV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:48:01 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)) 10:48:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:48:01 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-ZrsAAJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:48:01 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)) 10:48:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:48:01 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-O76Ke3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:48:02 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)) 10:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:48:02 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-G9KJ8x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:48:02 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)) 10:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:48:02 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-PkROIS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:48:02 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)) 10:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:48:02 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-g1ES6Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:48:02 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)) 10:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:48:02 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-MwMxh1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:48:02 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)) 10:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:48:02 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-1CtcSi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:48:02 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)) 10:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:48:02 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-mmRaN4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:48:02 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)) 10:48:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:48:02 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-H6FuKa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:48:02 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)) 10:48:02 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)) 10:48:02 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)) 10:48:02 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)) 10:48:02 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)) 10:48:02 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)) 10:48:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:48:02 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 10:48:02 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 10:48:02 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 10:48:02 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 10:48:02 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 10:48:02 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 10:48:06 INFO - Downloading blessings-1.6.tar.gz 10:48:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:48:06 INFO - Installing collected packages: blessings 10:48:06 INFO - Running setup.py install for blessings 10:48:06 INFO - Successfully installed blessings 10:48:06 INFO - Cleaning up... 10:48:06 INFO - Return code: 0 10:48:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:48:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:48:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:48:06 INFO - Reading from file tmpfile_stdout 10:48:06 INFO - Current package versions: 10:48:06 INFO - blessings == 1.6 10:48:06 INFO - blobuploader == 1.2.4 10:48:06 INFO - docopt == 0.6.1 10:48:06 INFO - functools32 == 3.2.3-2 10:48:06 INFO - jsonschema == 2.5.1 10:48:06 INFO - manifestparser == 1.1 10:48:06 INFO - mozInstall == 1.12 10:48:06 INFO - mozcrash == 0.17 10:48:06 INFO - mozdebug == 0.1 10:48:06 INFO - mozdevice == 0.48 10:48:06 INFO - mozfile == 1.2 10:48:06 INFO - mozhttpd == 0.7 10:48:06 INFO - mozinfo == 0.9 10:48:06 INFO - mozleak == 0.1 10:48:06 INFO - mozlog == 3.4 10:48:06 INFO - moznetwork == 0.27 10:48:06 INFO - mozprocess == 0.23 10:48:06 INFO - mozprofile == 0.28 10:48:06 INFO - mozrunner == 6.12 10:48:06 INFO - mozscreenshot == 0.1 10:48:06 INFO - mozsystemmonitor == 0.3 10:48:06 INFO - moztest == 0.7 10:48:06 INFO - mozversion == 1.4 10:48:06 INFO - psutil == 3.1.1 10:48:06 INFO - requests == 1.2.3 10:48:06 INFO - wsgiref == 0.1.2 10:48:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:48:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:48:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:48:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:06 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 0x10587e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1057f4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0ea49a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105692d30>, '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 0x1054b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10568ab20>, '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.RHJPoLaqP5/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.kyDzGqMb6F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 10:48:06 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 10:48:06 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 10:48:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RHJPoLaqP5/Render', 10:48:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:06 INFO - 'HOME': '/Users/cltbld', 10:48:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:06 INFO - 'LOGNAME': 'cltbld', 10:48:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:06 INFO - 'MOZ_NO_REMOTE': '1', 10:48:06 INFO - 'NO_EM_RESTART': '1', 10:48:06 INFO - 'PAGER': '/bin/cat', 10:48:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:06 INFO - 'PWD': '/builds/slave/test', 10:48:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:06 INFO - 'SHELL': '/bin/bash', 10:48:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kyDzGqMb6F/Listeners', 10:48:06 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 10:48:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:06 INFO - 'USER': 'cltbld', 10:48:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:06 INFO - 'XPC_FLAGS': '0x0', 10:48:06 INFO - 'XPC_SERVICE_NAME': '0', 10:48:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:07 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)) 10:48:07 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)) 10:48:07 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)) 10:48:07 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)) 10:48:07 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)) 10:48:07 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)) 10:48:07 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)) 10:48:07 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)) 10:48:07 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)) 10:48:07 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)) 10:48:07 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)) 10:48:07 INFO - Cleaning up... 10:48:07 INFO - Return code: 0 10:48:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:48:07 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')]} 10:48:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:07 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')]} 10:48:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:07 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 0x10587e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1057f4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0ea49a230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105692d30>, '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 0x1054b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10568ab20>, '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.RHJPoLaqP5/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.kyDzGqMb6F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 10:48:07 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 10:48:07 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 10:48:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RHJPoLaqP5/Render', 10:48:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:07 INFO - 'HOME': '/Users/cltbld', 10:48:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:07 INFO - 'LOGNAME': 'cltbld', 10:48:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:07 INFO - 'MOZ_NO_REMOTE': '1', 10:48:07 INFO - 'NO_EM_RESTART': '1', 10:48:07 INFO - 'PAGER': '/bin/cat', 10:48:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:07 INFO - 'PWD': '/builds/slave/test', 10:48:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:07 INFO - 'SHELL': '/bin/bash', 10:48:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kyDzGqMb6F/Listeners', 10:48:07 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 10:48:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:07 INFO - 'USER': 'cltbld', 10:48:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:07 INFO - 'XPC_FLAGS': '0x0', 10:48:07 INFO - 'XPC_SERVICE_NAME': '0', 10:48:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:48:08 INFO - Cleaning up... 10:48:08 INFO - Return code: 0 10:48:08 INFO - Running post-action listener: _resource_record_post_action 10:48:08 INFO - Running post-action listener: _start_resource_monitoring 10:48:08 INFO - Starting resource monitoring. 10:48:08 INFO - [mozharness: 2016-10-05 17:48:08.171522Z] Finished create-virtualenv step (success) 10:48:08 INFO - [mozharness: 2016-10-05 17:48:08.172437Z] Running install step. 10:48:08 INFO - Running pre-action listener: _resource_record_pre_action 10:48:08 INFO - Running main action method: install 10:48:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:48:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:48:08 INFO - Reading from file tmpfile_stdout 10:48:08 INFO - Detecting whether we're running mozinstall >=1.0... 10:48:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:48:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:48:08 INFO - Reading from file tmpfile_stdout 10:48:08 INFO - Output received: 10:48:08 INFO - Usage: mozinstall [options] installer 10:48:08 INFO - Options: 10:48:08 INFO - -h, --help show this help message and exit 10:48:08 INFO - -d DEST, --destination=DEST 10:48:08 INFO - Directory to install application into. [default: 10:48:08 INFO - "/builds/slave/test"] 10:48:08 INFO - --app=APP Application being installed. [default: firefox] 10:48:08 INFO - mkdir: /builds/slave/test/build/application 10:48:08 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'] 10:48:08 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 10:48:43 INFO - Reading from file tmpfile_stdout 10:48:43 INFO - Output received: 10:48:43 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:48:43 INFO - Running post-action listener: _resource_record_post_action 10:48:43 INFO - [mozharness: 2016-10-05 17:48:43.110354Z] Finished install step (success) 10:48:43 INFO - [mozharness: 2016-10-05 17:48:43.110508Z] Running run-tests step. 10:48:43 INFO - Running pre-action listener: _resource_record_pre_action 10:48:43 INFO - Running main action method: run_tests 10:48:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:48:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:48:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:48:43 INFO - 2016-10-05 10:48:43.122 screenresolution[1639:7811] starting screenresolution argv=screenresolution get 10:48:43 INFO - 2016-10-05 10:48:43.125 screenresolution[1639:7811] Display 0: 1600x1200x32@60 10:48:43 INFO - 2016-10-05 10:48:43.130 screenresolution[1640:7813] starting screenresolution argv=screenresolution list 10:48:43 INFO - Available Modes on Display 0 10:48:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:48:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:48:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:48:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:48:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:48:43 INFO - Intel Iris: 10:48:43 INFO - Chipset Model: Intel Iris 10:48:43 INFO - Type: GPU 10:48:43 INFO - Bus: Built-In 10:48:43 INFO - VRAM (Dynamic, Max): 1536 MB 10:48:43 INFO - Vendor: Intel (0x8086) 10:48:43 INFO - Device ID: 0x0a2e 10:48:43 INFO - Revision ID: 0x0009 10:48:43 INFO - Displays: 10:48:43 INFO - Display: 10:48:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:48:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:48:43 INFO - Display Serial Number: 1600x1200 60 10:48:43 INFO - Main Display: Yes 10:48:43 INFO - Mirror: Off 10:48:43 INFO - Online: Yes 10:48:43 INFO - Rotation: Supported 10:48:43 INFO - Return code: 0 10:48:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:48:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:48:43 INFO - Python 2.7.3 10:48:43 INFO - Return code: 0 10:48:43 INFO - grabbing minidump binary from tooltool 10:48:43 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')]} 10:48:43 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 0x105692d30>, '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 0x1054b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10568ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:48:43 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 10:48:43 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 10:48:43 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 10:48:43 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:48:43 INFO - Return code: 0 10:48:43 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:48:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:48:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:48:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:48:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:48:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JvRQ073fSE-eXPBlg5w5Jg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0003', '--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 10:48:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JvRQ073fSE-eXPBlg5w5Jg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0003 --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 10:48:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RHJPoLaqP5/Render', 10:48:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:43 INFO - 'HOME': '/Users/cltbld', 10:48:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:43 INFO - 'LOGNAME': 'cltbld', 10:48:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:48:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:48:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:43 INFO - 'MOZ_NO_REMOTE': '1', 10:48:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:48:43 INFO - 'NO_EM_RESTART': '1', 10:48:43 INFO - 'PAGER': '/bin/cat', 10:48:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:43 INFO - 'PWD': '/builds/slave/test', 10:48:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:48:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:43 INFO - 'SHELL': '/bin/bash', 10:48:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kyDzGqMb6F/Listeners', 10:48:43 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 10:48:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:43 INFO - 'USER': 'cltbld', 10:48:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:43 INFO - 'XPC_FLAGS': '0x0', 10:48:43 INFO - 'XPC_SERVICE_NAME': '0', 10:48:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:43 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JvRQ073fSE-eXPBlg5w5Jg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0003', '--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 10:48:43 INFO - mozversion application_buildid: 20161005080902 10:48:43 INFO - mozversion application_changeset: 4f41a594f88d17e25bbfd362f2e45e6dc891bfc6 10:48:43 INFO - mozversion application_display_name: Nightly 10:48:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:48:43 INFO - mozversion application_name: Firefox 10:48:43 INFO - mozversion application_remotingname: firefox 10:48:43 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 10:48:43 INFO - mozversion application_vendor: Mozilla 10:48:43 INFO - mozversion application_version: 52.0a1 10:48:43 INFO - mozversion platform_buildid: 20161005080902 10:48:43 INFO - mozversion platform_changeset: 4f41a594f88d17e25bbfd362f2e45e6dc891bfc6 10:48:43 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 10:48:43 INFO - mozversion platform_version: 52.0a1 10:48:43 INFO - using testdate: 1475689723 10:48:43 INFO - actual date: 1475689723 10:48:43 INFO - starting webserver on 'localhost:49270' 10:48:43 INFO - SUITE-START | Running 2 tests 10:48:43 INFO - TEST-START | dromaeo_css 10:48:43 INFO - operating with platform_type : mac_ 10:48:43 INFO - Initialising browser for dromaeo_css test... 10:48:43 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/tmpbHXgmR/profile http://localhost:49270/getInfo.html) 10:48:46 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 10:48:46 INFO - PROCESS | 1650 | colorDepth:24 10:48:46 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 10:48:46 INFO - PROCESS | 1650 | __metrics 10:48:46 INFO - PROCESS | 1650 | 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" 10:48:46 INFO - PROCESS | 1650 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:48:47 INFO - TEST-INFO | 1650: exit 0 10:48:47 INFO - Browser initialized. 10:48:47 INFO - Running cycle 1/1 for dromaeo_css test... 10:48:47 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/tmpbHXgmR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:48:59 INFO - PROCESS | 1652 | RSS: Main: 235786240 10:48:59 INFO - PROCESS | 1652 | 10:49:50 INFO - PROCESS | 1652 | 1475689790770 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] 10:52:02 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 10:52:06 INFO - PROCESS | 1652 | RSS: Main: 513683456 10:52:06 INFO - PROCESS | 1652 | 10:55:09 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 10:55:09 INFO - PROCESS | 1652 | RSS: Main: 371638272 10:55:09 INFO - PROCESS | 1652 | 10:58:12 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 10:58:12 INFO - PROCESS | 1652 | RSS: Main: 346497024 10:58:12 INFO - PROCESS | 1652 | 11:01:15 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 11:01:15 INFO - PROCESS | 1652 | RSS: Main: 345358336 11:01:15 INFO - PROCESS | 1652 | 11:04:19 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 11:04:19 INFO - PROCESS | 1652 | RSS: Main: 335241216 11:04:19 INFO - PROCESS | 1652 | 11:07:22 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 11:07:22 INFO - PROCESS | 1652 | RSS: Main: 457838592 11:07:22 INFO - PROCESS | 1652 | 11:07:22 INFO - PROCESS | 1652 | __start_tp_report 11:07:22 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 11:07:22 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 11:07:22 INFO - PROCESS | 1652 | |i|pagename|runs| 11:07:22 INFO - PROCESS | 1652 | |0;dojo.html;3139.0425920094367;3274.5251938468923;3289.227031647561;3307.355065762177;3344.84948177332;5469.015577196104;5559.249501317321;5630.915536266959;5639.859003524909;5640.294963129609;4599.103174880899;5536.529395001419;5667.829965101043;5682.772689092441;5723.4848863602265;5040.823571175009;5067.265246539249;5067.619928505377;5080.034793389263;5147.613928955343;5128.5897128229835;5232.084385232588;5233.659812112201;5243.292155559008;5259.789608415659;5050.267711181895;5191.6884986900905;5206.739663016872;5258;5274.314339135907;5090.236464530313;5160.251763494272;5173.689578625257;5191.5846732261325;5202.115640341151;5056.115179843513;5112.846614601568;5119.692818430906;5141.485851414866;5149.974250128763;5584.106542953108;5628.015097357987;5643.322801263834;5645.774169033234;5656.717164141776;6126.989046807272;6129.69351532426;6130.570860039799;6134.8159555213415;6136.662483563402;5908.852278693024;6026.126211699323;6167.907481387782;6183.567150299481;6188.907166392462;5582.972085139589;5647.152927060933;5670.291213598301;5670.801521946753;5673.687947162945;5232.529072383428;5717.085266357363;6247.843803904939;6276.592021518587;6295.433410992941;5800.970995144998;5944.138099975566;5949.9107513387335;5968.791092311748;5973.342932277533;6027.758889644453;6107.969460152671;6123.435141503484;6125.081237814275;6202.2867370253;6085.9695701521205;6104.152474528661;6116.591928251121;6122.398130291625;6122.632014270253;6098.9654693115635;6126.509879209652;6144.815655530252;6153.953827849276;6155.045967874987;4738.289256611536;4800.711957282574;4816.301636262722;4835.854924352275;4862.149123903374;4281.972326641584;4381.298992161239;4387.429634147604;4409.206342858252;4419.0940857124215;5579.497845193952;5581.6371935824045;5583.357913839879;5592.217089607459;5601.075822489325;5081.8729531762;6094.024956007106;6106.39999602577;6117.143599896018;6118.131909153758;5598.468145526169;5652.802151924663;5658.801941932013;5659.490645841894;5667.489925906688;5309.65487243328;5321.095413779667;5325.760340784674;5333.120035194227;5333.5199832015305;5696.974544582015;6360.236771587439;6371.55399122057;6375.203099612549;6376.936230637727;5826.359100498857;6066.545009124422;6103.694815259308;6114.663693496723;6126.356732543197;6354.650494222862;6362.872742545031;6370.840728981812;6375.872482550416;6387.329330420239;6090.817275481742;6098.29869564988;6098.634081955097;6111.266648002268;6113.5414843887775;5985.102234664835;6035.36628653985;6191;6199.659018753831;6202.34875338083;5884.382139875423;5893.116032595145;5897.262842144732;5900.498457631149;5903.321118071477;5734.168545560787;5879.794207202727;5884.323302820059;5884.94071067212;5891.23413956183;5720.8569785754025;5760.971195143998;5765.654060756368;5774.364819869895;5775.682337471311;5905.40945905406;5913.05391137416;5922.052471604523;5925;5929.733162007634;5551.528120109836;5726.656400615976;5749.54003679713;5789.334226563999;5792.507636850914 11:07:22 INFO - PROCESS | 1652 | |1;ext.html;448.3789950917979;511.5523916572996;518.0493793888215;529.2854646227592;547.9999001821674;38640.84077477676;40123.38272641833;40323.99190020251;40347.193056138894;42628.78685606575;41370.58629413712;41685.16629667405;41708.79145604268;41749.58250417495;41768;8740.606672699743;8972.192502674758;9654.758631034229;9854.655087071918;9866.013398660149;11795.646130616095;12159.635210943687;12168.452419641093;12321.876781232162;12325.260484370967;12116.81824772629;12177.208481448677;12198.878011219864;12206.694832629211;12221.877781222207;8833.072527384596;9045.954770226173;9051.14014168653;12122.818157727641;12209.206401583913;8790.164934331293;8851.07063758309;11645.6506304811;11997.580084696994;12008.339541325224;39826.80086599549;39985.20288543735;40040.79979600113;40123.796286111174;40231.59768402308;2914.3005678636973;3063.6783137770435;3270.0026491919957;3312.370649576597;3766.9811650941842;30407.543886841715;30839.845800770854;30870.38259234825;30875.536866947015;30978.53532197019;3220.6135263768265;3292.934141317184;3347.1799409144915;3369.79781213128;3383.441732114198;474357;478657;482232;482410;484919;56853;57166.42833571694;57188.71405643028;57215.427845721846;57294.41644668417;60227.69886150629;60237.38575685473;60276.095858562156;60330.69834650798;60334.39665603376;15843.207839607974;16037.919810400874;16047.518574442785;16069.517914462585;16091.275892584861;11213.439328033566;11483.827742583868;11486.023687986615;12503.312317822607;12635.93682031584;18986.905065474584;19299;19560.431880918397;19700.60598788016;19817.20731170737;7937.2459616336355;8047.315978141806;8051.4766540174705;8106.47307924983;8167.836643267101;855.3242938078972;952.4808979106404;964.1515466389533;978.4031740638347;1052.1162223732101;29687.96092136808;29947.25131871677;30023.399532009236;30498.69501305003;30553.84723076415;973.2505970402892;1042.864427624404;1045.2265323660436;1061.5435362794074;1111.4942701070993;13692.589222323346;15209.771853422208;15545.989510681995;15650.37398504047;15677.256480115933;657575.7121214364;663485;663903;663990.6800465967;664323;56119.71940140355;56670;56677.716611417505;56804;57027.42972570304;1135.5401062569838;1210.056156198167;1240.6774238698004;1360.2790521023874;1372.3618517389461;27560.862195688893;28606.415626218433;29057.418851622428;30105;30919.536206957364;42316.11361614095;44518.887027823264;47123;47489.76255118702;47591;520123;521409;521886;522499.68753905763;524103;26618.600720989587;26762.063327784213;27250.454990900467;27611.585826213024;28121.296967575974;46144.53855461537;46514.30228546507;46751.766241168574;47074.29388559106;47401.7629911862;30437.630306636696;36783.26433471369;38408.615913840506;38706;39204.41193382159;37883.62116378801;37907.81046094752;38967.610323897534;39657.80171099126;40501.15136058562;38050;38150.618493814705;39040.21919561536;39081.609183908935;39126.41310380289;26203.213903582924;28300.292492687026;31334.529982049833;34413.13967150841;34422.31155376929;21708.45728856791;22797.772022279565;25429;25436;25984.8700756495 11:07:22 INFO - PROCESS | 1652 | |2;jquery.html;350.98596056157754;365.95425571803526;368.96310368963105;379.21691706625825;400.9318415869302;15209.163496007728;15491.380344786208;16414.83585164148;20457.283995060177;20987.845668488226;19728.408147755556;20142.496437589067;20762.584748305024;20913;21058.89470552645;4845.491223421543;5974.67139307339;6149.723262453178;6202.4727898128485;6209.937900621004;5996.430339117798;6208.534359923001;6214.3474935131835;6214.471769899564;6218.937810621881;5918.615290006136;6051.727672254737;6054.666993315366;6147.815565533019;6163.660998645095;5294.947050529503;5990.820275391745;6143.385661433866;6146.262448506163;6153.84615384619;4113.465249517573;5294.1529355303355;5510.9448905511235;5572.944270557283;5606.859828504279;12676.619701408972;12925.095243332971;12927.741445171045;12987.41556629954;12989.350532473432;2086.968695469569;2087.8329733621426;2262.570111678776;2868.0965495868736;2950.586917831505;9734.853977190347;9805.852912206323;9885.75285617858;10233;10480.633177838816;1791.713325867868;1824.8175182481777;1832.532704160444;1862.1527205121638;1864.3474783825689;117816;120693.39653301677;121998.39000805028;124084;126884;10039.297249192487;10950.452477376099;11497.854505538302;11573.132015098901;11698;14488.565343039725;14533.709325813425;14541.927290363481;14546.927265363605;14559;6835;6984.266652001568;7258.838585826348;7884.369250459949;8454.788630284293;7496.7750967471065;7844.764657060297;8332.833403323539;8339.24946754795;8467.407281490237;3781.810909454571;4123.0516981094515;4494.505604383515;4514.616257618073;4578.496365399803;1861.2834058887427;1909.5798924236647;1912.9330473433363;1923.6825923722429;1959.6276707425543;811.8903947967067;852.3522123186299;889.6308032166578;934.9392289501161;978.2076518020426;20113.09491072905;20488.89755551213;20852.582948341253;21004.684929726067;21138.365849024554;796.8087658961923;885.9512726799959;899.9595018224196;951.619352259088;970.8543718442149;10280.845787313196;11132;11160.497777600027;11181;11264.66206013821;528036;562263;565219.1739041278;574447;575826;102888.48555757174;108870.91129088766;122658;126947;136878;924.7318277699526;926.8053708721244;963.7446076789656;964.4695417520336;967.5065716484503;15430.84268679831;17449.69127315431;18238.452846414628;18456;18656.720149197503;49723.50829475121;53019.734901325246;54728.726356367966;55214.447855522536;55811.44188558062;59023.52441188833;78128;78133;78255.6087219583;78338;7771.184025677222;8005.439619226599;8131.512109273463;8198.54907980043;8323.95838020806;11809;12938;13034.957203423513;13345;13370.665733356354;11166.832497512381;11196.384198868815;11479.942600286946;11615.709607259887;11668.766624667289;11013.2841365311;11160.720981975515;11305.660830175108;11525.020373268366;11693.824592631288;9295.907040929504;11781.469833857325;11849.763004739685;11851.70370740704;11970.76058478843;6682.264950855305;7956.602169891598;7965.761027169194;8231.300339471212;8401.949756280464;8215.835683286181;9153.77115572116;10990.780184396108;11464.541418343173;11699.23954942926 11:07:22 INFO - PROCESS | 1652 | |3;mootools.html;1328.4088580581645;1503.3760989189486;1529.5411376587024;1734.644397898431;1922.7884932657414;7788.376929845613;8272.214139656731;8549.273311768507;8778.473291602508;8795;8648.524331161783;8736.694215702451;8738;8753.080926502722;8760.474371537712;2329.510802731424;2357.7406485286606;2358.7995020423286;2359.0210062823908;2362.681038059866;2312.479692069288;2347.4131467133216;2348.1311914591624;2349.0721165139757;2355.858648481088;2316.2819525946925;2337.2170322941834;2345.589521833681;2353.1175809071597;2355.6231003039516;2220.400491867203;2251.0658076898067;2258.2999270226283;2264.4225722440838;2296.7014288142436;2311.9422014449606;2313.710786151731;2315.571619250444;2315.6989591353185;2318.2697450303267;8658.437201581877;8694.652213911437;8744.8688269676;8762.86855697165;8805.64777408903;5223.37319521656;5401.864953376158;5408.702521361323;5435.0488664483955;5461.644993075437;3599.5680518337967;3619.511365965587;3692.833822477995;3696.9260614787822;3706.388445906422;1069.1874175626501;1080.3193987787722;1083.8428427877993;1101.4878081692052;1106.093003736936;11387.658370248906;11425.028872545843;11501.942490287578;11510.884891151065;11524.88475115255;4318.438602981592;4450.5104438511735;4547.749873756975;4552.635789136861;4575.771211439414;8995.730128096167;9031.729048128567;9042.86435703465;9044.773880653036;9044.8643270351;5180.844574662766;5183.429822719498;5189.896202075937;5190.455002224788;5197.402298735694;4159.854405095807;4397.098594788061;4556.11155824611;4563.041761230113;4879.634027447955;3609.3683605368956;3623.8731644392847;3692.944605830861;3710.3135919854913;3711.9072023199637;1139.8917102875214;1143.2511704833348;1147.3861484105964;1151.792677318074;1155.306815910447;810.9513429194267;887.4985633117268;896.4173287363136;902.8916530016309;915.9038300978434;5685.147227915846;5769.740361683735;5909.704514774244;6125.571210015256;6173.32093469718;873.9257163141077;952.8475443929077;957.9568919398644;964.2045312616979;999.0608827701936;3863.787491687984;3872.806359682061;4004.2592120457807;4073.89815254621;4093.570175131628;10376;10713.035826775627;10796.190285728602;10801.729956751144;10841.566337346418;10024.949875250577;10523.315984460985;10726.678199654023;10758.677239682822;10759.623413180501;7957.164497727771;8163.95918020418;8173.100958894517;8176.591170441454;8179.386546008954;4726.598239149574;5843.474087332161;5855;6099.725512351866;6213.068039794067;9881.30830841863;9934.552945117632;9940.502974851084;9956.850647240442;9988.950055249677;9065.728028158903;9441.103095205945;9567.617295308388;10248.692539223835;10276.948615257175;6107.358727333566;7218.602976836324;7271.672774725044;7427.331540161412;7498.550086994798;8133.0646975598565;8199.139090395423;8200.58997050157;8267.586620668822;8281.337493000667;7450.105987281561;7491.9625401872645;7528.736494222676;7529.247075292427;7549.547027178387;7127.180374256819;7148.642567871481;7283.453740969342;7303.525270857377;7363.190049094703;6891.104156459628;6960.47796415281;7027.472939529658;7035.261297563682;7044.647767611496;4842.661013729005;5000.149974504416;5041.470645582308;5057.064443078042;5189.974050129726;4944.035912997075;5007.198848184273;5021.874453138701;5051.090803655377;5116 11:07:22 INFO - PROCESS | 1652 | |4;prototype.html;293.54353979561773;298.2603144202378;299.5132909022838;307.4404583657743;312.87328631904086;5071.391433028037;5116.97660467906;5118.283440318353;5126.436092029874;5175.896482070356;4282.635975942041;4554.8157479055435;4690.343351930733;4966.801327946887;5093.643444958854;3592.7844329340323;3740.933833857351;3907.745996510232;3940.093778430948;3960.4257382679493;3325.6508066653014;3524.68277854993;3937.7243592948507;3938.7045971552106;3938.975866274775;3603;3838.5201849768778;3909.9804500977457;3910.159315747114;3934.3311637021775;3217.935641287161;3677.9701683528388;3926.430667553203;3928.2143571285715;3949.565547789741;3120.4241857861634;3370.275390790992;3390.627031026585;3397.6092749333898;3408.3694516514524;3857.903552411184;4118.249743858848;4965.280034395028;5012.423571289312;5017.096922553938;4157.771322577257;4393.736375817429;4411.228035093845;4411.911761764779;4441.400410944513;4665.533446655341;5122.719320169957;5351.545118664918;5359.839204823861;5361.919571206435;3102.503599424104;3683.7605555639066;3687.520622319108;3690.335739566877;3702.3709567790356;4653.185692503798;5427.45725427458;5553.222548843206;5629.155626656035;5684.602077854552;3270.73834093272;3723.7579557328686;3738.4579236010627;3744.165358974083;3747.6252374762307;3805.5243094613174;3815;3817.828197731109;3821.7515861469474;3832.885013449601;3705.370087085202;4416.734995900256;4423.823047078081;4430.534793846664;4435.711678740936;4750.928736068961;5054.545090941833;5362.061639213122;5387.272718183073;5389.8113566025;1274.9235045897276;1288.5182040653324;1288.890444312225;1291.9935400322938;1297.7664020476216;697.9495858732575;827.3753316246195;836.43586493258;836.4521238588734;847.3856454070749;4819.373481447389;4897.534734200245;5053.46938571452;5060.367454068241;5147.867469156854;4605.815767369268;5316.468353164652;5528.944710552923;5536.640118392292;5556.861078473071;4801.351817504662;4951;5066.594672426197;5098.031374038921;5119.592112169184;2827;2882.3658795065053;2887.8411687356975;2887.8411687356975;2890.378568607717;4375.249900040009;4848.8302909398;5061.102086717481;5348.277982472394;5616.708157123802;4504.932426013613;5477.945220547823;5611.719414029282;5631.971840140855;5693.348928810681;5636.943630563724;5647.943520564741;5649.54803615718;5667.149927510906;5678.886422271614;4869.902601947871;5210.296609957607;5485.533729632866;5490.505854473116;5503.477169668875;4325.39444477767;4735.952640473552;4848.490908454703;5410.620291825717;5431.321084864392;5648.858778530405;5677.432256774289;5684.602077854511;5695.430456954271;5700.657960522382;2918.430905973315;3149.590553228066;3184.3949649566507;3203.08712017074;3438.862445502252;4549.975261008819;5479.506844384025;5482.835514934558;5506.301912062981;5529.834104976857;5348.224507446321;5360.383555891122;5386.649867758583;5394.919076213781;5399.487048730364;5009.499050094962;5301.973490132525;5321.36143662763;5347.625666203484;5389.380221274603;5017.62367822422;5292.52367286946;5304.090527410206;5316.521513063843;5318.282031925795;4462;4509.391232183613;5156.4990979375725;5289.788408463618;5406.107992181246;5279.89440211186;5302.787888484418;5307.256984022317;5322.627416080837;5326.387465441524 11:07:22 INFO - PROCESS | 1652 | |5;yui.html;592.304022897122;725.4776560876169;756.3155344413304;774.4888373673376;791.0783936713729;15290.694186116285;15803.604909877244;15955.601109972256;15964.28160732767;15983.520494385173;13880.097793643406;14283.928580357084;14392.71214575708;14402.855971440282;14582;685.2120061928797;828.2793969246749;851.459323329687;851.6806197675871;857.5840717252128;695.3463744080574;837.0792128658488;865.1737590600992;865.7402779166225;869.6608322754099;916.3860213656866;918.2103391083663;918.2332752151927;918.3066784577635;919.5034681272139;910.3445519226179;911.0479548871446;914.0128661046055;914.5518695838991;915.4370062411635;525.3065952942118;540.5162379670212;541.4504278157673;541.5748637319724;543.1526818163638;12353.62939111828;14348.282585870666;14924.403023879031;15261.008034477834;15297.158656273901;14406.783898241534;14612.86151480508;14774.113553186844;14788.630284242872;14924.70150596982;11572.363520006396;11881.5841445549;11994.400279986054;12146.696332591757;12322.81515777264;3567.571891373052;3747.381682022463;3799.2401519696173;3804.5814960354337;3928.116173860887;113973;115465;115753.42123289414;116232;116291;9735.123838854537;10345;10800.51397687106;11188.992990630883;11234.662960111209;21644.45888852791;21810.018549165325;21813.345599632037;21826.236081737065;21884;7910.644021019068;7918.2477664621765;8027.5584842834205;8064.637091330904;8114.350851931831;3723.311187430334;4452.398926144929;4490.663200259994;4585.151746926829;4719.150552900443;3506.2111025019167;3642.5446819147605;3645.5625324961175;3648.2703459307713;3657.3233951718485;531.9787208511616;637.219406227377;649.1172006071739;649.357136434926;649.4090377756219;561.1974875927463;566.9007923613351;574.7499837570671;625.9186305780311;688.6659969914588;8871.44784886493;9224.400413973204;9364.859527107099;9423.869135703759;9459.668911588062;562.3757629031771;609.4880300547472;611.9877602447926;646.6184950879004;716.5163514627606;4130.979345103316;4520.299353600197;4619.214733495314;4628.467726211461;4629.282461218517;144332;153482;153804.2309788444;156624;159101.40898591097;133439;140521;141573;145761;146707.266463667;118780.81219187871;121464;121678.78321216852;121813.78186218202;124893.12660310103;8853.335999800169;8940.776480587778;8992.191070399775;8993.640254389751;9208.723738287863;65172.674136629015;67133.66433167802;67842.66078669575;68167;68449.65775171292;88419;99809;101734;103240;103874.4806275994;11494.770104598028;13003.804942925675;13127.409266583218;13246.801297980346;13330.466781328638;14500.85499144995;14620.634484137981;14897.255137242877;15080.245987700791;15344.232788360761;11757.647270581896;14046.367913443715;14267;14409.927950360181;14704.264786760405;13571.932140339235;13617.80430187741;13829.930850345683;13846;14485.637859053608;13305.933470332973;13539.729205416033;13584.32078396096;13956.930215348859;14002;10876;11229.545262551148;11365.261258018203;11489.827652585385;11863.58477453285;11085;11490.892400105462;11531.308121512737;11552.768944620891;11714.23857449263 11:07:22 INFO - PROCESS | 1652 | __end_tp_report 11:07:22 INFO - PROCESS | 1652 | __start_cc_report 11:07:22 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,3020 11:07:22 INFO - PROCESS | 1652 | __end_cc_report 11:07:22 INFO - PROCESS | 1652 | __startTimestamp1475690842954__endTimestamp 11:07:22 INFO - PROCESS | 1652 | 11:07:22 INFO - PROCESS | 1652 | ------- Summary: start ------- 11:07:22 INFO - PROCESS | 1652 | Number of tests: 6 11:07:22 INFO - PROCESS | 1652 | 11:07:22 INFO - PROCESS | 1652 | [#0] dojo.html Cycles:165 Average:5621.48 Median:5734.17 stddev:629.75 (11.0%) stddev-sans-first:600.82 11:07:22 INFO - PROCESS | 1652 | Values: 3139.0 3274.5 3289.2 3307.4 3344.8 5469.0 5559.2 5630.9 5639.9 5640.3 4599.1 5536.5 5667.8 5682.8 5723.5 5040.8 5067.3 5067.6 5080.0 5147.6 5128.6 5232.1 5233.7 5243.3 5259.8 5050.3 5191.7 5206.7 5258.0 5274.3 5090.2 5160.3 5173.7 5191.6 5202.1 5056.1 5112.8 5119.7 5141.5 5150.0 5584.1 5628.0 5643.3 5645.8 5656.7 6127.0 6129.7 6130.6 6134.8 6136.7 5908.9 6026.1 6167.9 6183.6 6188.9 5583.0 5647.2 5670.3 5670.8 5673.7 5232.5 5717.1 6247.8 6276.6 6295.4 5801.0 5944.1 5949.9 5968.8 5973.3 6027.8 6108.0 6123.4 6125.1 6202.3 6086.0 6104.2 6116.6 6122.4 6122.6 6099.0 6126.5 6144.8 6154.0 6155.0 4738.3 4800.7 4816.3 4835.9 4862.1 4282.0 4381.3 4387.4 4409.2 4419.1 5579.5 5581.6 5583.4 5592.2 5601.1 5081.9 6094.0 6106.4 6117.1 6118.1 5598.5 5652.8 5658.8 5659.5 5667.5 5309.7 5321.1 5325.8 5333.1 5333.5 5697.0 6360.2 6371.6 6375.2 6376.9 5826.4 6066.5 6103.7 6114.7 6126.4 6354.7 6362.9 6370.8 6375.9 6387.3 6090.8 6098.3 6098.6 6111.3 6113.5 5985.1 6035.4 6191.0 6199.7 6202.3 5884.4 5893.1 5897.3 5900.5 5903.3 5734.2 5879.8 5884.3 5884.9 5891.2 5720.9 5761.0 5765.7 5774.4 5775.7 5905.4 5913.1 5922.1 5925.0 5929.7 5551.5 5726.7 5749.5 5789.3 5792.5 11:07:22 INFO - PROCESS | 1652 | 11:07:22 INFO - PROCESS | 1652 | [#1] ext.html Cycles:180 Average:68794.77 Median:38279.62 stddev:149730.96 (391.2%) stddev-sans-first:150063.06 11:07:22 INFO - PROCESS | 1652 | Values: 448.4 511.6 518.0 529.3 548.0 38640.8 40123.4 40324.0 40347.2 42628.8 41370.6 41685.2 41708.8 41749.6 41768.0 8740.6 8972.2 9654.8 9854.7 9866.0 11795.6 12159.6 12168.5 12321.9 12325.3 12116.8 12177.2 12198.9 12206.7 12221.9 8833.1 9046.0 9051.1 12122.8 12209.2 8790.2 8851.1 11645.7 11997.6 12008.3 39826.8 39985.2 40040.8 40123.8 40231.6 2914.3 3063.7 3270.0 3312.4 3767.0 30407.5 30839.8 30870.4 30875.5 30978.5 3220.6 3292.9 3347.2 3369.8 3383.4 474357.0 478657.0 482232.0 482410.0 484919.0 56853.0 57166.4 57188.7 57215.4 57294.4 60227.7 60237.4 60276.1 60330.7 60334.4 15843.2 16037.9 16047.5 16069.5 16091.3 11213.4 11483.8 11486.0 12503.3 12635.9 18986.9 19299.0 19560.4 19700.6 19817.2 7937.2 8047.3 8051.5 8106.5 8167.8 855.3 952.5 964.2 978.4 1052.1 29688.0 29947.3 30023.4 30498.7 30553.8 973.3 1042.9 1045.2 1061.5 1111.5 13692.6 15209.8 15546.0 15650.4 15677.3 657575.7 663485.0 663903.0 663990.7 664323.0 56119.7 56670.0 56677.7 56804.0 57027.4 1135.5 1210.1 1240.7 1360.3 1372.4 27560.9 28606.4 29057.4 30105.0 30919.5 42316.1 44518.9 47123.0 47489.8 47591.0 520123.0 521409.0 521886.0 522499.7 524103.0 26618.6 26762.1 27250.5 27611.6 28121.3 46144.5 46514.3 46751.8 47074.3 47401.8 30437.6 36783.3 38408.6 38706.0 39204.4 37883.6 37907.8 38967.6 39657.8 40501.2 38050.0 38150.6 39040.2 39081.6 39126.4 26203.2 28300.3 31334.5 34413.1 34422.3 21708.5 22797.8 25429.0 25436.0 25984.9 11:07:22 INFO - PROCESS | 1652 | 11:07:22 INFO - PROCESS | 1652 | [#2] jquery.html Cycles:180 Average:33308.97 Median:367.46 stddev:94025.18 (25588.0%) stddev-sans-first:94256.37 11:07:22 INFO - PROCESS | 1652 | Values: 351.0 366.0 369.0 379.2 400.9 15209.2 15491.4 16414.8 20457.3 20987.8 19728.4 20142.5 20762.6 20913.0 21058.9 4845.5 5974.7 6149.7 6202.5 6209.9 5996.4 6208.5 6214.3 6214.5 6218.9 5918.6 6051.7 6054.7 6147.8 6163.7 5294.9 5990.8 6143.4 6146.3 6153.8 4113.5 5294.2 5510.9 5572.9 5606.9 12676.6 12925.1 12927.7 12987.4 12989.4 2087.0 2087.8 2262.6 2868.1 2950.6 9734.9 9805.9 9885.8 10233.0 10480.6 1791.7 1824.8 1832.5 1862.2 1864.3 117816.0 120693.4 121998.4 124084.0 126884.0 10039.3 10950.5 11497.9 11573.1 11698.0 14488.6 14533.7 14541.9 14546.9 14559.0 6835.0 6984.3 7258.8 7884.4 8454.8 7496.8 7844.8 8332.8 8339.2 8467.4 3781.8 4123.1 4494.5 4514.6 4578.5 1861.3 1909.6 1912.9 1923.7 1959.6 811.9 852.4 889.6 934.9 978.2 20113.1 20488.9 20852.6 21004.7 21138.4 796.8 886.0 900.0 951.6 970.9 10280.8 11132.0 11160.5 11181.0 11264.7 528036.0 562263.0 565219.2 574447.0 575826.0 102888.5 108870.9 122658.0 126947.0 136878.0 924.7 926.8 963.7 964.5 967.5 15430.8 17449.7 18238.5 18456.0 18656.7 49723.5 53019.7 54728.7 55214.4 55811.4 59023.5 78128.0 78133.0 78255.6 78338.0 7771.2 8005.4 8131.5 8198.5 8324.0 11809.0 12938.0 13035.0 13345.0 13370.7 11166.8 11196.4 11479.9 11615.7 11668.8 11013.3 11160.7 11305.7 11525.0 11693.8 9295.9 11781.5 11849.8 11851.7 11970.8 6682.3 7956.6 7965.8 8231.3 8401.9 8215.8 9153.8 10990.8 11464.5 11699.2 11:07:22 INFO - PROCESS | 1652 | 11:07:22 INFO - PROCESS | 1652 | [#3] mootools.html Cycles:180 Average:5623.02 Median:5086.53 stddev:3138.51 (61.7%) stddev-sans-first:3130.72 11:07:22 INFO - PROCESS | 1652 | Values: 1328.4 1503.4 1529.5 1734.6 1922.8 7788.4 8272.2 8549.3 8778.5 8795.0 8648.5 8736.7 8738.0 8753.1 8760.5 2329.5 2357.7 2358.8 2359.0 2362.7 2312.5 2347.4 2348.1 2349.1 2355.9 2316.3 2337.2 2345.6 2353.1 2355.6 2220.4 2251.1 2258.3 2264.4 2296.7 2311.9 2313.7 2315.6 2315.7 2318.3 8658.4 8694.7 8744.9 8762.9 8805.6 5223.4 5401.9 5408.7 5435.0 5461.6 3599.6 3619.5 3692.8 3696.9 3706.4 1069.2 1080.3 1083.8 1101.5 1106.1 11387.7 11425.0 11501.9 11510.9 11524.9 4318.4 4450.5 4547.7 4552.6 4575.8 8995.7 9031.7 9042.9 9044.8 9044.9 5180.8 5183.4 5189.9 5190.5 5197.4 4159.9 4397.1 4556.1 4563.0 4879.6 3609.4 3623.9 3692.9 3710.3 3711.9 1139.9 1143.3 1147.4 1151.8 1155.3 811.0 887.5 896.4 902.9 915.9 5685.1 5769.7 5909.7 6125.6 6173.3 873.9 952.8 958.0 964.2 999.1 3863.8 3872.8 4004.3 4073.9 4093.6 10376.0 10713.0 10796.2 10801.7 10841.6 10024.9 10523.3 10726.7 10758.7 10759.6 7957.2 8164.0 8173.1 8176.6 8179.4 4726.6 5843.5 5855.0 6099.7 6213.1 9881.3 9934.6 9940.5 9956.9 9989.0 9065.7 9441.1 9567.6 10248.7 10276.9 6107.4 7218.6 7271.7 7427.3 7498.6 8133.1 8199.1 8200.6 8267.6 8281.3 7450.1 7492.0 7528.7 7529.2 7549.5 7127.2 7148.6 7283.5 7303.5 7363.2 6891.1 6960.5 7027.5 7035.3 7044.6 4842.7 5000.1 5041.5 5057.1 5190.0 4944.0 5007.2 5021.9 5051.1 5116.0 11:07:22 INFO - PROCESS | 1652 | 11:07:22 INFO - PROCESS | 1652 | [#4] prototype.html Cycles:180 Average:4323.02 Median:4883.72 stddev:1336.35 (27.4%) stddev-sans-first:1305.42 11:07:22 INFO - PROCESS | 1652 | Values: 293.5 298.3 299.5 307.4 312.9 5071.4 5117.0 5118.3 5126.4 5175.9 4282.6 4554.8 4690.3 4966.8 5093.6 3592.8 3740.9 3907.7 3940.1 3960.4 3325.7 3524.7 3937.7 3938.7 3939.0 3603.0 3838.5 3910.0 3910.2 3934.3 3217.9 3678.0 3926.4 3928.2 3949.6 3120.4 3370.3 3390.6 3397.6 3408.4 3857.9 4118.2 4965.3 5012.4 5017.1 4157.8 4393.7 4411.2 4411.9 4441.4 4665.5 5122.7 5351.5 5359.8 5361.9 3102.5 3683.8 3687.5 3690.3 3702.4 4653.2 5427.5 5553.2 5629.2 5684.6 3270.7 3723.8 3738.5 3744.2 3747.6 3805.5 3815.0 3817.8 3821.8 3832.9 3705.4 4416.7 4423.8 4430.5 4435.7 4750.9 5054.5 5362.1 5387.3 5389.8 1274.9 1288.5 1288.9 1292.0 1297.8 697.9 827.4 836.4 836.5 847.4 4819.4 4897.5 5053.5 5060.4 5147.9 4605.8 5316.5 5528.9 5536.6 5556.9 4801.4 4951.0 5066.6 5098.0 5119.6 2827.0 2882.4 2887.8 2887.8 2890.4 4375.2 4848.8 5061.1 5348.3 5616.7 4504.9 5477.9 5611.7 5632.0 5693.3 5636.9 5647.9 5649.5 5667.1 5678.9 4869.9 5210.3 5485.5 5490.5 5503.5 4325.4 4736.0 4848.5 5410.6 5431.3 5648.9 5677.4 5684.6 5695.4 5700.7 2918.4 3149.6 3184.4 3203.1 3438.9 4550.0 5479.5 5482.8 5506.3 5529.8 5348.2 5360.4 5386.6 5394.9 5399.5 5009.5 5302.0 5321.4 5347.6 5389.4 5017.6 5292.5 5304.1 5316.5 5318.3 4462.0 4509.4 5156.5 5289.8 5406.1 5279.9 5302.8 5307.3 5322.6 5326.4 11:07:22 INFO - PROCESS | 1652 | 11:07:22 INFO - PROCESS | 1652 | [#5] yui.html Cycles:180 Average:26219.58 Median:21855.12 stddev:42499.92 (194.5%) stddev-sans-first:42575.59 11:07:22 INFO - PROCESS | 1652 | Values: 592.3 725.5 756.3 774.5 791.1 15290.7 15803.6 15955.6 15964.3 15983.5 13880.1 14283.9 14392.7 14402.9 14582.0 685.2 828.3 851.5 851.7 857.6 695.3 837.1 865.2 865.7 869.7 916.4 918.2 918.2 918.3 919.5 910.3 911.0 914.0 914.6 915.4 525.3 540.5 541.5 541.6 543.2 12353.6 14348.3 14924.4 15261.0 15297.2 14406.8 14612.9 14774.1 14788.6 14924.7 11572.4 11881.6 11994.4 12146.7 12322.8 3567.6 3747.4 3799.2 3804.6 3928.1 113973.0 115465.0 115753.4 116232.0 116291.0 9735.1 10345.0 10800.5 11189.0 11234.7 21644.5 21810.0 21813.3 21826.2 21884.0 7910.6 7918.2 8027.6 8064.6 8114.4 3723.3 4452.4 4490.7 4585.2 4719.2 3506.2 3642.5 3645.6 3648.3 3657.3 532.0 637.2 649.1 649.4 649.4 561.2 566.9 574.7 625.9 688.7 8871.4 9224.4 9364.9 9423.9 9459.7 562.4 609.5 612.0 646.6 716.5 4131.0 4520.3 4619.2 4628.5 4629.3 144332.0 153482.0 153804.2 156624.0 159101.4 133439.0 140521.0 141573.0 145761.0 146707.3 118780.8 121464.0 121678.8 121813.8 124893.1 8853.3 8940.8 8992.2 8993.6 9208.7 65172.7 67133.7 67842.7 68167.0 68449.7 88419.0 99809.0 101734.0 103240.0 103874.5 11494.8 13003.8 13127.4 13246.8 13330.5 14500.9 14620.6 14897.3 15080.2 15344.2 11757.6 14046.4 14267.0 14409.9 14704.3 13571.9 13617.8 13829.9 13846.0 14485.6 13305.9 13539.7 13584.3 13956.9 14002.0 10876.0 11229.5 11365.3 11489.8 11863.6 11085.0 11490.9 11531.3 11552.8 11714.2 11:07:22 INFO - PROCESS | 1652 | -------- Summary: end -------- 11:07:22 INFO - PROCESS | 1652 | 11:07:23 INFO - TEST-INFO | 1652: exit 0 11:07:23 INFO - TEST-OK | dromaeo_css | took 1119886ms 11:07:23 INFO - TEST-START | kraken 11:07:23 INFO - operating with platform_type : mac_ 11:07:23 INFO - Initialising browser for kraken test... 11:07:23 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/tmpWw9xCQ/profile http://localhost:49270/getInfo.html) 11:07:26 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 11:07:26 INFO - PROCESS | 1660 | colorDepth:24 11:07:26 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 11:07:26 INFO - PROCESS | 1660 | __metrics 11:07:26 INFO - PROCESS | 1660 | 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" 11:07:26 INFO - PROCESS | 1660 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:07:26 INFO - TEST-INFO | 1660: exit 0 11:07:26 INFO - Browser initialized. 11:07:26 INFO - Running cycle 1/1 for kraken test... 11:07:26 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/tmpWw9xCQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:07:38 INFO - PROCESS | 1661 | RSS: Main: 235962368 11:07:38 INFO - PROCESS | 1661 | 11:07:42 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 11:07:42 INFO - PROCESS | 1661 | RSS: Main: 294555648 11:07:42 INFO - PROCESS | 1661 | 11:07:45 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 11:07:46 INFO - PROCESS | 1661 | RSS: Main: 293732352 11:07:46 INFO - PROCESS | 1661 | 11:07:49 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 11:07:50 INFO - PROCESS | 1661 | RSS: Main: 385552384 11:07:50 INFO - PROCESS | 1661 | 11:07:52 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 11:07:52 INFO - PROCESS | 1661 | RSS: Main: 285220864 11:07:52 INFO - PROCESS | 1661 | 11:07:55 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:07:55 INFO - PROCESS | 1661 | RSS: Main: 290189312 11:07:55 INFO - PROCESS | 1661 | 11:07:58 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 11:07:58 INFO - PROCESS | 1661 | RSS: Main: 328663040 11:07:58 INFO - PROCESS | 1661 | 11:08:01 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 11:08:02 INFO - PROCESS | 1661 | RSS: Main: 329863168 11:08:02 INFO - PROCESS | 1661 | 11:08:05 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 11:08:05 INFO - PROCESS | 1661 | RSS: Main: 326782976 11:08:05 INFO - PROCESS | 1661 | 11:08:07 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:08:07 INFO - PROCESS | 1661 | RSS: Main: 301735936 11:08:07 INFO - PROCESS | 1661 | 11:08:09 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:08:10 INFO - PROCESS | 1661 | RSS: Main: 306479104 11:08:10 INFO - PROCESS | 1661 | 11:08:12 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:08:13 INFO - PROCESS | 1661 | RSS: Main: 314294272 11:08:13 INFO - PROCESS | 1661 | 11:08:16 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:08:16 INFO - PROCESS | 1661 | RSS: Main: 317407232 11:08:16 INFO - PROCESS | 1661 | 11:08:20 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:08:20 INFO - PROCESS | 1661 | RSS: Main: 318918656 11:08:20 INFO - PROCESS | 1661 | 11:08:22 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 11:08:22 INFO - PROCESS | 1661 | RSS: Main: 363380736 11:08:22 INFO - PROCESS | 1661 | 11:08:22 INFO - PROCESS | 1661 | __start_tp_report 11:08:22 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 11:08:22 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 11:08:22 INFO - PROCESS | 1661 | |i|pagename|runs| 11:08:22 INFO - PROCESS | 1661 | |0;ai-astar;114;114;114;112;112;114;113;121;165;150 11:08:22 INFO - PROCESS | 1661 | |1;audio-beat-detection;149;113;114;148;113;111;113;112;144;137 11:08:22 INFO - PROCESS | 1661 | |2;audio-dft;246;185;184;185;184;183;181;180;185;184 11:08:22 INFO - PROCESS | 1661 | |3;audio-fft;80;61;58;90;60;58;59;58;82;58 11:08:22 INFO - PROCESS | 1661 | |4;audio-oscillator;87;56;92;92;97;99;129;68;69;90 11:08:22 INFO - PROCESS | 1661 | |5;imaging-gaussian-blur;87;84;89;85;87;85;88;84;88;85 11:08:22 INFO - PROCESS | 1661 | |6;imaging-darkroom;96;103;96;96;96;96;96;97;96;95 11:08:22 INFO - PROCESS | 1661 | |7;imaging-desaturate;86;86;86;86;86;85;83;87;90;84 11:08:22 INFO - PROCESS | 1661 | |8;json-parse-financial;57;55;55;52;54;55;55;54;54;55 11:08:22 INFO - PROCESS | 1661 | |9;json-stringify-tinderbox;65;71;73;75;71;68;69;69;70;70 11:08:22 INFO - PROCESS | 1661 | |10;stanford-crypto-aes;87;90;91;86;89;89;90;97;91;129 11:08:22 INFO - PROCESS | 1661 | |11;stanford-crypto-ccm;145;144;144;146;142;142;145;145;142;147 11:08:22 INFO - PROCESS | 1661 | |12;stanford-crypto-pbkdf2;169;173;182;237;241;171;173;172;173;175 11:08:22 INFO - PROCESS | 1661 | |13;stanford-crypto-sha256-iterative;67;69;69;69;68;68;69;69;68;71 11:08:22 INFO - PROCESS | 1661 | __end_tp_report 11:08:22 INFO - PROCESS | 1661 | __start_cc_report 11:08:22 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,1919 11:08:22 INFO - PROCESS | 1661 | __end_cc_report 11:08:22 INFO - PROCESS | 1661 | __startTimestamp1475690902595__endTimestamp 11:08:22 INFO - PROCESS | 1661 | 11:08:22 INFO - PROCESS | 1661 | ------- Summary: start ------- 11:08:22 INFO - PROCESS | 1661 | Number of tests: 14 11:08:22 INFO - PROCESS | 1661 | 11:08:22 INFO - PROCESS | 1661 | [#0] ai-astar Cycles:10 Average:122.90 Median:114.00 stddev:18.75 (16.4%) stddev-sans-first:19.61 11:08:22 INFO - PROCESS | 1661 | Values: 114.0 114.0 114.0 112.0 112.0 114.0 113.0 121.0 165.0 150.0 11:08:22 INFO - PROCESS | 1661 | 11:08:22 INFO - PROCESS | 1661 | [#1] audio-beat-detection Cycles:10 Average:125.40 Median:125.50 stddev:16.75 (13.4%) stddev-sans-first:15.44 11:08:22 INFO - PROCESS | 1661 | Values: 149.0 113.0 114.0 148.0 113.0 111.0 113.0 112.0 144.0 137.0 11:08:22 INFO - PROCESS | 1661 | 11:08:22 INFO - PROCESS | 1661 | [#2] audio-dft Cycles:10 Average:189.70 Median:184.50 stddev:19.86 (10.8%) stddev-sans-first:1.81 11:08:22 INFO - PROCESS | 1661 | Values: 246.0 185.0 184.0 185.0 184.0 183.0 181.0 180.0 185.0 184.0 11:08:22 INFO - PROCESS | 1661 | 11:08:22 INFO - PROCESS | 1661 | [#3] audio-fft Cycles:10 Average:66.40 Median:60.50 stddev:12.44 (20.6%) stddev-sans-first:12.18 11:08:22 INFO - PROCESS | 1661 | Values: 80.0 61.0 58.0 90.0 60.0 58.0 59.0 58.0 82.0 58.0 11:08:22 INFO - PROCESS | 1661 | 11:08:22 INFO - PROCESS | 1661 | [#4] audio-oscillator Cycles:10 Average:87.90 Median:91.00 stddev:20.29 (22.3%) stddev-sans-first:21.52 11:08:22 INFO - PROCESS | 1661 | Values: 87.0 56.0 92.0 92.0 97.0 99.0 129.0 68.0 69.0 90.0 11:08:22 INFO - PROCESS | 1661 | 11:08:22 INFO - PROCESS | 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:86.20 Median:87.00 stddev:1.81 (2.1%) stddev-sans-first:1.90 11:08:22 INFO - PROCESS | 1661 | Values: 87.0 84.0 89.0 85.0 87.0 85.0 88.0 84.0 88.0 85.0 11:08:22 INFO - PROCESS | 1661 | 11:08:22 INFO - PROCESS | 1661 | [#6] imaging-darkroom Cycles:10 Average:96.70 Median:96.00 stddev:2.26 (2.4%) stddev-sans-first:2.39 11:08:22 INFO - PROCESS | 1661 | Values: 96.0 103.0 96.0 96.0 96.0 96.0 96.0 97.0 96.0 95.0 11:08:22 INFO - PROCESS | 1661 | 11:08:22 INFO - PROCESS | 1661 | [#7] imaging-desaturate Cycles:10 Average:85.90 Median:86.00 stddev:1.85 (2.2%) stddev-sans-first:1.96 11:08:22 INFO - PROCESS | 1661 | Values: 86.0 86.0 86.0 86.0 86.0 85.0 83.0 87.0 90.0 84.0 11:08:22 INFO - PROCESS | 1661 | 11:08:22 INFO - PROCESS | 1661 | [#8] json-parse-financial Cycles:10 Average:54.60 Median:55.00 stddev:1.26 (2.3%) stddev-sans-first:1.00 11:08:22 INFO - PROCESS | 1661 | Values: 57.0 55.0 55.0 52.0 54.0 55.0 55.0 54.0 54.0 55.0 11:08:22 INFO - PROCESS | 1661 | 11:08:22 INFO - PROCESS | 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:70.10 Median:70.50 stddev:2.73 (3.9%) stddev-sans-first:2.18 11:08:22 INFO - PROCESS | 1661 | Values: 65.0 71.0 73.0 75.0 71.0 68.0 69.0 69.0 70.0 70.0 11:08:22 INFO - PROCESS | 1661 | 11:08:22 INFO - PROCESS | 1661 | [#10] stanford-crypto-aes Cycles:10 Average:93.90 Median:90.00 stddev:12.68 (14.1%) stddev-sans-first:13.20 11:08:22 INFO - PROCESS | 1661 | Values: 87.0 90.0 91.0 86.0 89.0 89.0 90.0 97.0 91.0 129.0 11:08:22 INFO - PROCESS | 1661 | 11:08:22 INFO - PROCESS | 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:144.20 Median:145.00 stddev:1.75 (1.2%) stddev-sans-first:1.83 11:08:22 INFO - PROCESS | 1661 | Values: 145.0 144.0 144.0 146.0 142.0 142.0 145.0 145.0 142.0 147.0 11:08:22 INFO - PROCESS | 1661 | 11:08:22 INFO - PROCESS | 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.60 Median:174.00 stddev:27.84 (16.0%) stddev-sans-first:28.79 11:08:22 INFO - PROCESS | 1661 | Values: 169.0 173.0 182.0 237.0 241.0 171.0 173.0 172.0 173.0 175.0 11:08:22 INFO - PROCESS | 1661 | 11:08:22 INFO - PROCESS | 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.70 Median:69.00 stddev:1.06 (1.5%) stddev-sans-first:0.93 11:08:22 INFO - PROCESS | 1661 | Values: 67.0 69.0 69.0 69.0 68.0 68.0 69.0 69.0 68.0 71.0 11:08:22 INFO - PROCESS | 1661 | -------- Summary: end -------- 11:08:22 INFO - PROCESS | 1661 | 11:08:23 INFO - TEST-INFO | 1661: exit 0 11:08:23 INFO - TEST-OK | kraken | took 59923ms 11:08:23 INFO - SUITE-END | took 1179s 11:08:23 INFO - Completed test suite (00:19:40) 11:08:23 INFO - javascript benchmark 11:08:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3139.0425920094367, 3274.5251938468923, 3289.227031647561, 3307.355065762177, 3344.84948177332, 5469.015577196104, 5559.249501317321, 5630.915536266959, 5639.859003524909, 5640.294963129609, 4599.103174880899, 5536.529395001419, 5667.829965101043, 5682.772689092441, 5723.4848863602265, 5040.823571175009, 5067.265246539249, 5067.619928505377, 5080.034793389263, 5147.613928955343, 5128.5897128229835, 5232.084385232588, 5233.659812112201, 5243.292155559008, 5259.789608415659, 5050.267711181895, 5191.6884986900905, 5206.739663016872, 5258.0, 5274.314339135907, 5090.236464530313, 5160.251763494272, 5173.689578625257, 5191.5846732261325, 5202.115640341151, 5056.115179843513, 5112.846614601568, 5119.692818430906, 5141.485851414866, 5149.974250128763, 5584.106542953108, 5628.015097357987, 5643.322801263834, 5645.774169033234, 5656.717164141776, 6126.989046807272, 6129.69351532426, 6130.570860039799, 6134.8159555213415, 6136.662483563402, 5908.852278693024, 6026.126211699323, 6167.907481387782, 6183.567150299481, 6188.907166392462, 5582.972085139589, 5647.152927060933, 5670.291213598301, 5670.801521946753, 5673.687947162945, 5232.529072383428, 5717.085266357363, 6247.843803904939, 6276.592021518587, 6295.433410992941, 5800.970995144998, 5944.138099975566, 5949.9107513387335, 5968.791092311748, 5973.342932277533, 6027.758889644453, 6107.969460152671, 6123.435141503484, 6125.081237814275, 6202.2867370253, 6085.9695701521205, 6104.152474528661, 6116.591928251121, 6122.398130291625, 6122.632014270253, 6098.9654693115635, 6126.509879209652, 6144.815655530252, 6153.953827849276, 6155.045967874987, 4738.289256611536, 4800.711957282574, 4816.301636262722, 4835.854924352275, 4862.149123903374, 4281.972326641584, 4381.298992161239, 4387.429634147604, 4409.206342858252, 4419.0940857124215, 5579.497845193952, 5581.6371935824045, 5583.357913839879, 5592.217089607459, 5601.075822489325, 5081.8729531762, 6094.024956007106, 6106.39999602577, 6117.143599896018, 6118.131909153758, 5598.468145526169, 5652.802151924663, 5658.801941932013, 5659.490645841894, 5667.489925906688, 5309.65487243328, 5321.095413779667, 5325.760340784674, 5333.120035194227, 5333.5199832015305, 5696.974544582015, 6360.236771587439, 6371.55399122057, 6375.203099612549, 6376.936230637727, 5826.359100498857, 6066.545009124422, 6103.694815259308, 6114.663693496723, 6126.356732543197, 6354.650494222862, 6362.872742545031, 6370.840728981812, 6375.872482550416, 6387.329330420239, 6090.817275481742, 6098.29869564988, 6098.634081955097, 6111.266648002268, 6113.5414843887775, 5985.102234664835, 6035.36628653985, 6191.0, 6199.659018753831, 6202.34875338083, 5884.382139875423, 5893.116032595145, 5897.262842144732, 5900.498457631149, 5903.321118071477, 5734.168545560787, 5879.794207202727, 5884.323302820059, 5884.94071067212, 5891.23413956183, 5720.8569785754025, 5760.971195143998, 5765.654060756368, 5774.364819869895, 5775.682337471311, 5905.40945905406, 5913.05391137416, 5922.052471604523, 5925.0, 5929.733162007634, 5551.528120109836, 5726.656400615976, 5749.54003679713, 5789.334226563999, 5792.507636850914], "value": 5581.6750766965415, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [448.3789950917979, 511.5523916572996, 518.0493793888215, 529.2854646227592, 547.9999001821674, 38640.84077477676, 40123.38272641833, 40323.99190020251, 40347.193056138894, 42628.78685606575, 41370.58629413712, 41685.16629667405, 41708.79145604268, 41749.58250417495, 41768.0, 8740.606672699743, 8972.192502674758, 9654.758631034229, 9854.655087071918, 9866.013398660149, 11795.646130616095, 12159.635210943687, 12168.452419641093, 12321.876781232162, 12325.260484370967, 12116.81824772629, 12177.208481448677, 12198.878011219864, 12206.694832629211, 12221.877781222207, 8833.072527384596, 9045.954770226173, 9051.14014168653, 12122.818157727641, 12209.206401583913, 8790.164934331293, 8851.07063758309, 11645.6506304811, 11997.580084696994, 12008.339541325224, 39826.80086599549, 39985.20288543735, 40040.79979600113, 40123.796286111174, 40231.59768402308, 2914.3005678636973, 3063.6783137770435, 3270.0026491919957, 3312.370649576597, 3766.9811650941842, 30407.543886841715, 30839.845800770854, 30870.38259234825, 30875.536866947015, 30978.53532197019, 3220.6135263768265, 3292.934141317184, 3347.1799409144915, 3369.79781213128, 3383.441732114198, 474357.0, 478657.0, 482232.0, 482410.0, 484919.0, 56853.0, 57166.42833571694, 57188.71405643028, 57215.427845721846, 57294.41644668417, 60227.69886150629, 60237.38575685473, 60276.095858562156, 60330.69834650798, 60334.39665603376, 15843.207839607974, 16037.919810400874, 16047.518574442785, 16069.517914462585, 16091.275892584861, 11213.439328033566, 11483.827742583868, 11486.023687986615, 12503.312317822607, 12635.93682031584, 18986.905065474584, 19299.0, 19560.431880918397, 19700.60598788016, 19817.20731170737, 7937.2459616336355, 8047.315978141806, 8051.4766540174705, 8106.47307924983, 8167.836643267101, 855.3242938078972, 952.4808979106404, 964.1515466389533, 978.4031740638347, 1052.1162223732101, 29687.96092136808, 29947.25131871677, 30023.399532009236, 30498.69501305003, 30553.84723076415, 973.2505970402892, 1042.864427624404, 1045.2265323660436, 1061.5435362794074, 1111.4942701070993, 13692.589222323346, 15209.771853422208, 15545.989510681995, 15650.37398504047, 15677.256480115933, 657575.7121214364, 663485.0, 663903.0, 663990.6800465967, 664323.0, 56119.71940140355, 56670.0, 56677.716611417505, 56804.0, 57027.42972570304, 1135.5401062569838, 1210.056156198167, 1240.6774238698004, 1360.2790521023874, 1372.3618517389461, 27560.862195688893, 28606.415626218433, 29057.418851622428, 30105.0, 30919.536206957364, 42316.11361614095, 44518.887027823264, 47123.0, 47489.76255118702, 47591.0, 520123.0, 521409.0, 521886.0, 522499.68753905763, 524103.0, 26618.600720989587, 26762.063327784213, 27250.454990900467, 27611.585826213024, 28121.296967575974, 46144.53855461537, 46514.30228546507, 46751.766241168574, 47074.29388559106, 47401.7629911862, 30437.630306636696, 36783.26433471369, 38408.615913840506, 38706.0, 39204.41193382159, 37883.62116378801, 37907.81046094752, 38967.610323897534, 39657.80171099126, 40501.15136058562, 38050.0, 38150.618493814705, 39040.21919561536, 39081.609183908935, 39126.41310380289, 26203.213903582924, 28300.292492687026, 31334.529982049833, 34413.13967150841, 34422.31155376929, 21708.45728856791, 22797.772022279565, 25429.0, 25436.0, 25984.8700756495], "value": 20013.247798759603, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.98596056157754, 365.95425571803526, 368.96310368963105, 379.21691706625825, 400.9318415869302, 15209.163496007728, 15491.380344786208, 16414.83585164148, 20457.283995060177, 20987.845668488226, 19728.408147755556, 20142.496437589067, 20762.584748305024, 20913.0, 21058.89470552645, 4845.491223421543, 5974.67139307339, 6149.723262453178, 6202.4727898128485, 6209.937900621004, 5996.430339117798, 6208.534359923001, 6214.3474935131835, 6214.471769899564, 6218.937810621881, 5918.615290006136, 6051.727672254737, 6054.666993315366, 6147.815565533019, 6163.660998645095, 5294.947050529503, 5990.820275391745, 6143.385661433866, 6146.262448506163, 6153.84615384619, 4113.465249517573, 5294.1529355303355, 5510.9448905511235, 5572.944270557283, 5606.859828504279, 12676.619701408972, 12925.095243332971, 12927.741445171045, 12987.41556629954, 12989.350532473432, 2086.968695469569, 2087.8329733621426, 2262.570111678776, 2868.0965495868736, 2950.586917831505, 9734.853977190347, 9805.852912206323, 9885.75285617858, 10233.0, 10480.633177838816, 1791.713325867868, 1824.8175182481777, 1832.532704160444, 1862.1527205121638, 1864.3474783825689, 117816.0, 120693.39653301677, 121998.39000805028, 124084.0, 126884.0, 10039.297249192487, 10950.452477376099, 11497.854505538302, 11573.132015098901, 11698.0, 14488.565343039725, 14533.709325813425, 14541.927290363481, 14546.927265363605, 14559.0, 6835.0, 6984.266652001568, 7258.838585826348, 7884.369250459949, 8454.788630284293, 7496.7750967471065, 7844.764657060297, 8332.833403323539, 8339.24946754795, 8467.407281490237, 3781.810909454571, 4123.0516981094515, 4494.505604383515, 4514.616257618073, 4578.496365399803, 1861.2834058887427, 1909.5798924236647, 1912.9330473433363, 1923.6825923722429, 1959.6276707425543, 811.8903947967067, 852.3522123186299, 889.6308032166578, 934.9392289501161, 978.2076518020426, 20113.09491072905, 20488.89755551213, 20852.582948341253, 21004.684929726067, 21138.365849024554, 796.8087658961923, 885.9512726799959, 899.9595018224196, 951.619352259088, 970.8543718442149, 10280.845787313196, 11132.0, 11160.497777600027, 11181.0, 11264.66206013821, 528036.0, 562263.0, 565219.1739041278, 574447.0, 575826.0, 102888.48555757174, 108870.91129088766, 122658.0, 126947.0, 136878.0, 924.7318277699526, 926.8053708721244, 963.7446076789656, 964.4695417520336, 967.5065716484503, 15430.84268679831, 17449.69127315431, 18238.452846414628, 18456.0, 18656.720149197503, 49723.50829475121, 53019.734901325246, 54728.726356367966, 55214.447855522536, 55811.44188558062, 59023.52441188833, 78128.0, 78133.0, 78255.6087219583, 78338.0, 7771.184025677222, 8005.439619226599, 8131.512109273463, 8198.54907980043, 8323.95838020806, 11809.0, 12938.0, 13034.957203423513, 13345.0, 13370.665733356354, 11166.832497512381, 11196.384198868815, 11479.942600286946, 11615.709607259887, 11668.766624667289, 11013.2841365311, 11160.720981975515, 11305.660830175108, 11525.020373268366, 11693.824592631288, 9295.907040929504, 11781.469833857325, 11849.763004739685, 11851.70370740704, 11970.76058478843, 6682.264950855305, 7956.602169891598, 7965.761027169194, 8231.300339471212, 8401.949756280464, 8215.835683286181, 9153.77115572116, 10990.780184396108, 11464.541418343173, 11699.23954942926], "value": 9224.98687738028, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1328.4088580581645, 1503.3760989189486, 1529.5411376587024, 1734.644397898431, 1922.7884932657414, 7788.376929845613, 8272.214139656731, 8549.273311768507, 8778.473291602508, 8795.0, 8648.524331161783, 8736.694215702451, 8738.0, 8753.080926502722, 8760.474371537712, 2329.510802731424, 2357.7406485286606, 2358.7995020423286, 2359.0210062823908, 2362.681038059866, 2312.479692069288, 2347.4131467133216, 2348.1311914591624, 2349.0721165139757, 2355.858648481088, 2316.2819525946925, 2337.2170322941834, 2345.589521833681, 2353.1175809071597, 2355.6231003039516, 2220.400491867203, 2251.0658076898067, 2258.2999270226283, 2264.4225722440838, 2296.7014288142436, 2311.9422014449606, 2313.710786151731, 2315.571619250444, 2315.6989591353185, 2318.2697450303267, 8658.437201581877, 8694.652213911437, 8744.8688269676, 8762.86855697165, 8805.64777408903, 5223.37319521656, 5401.864953376158, 5408.702521361323, 5435.0488664483955, 5461.644993075437, 3599.5680518337967, 3619.511365965587, 3692.833822477995, 3696.9260614787822, 3706.388445906422, 1069.1874175626501, 1080.3193987787722, 1083.8428427877993, 1101.4878081692052, 1106.093003736936, 11387.658370248906, 11425.028872545843, 11501.942490287578, 11510.884891151065, 11524.88475115255, 4318.438602981592, 4450.5104438511735, 4547.749873756975, 4552.635789136861, 4575.771211439414, 8995.730128096167, 9031.729048128567, 9042.86435703465, 9044.773880653036, 9044.8643270351, 5180.844574662766, 5183.429822719498, 5189.896202075937, 5190.455002224788, 5197.402298735694, 4159.854405095807, 4397.098594788061, 4556.11155824611, 4563.041761230113, 4879.634027447955, 3609.3683605368956, 3623.8731644392847, 3692.944605830861, 3710.3135919854913, 3711.9072023199637, 1139.8917102875214, 1143.2511704833348, 1147.3861484105964, 1151.792677318074, 1155.306815910447, 810.9513429194267, 887.4985633117268, 896.4173287363136, 902.8916530016309, 915.9038300978434, 5685.147227915846, 5769.740361683735, 5909.704514774244, 6125.571210015256, 6173.32093469718, 873.9257163141077, 952.8475443929077, 957.9568919398644, 964.2045312616979, 999.0608827701936, 3863.787491687984, 3872.806359682061, 4004.2592120457807, 4073.89815254621, 4093.570175131628, 10376.0, 10713.035826775627, 10796.190285728602, 10801.729956751144, 10841.566337346418, 10024.949875250577, 10523.315984460985, 10726.678199654023, 10758.677239682822, 10759.623413180501, 7957.164497727771, 8163.95918020418, 8173.100958894517, 8176.591170441454, 8179.386546008954, 4726.598239149574, 5843.474087332161, 5855.0, 6099.725512351866, 6213.068039794067, 9881.30830841863, 9934.552945117632, 9940.502974851084, 9956.850647240442, 9988.950055249677, 9065.728028158903, 9441.103095205945, 9567.617295308388, 10248.692539223835, 10276.948615257175, 6107.358727333566, 7218.602976836324, 7271.672774725044, 7427.331540161412, 7498.550086994798, 8133.0646975598565, 8199.139090395423, 8200.58997050157, 8267.586620668822, 8281.337493000667, 7450.105987281561, 7491.9625401872645, 7528.736494222676, 7529.247075292427, 7549.547027178387, 7127.180374256819, 7148.642567871481, 7283.453740969342, 7303.525270857377, 7363.190049094703, 6891.104156459628, 6960.47796415281, 7027.472939529658, 7035.261297563682, 7044.647767611496, 4842.661013729005, 5000.149974504416, 5041.470645582308, 5057.064443078042, 5189.974050129726, 4944.035912997075, 5007.198848184273, 5021.874453138701, 5051.090803655377, 5116.0], "value": 4521.62478949216, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.54353979561773, 298.2603144202378, 299.5132909022838, 307.4404583657743, 312.87328631904086, 5071.391433028037, 5116.97660467906, 5118.283440318353, 5126.436092029874, 5175.896482070356, 4282.635975942041, 4554.8157479055435, 4690.343351930733, 4966.801327946887, 5093.643444958854, 3592.7844329340323, 3740.933833857351, 3907.745996510232, 3940.093778430948, 3960.4257382679493, 3325.6508066653014, 3524.68277854993, 3937.7243592948507, 3938.7045971552106, 3938.975866274775, 3603.0, 3838.5201849768778, 3909.9804500977457, 3910.159315747114, 3934.3311637021775, 3217.935641287161, 3677.9701683528388, 3926.430667553203, 3928.2143571285715, 3949.565547789741, 3120.4241857861634, 3370.275390790992, 3390.627031026585, 3397.6092749333898, 3408.3694516514524, 3857.903552411184, 4118.249743858848, 4965.280034395028, 5012.423571289312, 5017.096922553938, 4157.771322577257, 4393.736375817429, 4411.228035093845, 4411.911761764779, 4441.400410944513, 4665.533446655341, 5122.719320169957, 5351.545118664918, 5359.839204823861, 5361.919571206435, 3102.503599424104, 3683.7605555639066, 3687.520622319108, 3690.335739566877, 3702.3709567790356, 4653.185692503798, 5427.45725427458, 5553.222548843206, 5629.155626656035, 5684.602077854552, 3270.73834093272, 3723.7579557328686, 3738.4579236010627, 3744.165358974083, 3747.6252374762307, 3805.5243094613174, 3815.0, 3817.828197731109, 3821.7515861469474, 3832.885013449601, 3705.370087085202, 4416.734995900256, 4423.823047078081, 4430.534793846664, 4435.711678740936, 4750.928736068961, 5054.545090941833, 5362.061639213122, 5387.272718183073, 5389.8113566025, 1274.9235045897276, 1288.5182040653324, 1288.890444312225, 1291.9935400322938, 1297.7664020476216, 697.9495858732575, 827.3753316246195, 836.43586493258, 836.4521238588734, 847.3856454070749, 4819.373481447389, 4897.534734200245, 5053.46938571452, 5060.367454068241, 5147.867469156854, 4605.815767369268, 5316.468353164652, 5528.944710552923, 5536.640118392292, 5556.861078473071, 4801.351817504662, 4951.0, 5066.594672426197, 5098.031374038921, 5119.592112169184, 2827.0, 2882.3658795065053, 2887.8411687356975, 2887.8411687356975, 2890.378568607717, 4375.249900040009, 4848.8302909398, 5061.102086717481, 5348.277982472394, 5616.708157123802, 4504.932426013613, 5477.945220547823, 5611.719414029282, 5631.971840140855, 5693.348928810681, 5636.943630563724, 5647.943520564741, 5649.54803615718, 5667.149927510906, 5678.886422271614, 4869.902601947871, 5210.296609957607, 5485.533729632866, 5490.505854473116, 5503.477169668875, 4325.39444477767, 4735.952640473552, 4848.490908454703, 5410.620291825717, 5431.321084864392, 5648.858778530405, 5677.432256774289, 5684.602077854511, 5695.430456954271, 5700.657960522382, 2918.430905973315, 3149.590553228066, 3184.3949649566507, 3203.08712017074, 3438.862445502252, 4549.975261008819, 5479.506844384025, 5482.835514934558, 5506.301912062981, 5529.834104976857, 5348.224507446321, 5360.383555891122, 5386.649867758583, 5394.919076213781, 5399.487048730364, 5009.499050094962, 5301.973490132525, 5321.36143662763, 5347.625666203484, 5389.380221274603, 5017.62367822422, 5292.52367286946, 5304.090527410206, 5316.521513063843, 5318.282031925795, 4462.0, 4509.391232183613, 5156.4990979375725, 5289.788408463618, 5406.107992181246, 5279.89440211186, 5302.787888484418, 5307.256984022317, 5322.627416080837, 5326.387465441524], "value": 3898.837014846186, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [592.304022897122, 725.4776560876169, 756.3155344413304, 774.4888373673376, 791.0783936713729, 15290.694186116285, 15803.604909877244, 15955.601109972256, 15964.28160732767, 15983.520494385173, 13880.097793643406, 14283.928580357084, 14392.71214575708, 14402.855971440282, 14582.0, 685.2120061928797, 828.2793969246749, 851.459323329687, 851.6806197675871, 857.5840717252128, 695.3463744080574, 837.0792128658488, 865.1737590600992, 865.7402779166225, 869.6608322754099, 916.3860213656866, 918.2103391083663, 918.2332752151927, 918.3066784577635, 919.5034681272139, 910.3445519226179, 911.0479548871446, 914.0128661046055, 914.5518695838991, 915.4370062411635, 525.3065952942118, 540.5162379670212, 541.4504278157673, 541.5748637319724, 543.1526818163638, 12353.62939111828, 14348.282585870666, 14924.403023879031, 15261.008034477834, 15297.158656273901, 14406.783898241534, 14612.86151480508, 14774.113553186844, 14788.630284242872, 14924.70150596982, 11572.363520006396, 11881.5841445549, 11994.400279986054, 12146.696332591757, 12322.81515777264, 3567.571891373052, 3747.381682022463, 3799.2401519696173, 3804.5814960354337, 3928.116173860887, 113973.0, 115465.0, 115753.42123289414, 116232.0, 116291.0, 9735.123838854537, 10345.0, 10800.51397687106, 11188.992990630883, 11234.662960111209, 21644.45888852791, 21810.018549165325, 21813.345599632037, 21826.236081737065, 21884.0, 7910.644021019068, 7918.2477664621765, 8027.5584842834205, 8064.637091330904, 8114.350851931831, 3723.311187430334, 4452.398926144929, 4490.663200259994, 4585.151746926829, 4719.150552900443, 3506.2111025019167, 3642.5446819147605, 3645.5625324961175, 3648.2703459307713, 3657.3233951718485, 531.9787208511616, 637.219406227377, 649.1172006071739, 649.357136434926, 649.4090377756219, 561.1974875927463, 566.9007923613351, 574.7499837570671, 625.9186305780311, 688.6659969914588, 8871.44784886493, 9224.400413973204, 9364.859527107099, 9423.869135703759, 9459.668911588062, 562.3757629031771, 609.4880300547472, 611.9877602447926, 646.6184950879004, 716.5163514627606, 4130.979345103316, 4520.299353600197, 4619.214733495314, 4628.467726211461, 4629.282461218517, 144332.0, 153482.0, 153804.2309788444, 156624.0, 159101.40898591097, 133439.0, 140521.0, 141573.0, 145761.0, 146707.266463667, 118780.81219187871, 121464.0, 121678.78321216852, 121813.78186218202, 124893.12660310103, 8853.335999800169, 8940.776480587778, 8992.191070399775, 8993.640254389751, 9208.723738287863, 65172.674136629015, 67133.66433167802, 67842.66078669575, 68167.0, 68449.65775171292, 88419.0, 99809.0, 101734.0, 103240.0, 103874.4806275994, 11494.770104598028, 13003.804942925675, 13127.409266583218, 13246.801297980346, 13330.466781328638, 14500.85499144995, 14620.634484137981, 14897.255137242877, 15080.245987700791, 15344.232788360761, 11757.647270581896, 14046.367913443715, 14267.0, 14409.927950360181, 14704.264786760405, 13571.932140339235, 13617.80430187741, 13829.930850345683, 13846.0, 14485.637859053608, 13305.933470332973, 13539.729205416033, 13584.32078396096, 13956.930215348859, 14002.0, 10876.0, 11229.545262551148, 11365.261258018203, 11489.827652585385, 11863.58477453285, 11085.0, 11490.892400105462, 11531.308121512737, 11552.768944620891, 11714.23857449263], "value": 7858.835353352879, "unit": "score"}], "name": "dromaeo_css", "value": 7229.596948517973, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 112.0, 112.0, 114.0, 113.0, 121.0, 165.0, 150.0], "value": 122.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 113.0, 114.0, 148.0, 113.0, 111.0, 113.0, 112.0, 144.0, 137.0], "value": 125.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [246.0, 185.0, 184.0, 185.0, 184.0, 183.0, 181.0, 180.0, 185.0, 184.0], "value": 189.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 61.0, 58.0, 90.0, 60.0, 58.0, 59.0, 58.0, 82.0, 58.0], "value": 66.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 56.0, 92.0, 92.0, 97.0, 99.0, 129.0, 68.0, 69.0, 90.0], "value": 87.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 89.0, 85.0, 87.0, 85.0, 88.0, 84.0, 88.0, 85.0], "value": 86.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 103.0, 96.0, 96.0, 96.0, 96.0, 96.0, 97.0, 96.0, 95.0], "value": 96.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 86.0, 86.0, 86.0, 85.0, 83.0, 87.0, 90.0, 84.0], "value": 85.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 55.0, 55.0, 52.0, 54.0, 55.0, 55.0, 54.0, 54.0, 55.0], "value": 54.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 71.0, 73.0, 75.0, 71.0, 68.0, 69.0, 69.0, 70.0, 70.0], "value": 70.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 90.0, 91.0, 86.0, 89.0, 89.0, 90.0, 97.0, 91.0, 129.0], "value": 93.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 144.0, 144.0, 146.0, 142.0, 142.0, 145.0, 145.0, 142.0, 147.0], "value": 144.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 173.0, 182.0, 237.0, 241.0, 171.0, 173.0, 172.0, 173.0, 175.0], "value": 186.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 69.0, 69.0, 68.0, 68.0, 69.0, 69.0, 68.0, 71.0], "value": 68.7, "unit": "score"}], "name": "kraken", "value": 1479.2, "alertThreshold": 2.0}]} 11:08:23 INFO - Return code: 0 11:08:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:08:23 INFO - # TBPL SUCCESS # 11:08:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:08:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:08:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:08:23 INFO - 2016-10-05 11:08:23.926 screenresolution[1665:10188] starting screenresolution argv=screenresolution get 11:08:23 INFO - 2016-10-05 11:08:23.930 screenresolution[1665:10188] Display 0: 1600x1200x32@60 11:08:23 INFO - 2016-10-05 11:08:23.936 screenresolution[1666:10191] starting screenresolution argv=screenresolution list 11:08:23 INFO - Available Modes on Display 0 11:08:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:08:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:08:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:08:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:08:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:08:24 INFO - Intel Iris: 11:08:24 INFO - Chipset Model: Intel Iris 11:08:24 INFO - Type: GPU 11:08:24 INFO - Bus: Built-In 11:08:24 INFO - VRAM (Dynamic, Max): 1536 MB 11:08:24 INFO - Vendor: Intel (0x8086) 11:08:24 INFO - Device ID: 0x0a2e 11:08:24 INFO - Revision ID: 0x0009 11:08:24 INFO - Displays: 11:08:24 INFO - Display: 11:08:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:08:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:08:24 INFO - Display Serial Number: 1600x1200 60 11:08:24 INFO - Main Display: Yes 11:08:24 INFO - Mirror: Off 11:08:24 INFO - Online: Yes 11:08:24 INFO - Rotation: Supported 11:08:24 INFO - Return code: 0 11:08:24 INFO - Running post-action listener: _resource_record_post_action 11:08:24 INFO - [mozharness: 2016-10-05 18:08:24.178417Z] Finished run-tests step (success) 11:08:24 INFO - Running post-run listener: _resource_record_post_run 11:08:24 INFO - Total resource usage - Wall time: 1215s; CPU: 53.0%; Read bytes: 37180416; Write bytes: 706812928; Read time: 401; Write time: 1858 11:08:24 INFO - TinderboxPrint: CPU usage
53.5% 11:08:24 INFO - TinderboxPrint: I/O read bytes / time
37,180,416 / 401 11:08:24 INFO - TinderboxPrint: I/O write bytes / time
706,812,928 / 1,858 11:08:24 INFO - TinderboxPrint: CPU idle
2,261.0 (46.5%) 11:08:24 INFO - TinderboxPrint: CPU system
971.5 (20.0%) 11:08:24 INFO - TinderboxPrint: CPU user
1,629.2 (33.5%) 11:08:24 INFO - TinderboxPrint: Swap in / out
443,920,384 / 0 11:08:24 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 392831488; Write bytes: 381941760; Read time: 28943; Write time: 666 11:08:24 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 30989312; Write bytes: 296338432; Read time: 310; Write time: 1139 11:08:24 INFO - Running post-run listener: _upload_blobber_files 11:08:24 INFO - Blob upload gear active. 11:08:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:08:24 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 11:08:24 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:08:24 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:08:25 INFO - (blobuploader) - INFO - Open directory for files ... 11:08:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:08:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:08:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:08:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:08:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:08:25 INFO - (blobuploader) - INFO - Done attempting. 11:08:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:08:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:08:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:08:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:08:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:08:26 INFO - (blobuploader) - INFO - Done attempting. 11:08:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:08:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:08:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:08:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:08:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:08:29 INFO - (blobuploader) - INFO - Done attempting. 11:08:29 INFO - (blobuploader) - INFO - Iteration through files over. 11:08:29 INFO - Return code: 0 11:08:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:08:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:08:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cae3f768287ac07e189aeca6d0490f154366f17f50689c9e6c3ea660ccf4619f1c1958f3f0ea52e272303bfb1a8637de55f225f4c628b7afe9d8e36fb9937047", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4786fa33e9152c81867c66d912ad2421962ea40030e94c21040593ed97699c75db9e26eb1f4bcfa999556107a46cf8acc1207054cc582d9989515c07e39e5732", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/42dbc0699cf03fd8a2c84a26aa0f2b4f18ac06d70184815beac6900d5269cdd217bee12a9bd56547ed81ff3e95894423fb1ade5f75dacff493ba451167706c74"} 11:08:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:08:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:08:29 INFO - Contents: 11:08:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cae3f768287ac07e189aeca6d0490f154366f17f50689c9e6c3ea660ccf4619f1c1958f3f0ea52e272303bfb1a8637de55f225f4c628b7afe9d8e36fb9937047", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4786fa33e9152c81867c66d912ad2421962ea40030e94c21040593ed97699c75db9e26eb1f4bcfa999556107a46cf8acc1207054cc582d9989515c07e39e5732", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/42dbc0699cf03fd8a2c84a26aa0f2b4f18ac06d70184815beac6900d5269cdd217bee12a9bd56547ed81ff3e95894423fb1ade5f75dacff493ba451167706c74"} 11:08:29 INFO - Running post-run listener: copy_logs_to_upload_dir 11:08:29 INFO - Copying logs to upload dir... 11:08:29 INFO - mkdir: /builds/slave/test/build/upload/logs 11:08:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.321899 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-10-05 11:08:29.588553) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-05 11:08:29.591895) ========= 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.RHJPoLaqP5/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.kyDzGqMb6F/Listeners TMPDIR=/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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/Fx-Team/sha512/cae3f768287ac07e189aeca6d0490f154366f17f50689c9e6c3ea660ccf4619f1c1958f3f0ea52e272303bfb1a8637de55f225f4c628b7afe9d8e36fb9937047", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4786fa33e9152c81867c66d912ad2421962ea40030e94c21040593ed97699c75db9e26eb1f4bcfa999556107a46cf8acc1207054cc582d9989515c07e39e5732", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/42dbc0699cf03fd8a2c84a26aa0f2b4f18ac06d70184815beac6900d5269cdd217bee12a9bd56547ed81ff3e95894423fb1ade5f75dacff493ba451167706c74"} build_url:https://queue.taskcluster.net/v1/task/JvRQ073fSE-eXPBlg5w5Jg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010897 build_url: 'https://queue.taskcluster.net/v1/task/JvRQ073fSE-eXPBlg5w5Jg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cae3f768287ac07e189aeca6d0490f154366f17f50689c9e6c3ea660ccf4619f1c1958f3f0ea52e272303bfb1a8637de55f225f4c628b7afe9d8e36fb9937047", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4786fa33e9152c81867c66d912ad2421962ea40030e94c21040593ed97699c75db9e26eb1f4bcfa999556107a46cf8acc1207054cc582d9989515c07e39e5732", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/42dbc0699cf03fd8a2c84a26aa0f2b4f18ac06d70184815beac6900d5269cdd217bee12a9bd56547ed81ff3e95894423fb1ade5f75dacff493ba451167706c74"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-05 11:08:29.624135) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 11:08:29.624462) ========= 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.RHJPoLaqP5/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.kyDzGqMb6F/Listeners TMPDIR=/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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.004669 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-05 11:08:29.673361) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-05 11:08:29.673735) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-05 11:08:29.674028) ========= ========= Total master_lag: 0.18 =========