builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0148 starttime: 1476981792.86 results: success (0) buildid: 20161020074550 builduid: 6b1f0f2726af4cbd8b2403ba5ceade1d revision: c2753c9ba68ebd62aa8c086e7f36835ebd064044 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-20 09:43:12.857679) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-20 09:43:12.858121) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-20 09:43:12.858428) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-20 09:43:12.883012) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 09:43:12.883399) ========= 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.P9FeILN9Hn/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.85Ya9q3yTA/Listeners TMPDIR=/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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-20 09:43:12-- 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: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 157M=0s 2016-10-20 09:43:13 (157 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.148133 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 09:43:13.054622) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 09:43:13.054954) ========= 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.P9FeILN9Hn/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.85Ya9q3yTA/Listeners TMPDIR=/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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.027641 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 09:43:13.105266) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-20 09:43:13.105617) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c2753c9ba68ebd62aa8c086e7f36835ebd064044 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev c2753c9ba68ebd62aa8c086e7f36835ebd064044 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.P9FeILN9Hn/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.85Ya9q3yTA/Listeners TMPDIR=/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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-20 09:43:13,175 truncating revision to first 12 chars 2016-10-20 09:43:13,175 Setting DEBUG logging. 2016-10-20 09:43:13,175 attempt 1/10 2016-10-20 09:43:13,175 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c2753c9ba68e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-20 09:43:15,193 unpacking tar archive at: mozilla-central-c2753c9ba68e/testing/mozharness/ program finished with exit code 0 elapsedTime=2.281052 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-20 09:43:15.408429) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-20 09:43:15.408822) ========= script_repo_revision: c2753c9ba68ebd62aa8c086e7f36835ebd064044 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-20 09:43:15.409297) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-20 09:43:15.409609) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-20 09:43:15.428121) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-10-20 09:43:15.428547) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.P9FeILN9Hn/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.85Ya9q3yTA/Listeners TMPDIR=/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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 09:43:15 INFO - MultiFileLogger online at 20161020 09:43:15 in /builds/slave/test 09:43:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:43:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:43:15 INFO - {'append_to_log': False, 09:43:15 INFO - 'base_work_dir': '/builds/slave/test', 09:43:15 INFO - 'blob_upload_branch': 'Firefox', 09:43:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:43:15 INFO - 'branch': 'Firefox', 09:43:15 INFO - 'buildbot_json_path': 'buildprops.json', 09:43:15 INFO - 'config_files': ('talos/mac_config.py',), 09:43:15 INFO - 'default_actions': ('clobber', 09:43:15 INFO - 'read-buildbot-config', 09:43:15 INFO - 'download-and-extract', 09:43:15 INFO - 'populate-webroot', 09:43:15 INFO - 'create-virtualenv', 09:43:15 INFO - 'install', 09:43:15 INFO - 'run-tests'), 09:43:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:43:15 INFO - 'download_minidump_stackwalk': True, 09:43:15 INFO - 'download_symbols': 'ondemand', 09:43:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:43:15 INFO - 'tooltool.py': '/tools/tooltool.py', 09:43:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:43:15 INFO - '/tools/misc-python/virtualenv.py')}, 09:43:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:43:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:43:15 INFO - 'installer_path': 'installer.exe', 09:43:15 INFO - 'log_level': 'info', 09:43:15 INFO - 'log_name': 'talos', 09:43:15 INFO - 'log_to_console': True, 09:43:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:43:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:43:15 INFO - 'opt_config_files': (), 09:43:15 INFO - 'pip_index': False, 09:43:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:43:15 INFO - 'cmd': ('bash', 09:43:15 INFO - '-c', 09:43:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:43:15 INFO - 'enabled': True, 09:43:15 INFO - 'halt_on_failure': False, 09:43:15 INFO - 'name': 'check_screen_resolution'},), 09:43:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:43:15 INFO - 'cmd': ('bash', 09:43:15 INFO - '-c', 09:43:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:43:15 INFO - 'enabled': True, 09:43:15 INFO - 'halt_on_failure': False, 09:43:15 INFO - 'name': 'check_screen_resolution'},), 09:43:15 INFO - 'run_cmd_checks_enabled': True, 09:43:15 INFO - 'sps_profile': False, 09:43:15 INFO - 'sps_profile_interval': 0, 09:43:15 INFO - 'suite': 'dromaeojs', 09:43:15 INFO - 'system_bits': '32', 09:43:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:43:15 INFO - 'title': 't-yosemite-r7-0148', 09:43:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:43:15 INFO - 'use_talos_json': True, 09:43:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:43:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:43:15 INFO - 'work_dir': 'build'} 09:43:15 INFO - [mozharness: 2016-10-20 16:43:15.631187Z] Running clobber step. 09:43:15 INFO - Running pre-action listener: _resource_record_pre_action 09:43:15 INFO - Running main action method: clobber 09:43:15 INFO - rmtree: /builds/slave/test/build 09:43:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:43:17 INFO - Running post-action listener: _resource_record_post_action 09:43:17 INFO - [mozharness: 2016-10-20 16:43:17.889170Z] Finished clobber step (success) 09:43:17 INFO - [mozharness: 2016-10-20 16:43:17.889320Z] Running read-buildbot-config step. 09:43:17 INFO - Running pre-action listener: _resource_record_pre_action 09:43:17 INFO - Running main action method: read_buildbot_config 09:43:17 INFO - Using buildbot properties: 09:43:17 INFO - { 09:43:17 INFO - "project": "", 09:43:17 INFO - "product": "firefox", 09:43:17 INFO - "script_repo_revision": "production", 09:43:17 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 09:43:17 INFO - "repository": "", 09:43:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 09:43:17 INFO - "buildid": "20161020074550", 09:43:17 INFO - "pgo_build": "False", 09:43:17 INFO - "basedir": "/builds/slave/test", 09:43:17 INFO - "buildnumber": 10, 09:43:17 INFO - "slavename": "t-yosemite-r7-0148", 09:43:17 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 09:43:17 INFO - "platform": "yosemite_r7", 09:43:17 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:43:17 INFO - "branch": "mozilla-central", 09:43:17 INFO - "repo_path": "mozilla-central", 09:43:17 INFO - "revision": "c2753c9ba68ebd62aa8c086e7f36835ebd064044", 09:43:17 INFO - "stage_platform": "macosx64", 09:43:17 INFO - "builduid": "6b1f0f2726af4cbd8b2403ba5ceade1d", 09:43:17 INFO - "slavebuilddir": "test" 09:43:17 INFO - } 09:43:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/CVNtsFmST8GptvQawfFZZg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 09:43:17 INFO - Running post-action listener: _resource_record_post_action 09:43:17 INFO - [mozharness: 2016-10-20 16:43:17.892673Z] Finished read-buildbot-config step (success) 09:43:17 INFO - [mozharness: 2016-10-20 16:43:17.892800Z] Running download-and-extract step. 09:43:17 INFO - Running pre-action listener: _resource_record_pre_action 09:43:17 INFO - Running main action method: download_and_extract 09:43:17 INFO - mkdir: /builds/slave/test/build/tests 09:43:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:43:17 INFO - trying https://queue.taskcluster.net/v1/task/CVNtsFmST8GptvQawfFZZg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 09:43:17 INFO - Downloading https://queue.taskcluster.net/v1/task/CVNtsFmST8GptvQawfFZZg/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 09:43:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CVNtsFmST8GptvQawfFZZg/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 09:43:19 INFO - Downloaded 1199 bytes. 09:43:19 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 09:43:19 INFO - Using the following test package requirements: 09:43:19 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 09:43:19 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:43:19 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 09:43:19 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:43:19 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 09:43:19 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:43:19 INFO - u'jsshell-mac.zip'], 09:43:19 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:43:19 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 09:43:19 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 09:43:19 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:43:19 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 09:43:19 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:43:19 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 09:43:19 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:43:19 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 09:43:19 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:43:19 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 09:43:19 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:43:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CVNtsFmST8GptvQawfFZZg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 09:43:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CVNtsFmST8GptvQawfFZZg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:43:19 INFO - Fetch https://queue.taskcluster.net/v1/task/CVNtsFmST8GptvQawfFZZg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 09:43:19 INFO - Http code: 200 09:43:19 INFO - via: 1.1 336f0e6ef9a3462f682d6ca49029b665.cloudfront.net (CloudFront) 09:43:19 INFO - x-amz-cf-id: Rb6WCfo-oJ8vRLw4xydhw3zj4JK2uVjadjMrXQQBj3vQF9kqKH1dOA== 09:43:19 INFO - x-amz-version-id: _C52GJL4AHS03BmNRaNoOFoW6bghNLO8 09:43:20 INFO - Expected file size: 16505852 09:43:20 INFO - Obtained file size: 16505852 09:43:26 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 09:43:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CVNtsFmST8GptvQawfFZZg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 09:43:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CVNtsFmST8GptvQawfFZZg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:43:26 INFO - Fetch https://queue.taskcluster.net/v1/task/CVNtsFmST8GptvQawfFZZg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 09:43:27 INFO - Http code: 200 09:43:27 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 09:43:27 INFO - x-amz-cf-id: 4i_OZaS2aozaWEShyhuZw1j94NC8DA0oYaVFRaE8kjoUEwBgkwzydw== 09:43:27 INFO - x-amz-version-id: _C52GJL4AHS03BmNRaNoOFoW6bghNLO8 09:43:27 INFO - Expected file size: 16505852 09:43:27 INFO - Obtained file size: 16505852 09:43:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CVNtsFmST8GptvQawfFZZg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 09:43:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CVNtsFmST8GptvQawfFZZg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:43:34 INFO - Fetch https://queue.taskcluster.net/v1/task/CVNtsFmST8GptvQawfFZZg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 09:43:35 INFO - Http code: 200 09:43:35 INFO - via: 1.1 b9a8ce441329f20cea895e08f19f1ec8.cloudfront.net (CloudFront) 09:43:35 INFO - x-amz-cf-id: Qj27MdFH4tIVuVbmG0jzOrmfM2B25f0IOG3-vr1tWi-0jmgWYzlJ5A== 09:43:35 INFO - x-amz-version-id: L9A5heI9Ljjf7_4MYETpQLSx2HSzHqK5 09:43:35 INFO - Expected file size: 14409028 09:43:35 INFO - Obtained file size: 14409028 09:43:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:43:35 INFO - trying https://queue.taskcluster.net/v1/task/CVNtsFmST8GptvQawfFZZg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 09:43:35 INFO - Downloading https://queue.taskcluster.net/v1/task/CVNtsFmST8GptvQawfFZZg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 09:43:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CVNtsFmST8GptvQawfFZZg/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 09:43:38 INFO - Downloaded 111640460 bytes. 09:43:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CVNtsFmST8GptvQawfFZZg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 09:43:38 INFO - mkdir: /builds/slave/test/properties 09:43:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:43:38 INFO - Writing to file /builds/slave/test/properties/build_url 09:43:38 INFO - Contents: 09:43:38 INFO - build_url:https://queue.taskcluster.net/v1/task/CVNtsFmST8GptvQawfFZZg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 09:43:39 INFO - Running post-action listener: _resource_record_post_action 09:43:39 INFO - Running post-action listener: set_extra_try_arguments 09:43:39 INFO - [mozharness: 2016-10-20 16:43:39.423908Z] Finished download-and-extract step (success) 09:43:39 INFO - [mozharness: 2016-10-20 16:43:39.424065Z] Running populate-webroot step. 09:43:39 INFO - Running pre-action listener: _resource_record_pre_action 09:43:39 INFO - Running main action method: populate_webroot 09:43:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:43:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:43:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:43:39 INFO - '--tppagecycles', 09:43:39 INFO - '7'], 09:43:39 INFO - 'tests': ['tsvgm']}}, 09:43:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:43:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:43:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:43:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:43:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:43:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:43:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:39 INFO - 'talos_options': ['--disable-e10s'], 09:43:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:43:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:43:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:39 INFO - 'talos_options': ['--disable-e10s'], 09:43:39 INFO - 'tests': ['damp', 'tps']}, 09:43:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:39 INFO - 'tests': ['damp', 'tps']}, 09:43:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:43:39 INFO - 'tests': ['dromaeo_dom']}, 09:43:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:43:39 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:43:39 INFO - 'tests': ['basic_compositor_video']}, 09:43:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:43:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:43:39 INFO - 'tests': ['a11yr', 09:43:39 INFO - 'ts_paint', 09:43:39 INFO - 'tpaint', 09:43:39 INFO - 'sessionrestore', 09:43:39 INFO - 'sessionrestore_no_auto_restore', 09:43:39 INFO - 'tabpaint']}, 09:43:39 INFO - 'other-e10s': {'tests': ['a11yr', 09:43:39 INFO - 'ts_paint', 09:43:39 INFO - 'tpaint', 09:43:39 INFO - 'sessionrestore', 09:43:39 INFO - 'sessionrestore_no_auto_restore', 09:43:39 INFO - 'tabpaint']}, 09:43:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:43:39 INFO - 'ts_paint', 09:43:39 INFO - 'tpaint', 09:43:39 INFO - 'sessionrestore', 09:43:39 INFO - 'sessionrestore_no_auto_restore', 09:43:39 INFO - 'tabpaint']}, 09:43:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:43:39 INFO - 'ts_paint', 09:43:39 INFO - 'tpaint', 09:43:39 INFO - 'sessionrestore', 09:43:39 INFO - 'sessionrestore_no_auto_restore', 09:43:39 INFO - 'tabpaint']}, 09:43:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:43:39 INFO - 'tests': ['a11yr', 09:43:39 INFO - 'ts_paint', 09:43:39 INFO - 'tpaint', 09:43:39 INFO - 'sessionrestore', 09:43:39 INFO - 'sessionrestore_no_auto_restore', 09:43:39 INFO - 'tabpaint']}, 09:43:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:43:39 INFO - 'tests': ['a11yr', 09:43:39 INFO - 'ts_paint', 09:43:39 INFO - 'tpaint', 09:43:39 INFO - 'sessionrestore', 09:43:39 INFO - 'sessionrestore_no_auto_restore', 09:43:39 INFO - 'tabpaint']}, 09:43:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:43:39 INFO - 'tests': ['tsvgx', 09:43:39 INFO - 'tsvgr_opacity', 09:43:39 INFO - 'tart', 09:43:39 INFO - 'tscrollx', 09:43:39 INFO - 'cart']}, 09:43:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:43:39 INFO - 'tsvgr_opacity', 09:43:39 INFO - 'tart', 09:43:39 INFO - 'tscrollx', 09:43:39 INFO - 'cart']}, 09:43:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:39 INFO - 'talos_options': ['--disable-e10s'], 09:43:39 INFO - 'tests': ['tp5o']}, 09:43:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:39 INFO - 'tests': ['tp5o']}, 09:43:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:39 INFO - 'talos_options': ['--disable-e10s', 09:43:39 INFO - '--xperf_path', 09:43:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:43:39 INFO - 'tests': ['tp5n']}, 09:43:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:39 INFO - 'talos_options': ['--xperf_path', 09:43:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:43:39 INFO - 'tests': ['tp5n']}}, 09:43:39 INFO - 'talos.zip': {'path': '', 09:43:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:43:39 INFO - Running post-action listener: _resource_record_post_action 09:43:39 INFO - [mozharness: 2016-10-20 16:43:39.443088Z] Finished populate-webroot step (success) 09:43:39 INFO - [mozharness: 2016-10-20 16:43:39.443220Z] Running create-virtualenv step. 09:43:39 INFO - Running pre-action listener: _resource_record_pre_action 09:43:39 INFO - Running main action method: create_virtualenv 09:43:39 INFO - Creating virtualenv /builds/slave/test/build/venv 09:43:39 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 09:43:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:43:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:43:39 INFO - Using real prefix '/tools/python27' 09:43:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:43:40 INFO - Installing distribute.............................................................................................................................................................................................done. 09:43:43 INFO - Installing pip.................done. 09:43:43 INFO - Return code: 0 09:43:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:43:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:43:43 INFO - Reading from file tmpfile_stdout 09:43:43 INFO - Output received: 09:43:43 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) 09:43:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:43: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')]} 09:43:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43: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')]} 09:43:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:43 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 0x104d40490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cb6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84b2551dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b54d30>, '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 0x10497adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104b4cb20>, '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.P9FeILN9Hn/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.85Ya9q3yTA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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 09:43:43 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 09:43:43 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 09:43:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P9FeILN9Hn/Render', 09:43:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:43 INFO - 'HOME': '/Users/cltbld', 09:43:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:43 INFO - 'LOGNAME': 'cltbld', 09:43:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:43 INFO - 'MOZ_NO_REMOTE': '1', 09:43:43 INFO - 'NO_EM_RESTART': '1', 09:43:43 INFO - 'PAGER': '/bin/cat', 09:43:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:43 INFO - 'PWD': '/builds/slave/test', 09:43:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:43 INFO - 'SHELL': '/bin/bash', 09:43:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.85Ya9q3yTA/Listeners', 09:43:43 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 09:43:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:43 INFO - 'USER': 'cltbld', 09:43:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:43 INFO - 'XPC_FLAGS': '0x0', 09:43:43 INFO - 'XPC_SERVICE_NAME': '0', 09:43:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:43 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 09:43:43 INFO - Cleaning up... 09:43:43 INFO - Return code: 0 09:43:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:43: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')]} 09:43:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43: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')]} 09:43:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:43 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 0x104d40490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cb6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84b2551dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b54d30>, '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 0x10497adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104b4cb20>, '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.P9FeILN9Hn/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.85Ya9q3yTA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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 09:43:43 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 09:43:43 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 09:43:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P9FeILN9Hn/Render', 09:43:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:43 INFO - 'HOME': '/Users/cltbld', 09:43:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:43 INFO - 'LOGNAME': 'cltbld', 09:43:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:43 INFO - 'MOZ_NO_REMOTE': '1', 09:43:43 INFO - 'NO_EM_RESTART': '1', 09:43:43 INFO - 'PAGER': '/bin/cat', 09:43:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:43 INFO - 'PWD': '/builds/slave/test', 09:43:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:43 INFO - 'SHELL': '/bin/bash', 09:43:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.85Ya9q3yTA/Listeners', 09:43:43 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 09:43:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:43 INFO - 'USER': 'cltbld', 09:43:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:43 INFO - 'XPC_FLAGS': '0x0', 09:43:43 INFO - 'XPC_SERVICE_NAME': '0', 09:43:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:43 INFO - Downloading/unpacking psutil>=3.1.1 09:43:43 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 09:43:43 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 09:43:43 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 09:43:43 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 09:43:43 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 09:43:43 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 09:43:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:43:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:43:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:43:47 INFO - Installing collected packages: psutil 09:43:47 INFO - Running setup.py install for psutil 09:43:47 INFO - building 'psutil._psutil_osx' extension 09:43:47 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 09:43:48 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 09:43:48 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 09:43:48 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 09:43:48 INFO - building 'psutil._psutil_posix' extension 09:43:48 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 09:43:48 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:43:48 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:43:48 INFO - ^ 09:43:48 INFO - 1 warning generated. 09:43:48 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 09:43:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:43:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:43:48 INFO - Successfully installed psutil 09:43:48 INFO - Cleaning up... 09:43:48 INFO - Return code: 0 09:43:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:43:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:43:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:43:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104d40490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cb6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84b2551dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b54d30>, '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 0x10497adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104b4cb20>, '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.P9FeILN9Hn/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.85Ya9q3yTA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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 09:43:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:43:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:43:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P9FeILN9Hn/Render', 09:43:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:48 INFO - 'HOME': '/Users/cltbld', 09:43:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:48 INFO - 'LOGNAME': 'cltbld', 09:43:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:48 INFO - 'MOZ_NO_REMOTE': '1', 09:43:48 INFO - 'NO_EM_RESTART': '1', 09:43:48 INFO - 'PAGER': '/bin/cat', 09:43:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:48 INFO - 'PWD': '/builds/slave/test', 09:43:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:48 INFO - 'SHELL': '/bin/bash', 09:43:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.85Ya9q3yTA/Listeners', 09:43:48 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 09:43:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:48 INFO - 'USER': 'cltbld', 09:43:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:48 INFO - 'XPC_FLAGS': '0x0', 09:43:48 INFO - 'XPC_SERVICE_NAME': '0', 09:43:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:48 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:43:48 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 09:43:48 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 09:43:48 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 09:43:48 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 09:43:48 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 09:43:48 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 09:43:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:43:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:43:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:43:51 INFO - Installing collected packages: mozsystemmonitor 09:43:51 INFO - Running setup.py install for mozsystemmonitor 09:43:51 INFO - Successfully installed mozsystemmonitor 09:43:51 INFO - Cleaning up... 09:43:51 INFO - Return code: 0 09:43:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:43: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')]} 09:43:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43: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')]} 09:43:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43: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', '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 0x104d40490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cb6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84b2551dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b54d30>, '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 0x10497adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104b4cb20>, '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.P9FeILN9Hn/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.85Ya9q3yTA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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 09:43: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:43: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 jsonschema==2.5.1 09:43:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P9FeILN9Hn/Render', 09:43:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:51 INFO - 'HOME': '/Users/cltbld', 09:43:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:51 INFO - 'LOGNAME': 'cltbld', 09:43:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:51 INFO - 'MOZ_NO_REMOTE': '1', 09:43:51 INFO - 'NO_EM_RESTART': '1', 09:43:51 INFO - 'PAGER': '/bin/cat', 09:43:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:51 INFO - 'PWD': '/builds/slave/test', 09:43:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:51 INFO - 'SHELL': '/bin/bash', 09:43:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.85Ya9q3yTA/Listeners', 09:43:51 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 09:43:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:51 INFO - 'USER': 'cltbld', 09:43:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:51 INFO - 'XPC_FLAGS': '0x0', 09:43:51 INFO - 'XPC_SERVICE_NAME': '0', 09:43:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:51 INFO - Downloading/unpacking jsonschema==2.5.1 09:43: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 09:43: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 09:43: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 09:43: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 09:43: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 09:43: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 09:43:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:43:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:43: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 09:43: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 09:43: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 09:43: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 09:43: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 09:43: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 09:43:54 INFO - Downloading functools32-3.2.3-2.tar.gz 09:43:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:43:54 INFO - warning: no files found matching '*.txt' 09:43:54 INFO - no previously-included directories found matching 'build' 09:43:54 INFO - no previously-included directories found matching 'dist' 09:43:54 INFO - no previously-included directories found matching '.git*' 09:43:54 INFO - Installing collected packages: jsonschema, functools32 09:43:54 INFO - Running setup.py install for functools32 09:43:55 INFO - warning: no files found matching '*.txt' 09:43:55 INFO - no previously-included directories found matching 'build' 09:43:55 INFO - no previously-included directories found matching 'dist' 09:43:55 INFO - no previously-included directories found matching '.git*' 09:43:55 INFO - Successfully installed jsonschema functools32 09:43:55 INFO - Cleaning up... 09:43:55 INFO - Return code: 0 09:43:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:43:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:43:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:43:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:55 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 0x104d40490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cb6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84b2551dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b54d30>, '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 0x10497adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104b4cb20>, '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.P9FeILN9Hn/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.85Ya9q3yTA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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 09:43:55 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 09:43:55 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 09:43:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P9FeILN9Hn/Render', 09:43:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:55 INFO - 'HOME': '/Users/cltbld', 09:43:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:55 INFO - 'LOGNAME': 'cltbld', 09:43:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:55 INFO - 'MOZ_NO_REMOTE': '1', 09:43:55 INFO - 'NO_EM_RESTART': '1', 09:43:55 INFO - 'PAGER': '/bin/cat', 09:43:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:55 INFO - 'PWD': '/builds/slave/test', 09:43:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:55 INFO - 'SHELL': '/bin/bash', 09:43:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.85Ya9q3yTA/Listeners', 09:43:55 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 09:43:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:55 INFO - 'USER': 'cltbld', 09:43:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:55 INFO - 'XPC_FLAGS': '0x0', 09:43:55 INFO - 'XPC_SERVICE_NAME': '0', 09:43:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:43:55 INFO - Cleaning up... 09:43:55 INFO - Return code: 0 09:43:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:43:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:43:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:43:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:55 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 0x104d40490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cb6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84b2551dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b54d30>, '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 0x10497adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104b4cb20>, '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.P9FeILN9Hn/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.85Ya9q3yTA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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 09:43:55 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 09:43:55 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 09:43:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P9FeILN9Hn/Render', 09:43:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:55 INFO - 'HOME': '/Users/cltbld', 09:43:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:55 INFO - 'LOGNAME': 'cltbld', 09:43:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:55 INFO - 'MOZ_NO_REMOTE': '1', 09:43:55 INFO - 'NO_EM_RESTART': '1', 09:43:55 INFO - 'PAGER': '/bin/cat', 09:43:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:55 INFO - 'PWD': '/builds/slave/test', 09:43:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:55 INFO - 'SHELL': '/bin/bash', 09:43:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.85Ya9q3yTA/Listeners', 09:43:55 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 09:43:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:55 INFO - 'USER': 'cltbld', 09:43:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:55 INFO - 'XPC_FLAGS': '0x0', 09:43:55 INFO - 'XPC_SERVICE_NAME': '0', 09:43:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:55 INFO - Downloading/unpacking blobuploader==1.2.4 09:43:55 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 09:43:55 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 09:43:55 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 09:43:55 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 09:43:55 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 09:43:55 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 09:43:58 INFO - Downloading blobuploader-1.2.4.tar.gz 09:43:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:43:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:43:58 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 09:43:58 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 09:43:58 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 09:43:58 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 09:43:58 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 09:43:58 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 09:43:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:43:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:43:58 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 09:43:58 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 09:43:58 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 09:43:58 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 09:43:58 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 09:43:58 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 09:43:59 INFO - Downloading docopt-0.6.1.tar.gz 09:43:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:43:59 INFO - Installing collected packages: blobuploader, requests, docopt 09:43:59 INFO - Running setup.py install for blobuploader 09:43:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:43:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:43:59 INFO - Running setup.py install for requests 09:44:00 INFO - Running setup.py install for docopt 09:44:00 INFO - Successfully installed blobuploader requests docopt 09:44:00 INFO - Cleaning up... 09:44:00 INFO - Return code: 0 09:44:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:44: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')]} 09:44:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44: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')]} 09:44:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:00 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 0x104d40490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cb6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84b2551dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b54d30>, '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 0x10497adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104b4cb20>, '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.P9FeILN9Hn/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.85Ya9q3yTA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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 09:44:00 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 09:44:00 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 09:44:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P9FeILN9Hn/Render', 09:44:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:00 INFO - 'HOME': '/Users/cltbld', 09:44:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:00 INFO - 'LOGNAME': 'cltbld', 09:44:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:00 INFO - 'MOZ_NO_REMOTE': '1', 09:44:00 INFO - 'NO_EM_RESTART': '1', 09:44:00 INFO - 'PAGER': '/bin/cat', 09:44:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:00 INFO - 'PWD': '/builds/slave/test', 09:44:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:00 INFO - 'SHELL': '/bin/bash', 09:44:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.85Ya9q3yTA/Listeners', 09:44:00 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 09:44:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:00 INFO - 'USER': 'cltbld', 09:44:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:00 INFO - 'XPC_FLAGS': '0x0', 09:44:00 INFO - 'XPC_SERVICE_NAME': '0', 09:44:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:44:00 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-QPhWpC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:44:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:44:00 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-qWSt45-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:44:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:44:00 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-iENQdY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:44:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:44:00 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-_06ABw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:44:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:44:01 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-cZWnuc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:44:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:44:01 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-yJnNAP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:44:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:44:01 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-SYQUAi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:44:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:44:01 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-0M8NIl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:44:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:44:01 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-zqGmve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:44:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:44:01 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-CkAQpT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:44:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:44:01 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-su1Sy3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:44:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:44:01 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-Q0n7yQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:44:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:44:01 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-ioBCkO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:44:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:44:01 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-__fqK9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:44:02 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-eXmE6_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:44:02 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-Lsx4ie-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:44:02 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-YCOoek-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:44:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:44:02 INFO - Running setup.py install for manifestparser 09:44:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:44:02 INFO - Running setup.py install for mozcrash 09:44:02 INFO - Running setup.py install for mozdebug 09:44:02 INFO - Running setup.py install for mozdevice 09:44:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:44:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:44:03 INFO - Running setup.py install for mozfile 09:44:03 INFO - Running setup.py install for mozhttpd 09:44:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:44:03 INFO - Running setup.py install for mozinfo 09:44:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:44:03 INFO - Running setup.py install for mozInstall 09:44:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:44:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:44:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:44:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:44:03 INFO - Running setup.py install for mozleak 09:44:03 INFO - Running setup.py install for mozlog 09:44:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:44:03 INFO - Running setup.py install for moznetwork 09:44:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:44:04 INFO - Running setup.py install for mozprocess 09:44:04 INFO - Running setup.py install for mozprofile 09:44:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:44:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:44:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:44:04 INFO - Running setup.py install for mozrunner 09:44:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:44:04 INFO - Running setup.py install for mozscreenshot 09:44:04 INFO - Running setup.py install for moztest 09:44:04 INFO - Running setup.py install for mozversion 09:44:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:44:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:44:04 INFO - Cleaning up... 09:44:04 INFO - Return code: 0 09:44:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:44:04 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')]} 09:44:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:04 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')]} 09:44:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:04 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 0x104d40490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cb6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84b2551dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b54d30>, '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 0x10497adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104b4cb20>, '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.P9FeILN9Hn/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.85Ya9q3yTA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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 09:44:04 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 09:44:04 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 09:44:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P9FeILN9Hn/Render', 09:44:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:05 INFO - 'HOME': '/Users/cltbld', 09:44:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:05 INFO - 'LOGNAME': 'cltbld', 09:44:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:05 INFO - 'MOZ_NO_REMOTE': '1', 09:44:05 INFO - 'NO_EM_RESTART': '1', 09:44:05 INFO - 'PAGER': '/bin/cat', 09:44:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:05 INFO - 'PWD': '/builds/slave/test', 09:44:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:05 INFO - 'SHELL': '/bin/bash', 09:44:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.85Ya9q3yTA/Listeners', 09:44:05 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 09:44:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:05 INFO - 'USER': 'cltbld', 09:44:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:05 INFO - 'XPC_FLAGS': '0x0', 09:44:05 INFO - 'XPC_SERVICE_NAME': '0', 09:44:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:44:05 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-73wyLM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:44:05 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)) 09:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:44:05 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-prVVYF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:44:05 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)) 09:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:44:05 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-l8LKWw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:44:05 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)) 09:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:44:05 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-KWtZZv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:44:05 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)) 09:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:44:05 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-xnQJ8Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:44:05 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)) 09:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:44:05 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-yF_adh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:44:05 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)) 09:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:44:05 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-C2FH6f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:44:06 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)) 09:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:44:06 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-jaI_2F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:44:06 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)) 09:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:44:06 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-_3GVMP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:44:06 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)) 09:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:44:06 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-wk6pV8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:44:06 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)) 09:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:44:06 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-ZPRGY6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:44:06 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)) 09:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:44:06 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-jhi2e6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:44:06 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)) 09:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:44:06 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-XKXyB5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:44:06 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)) 09:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:44:06 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-mkL7tn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:44:06 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)) 09:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:44:06 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-kgXscb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:44:06 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)) 09:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:44:06 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-5yf5HQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:44:06 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)) 09:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:44:06 INFO - Running setup.py (path:/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/pip-bTFq6T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:44:07 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)) 09:44:07 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)) 09:44:07 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)) 09:44:07 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)) 09:44:07 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)) 09:44:07 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)) 09:44:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:44:07 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 09:44:07 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 09:44:07 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 09:44:07 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 09:44:07 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 09:44:07 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 09:44:09 INFO - Downloading blessings-1.6.tar.gz 09:44:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:44:09 INFO - Installing collected packages: blessings 09:44:09 INFO - Running setup.py install for blessings 09:44:09 INFO - Successfully installed blessings 09:44:09 INFO - Cleaning up... 09:44:09 INFO - Return code: 0 09:44:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:44:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:44:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:44:10 INFO - Reading from file tmpfile_stdout 09:44:10 INFO - Current package versions: 09:44:10 INFO - blessings == 1.6 09:44:10 INFO - blobuploader == 1.2.4 09:44:10 INFO - docopt == 0.6.1 09:44:10 INFO - functools32 == 3.2.3-2 09:44:10 INFO - jsonschema == 2.5.1 09:44:10 INFO - manifestparser == 1.1 09:44:10 INFO - mozInstall == 1.12 09:44:10 INFO - mozcrash == 0.17 09:44:10 INFO - mozdebug == 0.1 09:44:10 INFO - mozdevice == 0.48 09:44:10 INFO - mozfile == 1.2 09:44:10 INFO - mozhttpd == 0.7 09:44:10 INFO - mozinfo == 0.9 09:44:10 INFO - mozleak == 0.1 09:44:10 INFO - mozlog == 3.4 09:44:10 INFO - moznetwork == 0.27 09:44:10 INFO - mozprocess == 0.23 09:44:10 INFO - mozprofile == 0.28 09:44:10 INFO - mozrunner == 6.12 09:44:10 INFO - mozscreenshot == 0.1 09:44:10 INFO - mozsystemmonitor == 0.3 09:44:10 INFO - moztest == 0.7 09:44:10 INFO - mozversion == 1.4 09:44:10 INFO - psutil == 3.1.1 09:44:10 INFO - requests == 1.2.3 09:44:10 INFO - wsgiref == 0.1.2 09:44:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:44:10 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')]} 09:44:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:10 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')]} 09:44:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:10 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 0x104d40490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cb6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84b2551dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b54d30>, '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 0x10497adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104b4cb20>, '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.P9FeILN9Hn/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.85Ya9q3yTA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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 09:44:10 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 09:44:10 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 09:44:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P9FeILN9Hn/Render', 09:44:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:10 INFO - 'HOME': '/Users/cltbld', 09:44:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:10 INFO - 'LOGNAME': 'cltbld', 09:44:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:10 INFO - 'MOZ_NO_REMOTE': '1', 09:44:10 INFO - 'NO_EM_RESTART': '1', 09:44:10 INFO - 'PAGER': '/bin/cat', 09:44:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:10 INFO - 'PWD': '/builds/slave/test', 09:44:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:10 INFO - 'SHELL': '/bin/bash', 09:44:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.85Ya9q3yTA/Listeners', 09:44:10 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 09:44:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:10 INFO - 'USER': 'cltbld', 09:44:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:10 INFO - 'XPC_FLAGS': '0x0', 09:44:10 INFO - 'XPC_SERVICE_NAME': '0', 09:44:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:10 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)) 09:44:10 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)) 09:44:10 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)) 09:44:10 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)) 09:44:10 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)) 09:44:10 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)) 09:44:10 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)) 09:44:10 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)) 09:44:10 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)) 09:44:10 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)) 09:44:10 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)) 09:44:10 INFO - Cleaning up... 09:44:10 INFO - Return code: 0 09:44:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:44:10 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')]} 09:44:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:10 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')]} 09:44:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104d40490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cb6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f84b2551dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b54d30>, '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 0x10497adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104b4cb20>, '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.P9FeILN9Hn/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.85Ya9q3yTA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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 09:44:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:44:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:44:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P9FeILN9Hn/Render', 09:44:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:11 INFO - 'HOME': '/Users/cltbld', 09:44:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:11 INFO - 'LOGNAME': 'cltbld', 09:44:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:11 INFO - 'MOZ_NO_REMOTE': '1', 09:44:11 INFO - 'NO_EM_RESTART': '1', 09:44:11 INFO - 'PAGER': '/bin/cat', 09:44:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:11 INFO - 'PWD': '/builds/slave/test', 09:44:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:11 INFO - 'SHELL': '/bin/bash', 09:44:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.85Ya9q3yTA/Listeners', 09:44:11 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 09:44:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:11 INFO - 'USER': 'cltbld', 09:44:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:11 INFO - 'XPC_FLAGS': '0x0', 09:44:11 INFO - 'XPC_SERVICE_NAME': '0', 09:44:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:44:11 INFO - Cleaning up... 09:44:11 INFO - Return code: 0 09:44:11 INFO - Running post-action listener: _resource_record_post_action 09:44:11 INFO - Running post-action listener: _start_resource_monitoring 09:44:11 INFO - Starting resource monitoring. 09:44:11 INFO - [mozharness: 2016-10-20 16:44:11.515617Z] Finished create-virtualenv step (success) 09:44:11 INFO - [mozharness: 2016-10-20 16:44:11.516580Z] Running install step. 09:44:11 INFO - Running pre-action listener: _resource_record_pre_action 09:44:11 INFO - Running main action method: install 09:44:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:44:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:44:11 INFO - Reading from file tmpfile_stdout 09:44:11 INFO - Detecting whether we're running mozinstall >=1.0... 09:44:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:44:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:44:11 INFO - Reading from file tmpfile_stdout 09:44:11 INFO - Output received: 09:44:11 INFO - Usage: mozinstall [options] installer 09:44:11 INFO - Options: 09:44:11 INFO - -h, --help show this help message and exit 09:44:11 INFO - -d DEST, --destination=DEST 09:44:11 INFO - Directory to install application into. [default: 09:44:11 INFO - "/builds/slave/test"] 09:44:11 INFO - --app=APP Application being installed. [default: firefox] 09:44:11 INFO - mkdir: /builds/slave/test/build/application 09:44:11 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'] 09:44:11 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 09:44:46 INFO - Reading from file tmpfile_stdout 09:44:46 INFO - Output received: 09:44:46 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:44:46 INFO - Running post-action listener: _resource_record_post_action 09:44:46 INFO - [mozharness: 2016-10-20 16:44:46.678158Z] Finished install step (success) 09:44:46 INFO - [mozharness: 2016-10-20 16:44:46.678293Z] Running run-tests step. 09:44:46 INFO - Running pre-action listener: _resource_record_pre_action 09:44:46 INFO - Running main action method: run_tests 09:44:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:44:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:44:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:44:46 INFO - 2016-10-20 09:44:46.692 screenresolution[1631:7516] starting screenresolution argv=screenresolution get 09:44:46 INFO - 2016-10-20 09:44:46.695 screenresolution[1631:7516] Display 0: 1600x1200x32@60 09:44:46 INFO - 2016-10-20 09:44:46.701 screenresolution[1632:7518] starting screenresolution argv=screenresolution list 09:44:46 INFO - Available Modes on Display 0 09:44:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:44:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:44:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:44:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:44:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:44:46 INFO - Intel Iris: 09:44:46 INFO - Chipset Model: Intel Iris 09:44:46 INFO - Type: GPU 09:44:46 INFO - Bus: Built-In 09:44:46 INFO - VRAM (Dynamic, Max): 1536 MB 09:44:46 INFO - Vendor: Intel (0x8086) 09:44:46 INFO - Device ID: 0x0a2e 09:44:46 INFO - Revision ID: 0x0009 09:44:46 INFO - Displays: 09:44:46 INFO - Display: 09:44:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:44:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:44:46 INFO - Display Serial Number: 1600x1200 60 09:44:46 INFO - Main Display: Yes 09:44:46 INFO - Mirror: Off 09:44:46 INFO - Online: Yes 09:44:46 INFO - Rotation: Supported 09:44:46 INFO - Return code: 0 09:44:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:44:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:44:46 INFO - Python 2.7.3 09:44:46 INFO - Return code: 0 09:44:46 INFO - grabbing minidump binary from tooltool 09:44:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:44:46 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 0x104b54d30>, '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 0x10497adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104b4cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:44:46 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 09:44:46 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 09:44:46 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 09:44:47 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:44:47 INFO - Return code: 0 09:44:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:44:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:44:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:44:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:44:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:44:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CVNtsFmST8GptvQawfFZZg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0148', '--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 09:44:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CVNtsFmST8GptvQawfFZZg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0148 --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 09:44:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P9FeILN9Hn/Render', 09:44:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:47 INFO - 'HOME': '/Users/cltbld', 09:44:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:47 INFO - 'LOGNAME': 'cltbld', 09:44:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:44:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:44:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:47 INFO - 'MOZ_NO_REMOTE': '1', 09:44:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:44:47 INFO - 'NO_EM_RESTART': '1', 09:44:47 INFO - 'PAGER': '/bin/cat', 09:44:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:47 INFO - 'PWD': '/builds/slave/test', 09:44:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:44:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:47 INFO - 'SHELL': '/bin/bash', 09:44:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.85Ya9q3yTA/Listeners', 09:44:47 INFO - 'TMPDIR': '/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/', 09:44:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:47 INFO - 'USER': 'cltbld', 09:44:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:47 INFO - 'XPC_FLAGS': '0x0', 09:44:47 INFO - 'XPC_SERVICE_NAME': '0', 09:44:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CVNtsFmST8GptvQawfFZZg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0148', '--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 09:44:47 INFO - mozversion application_buildid: 20161020074550 09:44:47 INFO - mozversion application_changeset: c2753c9ba68ebd62aa8c086e7f36835ebd064044 09:44:47 INFO - mozversion application_display_name: Nightly 09:44:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:44:47 INFO - mozversion application_name: Firefox 09:44:47 INFO - mozversion application_remotingname: firefox 09:44:47 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:44:47 INFO - mozversion application_vendor: Mozilla 09:44:47 INFO - mozversion application_version: 52.0a1 09:44:47 INFO - mozversion platform_buildid: 20161020074550 09:44:47 INFO - mozversion platform_changeset: c2753c9ba68ebd62aa8c086e7f36835ebd064044 09:44:47 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:44:47 INFO - mozversion platform_version: 52.0a1 09:44:47 INFO - using testdate: 1476981887 09:44:47 INFO - actual date: 1476981887 09:44:47 INFO - starting webserver on 'localhost:49273' 09:44:47 INFO - SUITE-START | Running 2 tests 09:44:47 INFO - TEST-START | dromaeo_css 09:44:47 INFO - operating with platform_type : mac_ 09:44:47 INFO - Initialising browser for dromaeo_css test... 09:44:47 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/tmppUHfed/profile http://localhost:49273/getInfo.html) 09:44:49 INFO - PROCESS | 1642 | __metrics Screen width/height:1600/1200 09:44:49 INFO - PROCESS | 1642 | colorDepth:24 09:44:49 INFO - PROCESS | 1642 | Browser inner width/height: 1024/689 09:44:49 INFO - PROCESS | 1642 | __metrics 09:44:49 INFO - PROCESS | 1642 | 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" 09:44:49 INFO - PROCESS | 1642 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:44:50 INFO - TEST-INFO | 1642: exit 0 09:44:50 INFO - Browser initialized. 09:44:50 INFO - Running cycle 1/1 for dromaeo_css test... 09:44:50 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/tmppUHfed/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:45:02 INFO - PROCESS | 1644 | RSS: Main: 231636992 09:45:02 INFO - PROCESS | 1644 | 09:45:53 INFO - PROCESS | 1644 | 1476981953677 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] 09:48:06 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49273/tests/dromaeo/cssquery-ext.html) 09:48:08 INFO - PROCESS | 1644 | RSS: Main: 393760768 09:48:08 INFO - PROCESS | 1644 | 09:51:11 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-ext.html (next: http://localhost:49273/tests/dromaeo/cssquery-jquery.html) 09:51:12 INFO - PROCESS | 1644 | RSS: Main: 371646464 09:51:12 INFO - PROCESS | 1644 | 09:54:14 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49273/tests/dromaeo/cssquery-mootools.html) 09:54:14 INFO - PROCESS | 1644 | RSS: Main: 348151808 09:54:14 INFO - PROCESS | 1644 | 09:57:17 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49273/tests/dromaeo/cssquery-prototype.html) 09:57:17 INFO - PROCESS | 1644 | RSS: Main: 351424512 09:57:17 INFO - PROCESS | 1644 | 10:00:21 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49273/tests/dromaeo/cssquery-yui.html) 10:00:22 INFO - PROCESS | 1644 | RSS: Main: 335552512 10:00:22 INFO - PROCESS | 1644 | 10:03:25 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-yui.html (next: http://localhost:49273/tests/dromaeo/cssquery-dojo.html) 10:03:25 INFO - PROCESS | 1644 | RSS: Main: 522788864 10:03:25 INFO - PROCESS | 1644 | 10:03:25 INFO - PROCESS | 1644 | __start_tp_report 10:03:25 INFO - PROCESS | 1644 | _x_x_mozilla_page_load 10:03:25 INFO - PROCESS | 1644 | _x_x_mozilla_page_load_details 10:03:25 INFO - PROCESS | 1644 | |i|pagename|runs| 10:03:25 INFO - PROCESS | 1644 | |0;dojo.html;2940.485415052365;3278.737700983923;3288.671132886712;3301.059198128534;3332.766706330556;5494.697791621459;5494.835154945353;5567.192757050226;5571.665700057994;5629.690367029807;5588.91616625751;5608.635438696482;5610.186522954179;5618.15727640854;5621.072523033705;5086.389633244006;5119.667221630582;5124.333836601237;5155.948440515603;5211.687298762086;5130.9230361544605;5153.6907785532985;5179.922301165486;5221.086309895772;5243.86890327741;4450.977745111286;5202.609804264676;5224.817131400402;5229.60777941654;5275.28783614211;5212.817551385702;5216.843494695166;5225.346831646044;5247.160454327309;5275.419703832576;3233.2157269999534;4951.480094590088;5062.873428164288;5107.284980102789;5111.258867464234;5573.944260557383;5576.581756368289;5626.803061892814;5642.238297829782;5644.435556444364;5824.679642619654;5922.31893332268;6149.84625384369;6208.5033197344555;6213;6068.180795592582;6087.726052327655;6113.684880995636;6117.929362361569;6257.87484250317;4749.715017098985;5710.771569137188;5711.428857114339;5720.170575266647;5729.169270455803;6303.873922521524;6323;6328.778492752824;6349.111124442582;6358.936410635927;5824.708764561839;5867.882642347215;5868.413158684098;6022.2171117754415;6022.789202377896;6125.388069147376;6138.2121944883065;6144.170536977451;6200.937990620127;6204.937950620436;6053.061775424816;6111.008868573017;6123.826041755113;6126.172966649535;6137.5302083125;5991.430814072656;6068.393160683896;6135.478484328793;6138;6143.539234557426;4807.567318941242;4842.297866809293;4848.636352273594;4851.636127290397;4853.223484242504;4364.803583838735;4392.736435813861;4411.977940110343;4413.183561041154;4419.337099435111;5635.013872572284;5654.180143879114;5657.915131273035;5664.065429204217;5664.8867022659315;6119.99004232014;6126.264848218153;6143.389059649631;6155.446009859044;6170.043643235305;5649.491545760901;5681.772729090872;5684.261046064068;5684.8294551163535;5689.743961521742;5325.254464374991;5327.786888524493;5332.866678333073;5335.11970524859;5338.225957236256;6237.313895471494;6318.778842740482;6323.810285691345;6334.904976425358;6346.2067241594805;6186.133941248138;6233.937660623335;6254.437100660963;6255.46828519581;6258.3428739983465;6196.1635179250225;6256.249250090018;6282.2147231596055;6290.968545157245;6292.339304373158;6050.183225264709;6097.0105204680185;6112.5946701277735;6124.74712746768;6142.6314421134875;6054.666993315233;6203.4416902479;6219.875602488016;6225.750969961151;6235.532335074979;4630.58324750774;5840.970795145997;5988;5993.880122397615;6007.249093863267;5875.030619947661;5921.437463440966;5944.494717949023;5970.0746384310505;6039.63762174271;4784.880377990578;5897.9115313271195;5898.262717160355;5900.321463031634;5939.316978547522;5934.881302373842;6033.336333003454;6043.637381757108;6059.606125601822;6074.969625151846;5704.515116215002;5764.913526297193;5874.353821079763;5909.054551271776;5911.467967882912 10:03:25 INFO - PROCESS | 1644 | |1;ext.html;489.59119135521837;498.91983854954026;503.101962996052;506.24569391606497;519.3300642171598;27609.585856212147;40026.1994760105;41139.79430102853;41302.793486032526;41313;42079.737607871735;42412.15375155598;42542;42610.57389426105;42614;9414.95292523538;9576.42541447512;9596.664116755916;9636.373635713691;9650.951745241264;9356.906430935705;9456.243500519942;9458.385204961656;9464.905350946472;9478.62085516582;9435.245180385587;9496.857547136799;9501.19239864609;9521.428714277166;9542.618295268181;9439.19766819821;9473.66842160528;9485.525723713787;9490.003549627329;9495.240380769557;9388.530573471298;9389.577469013911;9478.715638530855;9482.288828337902;9498.09768072039;39126;39521.20957580832;39591;39615.60384396148;39680;3195.456772348683;3366.4445366514497;3427.9657203427896;3469;3510.80690562019;30206.0938171853;30473.39053218923;30824;30832.229194270098;30932.381352373053;3078.599782028344;3200.2159470929537;3201.9679803201902;3235.514672799099;3287.8356082196033;468246;473340;473846.8922966158;474217;476898;57922.71038644864;58252.70873645604;58314.8337033257;58371.70814145902;58414.7079264601;31616;33244.83377583145;34173.82913085419;36060.27879442397;60593.09110363348;16104.75842862358;16197.676046479004;16225.864189506858;16255.268512916948;16269.511914642342;12392.504299828086;12494;12504.874951250553;12506.936910362721;12513;16268.430604928772;18366.357177498725;18755.437336879644;18963.715544266848;19179.61640767177;7932.008498937633;8081.515109093473;8100.513969161869;8178.46839955401;8213.589320533949;864.3603733237359;921.7787730944659;948.2508818033676;1032.912202462799;1051.4479898053582;29415.558766618517;29700;29816.254593635334;30543;31048.844755776077;994.4878387630376;1009.9042538845288;1074.1245884604023;1078.568572570978;1138.9202755807014;14388;15059;15274.389024438899;15453;15810.457451356746;626432;631402;632371;632491.8880272767;633265;57014.42985570174;57396.71301643465;57479.712601436724;57564.13653795196;57600.559986000684;1225.822255772908;1269.6762325607096;1342.684469149749;1363.1820907455447;1374.5738820965532;30209;30387.848060759556;30899.227519312197;31397;31738.841305793325;49012.26481602708;49455.75272123616;49489.25766113439;49500.25749613687;49596.50403495919;523179;526550;527121;527600;527820.3608981931;23100.884495577415;23548.529029418973;25034;25795.613065803653;25851.870740646176;39000.80499597484;46305.3054204194;46319.07361852811;46684.53315466938;47076.7646161767;37955.27727198252;38080.42879356867;38101;39143.82568522949;39758.80723578297;38680.80659596684;38942;40673.59326406698;40817.59182408257;42038;38006.23987520179;38432.615673842905;38708.451661933206;38829;38910.4163437543;32776;33670.831645841616;34212.97361079172;34358.82820585881;34422.96731098071;22828.543429131656;25065.122720704687;25365.112221072173;25988.61017084783;26143.607845882707 10:03:25 INFO - PROCESS | 1644 | |2;jquery.html;350.55654596934863;373.2832960715426;374.9287635349284;382.40917782026776;403.1694708899667;14802.629934251649;15243.237838108093;19353.616149124082;21317.57364852702;21535;20295.98520073998;20697.379078627626;20719.274825381115;20770.273040443586;20969.790302096975;5778.595498315109;6165.167702360169;6167.691615419233;6178;6200.565960382775;5819.476247137757;6005.2193214882;6018.60879042863;6056.878862422749;6062.090686397031;5962.612430192024;5995.310539287994;5995.520358371342;6002.819915402545;6004.96997515014;5712.657240565559;5979.34227235006;6037.154798328237;6042.395760423967;6063.363346848583;4658.440987081538;4996.350474438336;5219.373675159005;5231.450697676765;5239.161734122522;25186.37034074163;25470.108546200794;25490.36274093144;25790.484190316278;25826;2196.165457126298;2281.494213818888;2294.7016887804643;2622.082271205082;2632.5919482480244;9594.712158635182;10245.026722461354;10257.281990260684;10302.690919272434;10308.896911030868;1657.983420165795;1799.9190036448392;1855.378448219848;1860.958537407542;1876.3807943378652;102792;131717;132199.99999999904;132493;133789.66210337868;11113.666590002313;11548.249363791327;11574.363410012531;11590.362530060927;11765.941170294094;12849.807252891027;13376.866231337757;13416.664583385493;13425.731485370237;14152.363143658355;8276.08963014068;8469.830603387898;8471.576421178916;8556.101609331055;8600.5699715014;6565.012919121788;8331.875021874677;8337.249647531751;8473.872891906627;8505.82988340242;4367.6287515561535;4466.57567531084;4482.394876691605;4537.069900670354;4538.863834084983;1656.6686662667514;1913.5598812273256;1922.2599299269882;1925.5186203449139;1934.8258656720964;710.9217986021533;840.970243355145;876.7676565710092;971.8590804333332;989.7575094101848;20280.797192028185;21694.13223471075;21778.455538611663;21831.45421364447;21874.671879921814;935.4060171790961;966.2993132087436;985.84719368498;991.8958509356414;1003.7540802503433;10808.243422960317;10967.983057182011;11346.3759493227;11861;11909.70225744346;526829;582303;593985;596529;611016;117643;131273;132170.33914830364;133236;138428;989.5942663508071;1009.9596016159271;1036.2073014144187;1036.2953191829477;1038.382162613244;19134.7129793056;19402.902985484983;19529.316473923343;19539.747280869986;19544.21823127116;53519;53874;54982.17526737022;55257;55738.72130639321;76898.84651730116;78001.21998780167;79901;80432.59783701044;81267.78098328412;7817.452778305464;7828.921710783047;8220.301274391744;8231.547264900488;8397.916020839957;12779.80830287565;12804.871951280367;12870.67823304425;13300.334983250605;13347.866521334663;11239.494222759831;11419.230019347015;11477.770444591228;11542.249753765978;11592;10876.347419154876;10966.287191332538;11057.06014988735;11416.486258118572;11595.594154204562;11352.205345625727;11497.655070347902;11565.884341156481;11744.354060526754;11796.4691588877;6344.555881088464;7881.921180788118;7886.369090472634;8317.251447369767;8377.581120943807;8766.91233087661;10798.67603971882;10984.725381865517;11346.94326528362;11434.085273178292 10:03:25 INFO - PROCESS | 1644 | |3;mootools.html;1374.7044385457125;1443.9350229239685;1514.9091054536725;1738.8782785205033;1950.1711772496685;7794.37644988401;8123.106458289583;8380.28767554757;8495.490270583769;8622.827543449128;8557;8613.009503907053;8630.95684521578;8653.264472519844;8658.13418658134;2338.099831564847;2373.252425485975;2373.3354660694945;2376.429656882353;2377.72656144543;2059.2689595193715;2325.848819826706;2372.205311220743;2373.371056669984;2378.7502312257225;2325.6395258734838;2349.847259928099;2357.24568138195;2366.4320563064825;2372.1697405907967;2150.7204063471804;2204.798384880244;2293.8738369389675;2327.93016209514;2338.649202619604;2321.5124823787023;2324.848884822481;2325.232673217851;2325.2559181062065;2326.325365643961;8556.957215213884;8608.655653773842;8625.568721563897;8651.221390074861;8666.869996949988;5084.008618319429;5283.996040752245;5288.788448462057;5305.204219367126;5308.362996440452;3420.572428446444;3573.356795776759;3639.4176931690804;3645.7447978641508;3653.488511608377;1047.1047254597286;1050.5062620568322;1083.4257843342962;1087.6845714742715;1105.6019832860163;9780.902190977999;11172.888271117266;11219;11358.545658173663;11366.488508017159;4293.720908140961;4362.323839804835;4525.366448697119;4542.182407166676;4542.659300552472;8901;8912.197902188835;8919.509426981449;8931.508767017876;8980.640774369083;5181.6890986540175;5183.067047931333;5184.248283998799;5192.792288308501;5202.791888324425;4106.650934670586;4339.0020295331615;4497.100579884036;4527.9547204527535;4860.659753817199;3493.161641206143;3792.488014118114;3793.6585707286476;3794.4118661607495;3800.9239815204096;1006.959721611142;1138.299945533491;1166.854143232096;1178.4932479033926;1183.3728124094243;829.1045670675655;859.7850537365658;892.8883889513811;904.412132114118;919.8298314811781;5563.638363506359;5758.971205143947;5857.502112320415;5883.794067207713;6044.365341639064;944.4086713887123;944.5560586524323;953.7615596100975;965.4834663455158;1000.3397757480174;3518.331517011811;3819.8090095495113;3859.536855577349;3912.3544615138753;3972;9993.450360230112;10044.598216071421;10632.096271816827;10644.840327395095;10650.201234907257;10407.42759148239;10674.412907290174;10677.733056673645;10686.73283167927;10706.785864282827;7722.3049925506975;7881.448298619041;8045.15525869787;8134.918650813534;8146.30756385702;5140.56305214061;5414;5619.494245517923;5784.033646788108;5837.3870743571315;8905.421147625384;9860.013998600369;9971.351862128939;9973.551190196604;10043.146332561522;9594.760130996781;9725.708228753148;9929.702108936743;10018.3488073275;10123.64567240143;6976.825579360557;7185.640717964186;7193.568385896863;7200.603966781878;7210.459215558749;6936.549124306904;7990.400719946144;8143.918560814316;8163.306118979954;8203.671853125808;7343.632818359168;7350.154732205865;7370;7446.478746487694;7530.397568194641;7034.894476582753;7155.284471552803;7218.530795498275;7289.708411663474;7299.635018249172;6804.081449004321;6946;6949.027136201037;6966.3730264276455;6974.860502790018;4733.290006498915;4927.384076990376;4939.135651261086;4941.357623508921;5119.513646203604;4847.491013443538;4856.927146092741;5003.124453220745;5064.696118232918;5071.898562028812 10:03:25 INFO - PROCESS | 1644 | |4;prototype.html;258.5694818127817;290.36555127047404;293.6652216473221;303.1345508573025;306.20539699479843;4089.2843752343333;4903.485134060917;5073.467285934975;5089.847304580869;5202.843914682556;3855.9614403855953;4675.509071547481;5024.191291135188;5036.3200967869325;5136.409312929014;3650.926981460369;3906.667933225679;3947.8026098695095;3950.960490395102;3964.563897971228;3021.5165573508243;3939.340944862221;3949.526056873165;3992.141689536749;3993.4608827808165;3015.638123425192;3314.9834250828712;3424.2124311408393;3699.6670299673024;3939.128913765967;3413.8805141819917;3443.1908501502126;3900.843966241347;3908.7654740715507;3909.6285852844076;3101.9224519387194;3373.645767194458;3382;3393.52490651309;3404.42124838778;4281.935770963438;4411.977940110279;4977.7262250576205;5025.497450254982;5079.095339247782;4428.557144285578;4447.043885564619;4488.977555112204;4493.573110554492;4514.8194072237075;4473.680264322033;4951.579115775164;5399.163129714901;5430.266913966603;5447.5641948644015;3140.147313085268;3554.662307080823;3692.6122757110384;3703.6481534254203;3720.3675375186285;4932.410484495668;5496.642718223303;5640.407757185478;5693.17448969897;5708.514776244025;3715.6098609646137;3730.459083432887;3731.3097077040293;3741.9438708419398;3746.925061498755;3798.1199306343538;3820.8853734388013;3824.9426258606145;3834.003159178578;3837.2517359115263;3534.994068801878;4048.8743409710137;4439.357437748054;4446.399736035659;4471.396361491157;5439.809606663827;5466.781328746805;5484.341878974469;5485.369182543979;5488.7530061147345;1154.007553503986;1208.486393282869;1249.3066348176849;1252.642996745923;1275.979216626695;721.8917162425573;781.1914668318263;829.3282441222628;844.6916875340579;885.1502557544701;4139.9571315083385;4620.198122732138;4781.346352774243;5099.3370861787735;5101.770420331094;5241.711705856214;5333.066713325153;5341.866453338697;5603.7478313475995;5606;4783.56947874686;5081.5426611605135;5157.252198431206;5166.173412254021;5204.375474942955;2615.882285297166;2655.522006038893;2943.823370597771;2958.778091643135;2964.7924645274625;4705.541427051115;5151.415240436015;5574.888502229933;5789;5820.94179058204;4890.731009794425;5095.566876815438;5757.481826635623;5794.015017447045;5799.4780469757925;5155.561777248976;5786.479216870502;5787.131930210502;5845.620034697731;5894.469497745138;4776.498467660846;5405.297311349656;5536.48090119003;5616.606837521498;5694.608264528825;5565.6104072714525;5574.045755749459;5586.525145362527;5599.720013999202;5628.887422251614;4935.333729946411;5139.948600513947;5154.819581314636;5552.750126244409;5761.913571296518;3297.225152089305;3405.421078416725;3904.1606054698377;3918.7256892017285;3925.823337949742;4689;5660;5666.829995100154;5668.376478587435;5700.942990570041;4280.67894907877;4650.674767541224;5526.088195447706;5538.73214803268;5558.972205138948;4880.389951256093;5306.8938621228135;5367.302250707383;5538.283132229022;5562.189899918569;4437.489688685713;5037.395512538519;5301.602379821606;5334.253204551444;5440.4908833802765;5207.4271830099415;5386.926033477218;5421.647592906448;5475.315585551806;5535.723213839373;4495.52796956328;5380.515753582196;5383.358075786922;5415.079436495726;5431.456854314537 10:03:25 INFO - PROCESS | 1644 | |5;yui.html;587.3597778421521;737.2001378504323;799.9760007199786;836.514821403586;845.6194712379429;14284.071535350195;15357.616059598515;15553.222338883039;15729.449469268578;15753.921230393846;13653.317334133304;13716.725665486709;14275.21486318252;14295.928520357384;14595.927020364883;877.271864352589;882.5851849630664;889.1197714262865;895.5656506594297;897.7396554999042;894.5840184314293;894.7584152278881;896.0546623312403;897.107378158733;897.8383890899636;953.41365060488;955.1403736637013;957.1720461800562;957.9089986451276;961.9038096190395;942.1050002497586;942.8302905477011;943.4905151218368;943.815955888602;945.5414124149781;701.9964900175466;702.0802748399612;702.2380716922146;702.7610612391811;703.5180901082774;13221.471141154343;13664.863351366459;13673.179609223478;13691.452341906312;13699.45202191911;11788.587399441065;11942.582009629621;12065.939670301681;12163.51345946161;12598.68503287416;10583.523741431654;10874.836877446845;10882.23824332297;11099.445027748661;11216.831747523795;3612.6929211017095;3649.7627654202392;3654.0572911468157;3683.0424089736593;3835.7506762060375;65634.67182664103;71230;74218;77283.22716772817;104209.83160673564;11106.944465277622;11451.255668381695;11463.656090317303;11719.648410547697;11729.061675065974;23036.769632303796;23046;23057.769422305897;23085.653715194287;23124.075036998667;7870.212978702084;8157.306628936607;8173.918260817316;8190.672373105009;8239.340852731768;3905.5703872573995;4240.573473859561;4474.507804141542;4474.530174331713;4479.507254202035;3529.1177205698577;3666.175110600094;3722.571904231048;3728.3102626014274;3728.9813550932613;670.4468813229106;678.1116737074449;678.3047376439189;690.5028379566704;696.864111498253;634.9587276826992;641.2817644238389;667.5127157175192;673.6968364236113;675.3314218923231;8855.105944307797;8982.550872456351;9598.42409455435;9599.376040557341;9647.469389183521;483.84033269021137;549.1378535698994;638.9616623002635;645.9523311103718;677.5934439336457;3818.236352729465;4269.188854117708;4564.748938808331;4596.862094137181;4643.187442197602;112146.43926780314;117594.41202793931;117892;134897.65102349047;138423;112571.67710681906;113276;117275.41362293305;118939;120954;87418.68871966926;90736.54631726799;95340.52329738307;96193.5190324044;101512.47731284036;9486.28852836054;9687.2734544908;9810.460424676712;9845.310828241954;9866.210703143588;40458.39312410257;55646.443535564125;55810;56685.71657141688;56899.71550142223;84122;86623;87001.56499217464;89132.55433722789;89397;12841.165324253894;12987.870121299044;13258.403371848482;13414.664633384244;13644.863551364358;14275.286235688382;14590.927045364706;14619.859658943056;14839.480618178744;14995.700085998;13251.469941202244;13347.599572012856;13834.446622135405;13988.510402136284;14210.28948552589;13623.250721210428;13677.384517696926;13717.794233086712;14226.502072427415;14422.06256593318;11973.042156627624;12654.620361389174;13078.673033174247;13148.868511315148;13261.270630115436;10978.890211097787;11104.44477776124;11354.545818167511;11471.827922581;11504.94247528757;9560.406698564482;9722.125008748964;10231.874493805824;11285.661430157108;11491.425428728697 10:03:25 INFO - PROCESS | 1644 | __end_tp_report 10:03:25 INFO - PROCESS | 1644 | __start_cc_report 10:03:25 INFO - PROCESS | 1644 | _x_x_mozilla_cycle_collect,1508 10:03:25 INFO - PROCESS | 1644 | __end_cc_report 10:03:25 INFO - PROCESS | 1644 | __startTimestamp1476983005395__endTimestamp 10:03:25 INFO - PROCESS | 1644 | 10:03:25 INFO - PROCESS | 1644 | ------- Summary: start ------- 10:03:25 INFO - PROCESS | 1644 | Number of tests: 6 10:03:25 INFO - PROCESS | 1644 | 10:03:25 INFO - PROCESS | 1644 | [#0] dojo.html Cycles:165 Average:5636.88 Median:5867.88 stddev:681.83 (11.6%) stddev-sans-first:650.29 10:03:25 INFO - PROCESS | 1644 | Values: 2940.5 3278.7 3288.7 3301.1 3332.8 5494.7 5494.8 5567.2 5571.7 5629.7 5588.9 5608.6 5610.2 5618.2 5621.1 5086.4 5119.7 5124.3 5155.9 5211.7 5130.9 5153.7 5179.9 5221.1 5243.9 4451.0 5202.6 5224.8 5229.6 5275.3 5212.8 5216.8 5225.3 5247.2 5275.4 3233.2 4951.5 5062.9 5107.3 5111.3 5573.9 5576.6 5626.8 5642.2 5644.4 5824.7 5922.3 6149.8 6208.5 6213.0 6068.2 6087.7 6113.7 6117.9 6257.9 4749.7 5710.8 5711.4 5720.2 5729.2 6303.9 6323.0 6328.8 6349.1 6358.9 5824.7 5867.9 5868.4 6022.2 6022.8 6125.4 6138.2 6144.2 6200.9 6204.9 6053.1 6111.0 6123.8 6126.2 6137.5 5991.4 6068.4 6135.5 6138.0 6143.5 4807.6 4842.3 4848.6 4851.6 4853.2 4364.8 4392.7 4412.0 4413.2 4419.3 5635.0 5654.2 5657.9 5664.1 5664.9 6120.0 6126.3 6143.4 6155.4 6170.0 5649.5 5681.8 5684.3 5684.8 5689.7 5325.3 5327.8 5332.9 5335.1 5338.2 6237.3 6318.8 6323.8 6334.9 6346.2 6186.1 6233.9 6254.4 6255.5 6258.3 6196.2 6256.2 6282.2 6291.0 6292.3 6050.2 6097.0 6112.6 6124.7 6142.6 6054.7 6203.4 6219.9 6225.8 6235.5 4630.6 5841.0 5988.0 5993.9 6007.2 5875.0 5921.4 5944.5 5970.1 6039.6 4784.9 5897.9 5898.3 5900.3 5939.3 5934.9 6033.3 6043.6 6059.6 6075.0 5704.5 5764.9 5874.4 5909.1 5911.5 10:03:25 INFO - PROCESS | 1644 | 10:03:25 INFO - PROCESS | 1644 | [#1] ext.html Cycles:180 Average:67260.94 Median:39603.30 stddev:146250.03 (369.3%) stddev-sans-first:146574.37 10:03:25 INFO - PROCESS | 1644 | Values: 489.6 498.9 503.1 506.2 519.3 27609.6 40026.2 41139.8 41302.8 41313.0 42079.7 42412.2 42542.0 42610.6 42614.0 9415.0 9576.4 9596.7 9636.4 9651.0 9356.9 9456.2 9458.4 9464.9 9478.6 9435.2 9496.9 9501.2 9521.4 9542.6 9439.2 9473.7 9485.5 9490.0 9495.2 9388.5 9389.6 9478.7 9482.3 9498.1 39126.0 39521.2 39591.0 39615.6 39680.0 3195.5 3366.4 3428.0 3469.0 3510.8 30206.1 30473.4 30824.0 30832.2 30932.4 3078.6 3200.2 3202.0 3235.5 3287.8 468246.0 473340.0 473846.9 474217.0 476898.0 57922.7 58252.7 58314.8 58371.7 58414.7 31616.0 33244.8 34173.8 36060.3 60593.1 16104.8 16197.7 16225.9 16255.3 16269.5 12392.5 12494.0 12504.9 12506.9 12513.0 16268.4 18366.4 18755.4 18963.7 19179.6 7932.0 8081.5 8100.5 8178.5 8213.6 864.4 921.8 948.3 1032.9 1051.4 29415.6 29700.0 29816.3 30543.0 31048.8 994.5 1009.9 1074.1 1078.6 1138.9 14388.0 15059.0 15274.4 15453.0 15810.5 626432.0 631402.0 632371.0 632491.9 633265.0 57014.4 57396.7 57479.7 57564.1 57600.6 1225.8 1269.7 1342.7 1363.2 1374.6 30209.0 30387.8 30899.2 31397.0 31738.8 49012.3 49455.8 49489.3 49500.3 49596.5 523179.0 526550.0 527121.0 527600.0 527820.4 23100.9 23548.5 25034.0 25795.6 25851.9 39000.8 46305.3 46319.1 46684.5 47076.8 37955.3 38080.4 38101.0 39143.8 39758.8 38680.8 38942.0 40673.6 40817.6 42038.0 38006.2 38432.6 38708.5 38829.0 38910.4 32776.0 33670.8 34213.0 34358.8 34423.0 22828.5 25065.1 25365.1 25988.6 26143.6 10:03:25 INFO - PROCESS | 1644 | 10:03:25 INFO - PROCESS | 1644 | [#2] jquery.html Cycles:180 Average:34960.67 Median:13740.54 stddev:97772.31 (711.6%) stddev-sans-first:98012.05 10:03:25 INFO - PROCESS | 1644 | Values: 350.6 373.3 374.9 382.4 403.2 14802.6 15243.2 19353.6 21317.6 21535.0 20296.0 20697.4 20719.3 20770.3 20969.8 5778.6 6165.2 6167.7 6178.0 6200.6 5819.5 6005.2 6018.6 6056.9 6062.1 5962.6 5995.3 5995.5 6002.8 6005.0 5712.7 5979.3 6037.2 6042.4 6063.4 4658.4 4996.4 5219.4 5231.5 5239.2 25186.4 25470.1 25490.4 25790.5 25826.0 2196.2 2281.5 2294.7 2622.1 2632.6 9594.7 10245.0 10257.3 10302.7 10308.9 1658.0 1799.9 1855.4 1861.0 1876.4 102792.0 131717.0 132200.0 132493.0 133789.7 11113.7 11548.2 11574.4 11590.4 11765.9 12849.8 13376.9 13416.7 13425.7 14152.4 8276.1 8469.8 8471.6 8556.1 8600.6 6565.0 8331.9 8337.2 8473.9 8505.8 4367.6 4466.6 4482.4 4537.1 4538.9 1656.7 1913.6 1922.3 1925.5 1934.8 710.9 841.0 876.8 971.9 989.8 20280.8 21694.1 21778.5 21831.5 21874.7 935.4 966.3 985.8 991.9 1003.8 10808.2 10968.0 11346.4 11861.0 11909.7 526829.0 582303.0 593985.0 596529.0 611016.0 117643.0 131273.0 132170.3 133236.0 138428.0 989.6 1010.0 1036.2 1036.3 1038.4 19134.7 19402.9 19529.3 19539.7 19544.2 53519.0 53874.0 54982.2 55257.0 55738.7 76898.8 78001.2 79901.0 80432.6 81267.8 7817.5 7828.9 8220.3 8231.5 8397.9 12779.8 12804.9 12870.7 13300.3 13347.9 11239.5 11419.2 11477.8 11542.2 11592.0 10876.3 10966.3 11057.1 11416.5 11595.6 11352.2 11497.7 11565.9 11744.4 11796.5 6344.6 7881.9 7886.4 8317.3 8377.6 8766.9 10798.7 10984.7 11346.9 11434.1 10:03:25 INFO - PROCESS | 1644 | 10:03:25 INFO - PROCESS | 1644 | [#3] mootools.html Cycles:180 Average:5547.30 Median:5077.95 stddev:3081.26 (60.7%) stddev-sans-first:3073.95 10:03:25 INFO - PROCESS | 1644 | Values: 1374.7 1443.9 1514.9 1738.9 1950.2 7794.4 8123.1 8380.3 8495.5 8622.8 8557.0 8613.0 8631.0 8653.3 8658.1 2338.1 2373.3 2373.3 2376.4 2377.7 2059.3 2325.8 2372.2 2373.4 2378.8 2325.6 2349.8 2357.2 2366.4 2372.2 2150.7 2204.8 2293.9 2327.9 2338.6 2321.5 2324.8 2325.2 2325.3 2326.3 8557.0 8608.7 8625.6 8651.2 8666.9 5084.0 5284.0 5288.8 5305.2 5308.4 3420.6 3573.4 3639.4 3645.7 3653.5 1047.1 1050.5 1083.4 1087.7 1105.6 9780.9 11172.9 11219.0 11358.5 11366.5 4293.7 4362.3 4525.4 4542.2 4542.7 8901.0 8912.2 8919.5 8931.5 8980.6 5181.7 5183.1 5184.2 5192.8 5202.8 4106.7 4339.0 4497.1 4528.0 4860.7 3493.2 3792.5 3793.7 3794.4 3800.9 1007.0 1138.3 1166.9 1178.5 1183.4 829.1 859.8 892.9 904.4 919.8 5563.6 5759.0 5857.5 5883.8 6044.4 944.4 944.6 953.8 965.5 1000.3 3518.3 3819.8 3859.5 3912.4 3972.0 9993.5 10044.6 10632.1 10644.8 10650.2 10407.4 10674.4 10677.7 10686.7 10706.8 7722.3 7881.4 8045.2 8134.9 8146.3 5140.6 5414.0 5619.5 5784.0 5837.4 8905.4 9860.0 9971.4 9973.6 10043.1 9594.8 9725.7 9929.7 10018.3 10123.6 6976.8 7185.6 7193.6 7200.6 7210.5 6936.5 7990.4 8143.9 8163.3 8203.7 7343.6 7350.2 7370.0 7446.5 7530.4 7034.9 7155.3 7218.5 7289.7 7299.6 6804.1 6946.0 6949.0 6966.4 6974.9 4733.3 4927.4 4939.1 4941.4 5119.5 4847.5 4856.9 5003.1 5064.7 5071.9 10:03:25 INFO - PROCESS | 1644 | 10:03:25 INFO - PROCESS | 1644 | [#4] prototype.html Cycles:180 Average:4363.42 Median:4933.87 stddev:1369.10 (27.7%) stddev-sans-first:1337.82 10:03:25 INFO - PROCESS | 1644 | Values: 258.6 290.4 293.7 303.1 306.2 4089.3 4903.5 5073.5 5089.8 5202.8 3856.0 4675.5 5024.2 5036.3 5136.4 3650.9 3906.7 3947.8 3951.0 3964.6 3021.5 3939.3 3949.5 3992.1 3993.5 3015.6 3315.0 3424.2 3699.7 3939.1 3413.9 3443.2 3900.8 3908.8 3909.6 3101.9 3373.6 3382.0 3393.5 3404.4 4281.9 4412.0 4977.7 5025.5 5079.1 4428.6 4447.0 4489.0 4493.6 4514.8 4473.7 4951.6 5399.2 5430.3 5447.6 3140.1 3554.7 3692.6 3703.6 3720.4 4932.4 5496.6 5640.4 5693.2 5708.5 3715.6 3730.5 3731.3 3741.9 3746.9 3798.1 3820.9 3824.9 3834.0 3837.3 3535.0 4048.9 4439.4 4446.4 4471.4 5439.8 5466.8 5484.3 5485.4 5488.8 1154.0 1208.5 1249.3 1252.6 1276.0 721.9 781.2 829.3 844.7 885.2 4140.0 4620.2 4781.3 5099.3 5101.8 5241.7 5333.1 5341.9 5603.7 5606.0 4783.6 5081.5 5157.3 5166.2 5204.4 2615.9 2655.5 2943.8 2958.8 2964.8 4705.5 5151.4 5574.9 5789.0 5820.9 4890.7 5095.6 5757.5 5794.0 5799.5 5155.6 5786.5 5787.1 5845.6 5894.5 4776.5 5405.3 5536.5 5616.6 5694.6 5565.6 5574.0 5586.5 5599.7 5628.9 4935.3 5139.9 5154.8 5552.8 5761.9 3297.2 3405.4 3904.2 3918.7 3925.8 4689.0 5660.0 5666.8 5668.4 5700.9 4280.7 4650.7 5526.1 5538.7 5559.0 4880.4 5306.9 5367.3 5538.3 5562.2 4437.5 5037.4 5301.6 5334.3 5440.5 5207.4 5386.9 5421.6 5475.3 5535.7 4495.5 5380.5 5383.4 5415.1 5431.5 10:03:25 INFO - PROCESS | 1644 | 10:03:25 INFO - PROCESS | 1644 | [#5] yui.html Cycles:180 Average:22083.62 Median:3870.66 stddev:33573.30 (867.4%) stddev-sans-first:33628.68 10:03:25 INFO - PROCESS | 1644 | Values: 587.4 737.2 800.0 836.5 845.6 14284.1 15357.6 15553.2 15729.4 15753.9 13653.3 13716.7 14275.2 14295.9 14595.9 877.3 882.6 889.1 895.6 897.7 894.6 894.8 896.1 897.1 897.8 953.4 955.1 957.2 957.9 961.9 942.1 942.8 943.5 943.8 945.5 702.0 702.1 702.2 702.8 703.5 13221.5 13664.9 13673.2 13691.5 13699.5 11788.6 11942.6 12065.9 12163.5 12598.7 10583.5 10874.8 10882.2 11099.4 11216.8 3612.7 3649.8 3654.1 3683.0 3835.8 65634.7 71230.0 74218.0 77283.2 104209.8 11106.9 11451.3 11463.7 11719.6 11729.1 23036.8 23046.0 23057.8 23085.7 23124.1 7870.2 8157.3 8173.9 8190.7 8239.3 3905.6 4240.6 4474.5 4474.5 4479.5 3529.1 3666.2 3722.6 3728.3 3729.0 670.4 678.1 678.3 690.5 696.9 635.0 641.3 667.5 673.7 675.3 8855.1 8982.6 9598.4 9599.4 9647.5 483.8 549.1 639.0 646.0 677.6 3818.2 4269.2 4564.7 4596.9 4643.2 112146.4 117594.4 117892.0 134897.7 138423.0 112571.7 113276.0 117275.4 118939.0 120954.0 87418.7 90736.5 95340.5 96193.5 101512.5 9486.3 9687.3 9810.5 9845.3 9866.2 40458.4 55646.4 55810.0 56685.7 56899.7 84122.0 86623.0 87001.6 89132.6 89397.0 12841.2 12987.9 13258.4 13414.7 13644.9 14275.3 14590.9 14619.9 14839.5 14995.7 13251.5 13347.6 13834.4 13988.5 14210.3 13623.3 13677.4 13717.8 14226.5 14422.1 11973.0 12654.6 13078.7 13148.9 13261.3 10978.9 11104.4 11354.5 11471.8 11504.9 9560.4 9722.1 10231.9 11285.7 11491.4 10:03:25 INFO - PROCESS | 1644 | -------- Summary: end -------- 10:03:25 INFO - PROCESS | 1644 | 10:03:26 INFO - TEST-INFO | 1644: exit 0 10:03:26 INFO - TEST-OK | dromaeo_css | took 1118775ms 10:03:26 INFO - TEST-START | kraken 10:03:26 INFO - operating with platform_type : mac_ 10:03:26 INFO - Initialising browser for kraken test... 10:03:26 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/tmpvAIT_5/profile http://localhost:49273/getInfo.html) 10:03:28 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 10:03:28 INFO - PROCESS | 1654 | colorDepth:24 10:03:28 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 10:03:28 INFO - PROCESS | 1654 | __metrics 10:03:28 INFO - PROCESS | 1654 | 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:03:28 INFO - PROCESS | 1654 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:03:29 INFO - TEST-INFO | 1654: exit 0 10:03:29 INFO - Browser initialized. 10:03:29 INFO - Running cycle 1/1 for kraken test... 10:03:29 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/tmpvAIT_5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:03:41 INFO - PROCESS | 1655 | RSS: Main: 231845888 10:03:41 INFO - PROCESS | 1655 | 10:03:43 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection) 10:03:44 INFO - PROCESS | 1655 | RSS: Main: 290058240 10:03:44 INFO - PROCESS | 1655 | 10:03:47 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-dft) 10:03:48 INFO - PROCESS | 1655 | RSS: Main: 291254272 10:03:48 INFO - PROCESS | 1655 | 10:03:51 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-fft) 10:03:52 INFO - PROCESS | 1655 | RSS: Main: 384012288 10:03:52 INFO - PROCESS | 1655 | 10:03:54 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator) 10:03:54 INFO - PROCESS | 1655 | RSS: Main: 286019584 10:03:54 INFO - PROCESS | 1655 | 10:03:56 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:03:57 INFO - PROCESS | 1655 | RSS: Main: 290467840 10:03:57 INFO - PROCESS | 1655 | 10:04:00 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom) 10:04:00 INFO - PROCESS | 1655 | RSS: Main: 328691712 10:04:00 INFO - PROCESS | 1655 | 10:04:03 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate) 10:04:04 INFO - PROCESS | 1655 | RSS: Main: 330661888 10:04:04 INFO - PROCESS | 1655 | 10:04:07 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial) 10:04:07 INFO - PROCESS | 1655 | RSS: Main: 328282112 10:04:07 INFO - PROCESS | 1655 | 10:04:09 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:04:09 INFO - PROCESS | 1655 | RSS: Main: 302657536 10:04:09 INFO - PROCESS | 1655 | 10:04:11 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:04:12 INFO - PROCESS | 1655 | RSS: Main: 305348608 10:04:12 INFO - PROCESS | 1655 | 10:04:14 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:04:15 INFO - PROCESS | 1655 | RSS: Main: 309706752 10:04:15 INFO - PROCESS | 1655 | 10:04:18 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:04:18 INFO - PROCESS | 1655 | RSS: Main: 317366272 10:04:18 INFO - PROCESS | 1655 | 10:04:21 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:04:22 INFO - PROCESS | 1655 | RSS: Main: 316915712 10:04:22 INFO - PROCESS | 1655 | 10:04:24 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49273/tests/kraken/driver.html?testName=ai-astar) 10:04:24 INFO - PROCESS | 1655 | RSS: Main: 360919040 10:04:24 INFO - PROCESS | 1655 | 10:04:24 INFO - PROCESS | 1655 | __start_tp_report 10:04:24 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 10:04:24 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 10:04:24 INFO - PROCESS | 1655 | |i|pagename|runs| 10:04:24 INFO - PROCESS | 1655 | |0;ai-astar;102;112;114;114;114;115;113;113;113;113 10:04:24 INFO - PROCESS | 1655 | |1;audio-beat-detection;148;114;114;149;137;136;119;112;144;113 10:04:24 INFO - PROCESS | 1655 | |2;audio-dft;185;184;184;186;184;183;180;184;184;182 10:04:24 INFO - PROCESS | 1655 | |3;audio-fft;80;59;57;103;84;82;63;59;82;57 10:04:24 INFO - PROCESS | 1655 | |4;audio-oscillator;88;70;89;69;67;68;92;67;68;90 10:04:24 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;86;128;94;86;88;86;90;86;89;86 10:04:24 INFO - PROCESS | 1655 | |6;imaging-darkroom;96;95;96;95;95;95;95;107;147;107 10:04:24 INFO - PROCESS | 1655 | |7;imaging-desaturate;84;84;83;79;83;80;85;84;82;81 10:04:24 INFO - PROCESS | 1655 | |8;json-parse-financial;56;55;53;53;54;54;53;55;55;54 10:04:24 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;65;71;70;78;72;70;70;71;71;72 10:04:24 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;87;86;90;87;86;89;93;88;104;106 10:04:24 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;151;148;147;147;152;151;142;147;180;147 10:04:24 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;173;236;208;172;173;174;171;173;175;172 10:04:24 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;69;69;68;69;68;68;68;68;69;68 10:04:24 INFO - PROCESS | 1655 | __end_tp_report 10:04:24 INFO - PROCESS | 1655 | __start_cc_report 10:04:24 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,1960 10:04:24 INFO - PROCESS | 1655 | __end_cc_report 10:04:24 INFO - PROCESS | 1655 | __startTimestamp1476983064570__endTimestamp 10:04:24 INFO - PROCESS | 1655 | 10:04:24 INFO - PROCESS | 1655 | ------- Summary: start ------- 10:04:24 INFO - PROCESS | 1655 | Number of tests: 14 10:04:24 INFO - PROCESS | 1655 | 10:04:24 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:112.30 Median:113.50 stddev:3.71 (3.3%) stddev-sans-first:0.88 10:04:24 INFO - PROCESS | 1655 | Values: 102.0 112.0 114.0 114.0 114.0 115.0 113.0 113.0 113.0 113.0 10:04:24 INFO - PROCESS | 1655 | 10:04:24 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:128.60 Median:136.50 stddev:15.61 (11.4%) stddev-sans-first:14.89 10:04:24 INFO - PROCESS | 1655 | Values: 148.0 114.0 114.0 149.0 137.0 136.0 119.0 112.0 144.0 113.0 10:04:24 INFO - PROCESS | 1655 | 10:04:24 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:183.60 Median:184.00 stddev:1.65 (0.9%) stddev-sans-first:1.67 10:04:24 INFO - PROCESS | 1655 | Values: 185.0 184.0 184.0 186.0 184.0 183.0 180.0 184.0 184.0 182.0 10:04:24 INFO - PROCESS | 1655 | 10:04:24 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:72.60 Median:71.50 stddev:15.76 (22.0%) stddev-sans-first:16.48 10:04:24 INFO - PROCESS | 1655 | Values: 80.0 59.0 57.0 103.0 84.0 82.0 63.0 59.0 82.0 57.0 10:04:24 INFO - PROCESS | 1655 | 10:04:24 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:76.80 Median:79.00 stddev:11.22 (14.2%) stddev-sans-first:11.15 10:04:24 INFO - PROCESS | 1655 | Values: 88.0 70.0 89.0 69.0 67.0 68.0 92.0 67.0 68.0 90.0 10:04:24 INFO - PROCESS | 1655 | 10:04:24 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:91.90 Median:87.00 stddev:12.95 (14.9%) stddev-sans-first:13.56 10:04:24 INFO - PROCESS | 1655 | Values: 86.0 128.0 94.0 86.0 88.0 86.0 90.0 86.0 89.0 86.0 10:04:24 INFO - PROCESS | 1655 | 10:04:24 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:102.80 Median:95.00 stddev:16.28 (17.1%) stddev-sans-first:17.08 10:04:24 INFO - PROCESS | 1655 | Values: 96.0 95.0 96.0 95.0 95.0 95.0 95.0 107.0 147.0 107.0 10:04:24 INFO - PROCESS | 1655 | 10:04:24 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:82.50 Median:83.50 stddev:1.96 (2.3%) stddev-sans-first:2.00 10:04:24 INFO - PROCESS | 1655 | Values: 84.0 84.0 83.0 79.0 83.0 80.0 85.0 84.0 82.0 81.0 10:04:24 INFO - PROCESS | 1655 | 10:04:24 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:54.20 Median:54.50 stddev:1.03 (1.9%) stddev-sans-first:0.87 10:04:24 INFO - PROCESS | 1655 | Values: 56.0 55.0 53.0 53.0 54.0 54.0 53.0 55.0 55.0 54.0 10:04:24 INFO - PROCESS | 1655 | 10:04:24 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:71.00 Median:71.00 stddev:3.16 (4.5%) stddev-sans-first:2.50 10:04:24 INFO - PROCESS | 1655 | Values: 65.0 71.0 70.0 78.0 72.0 70.0 70.0 71.0 71.0 72.0 10:04:24 INFO - PROCESS | 1655 | 10:04:24 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:91.60 Median:87.50 stddev:7.38 (8.4%) stddev-sans-first:7.64 10:04:24 INFO - PROCESS | 1655 | Values: 87.0 86.0 90.0 87.0 86.0 89.0 93.0 88.0 104.0 106.0 10:04:24 INFO - PROCESS | 1655 | 10:04:24 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:151.20 Median:149.50 stddev:10.52 (7.0%) stddev-sans-first:11.16 10:04:24 INFO - PROCESS | 1655 | Values: 151.0 148.0 147.0 147.0 152.0 151.0 142.0 147.0 180.0 147.0 10:04:24 INFO - PROCESS | 1655 | 10:04:24 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.70 Median:173.50 stddev:21.77 (12.5%) stddev-sans-first:22.80 10:04:24 INFO - PROCESS | 1655 | Values: 173.0 236.0 208.0 172.0 173.0 174.0 171.0 173.0 175.0 172.0 10:04:24 INFO - PROCESS | 1655 | 10:04:24 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.40 Median:68.50 stddev:0.52 (0.8%) stddev-sans-first:0.50 10:04:24 INFO - PROCESS | 1655 | Values: 69.0 69.0 68.0 69.0 68.0 68.0 68.0 68.0 69.0 68.0 10:04:24 INFO - PROCESS | 1655 | -------- Summary: end -------- 10:04:24 INFO - PROCESS | 1655 | 10:04:25 INFO - TEST-INFO | 1655: exit 0 10:04:25 INFO - TEST-OK | kraken | took 59533ms 10:04:25 INFO - SUITE-END | took 1178s 10:04:25 INFO - Completed test suite (00:19:38) 10:04:25 INFO - javascript benchmark 10:04:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2940.485415052365, 3278.737700983923, 3288.671132886712, 3301.059198128534, 3332.766706330556, 5494.697791621459, 5494.835154945353, 5567.192757050226, 5571.665700057994, 5629.690367029807, 5588.91616625751, 5608.635438696482, 5610.186522954179, 5618.15727640854, 5621.072523033705, 5086.389633244006, 5119.667221630582, 5124.333836601237, 5155.948440515603, 5211.687298762086, 5130.9230361544605, 5153.6907785532985, 5179.922301165486, 5221.086309895772, 5243.86890327741, 4450.977745111286, 5202.609804264676, 5224.817131400402, 5229.60777941654, 5275.28783614211, 5212.817551385702, 5216.843494695166, 5225.346831646044, 5247.160454327309, 5275.419703832576, 3233.2157269999534, 4951.480094590088, 5062.873428164288, 5107.284980102789, 5111.258867464234, 5573.944260557383, 5576.581756368289, 5626.803061892814, 5642.238297829782, 5644.435556444364, 5824.679642619654, 5922.31893332268, 6149.84625384369, 6208.5033197344555, 6213.0, 6068.180795592582, 6087.726052327655, 6113.684880995636, 6117.929362361569, 6257.87484250317, 4749.715017098985, 5710.771569137188, 5711.428857114339, 5720.170575266647, 5729.169270455803, 6303.873922521524, 6323.0, 6328.778492752824, 6349.111124442582, 6358.936410635927, 5824.708764561839, 5867.882642347215, 5868.413158684098, 6022.2171117754415, 6022.789202377896, 6125.388069147376, 6138.2121944883065, 6144.170536977451, 6200.937990620127, 6204.937950620436, 6053.061775424816, 6111.008868573017, 6123.826041755113, 6126.172966649535, 6137.5302083125, 5991.430814072656, 6068.393160683896, 6135.478484328793, 6138.0, 6143.539234557426, 4807.567318941242, 4842.297866809293, 4848.636352273594, 4851.636127290397, 4853.223484242504, 4364.803583838735, 4392.736435813861, 4411.977940110343, 4413.183561041154, 4419.337099435111, 5635.013872572284, 5654.180143879114, 5657.915131273035, 5664.065429204217, 5664.8867022659315, 6119.99004232014, 6126.264848218153, 6143.389059649631, 6155.446009859044, 6170.043643235305, 5649.491545760901, 5681.772729090872, 5684.261046064068, 5684.8294551163535, 5689.743961521742, 5325.254464374991, 5327.786888524493, 5332.866678333073, 5335.11970524859, 5338.225957236256, 6237.313895471494, 6318.778842740482, 6323.810285691345, 6334.904976425358, 6346.2067241594805, 6186.133941248138, 6233.937660623335, 6254.437100660963, 6255.46828519581, 6258.3428739983465, 6196.1635179250225, 6256.249250090018, 6282.2147231596055, 6290.968545157245, 6292.339304373158, 6050.183225264709, 6097.0105204680185, 6112.5946701277735, 6124.74712746768, 6142.6314421134875, 6054.666993315233, 6203.4416902479, 6219.875602488016, 6225.750969961151, 6235.532335074979, 4630.58324750774, 5840.970795145997, 5988.0, 5993.880122397615, 6007.249093863267, 5875.030619947661, 5921.437463440966, 5944.494717949023, 5970.0746384310505, 6039.63762174271, 4784.880377990578, 5897.9115313271195, 5898.262717160355, 5900.321463031634, 5939.316978547522, 5934.881302373842, 6033.336333003454, 6043.637381757108, 6059.606125601822, 6074.969625151846, 5704.515116215002, 5764.913526297193, 5874.353821079763, 5909.054551271776, 5911.467967882912], "value": 5592.394281057054, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [489.59119135521837, 498.91983854954026, 503.101962996052, 506.24569391606497, 519.3300642171598, 27609.585856212147, 40026.1994760105, 41139.79430102853, 41302.793486032526, 41313.0, 42079.737607871735, 42412.15375155598, 42542.0, 42610.57389426105, 42614.0, 9414.95292523538, 9576.42541447512, 9596.664116755916, 9636.373635713691, 9650.951745241264, 9356.906430935705, 9456.243500519942, 9458.385204961656, 9464.905350946472, 9478.62085516582, 9435.245180385587, 9496.857547136799, 9501.19239864609, 9521.428714277166, 9542.618295268181, 9439.19766819821, 9473.66842160528, 9485.525723713787, 9490.003549627329, 9495.240380769557, 9388.530573471298, 9389.577469013911, 9478.715638530855, 9482.288828337902, 9498.09768072039, 39126.0, 39521.20957580832, 39591.0, 39615.60384396148, 39680.0, 3195.456772348683, 3366.4445366514497, 3427.9657203427896, 3469.0, 3510.80690562019, 30206.0938171853, 30473.39053218923, 30824.0, 30832.229194270098, 30932.381352373053, 3078.599782028344, 3200.2159470929537, 3201.9679803201902, 3235.514672799099, 3287.8356082196033, 468246.0, 473340.0, 473846.8922966158, 474217.0, 476898.0, 57922.71038644864, 58252.70873645604, 58314.8337033257, 58371.70814145902, 58414.7079264601, 31616.0, 33244.83377583145, 34173.82913085419, 36060.27879442397, 60593.09110363348, 16104.75842862358, 16197.676046479004, 16225.864189506858, 16255.268512916948, 16269.511914642342, 12392.504299828086, 12494.0, 12504.874951250553, 12506.936910362721, 12513.0, 16268.430604928772, 18366.357177498725, 18755.437336879644, 18963.715544266848, 19179.61640767177, 7932.008498937633, 8081.515109093473, 8100.513969161869, 8178.46839955401, 8213.589320533949, 864.3603733237359, 921.7787730944659, 948.2508818033676, 1032.912202462799, 1051.4479898053582, 29415.558766618517, 29700.0, 29816.254593635334, 30543.0, 31048.844755776077, 994.4878387630376, 1009.9042538845288, 1074.1245884604023, 1078.568572570978, 1138.9202755807014, 14388.0, 15059.0, 15274.389024438899, 15453.0, 15810.457451356746, 626432.0, 631402.0, 632371.0, 632491.8880272767, 633265.0, 57014.42985570174, 57396.71301643465, 57479.712601436724, 57564.13653795196, 57600.559986000684, 1225.822255772908, 1269.6762325607096, 1342.684469149749, 1363.1820907455447, 1374.5738820965532, 30209.0, 30387.848060759556, 30899.227519312197, 31397.0, 31738.841305793325, 49012.26481602708, 49455.75272123616, 49489.25766113439, 49500.25749613687, 49596.50403495919, 523179.0, 526550.0, 527121.0, 527600.0, 527820.3608981931, 23100.884495577415, 23548.529029418973, 25034.0, 25795.613065803653, 25851.870740646176, 39000.80499597484, 46305.3054204194, 46319.07361852811, 46684.53315466938, 47076.7646161767, 37955.27727198252, 38080.42879356867, 38101.0, 39143.82568522949, 39758.80723578297, 38680.80659596684, 38942.0, 40673.59326406698, 40817.59182408257, 42038.0, 38006.23987520179, 38432.615673842905, 38708.451661933206, 38829.0, 38910.4163437543, 32776.0, 33670.831645841616, 34212.97361079172, 34358.82820585881, 34422.96731098071, 22828.543429131656, 25065.122720704687, 25365.112221072173, 25988.61017084783, 26143.607845882707], "value": 19493.755980597183, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.55654596934863, 373.2832960715426, 374.9287635349284, 382.40917782026776, 403.1694708899667, 14802.629934251649, 15243.237838108093, 19353.616149124082, 21317.57364852702, 21535.0, 20295.98520073998, 20697.379078627626, 20719.274825381115, 20770.273040443586, 20969.790302096975, 5778.595498315109, 6165.167702360169, 6167.691615419233, 6178.0, 6200.565960382775, 5819.476247137757, 6005.2193214882, 6018.60879042863, 6056.878862422749, 6062.090686397031, 5962.612430192024, 5995.310539287994, 5995.520358371342, 6002.819915402545, 6004.96997515014, 5712.657240565559, 5979.34227235006, 6037.154798328237, 6042.395760423967, 6063.363346848583, 4658.440987081538, 4996.350474438336, 5219.373675159005, 5231.450697676765, 5239.161734122522, 25186.37034074163, 25470.108546200794, 25490.36274093144, 25790.484190316278, 25826.0, 2196.165457126298, 2281.494213818888, 2294.7016887804643, 2622.082271205082, 2632.5919482480244, 9594.712158635182, 10245.026722461354, 10257.281990260684, 10302.690919272434, 10308.896911030868, 1657.983420165795, 1799.9190036448392, 1855.378448219848, 1860.958537407542, 1876.3807943378652, 102792.0, 131717.0, 132199.99999999904, 132493.0, 133789.66210337868, 11113.666590002313, 11548.249363791327, 11574.363410012531, 11590.362530060927, 11765.941170294094, 12849.807252891027, 13376.866231337757, 13416.664583385493, 13425.731485370237, 14152.363143658355, 8276.08963014068, 8469.830603387898, 8471.576421178916, 8556.101609331055, 8600.5699715014, 6565.012919121788, 8331.875021874677, 8337.249647531751, 8473.872891906627, 8505.82988340242, 4367.6287515561535, 4466.57567531084, 4482.394876691605, 4537.069900670354, 4538.863834084983, 1656.6686662667514, 1913.5598812273256, 1922.2599299269882, 1925.5186203449139, 1934.8258656720964, 710.9217986021533, 840.970243355145, 876.7676565710092, 971.8590804333332, 989.7575094101848, 20280.797192028185, 21694.13223471075, 21778.455538611663, 21831.45421364447, 21874.671879921814, 935.4060171790961, 966.2993132087436, 985.84719368498, 991.8958509356414, 1003.7540802503433, 10808.243422960317, 10967.983057182011, 11346.3759493227, 11861.0, 11909.70225744346, 526829.0, 582303.0, 593985.0, 596529.0, 611016.0, 117643.0, 131273.0, 132170.33914830364, 133236.0, 138428.0, 989.5942663508071, 1009.9596016159271, 1036.2073014144187, 1036.2953191829477, 1038.382162613244, 19134.7129793056, 19402.902985484983, 19529.316473923343, 19539.747280869986, 19544.21823127116, 53519.0, 53874.0, 54982.17526737022, 55257.0, 55738.72130639321, 76898.84651730116, 78001.21998780167, 79901.0, 80432.59783701044, 81267.78098328412, 7817.452778305464, 7828.921710783047, 8220.301274391744, 8231.547264900488, 8397.916020839957, 12779.80830287565, 12804.871951280367, 12870.67823304425, 13300.334983250605, 13347.866521334663, 11239.494222759831, 11419.230019347015, 11477.770444591228, 11542.249753765978, 11592.0, 10876.347419154876, 10966.287191332538, 11057.06014988735, 11416.486258118572, 11595.594154204562, 11352.205345625727, 11497.655070347902, 11565.884341156481, 11744.354060526754, 11796.4691588877, 6344.555881088464, 7881.921180788118, 7886.369090472634, 8317.251447369767, 8377.581120943807, 8766.91233087661, 10798.67603971882, 10984.725381865517, 11346.94326528362, 11434.085273178292], "value": 9582.520481708409, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1374.7044385457125, 1443.9350229239685, 1514.9091054536725, 1738.8782785205033, 1950.1711772496685, 7794.37644988401, 8123.106458289583, 8380.28767554757, 8495.490270583769, 8622.827543449128, 8557.0, 8613.009503907053, 8630.95684521578, 8653.264472519844, 8658.13418658134, 2338.099831564847, 2373.252425485975, 2373.3354660694945, 2376.429656882353, 2377.72656144543, 2059.2689595193715, 2325.848819826706, 2372.205311220743, 2373.371056669984, 2378.7502312257225, 2325.6395258734838, 2349.847259928099, 2357.24568138195, 2366.4320563064825, 2372.1697405907967, 2150.7204063471804, 2204.798384880244, 2293.8738369389675, 2327.93016209514, 2338.649202619604, 2321.5124823787023, 2324.848884822481, 2325.232673217851, 2325.2559181062065, 2326.325365643961, 8556.957215213884, 8608.655653773842, 8625.568721563897, 8651.221390074861, 8666.869996949988, 5084.008618319429, 5283.996040752245, 5288.788448462057, 5305.204219367126, 5308.362996440452, 3420.572428446444, 3573.356795776759, 3639.4176931690804, 3645.7447978641508, 3653.488511608377, 1047.1047254597286, 1050.5062620568322, 1083.4257843342962, 1087.6845714742715, 1105.6019832860163, 9780.902190977999, 11172.888271117266, 11219.0, 11358.545658173663, 11366.488508017159, 4293.720908140961, 4362.323839804835, 4525.366448697119, 4542.182407166676, 4542.659300552472, 8901.0, 8912.197902188835, 8919.509426981449, 8931.508767017876, 8980.640774369083, 5181.6890986540175, 5183.067047931333, 5184.248283998799, 5192.792288308501, 5202.791888324425, 4106.650934670586, 4339.0020295331615, 4497.100579884036, 4527.9547204527535, 4860.659753817199, 3493.161641206143, 3792.488014118114, 3793.6585707286476, 3794.4118661607495, 3800.9239815204096, 1006.959721611142, 1138.299945533491, 1166.854143232096, 1178.4932479033926, 1183.3728124094243, 829.1045670675655, 859.7850537365658, 892.8883889513811, 904.412132114118, 919.8298314811781, 5563.638363506359, 5758.971205143947, 5857.502112320415, 5883.794067207713, 6044.365341639064, 944.4086713887123, 944.5560586524323, 953.7615596100975, 965.4834663455158, 1000.3397757480174, 3518.331517011811, 3819.8090095495113, 3859.536855577349, 3912.3544615138753, 3972.0, 9993.450360230112, 10044.598216071421, 10632.096271816827, 10644.840327395095, 10650.201234907257, 10407.42759148239, 10674.412907290174, 10677.733056673645, 10686.73283167927, 10706.785864282827, 7722.3049925506975, 7881.448298619041, 8045.15525869787, 8134.918650813534, 8146.30756385702, 5140.56305214061, 5414.0, 5619.494245517923, 5784.033646788108, 5837.3870743571315, 8905.421147625384, 9860.013998600369, 9971.351862128939, 9973.551190196604, 10043.146332561522, 9594.760130996781, 9725.708228753148, 9929.702108936743, 10018.3488073275, 10123.64567240143, 6976.825579360557, 7185.640717964186, 7193.568385896863, 7200.603966781878, 7210.459215558749, 6936.549124306904, 7990.400719946144, 8143.918560814316, 8163.306118979954, 8203.671853125808, 7343.632818359168, 7350.154732205865, 7370.0, 7446.478746487694, 7530.397568194641, 7034.894476582753, 7155.284471552803, 7218.530795498275, 7289.708411663474, 7299.635018249172, 6804.081449004321, 6946.0, 6949.027136201037, 6966.3730264276455, 6974.860502790018, 4733.290006498915, 4927.384076990376, 4939.135651261086, 4941.357623508921, 5119.513646203604, 4847.491013443538, 4856.927146092741, 5003.124453220745, 5064.696118232918, 5071.898562028812], "value": 4473.052490779116, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [258.5694818127817, 290.36555127047404, 293.6652216473221, 303.1345508573025, 306.20539699479843, 4089.2843752343333, 4903.485134060917, 5073.467285934975, 5089.847304580869, 5202.843914682556, 3855.9614403855953, 4675.509071547481, 5024.191291135188, 5036.3200967869325, 5136.409312929014, 3650.926981460369, 3906.667933225679, 3947.8026098695095, 3950.960490395102, 3964.563897971228, 3021.5165573508243, 3939.340944862221, 3949.526056873165, 3992.141689536749, 3993.4608827808165, 3015.638123425192, 3314.9834250828712, 3424.2124311408393, 3699.6670299673024, 3939.128913765967, 3413.8805141819917, 3443.1908501502126, 3900.843966241347, 3908.7654740715507, 3909.6285852844076, 3101.9224519387194, 3373.645767194458, 3382.0, 3393.52490651309, 3404.42124838778, 4281.935770963438, 4411.977940110279, 4977.7262250576205, 5025.497450254982, 5079.095339247782, 4428.557144285578, 4447.043885564619, 4488.977555112204, 4493.573110554492, 4514.8194072237075, 4473.680264322033, 4951.579115775164, 5399.163129714901, 5430.266913966603, 5447.5641948644015, 3140.147313085268, 3554.662307080823, 3692.6122757110384, 3703.6481534254203, 3720.3675375186285, 4932.410484495668, 5496.642718223303, 5640.407757185478, 5693.17448969897, 5708.514776244025, 3715.6098609646137, 3730.459083432887, 3731.3097077040293, 3741.9438708419398, 3746.925061498755, 3798.1199306343538, 3820.8853734388013, 3824.9426258606145, 3834.003159178578, 3837.2517359115263, 3534.994068801878, 4048.8743409710137, 4439.357437748054, 4446.399736035659, 4471.396361491157, 5439.809606663827, 5466.781328746805, 5484.341878974469, 5485.369182543979, 5488.7530061147345, 1154.007553503986, 1208.486393282869, 1249.3066348176849, 1252.642996745923, 1275.979216626695, 721.8917162425573, 781.1914668318263, 829.3282441222628, 844.6916875340579, 885.1502557544701, 4139.9571315083385, 4620.198122732138, 4781.346352774243, 5099.3370861787735, 5101.770420331094, 5241.711705856214, 5333.066713325153, 5341.866453338697, 5603.7478313475995, 5606.0, 4783.56947874686, 5081.5426611605135, 5157.252198431206, 5166.173412254021, 5204.375474942955, 2615.882285297166, 2655.522006038893, 2943.823370597771, 2958.778091643135, 2964.7924645274625, 4705.541427051115, 5151.415240436015, 5574.888502229933, 5789.0, 5820.94179058204, 4890.731009794425, 5095.566876815438, 5757.481826635623, 5794.015017447045, 5799.4780469757925, 5155.561777248976, 5786.479216870502, 5787.131930210502, 5845.620034697731, 5894.469497745138, 4776.498467660846, 5405.297311349656, 5536.48090119003, 5616.606837521498, 5694.608264528825, 5565.6104072714525, 5574.045755749459, 5586.525145362527, 5599.720013999202, 5628.887422251614, 4935.333729946411, 5139.948600513947, 5154.819581314636, 5552.750126244409, 5761.913571296518, 3297.225152089305, 3405.421078416725, 3904.1606054698377, 3918.7256892017285, 3925.823337949742, 4689.0, 5660.0, 5666.829995100154, 5668.376478587435, 5700.942990570041, 4280.67894907877, 4650.674767541224, 5526.088195447706, 5538.73214803268, 5558.972205138948, 4880.389951256093, 5306.8938621228135, 5367.302250707383, 5538.283132229022, 5562.189899918569, 4437.489688685713, 5037.395512538519, 5301.602379821606, 5334.253204551444, 5440.4908833802765, 5207.4271830099415, 5386.926033477218, 5421.647592906448, 5475.315585551806, 5535.723213839373, 4495.52796956328, 5380.515753582196, 5383.358075786922, 5415.079436495726, 5431.456854314537], "value": 3923.7652402932663, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [587.3597778421521, 737.2001378504323, 799.9760007199786, 836.514821403586, 845.6194712379429, 14284.071535350195, 15357.616059598515, 15553.222338883039, 15729.449469268578, 15753.921230393846, 13653.317334133304, 13716.725665486709, 14275.21486318252, 14295.928520357384, 14595.927020364883, 877.271864352589, 882.5851849630664, 889.1197714262865, 895.5656506594297, 897.7396554999042, 894.5840184314293, 894.7584152278881, 896.0546623312403, 897.107378158733, 897.8383890899636, 953.41365060488, 955.1403736637013, 957.1720461800562, 957.9089986451276, 961.9038096190395, 942.1050002497586, 942.8302905477011, 943.4905151218368, 943.815955888602, 945.5414124149781, 701.9964900175466, 702.0802748399612, 702.2380716922146, 702.7610612391811, 703.5180901082774, 13221.471141154343, 13664.863351366459, 13673.179609223478, 13691.452341906312, 13699.45202191911, 11788.587399441065, 11942.582009629621, 12065.939670301681, 12163.51345946161, 12598.68503287416, 10583.523741431654, 10874.836877446845, 10882.23824332297, 11099.445027748661, 11216.831747523795, 3612.6929211017095, 3649.7627654202392, 3654.0572911468157, 3683.0424089736593, 3835.7506762060375, 65634.67182664103, 71230.0, 74218.0, 77283.22716772817, 104209.83160673564, 11106.944465277622, 11451.255668381695, 11463.656090317303, 11719.648410547697, 11729.061675065974, 23036.769632303796, 23046.0, 23057.769422305897, 23085.653715194287, 23124.075036998667, 7870.212978702084, 8157.306628936607, 8173.918260817316, 8190.672373105009, 8239.340852731768, 3905.5703872573995, 4240.573473859561, 4474.507804141542, 4474.530174331713, 4479.507254202035, 3529.1177205698577, 3666.175110600094, 3722.571904231048, 3728.3102626014274, 3728.9813550932613, 670.4468813229106, 678.1116737074449, 678.3047376439189, 690.5028379566704, 696.864111498253, 634.9587276826992, 641.2817644238389, 667.5127157175192, 673.6968364236113, 675.3314218923231, 8855.105944307797, 8982.550872456351, 9598.42409455435, 9599.376040557341, 9647.469389183521, 483.84033269021137, 549.1378535698994, 638.9616623002635, 645.9523311103718, 677.5934439336457, 3818.236352729465, 4269.188854117708, 4564.748938808331, 4596.862094137181, 4643.187442197602, 112146.43926780314, 117594.41202793931, 117892.0, 134897.65102349047, 138423.0, 112571.67710681906, 113276.0, 117275.41362293305, 118939.0, 120954.0, 87418.68871966926, 90736.54631726799, 95340.52329738307, 96193.5190324044, 101512.47731284036, 9486.28852836054, 9687.2734544908, 9810.460424676712, 9845.310828241954, 9866.210703143588, 40458.39312410257, 55646.443535564125, 55810.0, 56685.71657141688, 56899.71550142223, 84122.0, 86623.0, 87001.56499217464, 89132.55433722789, 89397.0, 12841.165324253894, 12987.870121299044, 13258.403371848482, 13414.664633384244, 13644.863551364358, 14275.286235688382, 14590.927045364706, 14619.859658943056, 14839.480618178744, 14995.700085998, 13251.469941202244, 13347.599572012856, 13834.446622135405, 13988.510402136284, 14210.28948552589, 13623.250721210428, 13677.384517696926, 13717.794233086712, 14226.502072427415, 14422.06256593318, 11973.042156627624, 12654.620361389174, 13078.673033174247, 13148.868511315148, 13261.270630115436, 10978.890211097787, 11104.44477776124, 11354.545818167511, 11471.827922581, 11504.94247528757, 9560.406698564482, 9722.125008748964, 10231.874493805824, 11285.661430157108, 11491.425428728697], "value": 7622.379017557877, "unit": "score"}], "name": "dromaeo_css", "value": 7203.935503326353, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 112.0, 114.0, 114.0, 114.0, 115.0, 113.0, 113.0, 113.0, 113.0], "value": 112.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 114.0, 114.0, 149.0, 137.0, 136.0, 119.0, 112.0, 144.0, 113.0], "value": 128.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 184.0, 186.0, 184.0, 183.0, 180.0, 184.0, 184.0, 182.0], "value": 183.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 57.0, 103.0, 84.0, 82.0, 63.0, 59.0, 82.0, 57.0], "value": 72.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 89.0, 69.0, 67.0, 68.0, 92.0, 67.0, 68.0, 90.0], "value": 76.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 128.0, 94.0, 86.0, 88.0, 86.0, 90.0, 86.0, 89.0, 86.0], "value": 91.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 107.0, 147.0, 107.0], "value": 102.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 83.0, 79.0, 83.0, 80.0, 85.0, 84.0, 82.0, 81.0], "value": 82.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 53.0, 53.0, 54.0, 54.0, 53.0, 55.0, 55.0, 54.0], "value": 54.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 71.0, 70.0, 78.0, 72.0, 70.0, 70.0, 71.0, 71.0, 72.0], "value": 71.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 90.0, 87.0, 86.0, 89.0, 93.0, 88.0, 104.0, 106.0], "value": 91.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 148.0, 147.0, 147.0, 152.0, 151.0, 142.0, 147.0, 180.0, 147.0], "value": 151.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 236.0, 208.0, 172.0, 173.0, 174.0, 171.0, 173.0, 175.0, 172.0], "value": 182.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 68.0, 69.0, 68.0, 68.0, 68.0, 68.0, 69.0, 68.0], "value": 68.4, "unit": "score"}], "name": "kraken", "value": 1470.2, "alertThreshold": 2.0}]} 10:04:25 INFO - Return code: 0 10:04:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:04:25 INFO - # TBPL SUCCESS # 10:04:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:04:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:04:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:04:25 INFO - 2016-10-20 10:04:25.907 screenresolution[1659:9839] starting screenresolution argv=screenresolution get 10:04:25 INFO - 2016-10-20 10:04:25.910 screenresolution[1659:9839] Display 0: 1600x1200x32@60 10:04:25 INFO - 2016-10-20 10:04:25.916 screenresolution[1660:9842] starting screenresolution argv=screenresolution list 10:04:25 INFO - Available Modes on Display 0 10:04:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:04:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:04:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:04:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:04:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:04:26 INFO - Intel Iris: 10:04:26 INFO - Chipset Model: Intel Iris 10:04:26 INFO - Type: GPU 10:04:26 INFO - Bus: Built-In 10:04:26 INFO - VRAM (Dynamic, Max): 1536 MB 10:04:26 INFO - Vendor: Intel (0x8086) 10:04:26 INFO - Device ID: 0x0a2e 10:04:26 INFO - Revision ID: 0x0009 10:04:26 INFO - Displays: 10:04:26 INFO - Display: 10:04:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:04:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:04:26 INFO - Display Serial Number: 1600x1200 60 10:04:26 INFO - Main Display: Yes 10:04:26 INFO - Mirror: Off 10:04:26 INFO - Online: Yes 10:04:26 INFO - Rotation: Supported 10:04:26 INFO - Return code: 0 10:04:26 INFO - Running post-action listener: _resource_record_post_action 10:04:26 INFO - [mozharness: 2016-10-20 17:04:26.160015Z] Finished run-tests step (success) 10:04:26 INFO - Running post-run listener: _resource_record_post_run 10:04:26 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 38609920; Write bytes: 708536832; Read time: 374; Write time: 1863 10:04:26 INFO - TinderboxPrint: CPU usage
53.7% 10:04:26 INFO - TinderboxPrint: I/O read bytes / time
38,609,920 / 374 10:04:26 INFO - TinderboxPrint: I/O write bytes / time
708,536,832 / 1,863 10:04:26 INFO - TinderboxPrint: CPU idle
2,247.7 (46.3%) 10:04:26 INFO - TinderboxPrint: CPU system
975.0 (20.1%) 10:04:26 INFO - TinderboxPrint: CPU user
1,635.0 (33.7%) 10:04:26 INFO - TinderboxPrint: Swap in / out
445,071,360 / 0 10:04:26 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 407214592; Write bytes: 409262080; Read time: 30872; Write time: 748 10:04:26 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 34616320; Write bytes: 297792000; Read time: 345; Write time: 1109 10:04:26 INFO - Running post-run listener: _upload_blobber_files 10:04:26 INFO - Blob upload gear active. 10:04:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:04:26 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 10:04:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:04:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:04:27 INFO - (blobuploader) - INFO - Open directory for files ... 10:04:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:04:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:04:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:04:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:04:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:04:27 INFO - (blobuploader) - INFO - Done attempting. 10:04:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:04:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:04:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:04:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:04:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:04:28 INFO - (blobuploader) - INFO - Done attempting. 10:04:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:04:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:04:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:04:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:04:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:04:31 INFO - (blobuploader) - INFO - Done attempting. 10:04:31 INFO - (blobuploader) - INFO - Iteration through files over. 10:04:31 INFO - Return code: 0 10:04:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:04:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:04:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/220db0b87d593dd31be114edec3aa1e7c4231264b35440fd41958b3fafde2a75e7e79974f2835906dac012361468262722cea9e5c666616cf0910716f88620a3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4037e85a897a4a460f4f1ff2f09c68d474ee42471887c65fba079a0e4950f7ac802fb9c07da0be4b8560412e5575359b64247377545ecc89f1b5509c1ee12403", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d0a03d4f27f243ef6d96d0a95693196610cbf2f8bdb8a003f0077de5d901085139ba81486aeb70133e2f88ed30f6c656f31a893cc86ac810f4b163e2e02531f7"} 10:04:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:04:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:04:31 INFO - Contents: 10:04:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/220db0b87d593dd31be114edec3aa1e7c4231264b35440fd41958b3fafde2a75e7e79974f2835906dac012361468262722cea9e5c666616cf0910716f88620a3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4037e85a897a4a460f4f1ff2f09c68d474ee42471887c65fba079a0e4950f7ac802fb9c07da0be4b8560412e5575359b64247377545ecc89f1b5509c1ee12403", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d0a03d4f27f243ef6d96d0a95693196610cbf2f8bdb8a003f0077de5d901085139ba81486aeb70133e2f88ed30f6c656f31a893cc86ac810f4b163e2e02531f7"} 10:04:31 INFO - Running post-run listener: copy_logs_to_upload_dir 10:04:31 INFO - Copying logs to upload dir... 10:04:31 INFO - mkdir: /builds/slave/test/build/upload/logs 10:04:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.174033 ========= master_lag: 0.15 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-10-20 10:04:31.748504) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 3 secs) (at 2016-10-20 10:04:31.751508) ========= 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.P9FeILN9Hn/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.85Ya9q3yTA/Listeners TMPDIR=/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/220db0b87d593dd31be114edec3aa1e7c4231264b35440fd41958b3fafde2a75e7e79974f2835906dac012361468262722cea9e5c666616cf0910716f88620a3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4037e85a897a4a460f4f1ff2f09c68d474ee42471887c65fba079a0e4950f7ac802fb9c07da0be4b8560412e5575359b64247377545ecc89f1b5509c1ee12403", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d0a03d4f27f243ef6d96d0a95693196610cbf2f8bdb8a003f0077de5d901085139ba81486aeb70133e2f88ed30f6c656f31a893cc86ac810f4b163e2e02531f7"} build_url:https://queue.taskcluster.net/v1/task/CVNtsFmST8GptvQawfFZZg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011002 build_url: 'https://queue.taskcluster.net/v1/task/CVNtsFmST8GptvQawfFZZg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/220db0b87d593dd31be114edec3aa1e7c4231264b35440fd41958b3fafde2a75e7e79974f2835906dac012361468262722cea9e5c666616cf0910716f88620a3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4037e85a897a4a460f4f1ff2f09c68d474ee42471887c65fba079a0e4950f7ac802fb9c07da0be4b8560412e5575359b64247377545ecc89f1b5509c1ee12403", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d0a03d4f27f243ef6d96d0a95693196610cbf2f8bdb8a003f0077de5d901085139ba81486aeb70133e2f88ed30f6c656f31a893cc86ac810f4b163e2e02531f7"}' ========= master_lag: 3.46 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 3 secs) (at 2016-10-20 10:04:35.224981) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 10:04:35.225337) ========= 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.P9FeILN9Hn/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.85Ya9q3yTA/Listeners TMPDIR=/var/folders/jj/s59qmh6s1dz_jfxlkd9gr08000000w/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.004843 ========= master_lag: 0.35 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 10:04:35.576007) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-20 10:04:35.576349) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-20 10:04:35.577141) ========= ========= Total master_lag: 4.02 =========