builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0374 starttime: 1478797307.5 results: success (0) buildid: 20161110061022 builduid: 1c67273b918d45e594501a50855c7138 revision: d2e33428e45718bbeff192e80fd3a75ad264210d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-10 09:01:47.501377) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-10 09:01:47.501861) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-10 09:01:47.502267) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-10 09:01:47.529476) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-10 09:01:47.530182) ========= 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.MMANeWiGp6/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.vAN9dpeYnu/Listeners TMPDIR=/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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-11-10 09:01:47-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 30.0M=0s 2016-11-10 09:01:47 (30.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.153525 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-10 09:01:47.707255) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-10 09:01:47.707571) ========= 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.MMANeWiGp6/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.vAN9dpeYnu/Listeners TMPDIR=/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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.025293 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-10 09:01:47.755870) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-10 09:01:47.756259) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d2e33428e45718bbeff192e80fd3a75ad264210d --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 d2e33428e45718bbeff192e80fd3a75ad264210d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.MMANeWiGp6/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.vAN9dpeYnu/Listeners TMPDIR=/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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-11-10 09:01:47,844 truncating revision to first 12 chars 2016-11-10 09:01:47,844 Setting DEBUG logging. 2016-11-10 09:01:47,844 attempt 1/10 2016-11-10 09:01:47,844 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d2e33428e457?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-10 09:01:48,527 unpacking tar archive at: mozilla-central-d2e33428e457/testing/mozharness/ program finished with exit code 0 elapsedTime=0.938626 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-10 09:01:48.717676) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-10 09:01:48.718052) ========= script_repo_revision: d2e33428e45718bbeff192e80fd3a75ad264210d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-10 09:01:48.718509) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-10 09:01:48.718798) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-10 09:01:48.737119) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-11-10 09:01:48.737515) ========= /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.MMANeWiGp6/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.vAN9dpeYnu/Listeners TMPDIR=/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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:01:48 INFO - MultiFileLogger online at 20161110 09:01:48 in /builds/slave/test 09:01:48 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:01:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:01:48 INFO - {'append_to_log': False, 09:01:48 INFO - 'base_work_dir': '/builds/slave/test', 09:01:48 INFO - 'blob_upload_branch': 'Firefox', 09:01:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:01:48 INFO - 'branch': 'Firefox', 09:01:48 INFO - 'buildbot_json_path': 'buildprops.json', 09:01:48 INFO - 'config_files': ('talos/mac_config.py',), 09:01:48 INFO - 'default_actions': ('clobber', 09:01:48 INFO - 'read-buildbot-config', 09:01:48 INFO - 'download-and-extract', 09:01:48 INFO - 'populate-webroot', 09:01:48 INFO - 'create-virtualenv', 09:01:48 INFO - 'install', 09:01:48 INFO - 'run-tests'), 09:01:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:01:48 INFO - 'download_minidump_stackwalk': True, 09:01:48 INFO - 'download_symbols': 'ondemand', 09:01:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:01:48 INFO - 'tooltool.py': '/tools/tooltool.py', 09:01:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:01:48 INFO - '/tools/misc-python/virtualenv.py')}, 09:01:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:01:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:01:48 INFO - 'installer_path': 'installer.exe', 09:01:48 INFO - 'log_level': 'info', 09:01:48 INFO - 'log_name': 'talos', 09:01:48 INFO - 'log_to_console': True, 09:01:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:01:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:01:48 INFO - 'opt_config_files': (), 09:01:48 INFO - 'pip_index': False, 09:01:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:01:48 INFO - 'cmd': ('bash', 09:01:48 INFO - '-c', 09:01:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:01:48 INFO - 'enabled': True, 09:01:48 INFO - 'halt_on_failure': False, 09:01:48 INFO - 'name': 'check_screen_resolution'},), 09:01:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:01:48 INFO - 'cmd': ('bash', 09:01:48 INFO - '-c', 09:01:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:01:48 INFO - 'enabled': True, 09:01:48 INFO - 'halt_on_failure': False, 09:01:48 INFO - 'name': 'check_screen_resolution'},), 09:01:48 INFO - 'run_cmd_checks_enabled': True, 09:01:48 INFO - 'sps_profile': False, 09:01:48 INFO - 'sps_profile_interval': 0, 09:01:48 INFO - 'suite': 'dromaeojs', 09:01:48 INFO - 'system_bits': '32', 09:01:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:01:48 INFO - 'title': 't-yosemite-r7-0374', 09:01:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:01:48 INFO - 'use_talos_json': True, 09:01:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:01:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:01:48 INFO - 'work_dir': 'build'} 09:01:48 INFO - [mozharness: 2016-11-10 17:01:48.946637Z] Running clobber step. 09:01:48 INFO - Running pre-action listener: _resource_record_pre_action 09:01:48 INFO - Running main action method: clobber 09:01:48 INFO - rmtree: /builds/slave/test/build 09:01:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:01:51 INFO - Running post-action listener: _resource_record_post_action 09:01:51 INFO - [mozharness: 2016-11-10 17:01:51.204378Z] Finished clobber step (success) 09:01:51 INFO - [mozharness: 2016-11-10 17:01:51.204557Z] Running read-buildbot-config step. 09:01:51 INFO - Running pre-action listener: _resource_record_pre_action 09:01:51 INFO - Running main action method: read_buildbot_config 09:01:51 INFO - Using buildbot properties: 09:01:51 INFO - { 09:01:51 INFO - "project": "", 09:01:51 INFO - "product": "firefox", 09:01:51 INFO - "script_repo_revision": "production", 09:01:51 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 09:01:51 INFO - "repository": "", 09:01:51 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 09:01:51 INFO - "buildid": "20161110061022", 09:01:51 INFO - "pgo_build": "False", 09:01:51 INFO - "basedir": "/builds/slave/test", 09:01:51 INFO - "buildnumber": 3, 09:01:51 INFO - "slavename": "t-yosemite-r7-0374", 09:01:51 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 09:01:51 INFO - "platform": "yosemite_r7", 09:01:51 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:01:51 INFO - "branch": "mozilla-central", 09:01:51 INFO - "repo_path": "mozilla-central", 09:01:51 INFO - "revision": "d2e33428e45718bbeff192e80fd3a75ad264210d", 09:01:51 INFO - "stage_platform": "macosx64", 09:01:51 INFO - "builduid": "1c67273b918d45e594501a50855c7138", 09:01:51 INFO - "slavebuilddir": "test" 09:01:51 INFO - } 09:01:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/CVQAqw-kS2O8RYxxSZMCWg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 09:01:51 INFO - Running post-action listener: _resource_record_post_action 09:01:51 INFO - [mozharness: 2016-11-10 17:01:51.207800Z] Finished read-buildbot-config step (success) 09:01:51 INFO - [mozharness: 2016-11-10 17:01:51.207922Z] Running download-and-extract step. 09:01:51 INFO - Running pre-action listener: _resource_record_pre_action 09:01:51 INFO - Running main action method: download_and_extract 09:01:51 INFO - mkdir: /builds/slave/test/build/tests 09:01: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:01:51 INFO - trying https://queue.taskcluster.net/v1/task/CVQAqw-kS2O8RYxxSZMCWg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 09:01:51 INFO - Downloading https://queue.taskcluster.net/v1/task/CVQAqw-kS2O8RYxxSZMCWg/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:01:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CVQAqw-kS2O8RYxxSZMCWg/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:01:52 INFO - Downloaded 1199 bytes. 09:01:52 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 09:01:52 INFO - Using the following test package requirements: 09:01:52 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 09:01:52 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:01:52 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 09:01:52 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:01:52 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 09:01:52 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:01:52 INFO - u'jsshell-mac.zip'], 09:01:52 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:01:52 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 09:01:52 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 09:01:52 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:01:52 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 09:01:52 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:01:52 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 09:01:52 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:01:52 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 09:01:52 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:01:52 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 09:01:52 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:01:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CVQAqw-kS2O8RYxxSZMCWg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 09:01:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CVQAqw-kS2O8RYxxSZMCWg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:01:52 INFO - Fetch https://queue.taskcluster.net/v1/task/CVQAqw-kS2O8RYxxSZMCWg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 09:01:53 INFO - Http code: 200 09:01:53 INFO - via: 1.1 20f1c35f343f4b271ae8dcacfd7ea0e9.cloudfront.net (CloudFront) 09:01:53 INFO - x-amz-cf-id: 6rbCtiiuvcZTJ0A5fWEkzN2fy8NHlzrejjpOL1e9Sjm5s__HShGucw== 09:01:53 INFO - x-amz-version-id: d5dqv_0tSjcjAsjyKa.s7px_Jxo1AFOJ 09:01:53 INFO - Expected file size: 16320424 09:01:53 INFO - Obtained file size: 16320424 09:02:00 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:02:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CVQAqw-kS2O8RYxxSZMCWg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 09:02:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CVQAqw-kS2O8RYxxSZMCWg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:02:00 INFO - Fetch https://queue.taskcluster.net/v1/task/CVQAqw-kS2O8RYxxSZMCWg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 09:02:00 INFO - Http code: 200 09:02:00 INFO - via: 1.1 441811a054e8d055b893175754efd0c3.cloudfront.net (CloudFront) 09:02:00 INFO - x-amz-cf-id: -l42UDcx_4u-FDeTz7XdIWYnW0Lq0LTZYjFWI1jfVzU-4BWq7EoqhQ== 09:02:00 INFO - x-amz-version-id: d5dqv_0tSjcjAsjyKa.s7px_Jxo1AFOJ 09:02:01 INFO - Expected file size: 16320424 09:02:01 INFO - Obtained file size: 16320424 09:02:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CVQAqw-kS2O8RYxxSZMCWg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 09:02:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CVQAqw-kS2O8RYxxSZMCWg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:02:07 INFO - Fetch https://queue.taskcluster.net/v1/task/CVQAqw-kS2O8RYxxSZMCWg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 09:02:07 INFO - Http code: 200 09:02:07 INFO - via: 1.1 66ed69e8b47ad05050331602c798132f.cloudfront.net (CloudFront) 09:02:07 INFO - x-amz-cf-id: yse0fkK-hNvaV94C2E4A-plz6_Ws50-ZbSFwMwNvmXuzHo1VzPQezQ== 09:02:07 INFO - x-amz-version-id: 6a_AsLdSIruA5RMRimDuGG5ubGq6E_cm 09:02:08 INFO - Expected file size: 14409038 09:02:08 INFO - Obtained file size: 14409038 09:02:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:08 INFO - trying https://queue.taskcluster.net/v1/task/CVQAqw-kS2O8RYxxSZMCWg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 09:02:08 INFO - Downloading https://queue.taskcluster.net/v1/task/CVQAqw-kS2O8RYxxSZMCWg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 09:02:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CVQAqw-kS2O8RYxxSZMCWg/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:02:11 INFO - Downloaded 110967467 bytes. 09:02:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CVQAqw-kS2O8RYxxSZMCWg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 09:02:11 INFO - mkdir: /builds/slave/test/properties 09:02:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:02:11 INFO - Writing to file /builds/slave/test/properties/build_url 09:02:11 INFO - Contents: 09:02:11 INFO - build_url:https://queue.taskcluster.net/v1/task/CVQAqw-kS2O8RYxxSZMCWg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 09:02:12 INFO - Running post-action listener: _resource_record_post_action 09:02:12 INFO - Running post-action listener: set_extra_try_arguments 09:02:12 INFO - [mozharness: 2016-11-10 17:02:12.186673Z] Finished download-and-extract step (success) 09:02:12 INFO - [mozharness: 2016-11-10 17:02:12.186767Z] Running populate-webroot step. 09:02:12 INFO - Running pre-action listener: _resource_record_pre_action 09:02:12 INFO - Running main action method: populate_webroot 09:02:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:02:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:02:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:02:12 INFO - '--tppagecycles', 09:02:12 INFO - '7'], 09:02:12 INFO - 'tests': ['tsvgm']}}, 09:02:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:02:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:02:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:02:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:02:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:02:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:02:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:12 INFO - 'talos_options': ['--disable-e10s'], 09:02:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:02:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:02:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:12 INFO - 'talos_options': ['--disable-e10s'], 09:02:12 INFO - 'tests': ['damp', 'tps']}, 09:02:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:12 INFO - 'tests': ['damp', 'tps']}, 09:02:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:02:12 INFO - 'tests': ['dromaeo_dom']}, 09:02:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:02:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:02:12 INFO - 'tests': ['basic_compositor_video']}, 09:02:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:02:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:02:12 INFO - 'tests': ['a11yr', 09:02:12 INFO - 'ts_paint', 09:02:12 INFO - 'tpaint', 09:02:12 INFO - 'sessionrestore', 09:02:12 INFO - 'sessionrestore_no_auto_restore', 09:02:12 INFO - 'tabpaint']}, 09:02:12 INFO - 'other-e10s': {'tests': ['a11yr', 09:02:12 INFO - 'ts_paint', 09:02:12 INFO - 'tpaint', 09:02:12 INFO - 'sessionrestore', 09:02:12 INFO - 'sessionrestore_no_auto_restore', 09:02:12 INFO - 'tabpaint']}, 09:02:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:02:12 INFO - 'ts_paint', 09:02:12 INFO - 'tpaint', 09:02:12 INFO - 'sessionrestore', 09:02:12 INFO - 'sessionrestore_no_auto_restore', 09:02:12 INFO - 'tabpaint']}, 09:02:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:02:12 INFO - 'ts_paint', 09:02:12 INFO - 'tpaint', 09:02:12 INFO - 'sessionrestore', 09:02:12 INFO - 'sessionrestore_no_auto_restore', 09:02:12 INFO - 'tabpaint']}, 09:02:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:02:12 INFO - 'tests': ['a11yr', 09:02:12 INFO - 'ts_paint', 09:02:12 INFO - 'tpaint', 09:02:12 INFO - 'sessionrestore', 09:02:12 INFO - 'sessionrestore_no_auto_restore', 09:02:12 INFO - 'tabpaint']}, 09:02:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:02:12 INFO - 'tests': ['a11yr', 09:02:12 INFO - 'ts_paint', 09:02:12 INFO - 'tpaint', 09:02:12 INFO - 'sessionrestore', 09:02:12 INFO - 'sessionrestore_no_auto_restore', 09:02:12 INFO - 'tabpaint']}, 09:02:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:02:12 INFO - 'tests': ['tsvgx', 09:02:12 INFO - 'tsvgr_opacity', 09:02:12 INFO - 'tart', 09:02:12 INFO - 'tscrollx', 09:02:12 INFO - 'cart']}, 09:02:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:02:12 INFO - 'tsvgr_opacity', 09:02:12 INFO - 'tart', 09:02:12 INFO - 'tscrollx', 09:02:12 INFO - 'cart']}, 09:02:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:12 INFO - 'talos_options': ['--disable-e10s'], 09:02:12 INFO - 'tests': ['tp5o']}, 09:02:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:12 INFO - 'tests': ['tp5o']}, 09:02:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:12 INFO - 'talos_options': ['--disable-e10s', 09:02:12 INFO - '--xperf_path', 09:02:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:02:12 INFO - 'tests': ['tp5n']}, 09:02:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:12 INFO - 'talos_options': ['--xperf_path', 09:02:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:02:12 INFO - 'tests': ['tp5n']}}, 09:02:12 INFO - 'talos.zip': {'path': '', 09:02:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:02:12 INFO - Running post-action listener: _resource_record_post_action 09:02:12 INFO - [mozharness: 2016-11-10 17:02:12.205778Z] Finished populate-webroot step (success) 09:02:12 INFO - [mozharness: 2016-11-10 17:02:12.205874Z] Running create-virtualenv step. 09:02:12 INFO - Running pre-action listener: _resource_record_pre_action 09:02:12 INFO - Running main action method: create_virtualenv 09:02:12 INFO - Creating virtualenv /builds/slave/test/build/venv 09:02:12 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:02:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:02:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:02:12 INFO - Using real prefix '/tools/python27' 09:02:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:02:13 INFO - Installing distribute.............................................................................................................................................................................................done. 09:02:15 INFO - Installing pip.................done. 09:02:15 INFO - Return code: 0 09:02:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:02:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:02:16 INFO - Reading from file tmpfile_stdout 09:02:16 INFO - Output received: 09:02:16 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:02:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:02:16 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:02:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:16 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:02:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:16 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 0x107ec7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e3d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa063d5ef00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cdbd30>, '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 0x107b01db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cd3b20>, '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.MMANeWiGp6/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.vAN9dpeYnu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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:02:16 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:02:16 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:02:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MMANeWiGp6/Render', 09:02:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:16 INFO - 'HOME': '/Users/cltbld', 09:02:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:16 INFO - 'LOGNAME': 'cltbld', 09:02:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:16 INFO - 'MOZ_NO_REMOTE': '1', 09:02:16 INFO - 'NO_EM_RESTART': '1', 09:02:16 INFO - 'PAGER': '/bin/cat', 09:02:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:16 INFO - 'PWD': '/builds/slave/test', 09:02:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:16 INFO - 'SHELL': '/bin/bash', 09:02:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vAN9dpeYnu/Listeners', 09:02:16 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 09:02:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:16 INFO - 'USER': 'cltbld', 09:02:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:16 INFO - 'XPC_FLAGS': '0x0', 09:02:16 INFO - 'XPC_SERVICE_NAME': '0', 09:02:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:16 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:02:16 INFO - Cleaning up... 09:02:16 INFO - Return code: 0 09:02:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:02:16 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:02:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:16 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:02:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:16 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 0x107ec7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e3d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa063d5ef00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cdbd30>, '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 0x107b01db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cd3b20>, '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.MMANeWiGp6/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.vAN9dpeYnu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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:02:16 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:02:16 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:02:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MMANeWiGp6/Render', 09:02:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:16 INFO - 'HOME': '/Users/cltbld', 09:02:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:16 INFO - 'LOGNAME': 'cltbld', 09:02:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:16 INFO - 'MOZ_NO_REMOTE': '1', 09:02:16 INFO - 'NO_EM_RESTART': '1', 09:02:16 INFO - 'PAGER': '/bin/cat', 09:02:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:16 INFO - 'PWD': '/builds/slave/test', 09:02:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:16 INFO - 'SHELL': '/bin/bash', 09:02:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vAN9dpeYnu/Listeners', 09:02:16 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 09:02:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:16 INFO - 'USER': 'cltbld', 09:02:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:16 INFO - 'XPC_FLAGS': '0x0', 09:02:16 INFO - 'XPC_SERVICE_NAME': '0', 09:02:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:16 INFO - Downloading/unpacking psutil>=3.1.1 09:02:16 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:02:16 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:02:16 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:02:16 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:02:16 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:02:16 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:02:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:02:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:02:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:02:20 INFO - Installing collected packages: psutil 09:02:20 INFO - Running setup.py install for psutil 09:02:20 INFO - building 'psutil._psutil_osx' extension 09:02:20 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:02:20 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:02:20 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:02:20 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:02:20 INFO - building 'psutil._psutil_posix' extension 09:02:20 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:02:21 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:02:21 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:02:21 INFO - ^ 09:02:21 INFO - 1 warning generated. 09:02:21 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:02:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:02:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:02:21 INFO - Successfully installed psutil 09:02:21 INFO - Cleaning up... 09:02:21 INFO - Return code: 0 09:02:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:02:21 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:02:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:21 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:02:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:21 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 0x107ec7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e3d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa063d5ef00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cdbd30>, '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 0x107b01db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cd3b20>, '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.MMANeWiGp6/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.vAN9dpeYnu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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:02:21 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:02:21 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:02:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MMANeWiGp6/Render', 09:02:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:21 INFO - 'HOME': '/Users/cltbld', 09:02:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:21 INFO - 'LOGNAME': 'cltbld', 09:02:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:21 INFO - 'MOZ_NO_REMOTE': '1', 09:02:21 INFO - 'NO_EM_RESTART': '1', 09:02:21 INFO - 'PAGER': '/bin/cat', 09:02:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:21 INFO - 'PWD': '/builds/slave/test', 09:02:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:21 INFO - 'SHELL': '/bin/bash', 09:02:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vAN9dpeYnu/Listeners', 09:02:21 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 09:02:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:21 INFO - 'USER': 'cltbld', 09:02:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:21 INFO - 'XPC_FLAGS': '0x0', 09:02:21 INFO - 'XPC_SERVICE_NAME': '0', 09:02:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:02:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:02:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:02:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:02:24 INFO - Installing collected packages: mozsystemmonitor 09:02:24 INFO - Running setup.py install for mozsystemmonitor 09:02:24 INFO - Successfully installed mozsystemmonitor 09:02:24 INFO - Cleaning up... 09:02:24 INFO - Return code: 0 09:02:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:02:24 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:02:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:24 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:02:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:24 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 0x107ec7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e3d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa063d5ef00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cdbd30>, '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 0x107b01db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cd3b20>, '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.MMANeWiGp6/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.vAN9dpeYnu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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:02:24 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:02:24 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:02:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MMANeWiGp6/Render', 09:02:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:24 INFO - 'HOME': '/Users/cltbld', 09:02:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:24 INFO - 'LOGNAME': 'cltbld', 09:02:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:24 INFO - 'MOZ_NO_REMOTE': '1', 09:02:24 INFO - 'NO_EM_RESTART': '1', 09:02:24 INFO - 'PAGER': '/bin/cat', 09:02:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:24 INFO - 'PWD': '/builds/slave/test', 09:02:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:24 INFO - 'SHELL': '/bin/bash', 09:02:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vAN9dpeYnu/Listeners', 09:02:24 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 09:02:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:24 INFO - 'USER': 'cltbld', 09:02:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:24 INFO - 'XPC_FLAGS': '0x0', 09:02:24 INFO - 'XPC_SERVICE_NAME': '0', 09:02:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:24 INFO - Downloading/unpacking jsonschema==2.5.1 09:02:24 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:02:24 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:02:24 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:02:24 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:02:24 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:02:24 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:02:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:02:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:02:27 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:02:27 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:02:27 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:02:27 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:02:27 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:02:27 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:02:27 INFO - Downloading functools32-3.2.3-2.tar.gz 09:02:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:02:27 INFO - warning: no files found matching '*.txt' 09:02:27 INFO - no previously-included directories found matching 'build' 09:02:27 INFO - no previously-included directories found matching 'dist' 09:02:27 INFO - no previously-included directories found matching '.git*' 09:02:27 INFO - Installing collected packages: jsonschema, functools32 09:02:27 INFO - Running setup.py install for functools32 09:02:27 INFO - warning: no files found matching '*.txt' 09:02:27 INFO - no previously-included directories found matching 'build' 09:02:27 INFO - no previously-included directories found matching 'dist' 09:02:27 INFO - no previously-included directories found matching '.git*' 09:02:27 INFO - Successfully installed jsonschema functools32 09:02:27 INFO - Cleaning up... 09:02:28 INFO - Return code: 0 09:02:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:02:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:28 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 0x107ec7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e3d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa063d5ef00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cdbd30>, '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 0x107b01db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cd3b20>, '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.MMANeWiGp6/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.vAN9dpeYnu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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:02:28 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:02:28 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:02:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MMANeWiGp6/Render', 09:02:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:28 INFO - 'HOME': '/Users/cltbld', 09:02:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:28 INFO - 'LOGNAME': 'cltbld', 09:02:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:28 INFO - 'MOZ_NO_REMOTE': '1', 09:02:28 INFO - 'NO_EM_RESTART': '1', 09:02:28 INFO - 'PAGER': '/bin/cat', 09:02:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:28 INFO - 'PWD': '/builds/slave/test', 09:02:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:28 INFO - 'SHELL': '/bin/bash', 09:02:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vAN9dpeYnu/Listeners', 09:02:28 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 09:02:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:28 INFO - 'USER': 'cltbld', 09:02:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:28 INFO - 'XPC_FLAGS': '0x0', 09:02:28 INFO - 'XPC_SERVICE_NAME': '0', 09:02:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:02:28 INFO - Cleaning up... 09:02:28 INFO - Return code: 0 09:02:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:02:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:28 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 0x107ec7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e3d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa063d5ef00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cdbd30>, '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 0x107b01db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cd3b20>, '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.MMANeWiGp6/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.vAN9dpeYnu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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:02:28 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:02:28 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:02:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MMANeWiGp6/Render', 09:02:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:28 INFO - 'HOME': '/Users/cltbld', 09:02:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:28 INFO - 'LOGNAME': 'cltbld', 09:02:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:28 INFO - 'MOZ_NO_REMOTE': '1', 09:02:28 INFO - 'NO_EM_RESTART': '1', 09:02:28 INFO - 'PAGER': '/bin/cat', 09:02:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:28 INFO - 'PWD': '/builds/slave/test', 09:02:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:28 INFO - 'SHELL': '/bin/bash', 09:02:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vAN9dpeYnu/Listeners', 09:02:28 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 09:02:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:28 INFO - 'USER': 'cltbld', 09:02:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:28 INFO - 'XPC_FLAGS': '0x0', 09:02:28 INFO - 'XPC_SERVICE_NAME': '0', 09:02:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:28 INFO - Downloading/unpacking blobuploader==1.2.4 09:02:28 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:02:28 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:02:28 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:02:28 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:02:28 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:02:28 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:02:31 INFO - Downloading blobuploader-1.2.4.tar.gz 09:02:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:02:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:02:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:02:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:02:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:32 INFO - Downloading docopt-0.6.1.tar.gz 09:02:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:02:32 INFO - Installing collected packages: blobuploader, requests, docopt 09:02:32 INFO - Running setup.py install for blobuploader 09:02:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:02:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:02:32 INFO - Running setup.py install for requests 09:02:33 INFO - Running setup.py install for docopt 09:02:33 INFO - Successfully installed blobuploader requests docopt 09:02:33 INFO - Cleaning up... 09:02:33 INFO - Return code: 0 09:02:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:02:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:33 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 0x107ec7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e3d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa063d5ef00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cdbd30>, '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 0x107b01db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cd3b20>, '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.MMANeWiGp6/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.vAN9dpeYnu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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:02:33 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:02:33 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:02:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MMANeWiGp6/Render', 09:02:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:33 INFO - 'HOME': '/Users/cltbld', 09:02:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:33 INFO - 'LOGNAME': 'cltbld', 09:02:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:33 INFO - 'MOZ_NO_REMOTE': '1', 09:02:33 INFO - 'NO_EM_RESTART': '1', 09:02:33 INFO - 'PAGER': '/bin/cat', 09:02:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:33 INFO - 'PWD': '/builds/slave/test', 09:02:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:33 INFO - 'SHELL': '/bin/bash', 09:02:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vAN9dpeYnu/Listeners', 09:02:33 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 09:02:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:33 INFO - 'USER': 'cltbld', 09:02:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:33 INFO - 'XPC_FLAGS': '0x0', 09:02:33 INFO - 'XPC_SERVICE_NAME': '0', 09:02:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:02:33 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-l8YPR1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:02:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:02:33 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-xowP0y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:02:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:02:33 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-AIXR20-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:02:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:02:33 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-Dl6bhw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:02:34 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-kQbUMU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:02:34 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-oYDOPp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:02:34 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-NCYd3l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:02:34 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-RygPqm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:02:34 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-CgWozB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:02:34 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-P4fx_c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:02:34 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-G7zBOu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:02:34 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-4MsNyJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:02:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:02:34 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-9xT6hz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:02:35 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-9uZP4c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:35 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-s_D3qR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:02:35 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-jrAF3L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:02:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:02:35 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-EjcADt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:02:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:02:35 INFO - Running setup.py install for manifestparser 09:02:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:02:35 INFO - Running setup.py install for mozcrash 09:02:35 INFO - Running setup.py install for mozdebug 09:02:35 INFO - Running setup.py install for mozdevice 09:02:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:02:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:02:36 INFO - Running setup.py install for mozfile 09:02:36 INFO - Running setup.py install for mozhttpd 09:02:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:02:36 INFO - Running setup.py install for mozinfo 09:02:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:02:36 INFO - Running setup.py install for mozInstall 09:02:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:02:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:02:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:02:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:02:36 INFO - Running setup.py install for mozleak 09:02:36 INFO - Running setup.py install for mozlog 09:02:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:02:36 INFO - Running setup.py install for moznetwork 09:02:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:02:37 INFO - Running setup.py install for mozprocess 09:02:37 INFO - Running setup.py install for mozprofile 09:02:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:02:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:02:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:02:37 INFO - Running setup.py install for mozrunner 09:02:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:02:37 INFO - Running setup.py install for mozscreenshot 09:02:37 INFO - Running setup.py install for moztest 09:02:37 INFO - Running setup.py install for mozversion 09:02:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:02:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:02:37 INFO - Cleaning up... 09:02:38 INFO - Return code: 0 09:02:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:02:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:38 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 0x107ec7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e3d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa063d5ef00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cdbd30>, '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 0x107b01db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cd3b20>, '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.MMANeWiGp6/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.vAN9dpeYnu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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:02:38 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:02:38 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:02:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MMANeWiGp6/Render', 09:02:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:38 INFO - 'HOME': '/Users/cltbld', 09:02:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:38 INFO - 'LOGNAME': 'cltbld', 09:02:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:38 INFO - 'MOZ_NO_REMOTE': '1', 09:02:38 INFO - 'NO_EM_RESTART': '1', 09:02:38 INFO - 'PAGER': '/bin/cat', 09:02:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:38 INFO - 'PWD': '/builds/slave/test', 09:02:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:38 INFO - 'SHELL': '/bin/bash', 09:02:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vAN9dpeYnu/Listeners', 09:02:38 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 09:02:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:38 INFO - 'USER': 'cltbld', 09:02:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:38 INFO - 'XPC_FLAGS': '0x0', 09:02:38 INFO - 'XPC_SERVICE_NAME': '0', 09:02:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:02:38 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-VhDDcL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:02:38 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:02:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:02:38 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-LKt3d1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:02:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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:02:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:02:38 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-_y4WSE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:02:38 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:02:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:02:38 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-MXVICH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:02:38 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:02:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:02:38 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-qhd6Es-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:02:38 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:02:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:02:38 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-4HUFww-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:02:38 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:02:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:02:38 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-ZXjPn7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:02:39 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:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:02:39 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-1958nw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:02:39 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:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:02:39 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-evq7OL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:02:39 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:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:02:39 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-BWSqNH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:02:39 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:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:02:39 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-ad8T5J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:02:39 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:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:02:39 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-ujMyg6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:02:39 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:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:02:39 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-DEmNii-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:02:39 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:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:02:39 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-UYcMUU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:02:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:39 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-NR8BMm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:39 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:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:02:39 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-iSF0C2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:02:39 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:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:02:39 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-bBOHqb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:02:40 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:02:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:02:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:02:40 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:02:40 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:02:40 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:02:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:02:40 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:02:40 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:02:40 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:02:40 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:02:40 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:02:40 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:02:42 INFO - Downloading blessings-1.6.tar.gz 09:02:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:02:42 INFO - Installing collected packages: blessings 09:02:42 INFO - Running setup.py install for blessings 09:02:42 INFO - Successfully installed blessings 09:02:42 INFO - Cleaning up... 09:02:43 INFO - Return code: 0 09:02:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:02:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:02:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:02:43 INFO - Reading from file tmpfile_stdout 09:02:43 INFO - Current package versions: 09:02:43 INFO - blessings == 1.6 09:02:43 INFO - blobuploader == 1.2.4 09:02:43 INFO - docopt == 0.6.1 09:02:43 INFO - functools32 == 3.2.3-2 09:02:43 INFO - jsonschema == 2.5.1 09:02:43 INFO - manifestparser == 1.1 09:02:43 INFO - mozInstall == 1.12 09:02:43 INFO - mozcrash == 1.0 09:02:43 INFO - mozdebug == 0.1 09:02:43 INFO - mozdevice == 0.48 09:02:43 INFO - mozfile == 1.2 09:02:43 INFO - mozhttpd == 0.7 09:02:43 INFO - mozinfo == 0.9 09:02:43 INFO - mozleak == 0.1 09:02:43 INFO - mozlog == 3.4 09:02:43 INFO - moznetwork == 0.27 09:02:43 INFO - mozprocess == 0.23 09:02:43 INFO - mozprofile == 0.28 09:02:43 INFO - mozrunner == 6.13 09:02:43 INFO - mozscreenshot == 0.1 09:02:43 INFO - mozsystemmonitor == 0.3 09:02:43 INFO - moztest == 0.7 09:02:43 INFO - mozversion == 1.4 09:02:43 INFO - psutil == 3.1.1 09:02:43 INFO - requests == 1.2.3 09:02:43 INFO - wsgiref == 0.1.2 09:02:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:02: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:02:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02: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:02:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:43 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 0x107ec7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e3d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa063d5ef00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cdbd30>, '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 0x107b01db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cd3b20>, '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.MMANeWiGp6/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.vAN9dpeYnu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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:02:43 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:02:43 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:02:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MMANeWiGp6/Render', 09:02:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:43 INFO - 'HOME': '/Users/cltbld', 09:02:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:43 INFO - 'LOGNAME': 'cltbld', 09:02:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:43 INFO - 'MOZ_NO_REMOTE': '1', 09:02:43 INFO - 'NO_EM_RESTART': '1', 09:02:43 INFO - 'PAGER': '/bin/cat', 09:02:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:43 INFO - 'PWD': '/builds/slave/test', 09:02:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:43 INFO - 'SHELL': '/bin/bash', 09:02:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vAN9dpeYnu/Listeners', 09:02:43 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 09:02:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:43 INFO - 'USER': 'cltbld', 09:02:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:43 INFO - 'XPC_FLAGS': '0x0', 09:02:43 INFO - 'XPC_SERVICE_NAME': '0', 09:02:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:43 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:02:43 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:02:43 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:02:43 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:02:43 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:02:43 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:02:43 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:02:43 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:02:43 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:02:43 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:02:43 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:02:43 INFO - Cleaning up... 09:02:43 INFO - Return code: 0 09:02:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:02: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:02:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02: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:02:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107ec7490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e3d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa063d5ef00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cdbd30>, '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 0x107b01db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cd3b20>, '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.MMANeWiGp6/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.vAN9dpeYnu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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:02: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', 'jsonschema'] in /builds/slave/test/build 09:02: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 jsonschema 09:02:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MMANeWiGp6/Render', 09:02:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:43 INFO - 'HOME': '/Users/cltbld', 09:02:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:43 INFO - 'LOGNAME': 'cltbld', 09:02:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:43 INFO - 'MOZ_NO_REMOTE': '1', 09:02:43 INFO - 'NO_EM_RESTART': '1', 09:02:43 INFO - 'PAGER': '/bin/cat', 09:02:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:43 INFO - 'PWD': '/builds/slave/test', 09:02:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:43 INFO - 'SHELL': '/bin/bash', 09:02:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vAN9dpeYnu/Listeners', 09:02:43 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 09:02:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:43 INFO - 'USER': 'cltbld', 09:02:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:43 INFO - 'XPC_FLAGS': '0x0', 09:02:43 INFO - 'XPC_SERVICE_NAME': '0', 09:02:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:02:43 INFO - Cleaning up... 09:02:43 INFO - Return code: 0 09:02:43 INFO - Running post-action listener: _resource_record_post_action 09:02:43 INFO - Running post-action listener: _start_resource_monitoring 09:02:43 INFO - Starting resource monitoring. 09:02:43 INFO - [mozharness: 2016-11-10 17:02:43.841763Z] Finished create-virtualenv step (success) 09:02:43 INFO - [mozharness: 2016-11-10 17:02:43.842830Z] Running install step. 09:02:43 INFO - Running pre-action listener: _resource_record_pre_action 09:02:43 INFO - Running main action method: install 09:02:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:02:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:02:44 INFO - Reading from file tmpfile_stdout 09:02:44 INFO - Detecting whether we're running mozinstall >=1.0... 09:02:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:02:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:02:44 INFO - Reading from file tmpfile_stdout 09:02:44 INFO - Output received: 09:02:44 INFO - Usage: mozinstall [options] installer 09:02:44 INFO - Options: 09:02:44 INFO - -h, --help show this help message and exit 09:02:44 INFO - -d DEST, --destination=DEST 09:02:44 INFO - Directory to install application into. [default: 09:02:44 INFO - "/builds/slave/test"] 09:02:44 INFO - --app=APP Application being installed. [default: firefox] 09:02:44 INFO - mkdir: /builds/slave/test/build/application 09:02:44 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:02:44 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:03:18 INFO - Reading from file tmpfile_stdout 09:03:18 INFO - Output received: 09:03:18 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:03:18 INFO - Running post-action listener: _resource_record_post_action 09:03:18 INFO - [mozharness: 2016-11-10 17:03:18.100495Z] Finished install step (success) 09:03:18 INFO - [mozharness: 2016-11-10 17:03:18.100639Z] Running run-tests step. 09:03:18 INFO - Running pre-action listener: _resource_record_pre_action 09:03:18 INFO - Running main action method: run_tests 09:03:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:03:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:03:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:03:18 INFO - 2016-11-10 09:03:18.115 screenresolution[1640:7506] starting screenresolution argv=screenresolution get 09:03:18 INFO - 2016-11-10 09:03:18.117 screenresolution[1640:7506] Display 0: 1600x1200x32@60 09:03:18 INFO - 2016-11-10 09:03:18.123 screenresolution[1641:7508] starting screenresolution argv=screenresolution list 09:03:18 INFO - Available Modes on Display 0 09:03:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:03:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:03:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:03:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:03:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:03:18 INFO - Intel Iris: 09:03:18 INFO - Chipset Model: Intel Iris 09:03:18 INFO - Type: GPU 09:03:18 INFO - Bus: Built-In 09:03:18 INFO - VRAM (Dynamic, Max): 1536 MB 09:03:18 INFO - Vendor: Intel (0x8086) 09:03:18 INFO - Device ID: 0x0a2e 09:03:18 INFO - Revision ID: 0x0009 09:03:18 INFO - Displays: 09:03:18 INFO - Display: 09:03:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:03:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:03:18 INFO - Display Serial Number: 1600x1200 60 09:03:18 INFO - Main Display: Yes 09:03:18 INFO - Mirror: Off 09:03:18 INFO - Online: Yes 09:03:18 INFO - Rotation: Supported 09:03:18 INFO - Return code: 0 09:03:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:03:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:03:18 INFO - Python 2.7.3 09:03:18 INFO - Return code: 0 09:03:18 INFO - grabbing minidump binary from tooltool 09:03:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:03:18 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 0x107cdbd30>, '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 0x107b01db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cd3b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:03:18 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:03:18 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:03:18 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:03:18 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:03:18 INFO - Return code: 0 09:03:18 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:03:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:03:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:03:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:03:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:03:18 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/CVQAqw-kS2O8RYxxSZMCWg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0374', '--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:03:18 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/CVQAqw-kS2O8RYxxSZMCWg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0374 --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:03:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MMANeWiGp6/Render', 09:03:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:03:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:03:18 INFO - 'HOME': '/Users/cltbld', 09:03:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:03:18 INFO - 'LOGNAME': 'cltbld', 09:03:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:03:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:03:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:18 INFO - 'MOZ_NO_REMOTE': '1', 09:03:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:03:18 INFO - 'NO_EM_RESTART': '1', 09:03:18 INFO - 'PAGER': '/bin/cat', 09:03:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:03:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:18 INFO - 'PWD': '/builds/slave/test', 09:03:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:03:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:03:18 INFO - 'SHELL': '/bin/bash', 09:03:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vAN9dpeYnu/Listeners', 09:03:18 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 09:03:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:03:18 INFO - 'USER': 'cltbld', 09:03:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:03:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:03:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:18 INFO - 'XPC_FLAGS': '0x0', 09:03:18 INFO - 'XPC_SERVICE_NAME': '0', 09:03:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:03:18 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/CVQAqw-kS2O8RYxxSZMCWg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0374', '--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:03:18 INFO - mozversion application_buildid: 20161110061022 09:03:18 INFO - mozversion application_changeset: d2e33428e45718bbeff192e80fd3a75ad264210d 09:03:18 INFO - mozversion application_display_name: Nightly 09:03:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:03:18 INFO - mozversion application_name: Firefox 09:03:18 INFO - mozversion application_remotingname: firefox 09:03:18 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:03:18 INFO - mozversion application_vendor: Mozilla 09:03:18 INFO - mozversion application_version: 52.0a1 09:03:18 INFO - mozversion platform_buildid: 20161110061022 09:03:18 INFO - mozversion platform_changeset: d2e33428e45718bbeff192e80fd3a75ad264210d 09:03:18 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:03:18 INFO - mozversion platform_version: 52.0a1 09:03:18 INFO - using testdate: 1478797398 09:03:18 INFO - actual date: 1478797398 09:03:18 INFO - starting webserver on 'localhost:49272' 09:03:18 INFO - SUITE-START | Running 2 tests 09:03:18 INFO - TEST-START | dromaeo_css 09:03:18 INFO - operating with platform_type : mac_ 09:03:18 INFO - Initialising browser for dromaeo_css test... 09:03:18 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/tmpL2c1c_/profile http://localhost:49272/getInfo.html) 09:03:21 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 09:03:21 INFO - PROCESS | 1651 | colorDepth:24 09:03:21 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 09:03:21 INFO - PROCESS | 1651 | __metrics 09:03:21 INFO - PROCESS | 1651 | 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:03:21 INFO - PROCESS | 1651 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:03:21 INFO - TEST-INFO | 1651: exit 0 09:03:21 INFO - Browser initialized. 09:03:21 INFO - Running cycle 1/1 for dromaeo_css test... 09:03:21 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/tmpL2c1c_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:03:33 INFO - PROCESS | 1653 | RSS: Main: 237772800 09:03:33 INFO - PROCESS | 1653 | 09:04:25 INFO - PROCESS | 1653 | 1478797465090 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:06:38 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 09:06:40 INFO - PROCESS | 1653 | RSS: Main: 384778240 09:06:40 INFO - PROCESS | 1653 | 09:09:43 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 09:09:43 INFO - PROCESS | 1653 | RSS: Main: 371654656 09:09:43 INFO - PROCESS | 1653 | 09:12:45 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 09:12:46 INFO - PROCESS | 1653 | RSS: Main: 338337792 09:12:46 INFO - PROCESS | 1653 | 09:15:48 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 09:15:49 INFO - PROCESS | 1653 | RSS: Main: 345141248 09:15:49 INFO - PROCESS | 1653 | 09:18:52 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 09:18:53 INFO - PROCESS | 1653 | RSS: Main: 329035776 09:18:53 INFO - PROCESS | 1653 | 09:21:56 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 09:21:56 INFO - PROCESS | 1653 | RSS: Main: 491552768 09:21:56 INFO - PROCESS | 1653 | 09:21:56 INFO - PROCESS | 1653 | __start_tp_report 09:21:56 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 09:21:56 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 09:21:56 INFO - PROCESS | 1653 | |i|pagename|runs| 09:21:56 INFO - PROCESS | 1653 | |0;dojo.html;2581.406276556392;3231.2729635831943;3237.692419220174;3253.333066721322;3286.34273145371;5417.91873121903;5516.91724624131;5543.25166102576;5563.749631266593;5644.209810626516;5637.3517045539675;5648.435156484349;5775.797847075342;5796.62321949073;5800.796972105977;5063.772130254129;5112;5144.742762861842;5145.3568303962;5182.559482443997;5146.922796158042;5254.369475662926;5262.315898933133;5267.973660131714;5293.04725149473;5005.349304590416;5223.764930578133;5245.763940622681;5248.685078895279;5326.6005049621235;5067.746612669352;5115.437301896788;5135.204043373283;5135.794568217267;5141.974290128525;5071.847844564669;5113.463086375914;5116.718580478072;5123.87190320245;5129.871753206163;5567.359753628344;5574.637648552831;5599.356074051496;5600.663960162403;5611.242482264883;6066.726997285133;6080.513558915276;6081.6351018939;6090.208272924536;6094.299155597075;6032.879342413171;6108.236470441195;6116.327204007555;6129.785457508966;6143.600665956743;5721.027425337703;5801.274840644919;5802.100674395476;5805.332386775575;5813.6802475863815;5187.714675692873;6047.395260474005;6083.5741498095595;6111.205543279345;6138.263408391022;6094.329623741385;6098.72555734993;6101.359357267423;6103.969480152571;6106.847328816815;6058.091286307001;6070.696465176812;6082.422169893853;6113.969430152821;6114.419130182626;6100.122657953208;6105.808169897265;6124.908126378108;6137.355577664371;6140.078988151813;6082.330943596201;6094.6647934363145;6101.617499577997;6137.450935349545;6141.631502109888;4702.153612349742;4809.254565542346;4811.08589368019;4851.078295123915;4864.537868902448;4292.871213863589;4298.656107511391;4336.566343365638;4359.9782001090425;4400.889977750518;5531.3915469298345;5595.944040559623;5602.355729091125;5672.546196304285;5688.943110568925;5997.190379298739;6117.471378795421;6124.777645458507;6142.2839182294965;6161.537884658668;5643.068893632587;5647.887042259132;5705.087186050212;5707.429257074343;5716.028275193227;5358.19627055937;5359.598030147755;5362.570994320445;5367.481305673316;5387.676739395649;6115.605440958429;6127.678242056131;6130.9179418630665;6157.784477543264;6187.504999599932;6195.597286176384;6202.4417802398;6209.534284928739;6216.564840461125;6225.968870155621;6146.877062458636;6160.229971253593;6165.383461653978;6174.6912654368;6176.104464852661;5997.490213331916;6062.090686397076;6067.514598831995;6077;6100.73256437228;6163.599366041193;6176.104464852661;6200.534959878118;6202.193714817045;6237.345078766845;6050.697465126814;6074.179985701874;6111.1444397785235;6115.685127697567;6122.051082082285;5930.05119180929;6007.249093863267;6027.517798576016;6035;6125.0934462995265;4811.374521312207;4889.706617602955;5700.743466543932;5941.881162376641;5971.91042134377;6101.054336577838;6111.480524155497;6112.083187521729;6116.66358350295;6142.14010038586;5134.640575159703;5470.64440811346;5791.8552036199435;5917.615355001911;5925.318588362394 09:21:56 INFO - PROCESS | 1653 | |1;ext.html;465.61354076116805;476.3117295507992;512.8615273876055;518.9844304670859;524.7035424984883;30056.248593785167;31567.684323156762;39127.41308880365;40205.798971005104;40375.59624403755;41533;41757.582424175824;41766;41828.79085604575;42005.15989680205;9158.908410915872;9345.15893569582;9346.39248448852;9352.719418417424;9355.48544830034;9147;9227.169554740072;9256.768580785467;9269.768255793591;9278.953605231964;9188.494632795193;9254.53727313635;9272.211861991738;9295;9310.487923164223;9214.815703685923;9245.07549245077;9275.30435217358;9289.071092890725;9291.442513449214;9208.3093767967;9278.628854845798;9281.30390220736;9281.53592320388;9301.58142883565;39509;39935.201295974206;39981;40006;40054.799726001474;3261.608606967179;3304.5538852254876;3312.5528053712683;3352.798832070084;3646.690031347339;29784.40431191386;30338.696613033808;30601.928932487255;30645.16935383077;31007.759689612387;3217.260030193045;3217.7264932480766;3218.1150183699438;3222.629397619257;3347.313800670857;485607;486677;488908.55280260794;489966.99999999645;490704.99999999645;58644.41355586475;59236.40763592395;59274;59306.70346648325;59313.110303345486;33608;34133.80531681379;34429.96710098701;35387.115322117155;35438.468422973674;15699.822513311547;15832.76250856238;15861.365545378056;15874.365025398854;15879.364825407085;11974.341411222293;12039.939800300943;12080.577179798665;12098;12110.697232569257;18585.81414185841;18627;18640.440786776417;18671.90664046671;18727.28454374108;7155.642217889084;7492.887606685904;7576.393468833024;7721.150673425918;7805.648745806452;874.1957399192758;927.188709878856;967.7000129959735;989.5151375825938;1018.5009345420691;28361.574576381372;29343.11970640884;29456.705432945826;29662.851685741432;29879;962.2879069488528;973.2894986659778;1013.9797204055877;1086.766345235778;1107.5791199344199;14825.590114273777;15310.234488275542;15312.310946007456;15547.533573992798;15655.68688626221;675067;679314;679786;681317;681519;56290.718546407006;56629.43370566324;56701;56780.148297775566;56870;1213.7329787446924;1255.8053501707068;1321.4053675845716;1341.9798703019462;1374.299107455185;27418.314542136606;27805.721942780314;29950;30550.84724576452;30939;48471.5152848467;48737.75631121964;48738;48803.26795098005;48824.2676359862;480327;480388;481288;481764;482191;22821;23409.41476463102;23428;23574.29277121689;25779.871100644377;38129.428058579695;38622;43907.780461098766;44531;45075.32387014263;31934.84032579822;32897.01308960735;37364.439533406476;37431.81284093671;39266.411003835536;36945.81527092347;37249.81375093107;37252.44121338128;38236.808815955745;38537.80731096327;32381.676183237865;32866;33347;33517.66482335146;33525.16187095342;30226.69773302239;32949;32955.34089318248;32966;32978.17554561059;22069.448263793533;24213.51572968566;24295.757042429348;24492.265232043068;25335.493290134462 09:21:56 INFO - PROCESS | 1653 | |2;jquery.html;366.4905780964458;371.39833469778955;379.14313651148404;389.29731834039546;398.07447684134377;18336.44990650281;19507.609847803073;20004.399868003984;20016.29942951997;20153.992300384958;16865.325386984536;17299.308027678908;17334.56663583411;17978.370757023506;19694.310699125534;5406.40529541751;5422.2951016368015;5705.229793977802;5713.457221563945;5718.7426565804435;5447.836564903059;5630.183623374608;5636.689982050985;5642.069058605325;5667.149927510885;5523.6685798852195;5602.943970560304;5621.156826476041;5621.7189140542805;5625.493705566499;5715.971125197463;5838.912416313738;5859.4140585941495;5876.471117599393;5878.853028674274;5420.83737487876;5597.580181486404;5657.15142728594;5668.631538949996;5670.518005969457;12699.619011429579;12943.546975855894;12947.741045179138;12984.15602985803;22578.435539111488;1532.578540901261;2126.8404869634683;2187.628103222456;2320.396696858829;2580.935476613081;7922.405819563498;9515.096065873731;9624.711258662252;10074.848877266846;10183.28716989811;1756.8594512438974;1789.642071585688;1809.5385676652502;1876.4933467963574;1952.3557226115347;81348;94150.529247354;119228.40385798103;119575;119685;9378.155965963097;11109;11224.55101795919;11300.15248856339;11420.714482138013;14325.426982920775;14347.56957291262;14365.209913454652;14387.424503019762;14390.496332628518;8053.677852885936;8209.261166495044;8247.340212783083;8354.874676879852;8376;8082.353411727047;8106.797330066677;8128.349732021424;8259.38054645904;8295.95852020736;4344.196323680129;4364.454443194601;4411.84558540456;4524.6832721709825;4546.681732278775;1822.1162736072995;1891.5176629959547;1892.6593213221752;1892.943211703651;1897.6109897470985;647.8769033883547;782.5343920367372;826.2233500509502;882.5498995512206;903.074348792493;19526.511837204183;20510.692339614918;20765.273215437388;20779.79220207809;20843;886.7605746448423;904.1184844776291;962.3456049886145;969.9301710087494;997.7306127345576;10391.37651740898;10898.346099234072;10914.290571112851;10956.890431095588;10965;580538;580599;591532.0423397941;595367;600787;95944.3650071497;119657;119670;120489;121238;963.8120566489467;970.0541971577655;974.6734843827255;976.7655762616921;977.3207620703542;17293.56766080858;17524.649507010043;17720.468385948443;17733.822661773735;17992.820071799637;51232;51770.70573235699;52046.79532046765;52770.736146319025;54066.45933540614;60176;71100.93348599879;71546;73650.38240203327;77168;6137.631742095488;6906.689198985957;7988.760337189894;8082.353411727166;8129.79675508117;11657.591984280509;12666.746665066832;12897.48410063587;12942.611721647989;13101.344932753515;9707.417554946725;10951.233413660968;11086.944565277123;11144.387058711849;11303.208775385972;10957.671269861918;11052.336859788438;11059.50232239535;11158.330500170016;11439.142064345373;10892.237543371888;11180.776384472427;11227.49476273586;11419.942900285445;11520.366379849189;6635.734570617315;7263.891041634485;7292.598907060163;7438.033055702724;7511.661975211019;9282.30382721285;10615.363078215332;11528.481218345363;11571.247868888495;11644.2431241969 09:21:56 INFO - PROCESS | 1653 | |3;mootools.html;1459.6642772162402;1486.2940103450862;1594.4020992127953;1773.5832079461325;1800.5498625343664;7986.880196797053;8426.578671066438;8435.66257349706;8438.409311348209;8462.534560599172;7343.1188257409185;7676.731314403997;7680.116786569546;7945.324647404963;8182.22268884455;1923.9999999999966;2289.473421113145;2291.346966114658;2292.4727312718087;2303.2974942642486;2284.6230371988604;2318.084356679111;2327.208749025331;2327.5461285049423;2329.3477826208687;2283.1209984156135;2286.336962280945;2312.1791763923734;2315.050829160045;2322.140807901079;2275.5221403505284;2279.247848210095;2295.7933785959262;2296.9655455168186;2297.85063970843;2116.2487317002397;2162.6699579758492;2317.2005658047947;2319.7796209360085;2322.9430609072833;8295.917040829574;8346.70786522475;8361.540115293656;8366.288865446444;8391.370647201484;4983.651144419892;5150.201718733602;5158.97420512895;5162;5162.096633089194;2651.9204423867313;3324.6176689680756;3589.94615080774;3591.4792355108625;3597.406427939387;1042.6142327338932;1048.0792678375033;1052.8052310322614;1071.3625392891217;1087.1846115413439;10628.202884783672;10740.838887416696;10792.352458852416;10796.920307969218;10799.028087472165;3934.3311637021775;4391.8682439526865;4525.909481810345;4644.2801365788355;4670.486246512881;9356.157945784911;9356.90643093574;9359.110884465965;9385.671501497414;9390;5119.974400127976;5128.410232823273;5129.666571672829;5134.794608215704;5136.203888397305;4217.472815898012;4353.25994580922;4649.697769644962;4716.363290955677;4717.551832575899;3659.396199627085;3682.852685892588;3739.775613463201;3777.9622203777612;3778.3576791945434;1050.7110544600296;1247.0709321555498;1247.5839921688948;1257.867923867999;1263.9747205055846;862.8835107260566;865.9003066106136;868.3270465389373;885.7298523950257;931.9673811416568;4792.688475249098;5626.240457538262;5757.510611598061;5784.595078344475;5990.430909063632;898.766320756608;939.5677988125539;985.1084768284713;989.2382865193904;1048.3290693955873;3723.1461030278783;3735.3836616957897;3746.4380342948775;3844.961550384516;3859.845606175778;9719.56261968213;9934.850977235203;10059.39643621385;10059.647912323033;10079.49602519871;10020.048095430922;10048.949755251177;10061.346012509164;10064.39613623185;10066.396016239049;7507.5870827103945;7684.462087653922;7813.453058285865;7833.37333013369;7839.216078392115;5403.270558474555;5443.265159203457;5495.642783219078;5545.417731138334;5567.4432556744005;8938.4190027648;9264.583093760932;9277.211437027929;9369.672061477815;9401.576929038347;9297.071422501891;9370.578323975575;9413.435193888388;9428.198603118812;9553.522323883917;6937.583744975318;7100.538464999759;7137.464690148156;7210.098737657793;7275.708971641075;7908;7981.680732770624;7989.840203195788;7992.760217193261;7996.880046799419;7075.257098004841;7129;7148.606826624586;7233.63831808418;7303.890441643486;6897.172339319314;6992.61540615271;7023.543469674455;7052.858942821217;7190.496665233383;6169.84575385601;6400.743970241138;6717.361850624183;6718.093057437183;6718.630475323708;4781.115493633689;4814.15752243349;4865.36750222483;4885.828995985124;4964.503549645006;4181.686373521937;4542.3413605027745;4681.929771053505;4783.545563171493;4886.169351210232 09:21:56 INFO - PROCESS | 1653 | |4;prototype.html;299.8216061443441;300.2269156920928;309.9969000309996;313.4107877190881;316.2788841441514;4826.686265392747;5384.623076384654;5397.541208997235;5447.863803404912;5486.396496385404;4168.729032612878;4962.751862406883;5180.533751962323;5190.8442746717565;5196.740162991854;3377.7128944039664;3399.7280217582597;4066.877993660195;4125.855595054174;4128.318827393477;3463.5280005997474;4077.9592204078026;4083.9387409188885;4092.1611069730634;4100.507939047304;3375.5611770469804;4040.151568170688;4041.11095558978;4074.327735923569;4104;4052.53395859477;4067.7999990002722;4078.0425395810175;4082.040620502146;4113.547509773923;2977.493826049577;3266.346730653855;3536.345776031417;3547.4856145858707;3556.8399422026073;5018.899622007576;5112.821051263225;5128.2564028216075;5184.6629969052265;5249.737513124367;4034.273830710471;4294.441722576076;4611.423572053493;4686.9454372766195;4740.952590474086;4796.928046079277;5407.053765591006;5536.280283563111;5599.32808063035;5602.887942241173;3779.3952967525065;3897.7271590988644;3913.943235326446;3955.48578684772;3955.564887862333;5399.78400863965;5758.856028599276;5792.754557770099;5840.853978650525;5878.941210587882;3479.3389256041273;3589.6410358963894;3943.428202910562;3951.9604803951593;3957.5053118360206;3439.243366459375;3884.3396622573664;3947.940780888289;3957.2876882160913;4046.77742724153;3745.9063523412133;4254.5111110001;4603.723776573416;4640.443146822336;4711.45818230901;5297.761600727977;5389.2245493570845;5681.744321505459;5704.458076482728;5762.711864406763;1211.4003568233604;1270.8093785931999;1279.3347459321287;1281.4297637551201;1288.4667245977257;792.8652129137945;796.2077732655929;819.7049062337554;828.5608627427473;847.1105339393612;4667.439907211088;4933.629977751683;4981.626378021663;5002.973795612783;5011.699298042056;5381.811636592701;5456.508914197741;5489.2589500417735;5493.533049690741;5523.796198205031;3983.24318379507;4587.9082418351445;4970.850874473772;5141.460146684619;5439.945600544023;2652.4429869727187;2813.1292690360683;2938.5151450010508;2942.9264268393463;2949.9705002949695;4911.824948793497;5422.102264207496;5781.53747700183;5788.0449725795615;5792.913106303409;4482.820687172477;4993.403430216267;5065.859167316287;5075.87310317245;5563.610547261653;4586.77066146698;5181.4300426953005;5662.37713851476;5691.723310675763;5758.646718021261;4642.373279607345;5186.4035635902355;5418.864528386822;5608.943910560843;5612.943870561406;5097.566706829961;5116.7441627917715;5442.945570544244;5494.862628434321;5513.61404701667;5317.787288508571;5739.9139012914;5747.5401967841635;5784.2769653793275;5812;3365.9663403365653;3384.6784555467193;3389.6780705217006;3527.700145487662;3724.459953306701;4751.61987041029;4983.551480366785;5426.864328391822;5437.40188579248;5463.808766693147;4771.067717574388;4919.950800491949;5293.374038783446;5334.466553344634;5344.904294619516;4314.654827613721;4936.580390666833;5374.51228224437;5406.594505412031;5421.322334708161;5042.520960508746;5063.974680126576;5248.6325957182635;5268.894622107613;5301.363836339664;4820.1320214472335;5503.669779813224;5577.52591029767;5668.489835914787;5685.658860468385;4442.933355999598;5534.059209934401;5536.557075433875;5569.35952365467;5593.1889875966945 09:21:56 INFO - PROCESS | 1653 | |5;yui.html;688.9862202755945;705.9717611295547;725.4269127389363;761.2273542354511;775.1899265267795;13993.790093148611;14723.484678036271;14725.852741472581;14741.631459213499;14832;13320.733585328288;13460.192388456699;13465.192088474698;13503.72992540151;13516.526921557748;653.5784419049734;674.0798809624876;675.9222689390709;802.0055131636772;818.3739439328889;666.4568376772938;668.8261052126458;671.318611609217;800.027966021284;826.1531929771971;850.9276711479532;857.4340934982881;858.2147335188296;862.4911302331623;863.1109956744563;857.6140736668461;858.2233079063458;860.5869182792263;860.7288704058265;861.6122744764881;641.1920979565734;642.8360768004129;643.0579201469823;643.4498503779248;644.2397970394932;11413.885861141365;11539.365334906577;11801.94099029508;11835.763284734257;12002.759944801142;12310.569130080405;12908.806367904488;13022.218666880017;13104.157047319752;13405.329733513383;11051.668449946514;11314.886851131465;11325.660230193107;11355.43222838863;11582.594609188722;3632.3643362411735;3642.21692336149;3665.6334366563397;3670.3393389189937;3771.9434208486896;64678.67660661713;66886.99669504962;84698;85305.72041419384;85724;10049;10305.433201174008;10585.9470702646;10620.628278010387;10649.9467502662;20651.483712906997;20955.8952205238;21036.158553657682;21136.260230891847;21136.894315528323;7517.699292028257;7521.257344009476;7524.097108347033;7576.01511803473;7761.9611901940125;4189.72766770159;4252.87241382759;4286.249906266391;4350.673699472489;4378.584034516716;3321.9003429897143;3481.0079127448557;3490.406630872758;3492.2666240089357;3498.370293347171;633.3096924352478;634.3751404866204;644.2526669063864;659.8218481010197;660.1220376898713;645.3983100493544;653.5980372071142;656.1718991148167;667.5327270910382;667.7696194812781;8703.173198546128;8720.389572729848;8732.432391894507;8754.912450875538;8985.683675034681;634.4195061518659;650.5153660522941;659.947204223661;666.8966310221826;668.4518694670323;4052.49343832021;4096.180763847243;4106.932197628638;4695.882602934886;4718.622510199176;102406;104176.95823041824;104274.47862760638;105185;110901;94732;96429.0357096434;96967.51516242373;97015;100455;79412.20587794279;82061.17938820655;83130.58434707909;84136.57931710425;85001;9428.858567121635;9431.292653051185;9437.480938548446;9459.905400945903;9510.09654082861;43452;53008;53813.461865382415;53888.461115388345;53936.73031634817;72887.63556182185;77896.61051694705;79578;80185.59907200426;81725.59137204514;9878.753031174278;12038.578649747216;12081.879181208427;12456;12777.93611031939;13283.070185086952;14027.859721403063;14237.430502780187;14435.92782036083;14563.92718036403;10725.30285531438;12649.810252846399;13241.205527667986;13303.534376296782;13780.379882905494;12907.612771616867;13031.413586388446;13223.20660760319;13245.139065960682;13625.727485450037;12543.749125017632;12805.423755930819;12877.935610321889;13118.278494682883;13433.798493022417;9310.95344523273;9713.417194968324;10241.692749217535;11067.446627668745;11221.775564488828;8803.515806630698;9312.394694344846;10797.784044319227;11060.225784195029;11259 09:21:56 INFO - PROCESS | 1653 | __end_tp_report 09:21:56 INFO - PROCESS | 1653 | __start_cc_report 09:21:56 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,1509 09:21:56 INFO - PROCESS | 1653 | __end_cc_report 09:21:56 INFO - PROCESS | 1653 | __startTimestamp1478798516895__endTimestamp 09:21:56 INFO - PROCESS | 1653 | 09:21:56 INFO - PROCESS | 1653 | ------- Summary: start ------- 09:21:56 INFO - PROCESS | 1653 | Number of tests: 6 09:21:56 INFO - PROCESS | 1653 | 09:21:56 INFO - PROCESS | 1653 | [#0] dojo.html Cycles:165 Average:5640.98 Median:5917.62 stddev:657.06 (11.1%) stddev-sans-first:613.68 09:21:56 INFO - PROCESS | 1653 | Values: 2581.4 3231.3 3237.7 3253.3 3286.3 5417.9 5516.9 5543.3 5563.7 5644.2 5637.4 5648.4 5775.8 5796.6 5800.8 5063.8 5112.0 5144.7 5145.4 5182.6 5146.9 5254.4 5262.3 5268.0 5293.0 5005.3 5223.8 5245.8 5248.7 5326.6 5067.7 5115.4 5135.2 5135.8 5142.0 5071.8 5113.5 5116.7 5123.9 5129.9 5567.4 5574.6 5599.4 5600.7 5611.2 6066.7 6080.5 6081.6 6090.2 6094.3 6032.9 6108.2 6116.3 6129.8 6143.6 5721.0 5801.3 5802.1 5805.3 5813.7 5187.7 6047.4 6083.6 6111.2 6138.3 6094.3 6098.7 6101.4 6104.0 6106.8 6058.1 6070.7 6082.4 6114.0 6114.4 6100.1 6105.8 6124.9 6137.4 6140.1 6082.3 6094.7 6101.6 6137.5 6141.6 4702.2 4809.3 4811.1 4851.1 4864.5 4292.9 4298.7 4336.6 4360.0 4400.9 5531.4 5595.9 5602.4 5672.5 5688.9 5997.2 6117.5 6124.8 6142.3 6161.5 5643.1 5647.9 5705.1 5707.4 5716.0 5358.2 5359.6 5362.6 5367.5 5387.7 6115.6 6127.7 6130.9 6157.8 6187.5 6195.6 6202.4 6209.5 6216.6 6226.0 6146.9 6160.2 6165.4 6174.7 6176.1 5997.5 6062.1 6067.5 6077.0 6100.7 6163.6 6176.1 6200.5 6202.2 6237.3 6050.7 6074.2 6111.1 6115.7 6122.1 5930.1 6007.2 6027.5 6035.0 6125.1 4811.4 4889.7 5700.7 5941.9 5971.9 6101.1 6111.5 6112.1 6116.7 6142.1 5134.6 5470.6 5791.9 5917.6 5925.3 09:21:56 INFO - PROCESS | 1653 | 09:21:56 INFO - PROCESS | 1653 | [#1] ext.html Cycles:180 Average:66951.52 Median:38579.90 stddev:149221.12 (386.8%) stddev-sans-first:149556.23 09:21:56 INFO - PROCESS | 1653 | Values: 465.6 476.3 512.9 519.0 524.7 30056.2 31567.7 39127.4 40205.8 40375.6 41533.0 41757.6 41766.0 41828.8 42005.2 9158.9 9345.2 9346.4 9352.7 9355.5 9147.0 9227.2 9256.8 9269.8 9279.0 9188.5 9254.5 9272.2 9295.0 9310.5 9214.8 9245.1 9275.3 9289.1 9291.4 9208.3 9278.6 9281.3 9281.5 9301.6 39509.0 39935.2 39981.0 40006.0 40054.8 3261.6 3304.6 3312.6 3352.8 3646.7 29784.4 30338.7 30601.9 30645.2 31007.8 3217.3 3217.7 3218.1 3222.6 3347.3 485607.0 486677.0 488908.6 489967.0 490705.0 58644.4 59236.4 59274.0 59306.7 59313.1 33608.0 34133.8 34430.0 35387.1 35438.5 15699.8 15832.8 15861.4 15874.4 15879.4 11974.3 12039.9 12080.6 12098.0 12110.7 18585.8 18627.0 18640.4 18671.9 18727.3 7155.6 7492.9 7576.4 7721.2 7805.6 874.2 927.2 967.7 989.5 1018.5 28361.6 29343.1 29456.7 29662.9 29879.0 962.3 973.3 1014.0 1086.8 1107.6 14825.6 15310.2 15312.3 15547.5 15655.7 675067.0 679314.0 679786.0 681317.0 681519.0 56290.7 56629.4 56701.0 56780.1 56870.0 1213.7 1255.8 1321.4 1342.0 1374.3 27418.3 27805.7 29950.0 30550.8 30939.0 48471.5 48737.8 48738.0 48803.3 48824.3 480327.0 480388.0 481288.0 481764.0 482191.0 22821.0 23409.4 23428.0 23574.3 25779.9 38129.4 38622.0 43907.8 44531.0 45075.3 31934.8 32897.0 37364.4 37431.8 39266.4 36945.8 37249.8 37252.4 38236.8 38537.8 32381.7 32866.0 33347.0 33517.7 33525.2 30226.7 32949.0 32955.3 32966.0 32978.2 22069.4 24213.5 24295.8 24492.3 25335.5 09:21:56 INFO - PROCESS | 1653 | 09:21:56 INFO - PROCESS | 1653 | [#2] jquery.html Cycles:180 Average:33348.87 Median:2371.14 stddev:98024.39 (4134.1%) stddev-sans-first:98268.09 09:21:56 INFO - PROCESS | 1653 | Values: 366.5 371.4 379.1 389.3 398.1 18336.4 19507.6 20004.4 20016.3 20154.0 16865.3 17299.3 17334.6 17978.4 19694.3 5406.4 5422.3 5705.2 5713.5 5718.7 5447.8 5630.2 5636.7 5642.1 5667.1 5523.7 5602.9 5621.2 5621.7 5625.5 5716.0 5838.9 5859.4 5876.5 5878.9 5420.8 5597.6 5657.2 5668.6 5670.5 12699.6 12943.5 12947.7 12984.2 22578.4 1532.6 2126.8 2187.6 2320.4 2580.9 7922.4 9515.1 9624.7 10074.8 10183.3 1756.9 1789.6 1809.5 1876.5 1952.4 81348.0 94150.5 119228.4 119575.0 119685.0 9378.2 11109.0 11224.6 11300.2 11420.7 14325.4 14347.6 14365.2 14387.4 14390.5 8053.7 8209.3 8247.3 8354.9 8376.0 8082.4 8106.8 8128.3 8259.4 8296.0 4344.2 4364.5 4411.8 4524.7 4546.7 1822.1 1891.5 1892.7 1892.9 1897.6 647.9 782.5 826.2 882.5 903.1 19526.5 20510.7 20765.3 20779.8 20843.0 886.8 904.1 962.3 969.9 997.7 10391.4 10898.3 10914.3 10956.9 10965.0 580538.0 580599.0 591532.0 595367.0 600787.0 95944.4 119657.0 119670.0 120489.0 121238.0 963.8 970.1 974.7 976.8 977.3 17293.6 17524.6 17720.5 17733.8 17992.8 51232.0 51770.7 52046.8 52770.7 54066.5 60176.0 71100.9 71546.0 73650.4 77168.0 6137.6 6906.7 7988.8 8082.4 8129.8 11657.6 12666.7 12897.5 12942.6 13101.3 9707.4 10951.2 11086.9 11144.4 11303.2 10957.7 11052.3 11059.5 11158.3 11439.1 10892.2 11180.8 11227.5 11419.9 11520.4 6635.7 7263.9 7292.6 7438.0 7511.7 9282.3 10615.4 11528.5 11571.2 11644.2 09:21:56 INFO - PROCESS | 1653 | 09:21:56 INFO - PROCESS | 1653 | [#3] mootools.html Cycles:180 Average:5406.60 Median:5051.81 stddev:2969.87 (58.8%) stddev-sans-first:2963.39 09:21:56 INFO - PROCESS | 1653 | Values: 1459.7 1486.3 1594.4 1773.6 1800.5 7986.9 8426.6 8435.7 8438.4 8462.5 7343.1 7676.7 7680.1 7945.3 8182.2 1924.0 2289.5 2291.3 2292.5 2303.3 2284.6 2318.1 2327.2 2327.5 2329.3 2283.1 2286.3 2312.2 2315.1 2322.1 2275.5 2279.2 2295.8 2297.0 2297.9 2116.2 2162.7 2317.2 2319.8 2322.9 8295.9 8346.7 8361.5 8366.3 8391.4 4983.7 5150.2 5159.0 5162.0 5162.1 2651.9 3324.6 3589.9 3591.5 3597.4 1042.6 1048.1 1052.8 1071.4 1087.2 10628.2 10740.8 10792.4 10796.9 10799.0 3934.3 4391.9 4525.9 4644.3 4670.5 9356.2 9356.9 9359.1 9385.7 9390.0 5120.0 5128.4 5129.7 5134.8 5136.2 4217.5 4353.3 4649.7 4716.4 4717.6 3659.4 3682.9 3739.8 3778.0 3778.4 1050.7 1247.1 1247.6 1257.9 1264.0 862.9 865.9 868.3 885.7 932.0 4792.7 5626.2 5757.5 5784.6 5990.4 898.8 939.6 985.1 989.2 1048.3 3723.1 3735.4 3746.4 3845.0 3859.8 9719.6 9934.9 10059.4 10059.6 10079.5 10020.0 10048.9 10061.3 10064.4 10066.4 7507.6 7684.5 7813.5 7833.4 7839.2 5403.3 5443.3 5495.6 5545.4 5567.4 8938.4 9264.6 9277.2 9369.7 9401.6 9297.1 9370.6 9413.4 9428.2 9553.5 6937.6 7100.5 7137.5 7210.1 7275.7 7908.0 7981.7 7989.8 7992.8 7996.9 7075.3 7129.0 7148.6 7233.6 7303.9 6897.2 6992.6 7023.5 7052.9 7190.5 6169.8 6400.7 6717.4 6718.1 6718.6 4781.1 4814.2 4865.4 4885.8 4964.5 4181.7 4542.3 4681.9 4783.5 4886.2 09:21:56 INFO - PROCESS | 1653 | 09:21:56 INFO - PROCESS | 1653 | [#4] prototype.html Cycles:180 Average:4423.80 Median:4982.59 stddev:1353.82 (27.2%) stddev-sans-first:1321.76 09:21:56 INFO - PROCESS | 1653 | Values: 299.8 300.2 310.0 313.4 316.3 4826.7 5384.6 5397.5 5447.9 5486.4 4168.7 4962.8 5180.5 5190.8 5196.7 3377.7 3399.7 4066.9 4125.9 4128.3 3463.5 4078.0 4083.9 4092.2 4100.5 3375.6 4040.2 4041.1 4074.3 4104.0 4052.5 4067.8 4078.0 4082.0 4113.5 2977.5 3266.3 3536.3 3547.5 3556.8 5018.9 5112.8 5128.3 5184.7 5249.7 4034.3 4294.4 4611.4 4686.9 4741.0 4796.9 5407.1 5536.3 5599.3 5602.9 3779.4 3897.7 3913.9 3955.5 3955.6 5399.8 5758.9 5792.8 5840.9 5878.9 3479.3 3589.6 3943.4 3952.0 3957.5 3439.2 3884.3 3947.9 3957.3 4046.8 3745.9 4254.5 4603.7 4640.4 4711.5 5297.8 5389.2 5681.7 5704.5 5762.7 1211.4 1270.8 1279.3 1281.4 1288.5 792.9 796.2 819.7 828.6 847.1 4667.4 4933.6 4981.6 5003.0 5011.7 5381.8 5456.5 5489.3 5493.5 5523.8 3983.2 4587.9 4970.9 5141.5 5439.9 2652.4 2813.1 2938.5 2942.9 2950.0 4911.8 5422.1 5781.5 5788.0 5792.9 4482.8 4993.4 5065.9 5075.9 5563.6 4586.8 5181.4 5662.4 5691.7 5758.6 4642.4 5186.4 5418.9 5608.9 5612.9 5097.6 5116.7 5442.9 5494.9 5513.6 5317.8 5739.9 5747.5 5784.3 5812.0 3366.0 3384.7 3389.7 3527.7 3724.5 4751.6 4983.6 5426.9 5437.4 5463.8 4771.1 4920.0 5293.4 5334.5 5344.9 4314.7 4936.6 5374.5 5406.6 5421.3 5042.5 5064.0 5248.6 5268.9 5301.4 4820.1 5503.7 5577.5 5668.5 5685.7 4442.9 5534.1 5536.6 5569.4 5593.2 09:21:56 INFO - PROCESS | 1653 | 09:21:56 INFO - PROCESS | 1653 | [#5] yui.html Cycles:180 Average:20048.88 Median:4707.25 stddev:29234.51 (621.1%) stddev-sans-first:29280.38 09:21:56 INFO - PROCESS | 1653 | Values: 689.0 706.0 725.4 761.2 775.2 13993.8 14723.5 14725.9 14741.6 14832.0 13320.7 13460.2 13465.2 13503.7 13516.5 653.6 674.1 675.9 802.0 818.4 666.5 668.8 671.3 800.0 826.2 850.9 857.4 858.2 862.5 863.1 857.6 858.2 860.6 860.7 861.6 641.2 642.8 643.1 643.4 644.2 11413.9 11539.4 11801.9 11835.8 12002.8 12310.6 12908.8 13022.2 13104.2 13405.3 11051.7 11314.9 11325.7 11355.4 11582.6 3632.4 3642.2 3665.6 3670.3 3771.9 64678.7 66887.0 84698.0 85305.7 85724.0 10049.0 10305.4 10585.9 10620.6 10649.9 20651.5 20955.9 21036.2 21136.3 21136.9 7517.7 7521.3 7524.1 7576.0 7762.0 4189.7 4252.9 4286.2 4350.7 4378.6 3321.9 3481.0 3490.4 3492.3 3498.4 633.3 634.4 644.3 659.8 660.1 645.4 653.6 656.2 667.5 667.8 8703.2 8720.4 8732.4 8754.9 8985.7 634.4 650.5 659.9 666.9 668.5 4052.5 4096.2 4106.9 4695.9 4718.6 102406.0 104177.0 104274.5 105185.0 110901.0 94732.0 96429.0 96967.5 97015.0 100455.0 79412.2 82061.2 83130.6 84136.6 85001.0 9428.9 9431.3 9437.5 9459.9 9510.1 43452.0 53008.0 53813.5 53888.5 53936.7 72887.6 77896.6 79578.0 80185.6 81725.6 9878.8 12038.6 12081.9 12456.0 12777.9 13283.1 14027.9 14237.4 14435.9 14563.9 10725.3 12649.8 13241.2 13303.5 13780.4 12907.6 13031.4 13223.2 13245.1 13625.7 12543.7 12805.4 12877.9 13118.3 13433.8 9311.0 9713.4 10241.7 11067.4 11221.8 8803.5 9312.4 10797.8 11060.2 11259.0 09:21:56 INFO - PROCESS | 1653 | -------- Summary: end -------- 09:21:56 INFO - PROCESS | 1653 | 09:21:57 INFO - TEST-INFO | 1653: exit 0 09:21:57 INFO - TEST-OK | dromaeo_css | took 1118856ms 09:21:57 INFO - TEST-START | kraken 09:21:57 INFO - operating with platform_type : mac_ 09:21:57 INFO - Initialising browser for kraken test... 09:21:57 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/tmpLQ3ZYG/profile http://localhost:49272/getInfo.html) 09:21:59 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 09:21:59 INFO - PROCESS | 1663 | colorDepth:24 09:21:59 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 09:21:59 INFO - PROCESS | 1663 | __metrics 09:21:59 INFO - PROCESS | 1663 | 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:21:59 INFO - PROCESS | 1663 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:22:00 INFO - TEST-INFO | 1663: exit 0 09:22:00 INFO - Browser initialized. 09:22:00 INFO - Running cycle 1/1 for kraken test... 09:22:00 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/tmpLQ3ZYG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:22:13 INFO - PROCESS | 1664 | RSS: Main: 229376000 09:22:13 INFO - PROCESS | 1664 | 09:22:15 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 09:22:16 INFO - PROCESS | 1664 | RSS: Main: 288321536 09:22:16 INFO - PROCESS | 1664 | 09:22:19 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 09:22:19 INFO - PROCESS | 1664 | RSS: Main: 290856960 09:22:19 INFO - PROCESS | 1664 | 09:22:23 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 09:22:24 INFO - PROCESS | 1664 | RSS: Main: 380731392 09:22:24 INFO - PROCESS | 1664 | 09:22:25 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 09:22:26 INFO - PROCESS | 1664 | RSS: Main: 281321472 09:22:26 INFO - PROCESS | 1664 | 09:22:28 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:22:29 INFO - PROCESS | 1664 | RSS: Main: 286089216 09:22:29 INFO - PROCESS | 1664 | 09:22:31 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 09:22:32 INFO - PROCESS | 1664 | RSS: Main: 322744320 09:22:32 INFO - PROCESS | 1664 | 09:22:35 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 09:22:36 INFO - PROCESS | 1664 | RSS: Main: 323301376 09:22:36 INFO - PROCESS | 1664 | 09:22:39 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 09:22:39 INFO - PROCESS | 1664 | RSS: Main: 323747840 09:22:39 INFO - PROCESS | 1664 | 09:22:41 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:22:41 INFO - PROCESS | 1664 | RSS: Main: 301031424 09:22:41 INFO - PROCESS | 1664 | 09:22:43 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:22:44 INFO - PROCESS | 1664 | RSS: Main: 302067712 09:22:44 INFO - PROCESS | 1664 | 09:22:46 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:22:47 INFO - PROCESS | 1664 | RSS: Main: 309645312 09:22:47 INFO - PROCESS | 1664 | 09:22:50 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:22:50 INFO - PROCESS | 1664 | RSS: Main: 321417216 09:22:50 INFO - PROCESS | 1664 | 09:22:53 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:22:54 INFO - PROCESS | 1664 | RSS: Main: 315011072 09:22:54 INFO - PROCESS | 1664 | 09:22:56 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 09:22:56 INFO - PROCESS | 1664 | RSS: Main: 358748160 09:22:56 INFO - PROCESS | 1664 | 09:22:56 INFO - PROCESS | 1664 | __start_tp_report 09:22:56 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 09:22:56 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 09:22:56 INFO - PROCESS | 1664 | |i|pagename|runs| 09:22:56 INFO - PROCESS | 1664 | |0;ai-astar;111;115;114;114;114;115;114;114;114;114 09:22:56 INFO - PROCESS | 1664 | |1;audio-beat-detection;158;136;135;148;113;112;115;111;144;111 09:22:56 INFO - PROCESS | 1664 | |2;audio-dft;187;183;185;188;186;181;183;182;180;185 09:22:56 INFO - PROCESS | 1664 | |3;audio-fft;80;59;58;88;59;59;58;58;81;57 09:22:56 INFO - PROCESS | 1664 | |4;audio-oscillator;87;72;90;70;90;97;112;66;67;91 09:22:56 INFO - PROCESS | 1664 | |5;imaging-gaussian-blur;89;85;89;86;89;86;88;87;88;85 09:22:56 INFO - PROCESS | 1664 | |6;imaging-darkroom;95;108;149;150;100;94;96;133;149;94 09:22:56 INFO - PROCESS | 1664 | |7;imaging-desaturate;81;82;81;80;84;84;84;83;83;83 09:22:56 INFO - PROCESS | 1664 | |8;json-parse-financial;52;54;52;53;54;54;54;55;54;54 09:22:56 INFO - PROCESS | 1664 | |9;json-stringify-tinderbox;65;72;69;78;76;73;70;69;74;99 09:22:56 INFO - PROCESS | 1664 | |10;stanford-crypto-aes;86;91;86;87;90;89;89;86;88;84 09:22:56 INFO - PROCESS | 1664 | |11;stanford-crypto-ccm;148;147;150;150;143;150;145;135;214;183 09:22:56 INFO - PROCESS | 1664 | |12;stanford-crypto-pbkdf2;175;175;175;171;175;173;174;175;176;171 09:22:56 INFO - PROCESS | 1664 | |13;stanford-crypto-sha256-iterative;68;68;67;67;68;67;67;67;74;88 09:22:56 INFO - PROCESS | 1664 | __end_tp_report 09:22:56 INFO - PROCESS | 1664 | __start_cc_report 09:22:56 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,2002 09:22:56 INFO - PROCESS | 1664 | __end_cc_report 09:22:56 INFO - PROCESS | 1664 | __startTimestamp1478798576498__endTimestamp 09:22:56 INFO - PROCESS | 1664 | 09:22:56 INFO - PROCESS | 1664 | ------- Summary: start ------- 09:22:56 INFO - PROCESS | 1664 | Number of tests: 14 09:22:56 INFO - PROCESS | 1664 | 09:22:56 INFO - PROCESS | 1664 | [#0] ai-astar Cycles:10 Average:113.90 Median:114.00 stddev:1.10 (1.0%) stddev-sans-first:0.44 09:22:56 INFO - PROCESS | 1664 | Values: 111.0 115.0 114.0 114.0 114.0 115.0 114.0 114.0 114.0 114.0 09:22:56 INFO - PROCESS | 1664 | 09:22:56 INFO - PROCESS | 1664 | [#1] audio-beat-detection Cycles:10 Average:128.30 Median:135.50 stddev:17.94 (13.2%) stddev-sans-first:15.48 09:22:56 INFO - PROCESS | 1664 | Values: 158.0 136.0 135.0 148.0 113.0 112.0 115.0 111.0 144.0 111.0 09:22:56 INFO - PROCESS | 1664 | 09:22:56 INFO - PROCESS | 1664 | [#2] audio-dft Cycles:10 Average:184.00 Median:185.00 stddev:2.62 (1.4%) stddev-sans-first:2.55 09:22:56 INFO - PROCESS | 1664 | Values: 187.0 183.0 185.0 188.0 186.0 181.0 183.0 182.0 180.0 185.0 09:22:56 INFO - PROCESS | 1664 | 09:22:56 INFO - PROCESS | 1664 | [#3] audio-fft Cycles:10 Average:65.70 Median:59.00 stddev:12.13 (20.6%) stddev-sans-first:11.71 09:22:56 INFO - PROCESS | 1664 | Values: 80.0 59.0 58.0 88.0 59.0 59.0 58.0 58.0 81.0 57.0 09:22:56 INFO - PROCESS | 1664 | 09:22:56 INFO - PROCESS | 1664 | [#4] audio-oscillator Cycles:10 Average:84.20 Median:88.50 stddev:15.04 (17.0%) stddev-sans-first:15.92 09:22:56 INFO - PROCESS | 1664 | Values: 87.0 72.0 90.0 70.0 90.0 97.0 112.0 66.0 67.0 91.0 09:22:56 INFO - PROCESS | 1664 | 09:22:56 INFO - PROCESS | 1664 | [#5] imaging-gaussian-blur Cycles:10 Average:87.20 Median:88.00 stddev:1.62 (1.8%) stddev-sans-first:1.58 09:22:56 INFO - PROCESS | 1664 | Values: 89.0 85.0 89.0 86.0 89.0 86.0 88.0 87.0 88.0 85.0 09:22:56 INFO - PROCESS | 1664 | 09:22:56 INFO - PROCESS | 1664 | [#6] imaging-darkroom Cycles:10 Average:116.80 Median:122.00 stddev:25.27 (20.7%) stddev-sans-first:25.54 09:22:56 INFO - PROCESS | 1664 | Values: 95.0 108.0 149.0 150.0 100.0 94.0 96.0 133.0 149.0 94.0 09:22:56 INFO - PROCESS | 1664 | 09:22:56 INFO - PROCESS | 1664 | [#7] imaging-desaturate Cycles:10 Average:82.50 Median:83.00 stddev:1.43 (1.7%) stddev-sans-first:1.41 09:22:56 INFO - PROCESS | 1664 | Values: 81.0 82.0 81.0 80.0 84.0 84.0 84.0 83.0 83.0 83.0 09:22:56 INFO - PROCESS | 1664 | 09:22:56 INFO - PROCESS | 1664 | [#8] json-parse-financial Cycles:10 Average:53.60 Median:54.00 stddev:0.97 (1.8%) stddev-sans-first:0.83 09:22:56 INFO - PROCESS | 1664 | Values: 52.0 54.0 52.0 53.0 54.0 54.0 54.0 55.0 54.0 54.0 09:22:56 INFO - PROCESS | 1664 | 09:22:56 INFO - PROCESS | 1664 | [#9] json-stringify-tinderbox Cycles:10 Average:74.50 Median:73.50 stddev:9.40 (12.8%) stddev-sans-first:9.32 09:22:56 INFO - PROCESS | 1664 | Values: 65.0 72.0 69.0 78.0 76.0 73.0 70.0 69.0 74.0 99.0 09:22:56 INFO - PROCESS | 1664 | 09:22:56 INFO - PROCESS | 1664 | [#10] stanford-crypto-aes Cycles:10 Average:87.60 Median:88.50 stddev:2.17 (2.5%) stddev-sans-first:2.22 09:22:56 INFO - PROCESS | 1664 | Values: 86.0 91.0 86.0 87.0 90.0 89.0 89.0 86.0 88.0 84.0 09:22:56 INFO - PROCESS | 1664 | 09:22:56 INFO - PROCESS | 1664 | [#11] stanford-crypto-ccm Cycles:10 Average:156.50 Median:150.00 stddev:23.75 (15.8%) stddev-sans-first:24.99 09:22:56 INFO - PROCESS | 1664 | Values: 148.0 147.0 150.0 150.0 143.0 150.0 145.0 135.0 214.0 183.0 09:22:56 INFO - PROCESS | 1664 | 09:22:56 INFO - PROCESS | 1664 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.00 Median:175.00 stddev:1.76 (1.0%) stddev-sans-first:1.83 09:22:56 INFO - PROCESS | 1664 | Values: 175.0 175.0 175.0 171.0 175.0 173.0 174.0 175.0 176.0 171.0 09:22:56 INFO - PROCESS | 1664 | 09:22:56 INFO - PROCESS | 1664 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.10 Median:68.00 stddev:6.64 (9.8%) stddev-sans-first:7.00 09:22:56 INFO - PROCESS | 1664 | Values: 68.0 68.0 67.0 67.0 68.0 67.0 67.0 67.0 74.0 88.0 09:22:56 INFO - PROCESS | 1664 | -------- Summary: end -------- 09:22:56 INFO - PROCESS | 1664 | 09:22:57 INFO - TEST-INFO | 1664: exit 0 09:22:57 INFO - TEST-OK | kraken | took 59921ms 09:22:57 INFO - SUITE-END | took 1178s 09:22:57 INFO - Completed test suite (00:19:38) 09:22:57 INFO - javascript benchmark 09:22:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2581.406276556392, 3231.2729635831943, 3237.692419220174, 3253.333066721322, 3286.34273145371, 5417.91873121903, 5516.91724624131, 5543.25166102576, 5563.749631266593, 5644.209810626516, 5637.3517045539675, 5648.435156484349, 5775.797847075342, 5796.62321949073, 5800.796972105977, 5063.772130254129, 5112.0, 5144.742762861842, 5145.3568303962, 5182.559482443997, 5146.922796158042, 5254.369475662926, 5262.315898933133, 5267.973660131714, 5293.04725149473, 5005.349304590416, 5223.764930578133, 5245.763940622681, 5248.685078895279, 5326.6005049621235, 5067.746612669352, 5115.437301896788, 5135.204043373283, 5135.794568217267, 5141.974290128525, 5071.847844564669, 5113.463086375914, 5116.718580478072, 5123.87190320245, 5129.871753206163, 5567.359753628344, 5574.637648552831, 5599.356074051496, 5600.663960162403, 5611.242482264883, 6066.726997285133, 6080.513558915276, 6081.6351018939, 6090.208272924536, 6094.299155597075, 6032.879342413171, 6108.236470441195, 6116.327204007555, 6129.785457508966, 6143.600665956743, 5721.027425337703, 5801.274840644919, 5802.100674395476, 5805.332386775575, 5813.6802475863815, 5187.714675692873, 6047.395260474005, 6083.5741498095595, 6111.205543279345, 6138.263408391022, 6094.329623741385, 6098.72555734993, 6101.359357267423, 6103.969480152571, 6106.847328816815, 6058.091286307001, 6070.696465176812, 6082.422169893853, 6113.969430152821, 6114.419130182626, 6100.122657953208, 6105.808169897265, 6124.908126378108, 6137.355577664371, 6140.078988151813, 6082.330943596201, 6094.6647934363145, 6101.617499577997, 6137.450935349545, 6141.631502109888, 4702.153612349742, 4809.254565542346, 4811.08589368019, 4851.078295123915, 4864.537868902448, 4292.871213863589, 4298.656107511391, 4336.566343365638, 4359.9782001090425, 4400.889977750518, 5531.3915469298345, 5595.944040559623, 5602.355729091125, 5672.546196304285, 5688.943110568925, 5997.190379298739, 6117.471378795421, 6124.777645458507, 6142.2839182294965, 6161.537884658668, 5643.068893632587, 5647.887042259132, 5705.087186050212, 5707.429257074343, 5716.028275193227, 5358.19627055937, 5359.598030147755, 5362.570994320445, 5367.481305673316, 5387.676739395649, 6115.605440958429, 6127.678242056131, 6130.9179418630665, 6157.784477543264, 6187.504999599932, 6195.597286176384, 6202.4417802398, 6209.534284928739, 6216.564840461125, 6225.968870155621, 6146.877062458636, 6160.229971253593, 6165.383461653978, 6174.6912654368, 6176.104464852661, 5997.490213331916, 6062.090686397076, 6067.514598831995, 6077.0, 6100.73256437228, 6163.599366041193, 6176.104464852661, 6200.534959878118, 6202.193714817045, 6237.345078766845, 6050.697465126814, 6074.179985701874, 6111.1444397785235, 6115.685127697567, 6122.051082082285, 5930.05119180929, 6007.249093863267, 6027.517798576016, 6035.0, 6125.0934462995265, 4811.374521312207, 4889.706617602955, 5700.743466543932, 5941.881162376641, 5971.91042134377, 6101.054336577838, 6111.480524155497, 6112.083187521729, 6116.66358350295, 6142.14010038586, 5134.640575159703, 5470.64440811346, 5791.8552036199435, 5917.615355001911, 5925.318588362394], "value": 5596.21860653578, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [465.61354076116805, 476.3117295507992, 512.8615273876055, 518.9844304670859, 524.7035424984883, 30056.248593785167, 31567.684323156762, 39127.41308880365, 40205.798971005104, 40375.59624403755, 41533.0, 41757.582424175824, 41766.0, 41828.79085604575, 42005.15989680205, 9158.908410915872, 9345.15893569582, 9346.39248448852, 9352.719418417424, 9355.48544830034, 9147.0, 9227.169554740072, 9256.768580785467, 9269.768255793591, 9278.953605231964, 9188.494632795193, 9254.53727313635, 9272.211861991738, 9295.0, 9310.487923164223, 9214.815703685923, 9245.07549245077, 9275.30435217358, 9289.071092890725, 9291.442513449214, 9208.3093767967, 9278.628854845798, 9281.30390220736, 9281.53592320388, 9301.58142883565, 39509.0, 39935.201295974206, 39981.0, 40006.0, 40054.799726001474, 3261.608606967179, 3304.5538852254876, 3312.5528053712683, 3352.798832070084, 3646.690031347339, 29784.40431191386, 30338.696613033808, 30601.928932487255, 30645.16935383077, 31007.759689612387, 3217.260030193045, 3217.7264932480766, 3218.1150183699438, 3222.629397619257, 3347.313800670857, 485607.0, 486677.0, 488908.55280260794, 489966.99999999645, 490704.99999999645, 58644.41355586475, 59236.40763592395, 59274.0, 59306.70346648325, 59313.110303345486, 33608.0, 34133.80531681379, 34429.96710098701, 35387.115322117155, 35438.468422973674, 15699.822513311547, 15832.76250856238, 15861.365545378056, 15874.365025398854, 15879.364825407085, 11974.341411222293, 12039.939800300943, 12080.577179798665, 12098.0, 12110.697232569257, 18585.81414185841, 18627.0, 18640.440786776417, 18671.90664046671, 18727.28454374108, 7155.642217889084, 7492.887606685904, 7576.393468833024, 7721.150673425918, 7805.648745806452, 874.1957399192758, 927.188709878856, 967.7000129959735, 989.5151375825938, 1018.5009345420691, 28361.574576381372, 29343.11970640884, 29456.705432945826, 29662.851685741432, 29879.0, 962.2879069488528, 973.2894986659778, 1013.9797204055877, 1086.766345235778, 1107.5791199344199, 14825.590114273777, 15310.234488275542, 15312.310946007456, 15547.533573992798, 15655.68688626221, 675067.0, 679314.0, 679786.0, 681317.0, 681519.0, 56290.718546407006, 56629.43370566324, 56701.0, 56780.148297775566, 56870.0, 1213.7329787446924, 1255.8053501707068, 1321.4053675845716, 1341.9798703019462, 1374.299107455185, 27418.314542136606, 27805.721942780314, 29950.0, 30550.84724576452, 30939.0, 48471.5152848467, 48737.75631121964, 48738.0, 48803.26795098005, 48824.2676359862, 480327.0, 480388.0, 481288.0, 481764.0, 482191.0, 22821.0, 23409.41476463102, 23428.0, 23574.29277121689, 25779.871100644377, 38129.428058579695, 38622.0, 43907.780461098766, 44531.0, 45075.32387014263, 31934.84032579822, 32897.01308960735, 37364.439533406476, 37431.81284093671, 39266.411003835536, 36945.81527092347, 37249.81375093107, 37252.44121338128, 38236.808815955745, 38537.80731096327, 32381.676183237865, 32866.0, 33347.0, 33517.66482335146, 33525.16187095342, 30226.69773302239, 32949.0, 32955.34089318248, 32966.0, 32978.17554561059, 22069.448263793533, 24213.51572968566, 24295.757042429348, 24492.265232043068, 25335.493290134462], "value": 18951.543713406387, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.4905780964458, 371.39833469778955, 379.14313651148404, 389.29731834039546, 398.07447684134377, 18336.44990650281, 19507.609847803073, 20004.399868003984, 20016.29942951997, 20153.992300384958, 16865.325386984536, 17299.308027678908, 17334.56663583411, 17978.370757023506, 19694.310699125534, 5406.40529541751, 5422.2951016368015, 5705.229793977802, 5713.457221563945, 5718.7426565804435, 5447.836564903059, 5630.183623374608, 5636.689982050985, 5642.069058605325, 5667.149927510885, 5523.6685798852195, 5602.943970560304, 5621.156826476041, 5621.7189140542805, 5625.493705566499, 5715.971125197463, 5838.912416313738, 5859.4140585941495, 5876.471117599393, 5878.853028674274, 5420.83737487876, 5597.580181486404, 5657.15142728594, 5668.631538949996, 5670.518005969457, 12699.619011429579, 12943.546975855894, 12947.741045179138, 12984.15602985803, 22578.435539111488, 1532.578540901261, 2126.8404869634683, 2187.628103222456, 2320.396696858829, 2580.935476613081, 7922.405819563498, 9515.096065873731, 9624.711258662252, 10074.848877266846, 10183.28716989811, 1756.8594512438974, 1789.642071585688, 1809.5385676652502, 1876.4933467963574, 1952.3557226115347, 81348.0, 94150.529247354, 119228.40385798103, 119575.0, 119685.0, 9378.155965963097, 11109.0, 11224.55101795919, 11300.15248856339, 11420.714482138013, 14325.426982920775, 14347.56957291262, 14365.209913454652, 14387.424503019762, 14390.496332628518, 8053.677852885936, 8209.261166495044, 8247.340212783083, 8354.874676879852, 8376.0, 8082.353411727047, 8106.797330066677, 8128.349732021424, 8259.38054645904, 8295.95852020736, 4344.196323680129, 4364.454443194601, 4411.84558540456, 4524.6832721709825, 4546.681732278775, 1822.1162736072995, 1891.5176629959547, 1892.6593213221752, 1892.943211703651, 1897.6109897470985, 647.8769033883547, 782.5343920367372, 826.2233500509502, 882.5498995512206, 903.074348792493, 19526.511837204183, 20510.692339614918, 20765.273215437388, 20779.79220207809, 20843.0, 886.7605746448423, 904.1184844776291, 962.3456049886145, 969.9301710087494, 997.7306127345576, 10391.37651740898, 10898.346099234072, 10914.290571112851, 10956.890431095588, 10965.0, 580538.0, 580599.0, 591532.0423397941, 595367.0, 600787.0, 95944.3650071497, 119657.0, 119670.0, 120489.0, 121238.0, 963.8120566489467, 970.0541971577655, 974.6734843827255, 976.7655762616921, 977.3207620703542, 17293.56766080858, 17524.649507010043, 17720.468385948443, 17733.822661773735, 17992.820071799637, 51232.0, 51770.70573235699, 52046.79532046765, 52770.736146319025, 54066.45933540614, 60176.0, 71100.93348599879, 71546.0, 73650.38240203327, 77168.0, 6137.631742095488, 6906.689198985957, 7988.760337189894, 8082.353411727166, 8129.79675508117, 11657.591984280509, 12666.746665066832, 12897.48410063587, 12942.611721647989, 13101.344932753515, 9707.417554946725, 10951.233413660968, 11086.944565277123, 11144.387058711849, 11303.208775385972, 10957.671269861918, 11052.336859788438, 11059.50232239535, 11158.330500170016, 11439.142064345373, 10892.237543371888, 11180.776384472427, 11227.49476273586, 11419.942900285445, 11520.366379849189, 6635.734570617315, 7263.891041634485, 7292.598907060163, 7438.033055702724, 7511.661975211019, 9282.30382721285, 10615.363078215332, 11528.481218345363, 11571.247868888495, 11644.2431241969], "value": 9068.803352355251, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1459.6642772162402, 1486.2940103450862, 1594.4020992127953, 1773.5832079461325, 1800.5498625343664, 7986.880196797053, 8426.578671066438, 8435.66257349706, 8438.409311348209, 8462.534560599172, 7343.1188257409185, 7676.731314403997, 7680.116786569546, 7945.324647404963, 8182.22268884455, 1923.9999999999966, 2289.473421113145, 2291.346966114658, 2292.4727312718087, 2303.2974942642486, 2284.6230371988604, 2318.084356679111, 2327.208749025331, 2327.5461285049423, 2329.3477826208687, 2283.1209984156135, 2286.336962280945, 2312.1791763923734, 2315.050829160045, 2322.140807901079, 2275.5221403505284, 2279.247848210095, 2295.7933785959262, 2296.9655455168186, 2297.85063970843, 2116.2487317002397, 2162.6699579758492, 2317.2005658047947, 2319.7796209360085, 2322.9430609072833, 8295.917040829574, 8346.70786522475, 8361.540115293656, 8366.288865446444, 8391.370647201484, 4983.651144419892, 5150.201718733602, 5158.97420512895, 5162.0, 5162.096633089194, 2651.9204423867313, 3324.6176689680756, 3589.94615080774, 3591.4792355108625, 3597.406427939387, 1042.6142327338932, 1048.0792678375033, 1052.8052310322614, 1071.3625392891217, 1087.1846115413439, 10628.202884783672, 10740.838887416696, 10792.352458852416, 10796.920307969218, 10799.028087472165, 3934.3311637021775, 4391.8682439526865, 4525.909481810345, 4644.2801365788355, 4670.486246512881, 9356.157945784911, 9356.90643093574, 9359.110884465965, 9385.671501497414, 9390.0, 5119.974400127976, 5128.410232823273, 5129.666571672829, 5134.794608215704, 5136.203888397305, 4217.472815898012, 4353.25994580922, 4649.697769644962, 4716.363290955677, 4717.551832575899, 3659.396199627085, 3682.852685892588, 3739.775613463201, 3777.9622203777612, 3778.3576791945434, 1050.7110544600296, 1247.0709321555498, 1247.5839921688948, 1257.867923867999, 1263.9747205055846, 862.8835107260566, 865.9003066106136, 868.3270465389373, 885.7298523950257, 931.9673811416568, 4792.688475249098, 5626.240457538262, 5757.510611598061, 5784.595078344475, 5990.430909063632, 898.766320756608, 939.5677988125539, 985.1084768284713, 989.2382865193904, 1048.3290693955873, 3723.1461030278783, 3735.3836616957897, 3746.4380342948775, 3844.961550384516, 3859.845606175778, 9719.56261968213, 9934.850977235203, 10059.39643621385, 10059.647912323033, 10079.49602519871, 10020.048095430922, 10048.949755251177, 10061.346012509164, 10064.39613623185, 10066.396016239049, 7507.5870827103945, 7684.462087653922, 7813.453058285865, 7833.37333013369, 7839.216078392115, 5403.270558474555, 5443.265159203457, 5495.642783219078, 5545.417731138334, 5567.4432556744005, 8938.4190027648, 9264.583093760932, 9277.211437027929, 9369.672061477815, 9401.576929038347, 9297.071422501891, 9370.578323975575, 9413.435193888388, 9428.198603118812, 9553.522323883917, 6937.583744975318, 7100.538464999759, 7137.464690148156, 7210.098737657793, 7275.708971641075, 7908.0, 7981.680732770624, 7989.840203195788, 7992.760217193261, 7996.880046799419, 7075.257098004841, 7129.0, 7148.606826624586, 7233.63831808418, 7303.890441643486, 6897.172339319314, 6992.61540615271, 7023.543469674455, 7052.858942821217, 7190.496665233383, 6169.84575385601, 6400.743970241138, 6717.361850624183, 6718.093057437183, 6718.630475323708, 4781.115493633689, 4814.15752243349, 4865.36750222483, 4885.828995985124, 4964.503549645006, 4181.686373521937, 4542.3413605027745, 4681.929771053505, 4783.545563171493, 4886.169351210232], "value": 4386.140993296613, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.8216061443441, 300.2269156920928, 309.9969000309996, 313.4107877190881, 316.2788841441514, 4826.686265392747, 5384.623076384654, 5397.541208997235, 5447.863803404912, 5486.396496385404, 4168.729032612878, 4962.751862406883, 5180.533751962323, 5190.8442746717565, 5196.740162991854, 3377.7128944039664, 3399.7280217582597, 4066.877993660195, 4125.855595054174, 4128.318827393477, 3463.5280005997474, 4077.9592204078026, 4083.9387409188885, 4092.1611069730634, 4100.507939047304, 3375.5611770469804, 4040.151568170688, 4041.11095558978, 4074.327735923569, 4104.0, 4052.53395859477, 4067.7999990002722, 4078.0425395810175, 4082.040620502146, 4113.547509773923, 2977.493826049577, 3266.346730653855, 3536.345776031417, 3547.4856145858707, 3556.8399422026073, 5018.899622007576, 5112.821051263225, 5128.2564028216075, 5184.6629969052265, 5249.737513124367, 4034.273830710471, 4294.441722576076, 4611.423572053493, 4686.9454372766195, 4740.952590474086, 4796.928046079277, 5407.053765591006, 5536.280283563111, 5599.32808063035, 5602.887942241173, 3779.3952967525065, 3897.7271590988644, 3913.943235326446, 3955.48578684772, 3955.564887862333, 5399.78400863965, 5758.856028599276, 5792.754557770099, 5840.853978650525, 5878.941210587882, 3479.3389256041273, 3589.6410358963894, 3943.428202910562, 3951.9604803951593, 3957.5053118360206, 3439.243366459375, 3884.3396622573664, 3947.940780888289, 3957.2876882160913, 4046.77742724153, 3745.9063523412133, 4254.5111110001, 4603.723776573416, 4640.443146822336, 4711.45818230901, 5297.761600727977, 5389.2245493570845, 5681.744321505459, 5704.458076482728, 5762.711864406763, 1211.4003568233604, 1270.8093785931999, 1279.3347459321287, 1281.4297637551201, 1288.4667245977257, 792.8652129137945, 796.2077732655929, 819.7049062337554, 828.5608627427473, 847.1105339393612, 4667.439907211088, 4933.629977751683, 4981.626378021663, 5002.973795612783, 5011.699298042056, 5381.811636592701, 5456.508914197741, 5489.2589500417735, 5493.533049690741, 5523.796198205031, 3983.24318379507, 4587.9082418351445, 4970.850874473772, 5141.460146684619, 5439.945600544023, 2652.4429869727187, 2813.1292690360683, 2938.5151450010508, 2942.9264268393463, 2949.9705002949695, 4911.824948793497, 5422.102264207496, 5781.53747700183, 5788.0449725795615, 5792.913106303409, 4482.820687172477, 4993.403430216267, 5065.859167316287, 5075.87310317245, 5563.610547261653, 4586.77066146698, 5181.4300426953005, 5662.37713851476, 5691.723310675763, 5758.646718021261, 4642.373279607345, 5186.4035635902355, 5418.864528386822, 5608.943910560843, 5612.943870561406, 5097.566706829961, 5116.7441627917715, 5442.945570544244, 5494.862628434321, 5513.61404701667, 5317.787288508571, 5739.9139012914, 5747.5401967841635, 5784.2769653793275, 5812.0, 3365.9663403365653, 3384.6784555467193, 3389.6780705217006, 3527.700145487662, 3724.459953306701, 4751.61987041029, 4983.551480366785, 5426.864328391822, 5437.40188579248, 5463.808766693147, 4771.067717574388, 4919.950800491949, 5293.374038783446, 5334.466553344634, 5344.904294619516, 4314.654827613721, 4936.580390666833, 5374.51228224437, 5406.594505412031, 5421.322334708161, 5042.520960508746, 5063.974680126576, 5248.6325957182635, 5268.894622107613, 5301.363836339664, 4820.1320214472335, 5503.669779813224, 5577.52591029767, 5668.489835914787, 5685.658860468385, 4442.933355999598, 5534.059209934401, 5536.557075433875, 5569.35952365467, 5593.1889875966945], "value": 3990.2817118880685, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [688.9862202755945, 705.9717611295547, 725.4269127389363, 761.2273542354511, 775.1899265267795, 13993.790093148611, 14723.484678036271, 14725.852741472581, 14741.631459213499, 14832.0, 13320.733585328288, 13460.192388456699, 13465.192088474698, 13503.72992540151, 13516.526921557748, 653.5784419049734, 674.0798809624876, 675.9222689390709, 802.0055131636772, 818.3739439328889, 666.4568376772938, 668.8261052126458, 671.318611609217, 800.027966021284, 826.1531929771971, 850.9276711479532, 857.4340934982881, 858.2147335188296, 862.4911302331623, 863.1109956744563, 857.6140736668461, 858.2233079063458, 860.5869182792263, 860.7288704058265, 861.6122744764881, 641.1920979565734, 642.8360768004129, 643.0579201469823, 643.4498503779248, 644.2397970394932, 11413.885861141365, 11539.365334906577, 11801.94099029508, 11835.763284734257, 12002.759944801142, 12310.569130080405, 12908.806367904488, 13022.218666880017, 13104.157047319752, 13405.329733513383, 11051.668449946514, 11314.886851131465, 11325.660230193107, 11355.43222838863, 11582.594609188722, 3632.3643362411735, 3642.21692336149, 3665.6334366563397, 3670.3393389189937, 3771.9434208486896, 64678.67660661713, 66886.99669504962, 84698.0, 85305.72041419384, 85724.0, 10049.0, 10305.433201174008, 10585.9470702646, 10620.628278010387, 10649.9467502662, 20651.483712906997, 20955.8952205238, 21036.158553657682, 21136.260230891847, 21136.894315528323, 7517.699292028257, 7521.257344009476, 7524.097108347033, 7576.01511803473, 7761.9611901940125, 4189.72766770159, 4252.87241382759, 4286.249906266391, 4350.673699472489, 4378.584034516716, 3321.9003429897143, 3481.0079127448557, 3490.406630872758, 3492.2666240089357, 3498.370293347171, 633.3096924352478, 634.3751404866204, 644.2526669063864, 659.8218481010197, 660.1220376898713, 645.3983100493544, 653.5980372071142, 656.1718991148167, 667.5327270910382, 667.7696194812781, 8703.173198546128, 8720.389572729848, 8732.432391894507, 8754.912450875538, 8985.683675034681, 634.4195061518659, 650.5153660522941, 659.947204223661, 666.8966310221826, 668.4518694670323, 4052.49343832021, 4096.180763847243, 4106.932197628638, 4695.882602934886, 4718.622510199176, 102406.0, 104176.95823041824, 104274.47862760638, 105185.0, 110901.0, 94732.0, 96429.0357096434, 96967.51516242373, 97015.0, 100455.0, 79412.20587794279, 82061.17938820655, 83130.58434707909, 84136.57931710425, 85001.0, 9428.858567121635, 9431.292653051185, 9437.480938548446, 9459.905400945903, 9510.09654082861, 43452.0, 53008.0, 53813.461865382415, 53888.461115388345, 53936.73031634817, 72887.63556182185, 77896.61051694705, 79578.0, 80185.59907200426, 81725.59137204514, 9878.753031174278, 12038.578649747216, 12081.879181208427, 12456.0, 12777.93611031939, 13283.070185086952, 14027.859721403063, 14237.430502780187, 14435.92782036083, 14563.92718036403, 10725.30285531438, 12649.810252846399, 13241.205527667986, 13303.534376296782, 13780.379882905494, 12907.612771616867, 13031.413586388446, 13223.20660760319, 13245.139065960682, 13625.727485450037, 12543.749125017632, 12805.423755930819, 12877.935610321889, 13118.278494682883, 13433.798493022417, 9310.95344523273, 9713.417194968324, 10241.692749217535, 11067.446627668745, 11221.775564488828, 8803.515806630698, 9312.394694344846, 10797.784044319227, 11060.225784195029, 11259.0], "value": 7153.792660343227, "unit": "score"}], "name": "dromaeo_css", "value": 7027.372632595263, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 115.0, 114.0, 114.0, 114.0, 115.0, 114.0, 114.0, 114.0, 114.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 136.0, 135.0, 148.0, 113.0, 112.0, 115.0, 111.0, 144.0, 111.0], "value": 128.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 183.0, 185.0, 188.0, 186.0, 181.0, 183.0, 182.0, 180.0, 185.0], "value": 184.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 58.0, 88.0, 59.0, 59.0, 58.0, 58.0, 81.0, 57.0], "value": 65.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 72.0, 90.0, 70.0, 90.0, 97.0, 112.0, 66.0, 67.0, 91.0], "value": 84.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 89.0, 86.0, 89.0, 86.0, 88.0, 87.0, 88.0, 85.0], "value": 87.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 108.0, 149.0, 150.0, 100.0, 94.0, 96.0, 133.0, 149.0, 94.0], "value": 116.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 81.0, 80.0, 84.0, 84.0, 84.0, 83.0, 83.0, 83.0], "value": 82.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 54.0, 52.0, 53.0, 54.0, 54.0, 54.0, 55.0, 54.0, 54.0], "value": 53.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 72.0, 69.0, 78.0, 76.0, 73.0, 70.0, 69.0, 74.0, 99.0], "value": 74.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 91.0, 86.0, 87.0, 90.0, 89.0, 89.0, 86.0, 88.0, 84.0], "value": 87.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 150.0, 150.0, 143.0, 150.0, 145.0, 135.0, 214.0, 183.0], "value": 156.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 175.0, 175.0, 171.0, 175.0, 173.0, 174.0, 175.0, 176.0, 171.0], "value": 174.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 67.0, 67.0, 68.0, 67.0, 67.0, 67.0, 74.0, 88.0], "value": 70.1, "unit": "score"}], "name": "kraken", "value": 1478.8999999999999, "alertThreshold": 2.0}]} 09:22:57 INFO - Return code: 0 09:22:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:22:57 INFO - # TBPL SUCCESS # 09:22:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:22:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:22:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:22:57 INFO - 2016-11-10 09:22:57.793 screenresolution[1668:9935] starting screenresolution argv=screenresolution get 09:22:57 INFO - 2016-11-10 09:22:57.796 screenresolution[1668:9935] Display 0: 1600x1200x32@60 09:22:57 INFO - 2016-11-10 09:22:57.803 screenresolution[1669:9938] starting screenresolution argv=screenresolution list 09:22:57 INFO - Available Modes on Display 0 09:22:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:22:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:22:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:22:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:22:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:22:58 INFO - Intel Iris: 09:22:58 INFO - Chipset Model: Intel Iris 09:22:58 INFO - Type: GPU 09:22:58 INFO - Bus: Built-In 09:22:58 INFO - VRAM (Dynamic, Max): 1536 MB 09:22:58 INFO - Vendor: Intel (0x8086) 09:22:58 INFO - Device ID: 0x0a2e 09:22:58 INFO - Revision ID: 0x0009 09:22:58 INFO - Displays: 09:22:58 INFO - Display: 09:22:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:22:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:22:58 INFO - Display Serial Number: 1600x1200 60 09:22:58 INFO - Main Display: Yes 09:22:58 INFO - Mirror: Off 09:22:58 INFO - Online: Yes 09:22:58 INFO - Rotation: Supported 09:22:58 INFO - Return code: 0 09:22:58 INFO - Running post-action listener: _resource_record_post_action 09:22:58 INFO - [mozharness: 2016-11-10 17:22:58.042945Z] Finished run-tests step (success) 09:22:58 INFO - Running post-run listener: _resource_record_post_run 09:22:58 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 37438464; Write bytes: 698460160; Read time: 238; Write time: 1919 09:22:58 INFO - TinderboxPrint: CPU usage
54.0% 09:22:58 INFO - TinderboxPrint: I/O read bytes / time
37,438,464 / 238 09:22:58 INFO - TinderboxPrint: I/O write bytes / time
698,460,160 / 1,919 09:22:58 INFO - TinderboxPrint: CPU idle
2,232.5 (46.0%) 09:22:58 INFO - TinderboxPrint: CPU system
978.2 (20.2%) 09:22:58 INFO - TinderboxPrint: CPU user
1,642.9 (33.8%) 09:22:58 INFO - TinderboxPrint: Swap in / out
435,560,448 / 0 09:22:58 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 395686400; Write bytes: 390744064; Read time: 29574; Write time: 783 09:22:58 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 34317312; Write bytes: 295530496; Read time: 216; Write time: 1100 09:22:58 INFO - Running post-run listener: _upload_blobber_files 09:22:58 INFO - Blob upload gear active. 09:22:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:22:58 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 09:22:58 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'] 09:22:58 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 09:22:58 INFO - (blobuploader) - INFO - Open directory for files ... 09:22:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:22:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:22:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:23:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:23:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:23:00 INFO - (blobuploader) - INFO - Done attempting. 09:23:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:23:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:23:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:23:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:23:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:23:01 INFO - (blobuploader) - INFO - Done attempting. 09:23:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:23:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:23:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:23:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:23:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:23:04 INFO - (blobuploader) - INFO - Done attempting. 09:23:04 INFO - (blobuploader) - INFO - Iteration through files over. 09:23:04 INFO - Return code: 0 09:23:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:23:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:23:04 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/955a970f3f1b24f27131e10f0e04719ca348863ab170ca9dbe2fc5cfabf0843803389f9fcffe064543fb1af08304baad8ab86dcb41fa87f39030cd1bb5493883", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c262ae3b0127e349191620ad02c6c3d9aacee15be17648578a8150a04db0eef43ab96f321b786fdde12e658c5669d5d6d50a25b3fbb2f9a738c3402451019a24", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d5b981026fa9e7a7625e773276dc92b8d41582a79e46936b16a55562e66044f49a77a82be3462fdb824dda0974618bce6c0187d582090036e84355ca71a0a0ae"} 09:23:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:23:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:23:04 INFO - Contents: 09:23:04 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/955a970f3f1b24f27131e10f0e04719ca348863ab170ca9dbe2fc5cfabf0843803389f9fcffe064543fb1af08304baad8ab86dcb41fa87f39030cd1bb5493883", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c262ae3b0127e349191620ad02c6c3d9aacee15be17648578a8150a04db0eef43ab96f321b786fdde12e658c5669d5d6d50a25b3fbb2f9a738c3402451019a24", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d5b981026fa9e7a7625e773276dc92b8d41582a79e46936b16a55562e66044f49a77a82be3462fdb824dda0974618bce6c0187d582090036e84355ca71a0a0ae"} 09:23:04 INFO - Running post-run listener: copy_logs_to_upload_dir 09:23:04 INFO - Copying logs to upload dir... 09:23:04 INFO - mkdir: /builds/slave/test/build/upload/logs 09:23:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.438947 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-11-10 09:23:04.219460) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-10 09:23:04.223343) ========= 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.MMANeWiGp6/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.vAN9dpeYnu/Listeners TMPDIR=/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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/955a970f3f1b24f27131e10f0e04719ca348863ab170ca9dbe2fc5cfabf0843803389f9fcffe064543fb1af08304baad8ab86dcb41fa87f39030cd1bb5493883", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c262ae3b0127e349191620ad02c6c3d9aacee15be17648578a8150a04db0eef43ab96f321b786fdde12e658c5669d5d6d50a25b3fbb2f9a738c3402451019a24", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d5b981026fa9e7a7625e773276dc92b8d41582a79e46936b16a55562e66044f49a77a82be3462fdb824dda0974618bce6c0187d582090036e84355ca71a0a0ae"} build_url:https://queue.taskcluster.net/v1/task/CVQAqw-kS2O8RYxxSZMCWg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011094 build_url: 'https://queue.taskcluster.net/v1/task/CVQAqw-kS2O8RYxxSZMCWg/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/955a970f3f1b24f27131e10f0e04719ca348863ab170ca9dbe2fc5cfabf0843803389f9fcffe064543fb1af08304baad8ab86dcb41fa87f39030cd1bb5493883", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c262ae3b0127e349191620ad02c6c3d9aacee15be17648578a8150a04db0eef43ab96f321b786fdde12e658c5669d5d6d50a25b3fbb2f9a738c3402451019a24", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d5b981026fa9e7a7625e773276dc92b8d41582a79e46936b16a55562e66044f49a77a82be3462fdb824dda0974618bce6c0187d582090036e84355ca71a0a0ae"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-10 09:23:04.255581) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-10 09:23:04.255903) ========= 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.MMANeWiGp6/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.vAN9dpeYnu/Listeners TMPDIR=/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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.005280 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-10 09:23:04.304760) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-10 09:23:04.305080) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-10 09:23:04.305397) ========= ========= Total master_lag: 0.18 =========