builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0116 starttime: 1487941945.09 results: success (0) buildid: 20170224033759 builduid: 55781367e7b9467b85bf0d26d1c3d92d revision: 66e711359af5799d9f133f59fab3d730cf8a20cb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-24 05:12:25.085772) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-24 05:12:25.086203) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-24 05:12:25.086481) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-24 05:12:25.111903) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-24 05:12:25.112204) ========= 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.5z2R3af1qq/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.mVuD114YGO/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 --2017-02-24 05:12:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 164M=0s 2017-02-24 05:12:25 (164 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.140314 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-24 05:12:25.279340) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-24 05:12:25.280089) ========= 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.5z2R3af1qq/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.mVuD114YGO/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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.028431 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-24 05:12:25.331762) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-24 05:12:25.332364) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 66e711359af5799d9f133f59fab3d730cf8a20cb --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 66e711359af5799d9f133f59fab3d730cf8a20cb --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5z2R3af1qq/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.mVuD114YGO/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 2017-02-24 05:12:25,436 truncating revision to first 12 chars 2017-02-24 05:12:25,436 Setting DEBUG logging. 2017-02-24 05:12:25,436 attempt 1/10 2017-02-24 05:12:25,436 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/66e711359af5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-24 05:12:26,576 unpacking tar archive at: mozilla-aurora-66e711359af5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.400800 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-24 05:12:26.757265) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-24 05:12:26.757638) ========= script_repo_revision: 66e711359af5799d9f133f59fab3d730cf8a20cb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-24 05:12:26.758071) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-24 05:12:26.758357) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-24 05:12:26.777237) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-02-24 05:12:26.777509) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5z2R3af1qq/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.mVuD114YGO/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 05:12:26 INFO - MultiFileLogger online at 20170224 05:12:26 in /builds/slave/test 05:12:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 05:12:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:12:26 INFO - {'append_to_log': False, 05:12:26 INFO - 'base_work_dir': '/builds/slave/test', 05:12:26 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 05:12:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:12:26 INFO - 'branch': 'Mozilla-Aurora', 05:12:26 INFO - 'buildbot_json_path': 'buildprops.json', 05:12:26 INFO - 'config_files': ('talos/mac_config.py',), 05:12:26 INFO - 'default_actions': ('clobber', 05:12:26 INFO - 'read-buildbot-config', 05:12:26 INFO - 'download-and-extract', 05:12:26 INFO - 'populate-webroot', 05:12:26 INFO - 'create-virtualenv', 05:12:26 INFO - 'install', 05:12:26 INFO - 'run-tests'), 05:12:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:12:26 INFO - 'download_minidump_stackwalk': True, 05:12:26 INFO - 'download_symbols': 'ondemand', 05:12:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:12:26 INFO - 'tooltool.py': '/tools/tooltool.py', 05:12:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:12:26 INFO - '/tools/misc-python/virtualenv.py')}, 05:12:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:12:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:12:26 INFO - 'installer_path': 'installer.exe', 05:12:26 INFO - 'log_level': 'info', 05:12:26 INFO - 'log_name': 'talos', 05:12:26 INFO - 'log_to_console': True, 05:12:26 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:12:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:12:26 INFO - 'opt_config_files': (), 05:12:26 INFO - 'pip_index': False, 05:12:26 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:12:26 INFO - 'cmd': ('bash', 05:12:26 INFO - '-c', 05:12:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:12:26 INFO - 'enabled': True, 05:12:26 INFO - 'halt_on_failure': False, 05:12:26 INFO - 'name': 'check_screen_resolution'},), 05:12:26 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:12:26 INFO - 'cmd': ('bash', 05:12:26 INFO - '-c', 05:12:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:12:26 INFO - 'enabled': True, 05:12:26 INFO - 'halt_on_failure': False, 05:12:26 INFO - 'name': 'check_screen_resolution'},), 05:12:26 INFO - 'run_cmd_checks_enabled': True, 05:12:26 INFO - 'sps_profile': False, 05:12:26 INFO - 'sps_profile_interval': 0, 05:12:26 INFO - 'suite': 'dromaeojs', 05:12:26 INFO - 'system_bits': '32', 05:12:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:12:26 INFO - 'title': 't-yosemite-r7-0116', 05:12:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:12:26 INFO - 'use_talos_json': True, 05:12:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:12:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:12:26 INFO - 'work_dir': 'build'} 05:12:26 INFO - [mozharness: 2017-02-24 13:12:26.993598Z] Running clobber step. 05:12:26 INFO - Running pre-action listener: _resource_record_pre_action 05:12:26 INFO - Running main action method: clobber 05:12:26 INFO - rmtree: /builds/slave/test/build 05:12:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:12:29 INFO - Running post-action listener: _resource_record_post_action 05:12:29 INFO - [mozharness: 2017-02-24 13:12:29.685370Z] Finished clobber step (success) 05:12:29 INFO - [mozharness: 2017-02-24 13:12:29.685506Z] Running read-buildbot-config step. 05:12:29 INFO - Running pre-action listener: _resource_record_pre_action 05:12:29 INFO - Running main action method: read_buildbot_config 05:12:29 INFO - Using buildbot properties: 05:12:29 INFO - { 05:12:29 INFO - "project": "", 05:12:29 INFO - "product": "firefox", 05:12:29 INFO - "script_repo_revision": "production", 05:12:29 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 05:12:29 INFO - "repository": "", 05:12:29 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 05:12:29 INFO - "buildid": "20170224033759", 05:12:29 INFO - "pgo_build": "False", 05:12:29 INFO - "basedir": "/builds/slave/test", 05:12:29 INFO - "buildnumber": 2, 05:12:29 INFO - "slavename": "t-yosemite-r7-0116", 05:12:29 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 05:12:29 INFO - "platform": "yosemite_r7", 05:12:29 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 05:12:29 INFO - "branch": "mozilla-aurora", 05:12:29 INFO - "repo_path": "releases/mozilla-aurora", 05:12:29 INFO - "revision": "66e711359af5799d9f133f59fab3d730cf8a20cb", 05:12:29 INFO - "stage_platform": "macosx64", 05:12:29 INFO - "builduid": "55781367e7b9467b85bf0d26d1c3d92d", 05:12:29 INFO - "slavebuilddir": "test" 05:12:29 INFO - } 05:12:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/ddS3HRxjRi6lR80AtBF1rA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 05:12:29 INFO - Running post-action listener: _resource_record_post_action 05:12:29 INFO - [mozharness: 2017-02-24 13:12:29.689275Z] Finished read-buildbot-config step (success) 05:12:29 INFO - [mozharness: 2017-02-24 13:12:29.689400Z] Running download-and-extract step. 05:12:29 INFO - Running pre-action listener: _resource_record_pre_action 05:12:29 INFO - Running main action method: download_and_extract 05:12:29 INFO - mkdir: /builds/slave/test/build/tests 05:12:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:12:29 INFO - trying https://queue.taskcluster.net/v1/task/ddS3HRxjRi6lR80AtBF1rA/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 05:12:29 INFO - Downloading https://queue.taskcluster.net/v1/task/ddS3HRxjRi6lR80AtBF1rA/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 05:12:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ddS3HRxjRi6lR80AtBF1rA/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json'}, attempt #1 05:12:30 INFO - Downloaded 1199 bytes. 05:12:30 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 05:12:30 INFO - Using the following test package requirements: 05:12:30 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 05:12:30 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 05:12:30 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 05:12:30 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 05:12:30 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 05:12:30 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 05:12:30 INFO - u'jsshell-mac.zip'], 05:12:30 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 05:12:30 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 05:12:30 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 05:12:30 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 05:12:30 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 05:12:30 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 05:12:30 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 05:12:30 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 05:12:30 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 05:12:30 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 05:12:30 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 05:12:30 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 05:12:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ddS3HRxjRi6lR80AtBF1rA/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 05:12:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ddS3HRxjRi6lR80AtBF1rA/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 05:12:30 INFO - Fetch https://queue.taskcluster.net/v1/task/ddS3HRxjRi6lR80AtBF1rA/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 05:12:31 INFO - Expected file size: 16357728 05:12:31 INFO - Obtained file size: 16357728 05:12:37 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip', u'firefox-53.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 05:12:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ddS3HRxjRi6lR80AtBF1rA/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 05:12:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ddS3HRxjRi6lR80AtBF1rA/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 05:12:37 INFO - Fetch https://queue.taskcluster.net/v1/task/ddS3HRxjRi6lR80AtBF1rA/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 05:12:38 INFO - Expected file size: 16357728 05:12:38 INFO - Obtained file size: 16357728 05:12:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ddS3HRxjRi6lR80AtBF1rA/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 05:12:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ddS3HRxjRi6lR80AtBF1rA/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 05:12:45 INFO - Fetch https://queue.taskcluster.net/v1/task/ddS3HRxjRi6lR80AtBF1rA/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 05:12:46 INFO - Expected file size: 14403472 05:12:46 INFO - Obtained file size: 14403472 05:12:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:12:46 INFO - trying https://queue.taskcluster.net/v1/task/ddS3HRxjRi6lR80AtBF1rA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 05:12:46 INFO - Downloading https://queue.taskcluster.net/v1/task/ddS3HRxjRi6lR80AtBF1rA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 05:12:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ddS3HRxjRi6lR80AtBF1rA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg'}, attempt #1 05:12:48 INFO - Downloaded 73927195 bytes. 05:12:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ddS3HRxjRi6lR80AtBF1rA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 05:12:48 INFO - mkdir: /builds/slave/test/properties 05:12:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:12:48 INFO - Writing to file /builds/slave/test/properties/build_url 05:12:48 INFO - Contents: 05:12:48 INFO - build_url:https://queue.taskcluster.net/v1/task/ddS3HRxjRi6lR80AtBF1rA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 05:12:49 INFO - Running post-action listener: _resource_record_post_action 05:12:49 INFO - Running post-action listener: set_extra_try_arguments 05:12:49 INFO - [mozharness: 2017-02-24 13:12:49.047890Z] Finished download-and-extract step (success) 05:12:49 INFO - [mozharness: 2017-02-24 13:12:49.048044Z] Running populate-webroot step. 05:12:49 INFO - Running pre-action listener: _resource_record_pre_action 05:12:49 INFO - Running main action method: populate_webroot 05:12:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:12:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:12:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:12:49 INFO - '--tppagecycles', 05:12:49 INFO - '7'], 05:12:49 INFO - 'tests': ['tsvgm']}}, 05:12:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:12:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:12:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:12:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:12:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:12:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:12:49 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:12:49 INFO - 'talos_options': ['--disable-e10s'], 05:12:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:12:49 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:12:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:12:49 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:12:49 INFO - 'talos_options': ['--disable-e10s'], 05:12:49 INFO - 'tests': ['damp', 'tps']}, 05:12:49 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:12:49 INFO - 'tests': ['damp', 'tps']}, 05:12:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:12:49 INFO - 'tests': ['dromaeo_dom']}, 05:12:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:12:49 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:12:49 INFO - 'tests': ['basic_compositor_video']}, 05:12:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:12:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:12:49 INFO - 'tests': ['a11yr', 05:12:49 INFO - 'ts_paint', 05:12:49 INFO - 'tpaint', 05:12:49 INFO - 'sessionrestore', 05:12:49 INFO - 'sessionrestore_no_auto_restore', 05:12:49 INFO - 'tabpaint']}, 05:12:49 INFO - 'other-e10s': {'tests': ['a11yr', 05:12:49 INFO - 'ts_paint', 05:12:49 INFO - 'tpaint', 05:12:49 INFO - 'sessionrestore', 05:12:49 INFO - 'sessionrestore_no_auto_restore', 05:12:49 INFO - 'tabpaint']}, 05:12:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:12:49 INFO - 'ts_paint', 05:12:49 INFO - 'tpaint', 05:12:49 INFO - 'sessionrestore', 05:12:49 INFO - 'sessionrestore_no_auto_restore', 05:12:49 INFO - 'tabpaint']}, 05:12:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:12:49 INFO - 'ts_paint', 05:12:49 INFO - 'tpaint', 05:12:49 INFO - 'sessionrestore', 05:12:49 INFO - 'sessionrestore_no_auto_restore', 05:12:49 INFO - 'tabpaint']}, 05:12:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:12:49 INFO - 'tests': ['a11yr', 05:12:49 INFO - 'ts_paint', 05:12:49 INFO - 'tpaint', 05:12:49 INFO - 'sessionrestore', 05:12:49 INFO - 'sessionrestore_no_auto_restore', 05:12:49 INFO - 'tabpaint']}, 05:12:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:12:49 INFO - 'tests': ['a11yr', 05:12:49 INFO - 'ts_paint', 05:12:49 INFO - 'tpaint', 05:12:49 INFO - 'sessionrestore', 05:12:49 INFO - 'sessionrestore_no_auto_restore', 05:12:49 INFO - 'tabpaint']}, 05:12:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:12:49 INFO - 'tests': ['tsvgx', 05:12:49 INFO - 'tsvgr_opacity', 05:12:49 INFO - 'tart', 05:12:49 INFO - 'tscrollx', 05:12:49 INFO - 'cart', 05:12:49 INFO - 'tsvg_static']}, 05:12:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:12:49 INFO - 'tsvgr_opacity', 05:12:49 INFO - 'tart', 05:12:49 INFO - 'tscrollx', 05:12:49 INFO - 'cart', 05:12:49 INFO - 'tsvg_static']}, 05:12:49 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:12:49 INFO - 'talos_options': ['--disable-e10s'], 05:12:49 INFO - 'tests': ['tp5o']}, 05:12:49 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:12:49 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:12:49 INFO - 'talos_options': ['--disable-e10s', 05:12:49 INFO - '--xperf_path', 05:12:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:12:49 INFO - 'tests': ['tp5n']}, 05:12:49 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:12:49 INFO - 'talos_options': ['--xperf_path', 05:12:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:12:49 INFO - 'tests': ['tp5n']}}, 05:12:49 INFO - 'talos.zip': {'path': '', 05:12:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:12:49 INFO - Running post-action listener: _resource_record_post_action 05:12:49 INFO - [mozharness: 2017-02-24 13:12:49.066121Z] Finished populate-webroot step (success) 05:12:49 INFO - [mozharness: 2017-02-24 13:12:49.066252Z] Running create-virtualenv step. 05:12:49 INFO - Running pre-action listener: _resource_record_pre_action 05:12:49 INFO - Running main action method: create_virtualenv 05:12:49 INFO - Creating virtualenv /builds/slave/test/build/venv 05:12:49 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 05:12:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:12:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:12:49 INFO - Using real prefix '/tools/python27' 05:12:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:12:49 INFO - Installing distribute.............................................................................................................................................................................................done. 05:12:52 INFO - Installing pip.................done. 05:12:52 INFO - Return code: 0 05:12:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:12:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:12:53 INFO - Reading from file tmpfile_stdout 05:12:53 INFO - Output received: 05:12:53 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) 05:12:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:12:53 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')]} 05:12:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:53 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')]} 05:12:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:53 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 0x10550db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105510768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92b0dd2480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052ebd30>, '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 0x105212db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1052e0be8>, '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.5z2R3af1qq/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.mVuD114YGO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 05:12:53 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 05:12:53 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 05:12:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5z2R3af1qq/Render', 05:12:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:12:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:12:53 INFO - 'HOME': '/Users/cltbld', 05:12:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:12:53 INFO - 'LOGNAME': 'cltbld', 05:12:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:53 INFO - 'MOZ_NO_REMOTE': '1', 05:12:53 INFO - 'NO_EM_RESTART': '1', 05:12:53 INFO - 'PAGER': '/bin/cat', 05:12:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:12:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:53 INFO - 'PWD': '/builds/slave/test', 05:12:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:12:53 INFO - 'SHELL': '/bin/bash', 05:12:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mVuD114YGO/Listeners', 05:12:53 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 05:12:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:12:53 INFO - 'USER': 'cltbld', 05:12:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:12:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:12:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:53 INFO - 'XPC_FLAGS': '0x0', 05:12:53 INFO - 'XPC_SERVICE_NAME': '0', 05:12:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:12:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:53 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 05:12:53 INFO - Cleaning up... 05:12:53 INFO - Return code: 0 05:12:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:12:53 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')]} 05:12:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:53 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')]} 05:12:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:53 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 0x10550db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105510768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92b0dd2480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052ebd30>, '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 0x105212db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1052e0be8>, '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.5z2R3af1qq/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.mVuD114YGO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 05:12:53 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 05:12:53 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 05:12:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5z2R3af1qq/Render', 05:12:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:12:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:12:53 INFO - 'HOME': '/Users/cltbld', 05:12:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:12:53 INFO - 'LOGNAME': 'cltbld', 05:12:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:53 INFO - 'MOZ_NO_REMOTE': '1', 05:12:53 INFO - 'NO_EM_RESTART': '1', 05:12:53 INFO - 'PAGER': '/bin/cat', 05:12:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:12:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:53 INFO - 'PWD': '/builds/slave/test', 05:12:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:12:53 INFO - 'SHELL': '/bin/bash', 05:12:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mVuD114YGO/Listeners', 05:12:53 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 05:12:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:12:53 INFO - 'USER': 'cltbld', 05:12:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:12:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:12:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:53 INFO - 'XPC_FLAGS': '0x0', 05:12:53 INFO - 'XPC_SERVICE_NAME': '0', 05:12:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:12:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:53 INFO - Downloading/unpacking psutil>=3.1.1 05:12:53 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 05:12:53 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 05:12:53 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 05:12:53 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 05:12:53 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 05:12:53 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 05:12:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:12:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:12:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:12:57 INFO - Installing collected packages: psutil 05:12:57 INFO - Running setup.py install for psutil 05:12:57 INFO - building 'psutil._psutil_osx' extension 05:12:57 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 05:12:57 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 05:12:57 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 05:12:57 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 05:12:57 INFO - building 'psutil._psutil_posix' extension 05:12:57 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 05:12:58 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:12:58 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:12:58 INFO - ^ 05:12:58 INFO - 1 warning generated. 05:12:58 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 05:12:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:12:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:12:58 INFO - Successfully installed psutil 05:12:58 INFO - Cleaning up... 05:12:58 INFO - Return code: 0 05:12:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:12:58 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')]} 05:12:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:58 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')]} 05:12:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12:58 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 0x10550db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105510768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92b0dd2480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052ebd30>, '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 0x105212db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1052e0be8>, '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.5z2R3af1qq/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.mVuD114YGO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 05:12:58 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 05:12:58 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 05:12:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5z2R3af1qq/Render', 05:12:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:12:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:12:58 INFO - 'HOME': '/Users/cltbld', 05:12:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:12:58 INFO - 'LOGNAME': 'cltbld', 05:12:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:58 INFO - 'MOZ_NO_REMOTE': '1', 05:12:58 INFO - 'NO_EM_RESTART': '1', 05:12:58 INFO - 'PAGER': '/bin/cat', 05:12:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:12:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:58 INFO - 'PWD': '/builds/slave/test', 05:12:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:12:58 INFO - 'SHELL': '/bin/bash', 05:12:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mVuD114YGO/Listeners', 05:12:58 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 05:12:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:12:58 INFO - 'USER': 'cltbld', 05:12:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:12:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:12:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:58 INFO - 'XPC_FLAGS': '0x0', 05:12:58 INFO - 'XPC_SERVICE_NAME': '0', 05:12:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:12:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:12:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:12:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:12:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:12:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:12:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:12:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:13:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:13:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:13:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:13:01 INFO - Installing collected packages: mozsystemmonitor 05:13:01 INFO - Running setup.py install for mozsystemmonitor 05:13:01 INFO - Successfully installed mozsystemmonitor 05:13:01 INFO - Cleaning up... 05:13:01 INFO - Return code: 0 05:13:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:13:01 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')]} 05:13:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:01 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')]} 05:13:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:01 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 0x10550db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105510768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92b0dd2480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052ebd30>, '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 0x105212db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1052e0be8>, '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.5z2R3af1qq/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.mVuD114YGO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 05:13:01 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 05:13:01 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 05:13:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5z2R3af1qq/Render', 05:13:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:01 INFO - 'HOME': '/Users/cltbld', 05:13:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:01 INFO - 'LOGNAME': 'cltbld', 05:13:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:01 INFO - 'MOZ_NO_REMOTE': '1', 05:13:01 INFO - 'NO_EM_RESTART': '1', 05:13:01 INFO - 'PAGER': '/bin/cat', 05:13:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:01 INFO - 'PWD': '/builds/slave/test', 05:13:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:01 INFO - 'SHELL': '/bin/bash', 05:13:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mVuD114YGO/Listeners', 05:13:01 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 05:13:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:01 INFO - 'USER': 'cltbld', 05:13:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:01 INFO - 'XPC_FLAGS': '0x0', 05:13:01 INFO - 'XPC_SERVICE_NAME': '0', 05:13:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:01 INFO - Downloading/unpacking jsonschema==2.5.1 05:13:01 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 05:13:01 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 05:13:01 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 05:13:01 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 05:13:01 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 05:13:01 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 05:13:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:13:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:13:04 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 05:13:04 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 05:13:04 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 05:13:04 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 05:13:04 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 05:13:04 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 05:13:05 INFO - Downloading functools32-3.2.3-2.tar.gz 05:13:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:13:05 INFO - warning: no files found matching '*.txt' 05:13:05 INFO - no previously-included directories found matching 'build' 05:13:05 INFO - no previously-included directories found matching 'dist' 05:13:05 INFO - no previously-included directories found matching '.git*' 05:13:05 INFO - Installing collected packages: jsonschema, functools32 05:13:05 INFO - Running setup.py install for functools32 05:13:05 INFO - warning: no files found matching '*.txt' 05:13:05 INFO - no previously-included directories found matching 'build' 05:13:05 INFO - no previously-included directories found matching 'dist' 05:13:05 INFO - no previously-included directories found matching '.git*' 05:13:05 INFO - Successfully installed jsonschema functools32 05:13:05 INFO - Cleaning up... 05:13:05 INFO - Return code: 0 05:13:05 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:13:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:13:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:13:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:05 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 0x10550db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105510768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92b0dd2480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052ebd30>, '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 0x105212db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1052e0be8>, '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.5z2R3af1qq/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.mVuD114YGO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 05:13:05 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 05:13:05 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 05:13:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5z2R3af1qq/Render', 05:13:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:05 INFO - 'HOME': '/Users/cltbld', 05:13:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:05 INFO - 'LOGNAME': 'cltbld', 05:13:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:05 INFO - 'MOZ_NO_REMOTE': '1', 05:13:05 INFO - 'NO_EM_RESTART': '1', 05:13:05 INFO - 'PAGER': '/bin/cat', 05:13:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:05 INFO - 'PWD': '/builds/slave/test', 05:13:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:05 INFO - 'SHELL': '/bin/bash', 05:13:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mVuD114YGO/Listeners', 05:13:05 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 05:13:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:05 INFO - 'USER': 'cltbld', 05:13:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:05 INFO - 'XPC_FLAGS': '0x0', 05:13:05 INFO - 'XPC_SERVICE_NAME': '0', 05:13:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:13:05 INFO - Cleaning up... 05:13:05 INFO - Return code: 0 05:13:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:13:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:13:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:13:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:05 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 0x10550db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105510768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92b0dd2480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052ebd30>, '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 0x105212db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1052e0be8>, '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.5z2R3af1qq/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.mVuD114YGO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 05:13:05 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 05:13:05 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 05:13:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5z2R3af1qq/Render', 05:13:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:05 INFO - 'HOME': '/Users/cltbld', 05:13:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:05 INFO - 'LOGNAME': 'cltbld', 05:13:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:05 INFO - 'MOZ_NO_REMOTE': '1', 05:13:05 INFO - 'NO_EM_RESTART': '1', 05:13:05 INFO - 'PAGER': '/bin/cat', 05:13:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:05 INFO - 'PWD': '/builds/slave/test', 05:13:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:05 INFO - 'SHELL': '/bin/bash', 05:13:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mVuD114YGO/Listeners', 05:13:05 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 05:13:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:05 INFO - 'USER': 'cltbld', 05:13:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:05 INFO - 'XPC_FLAGS': '0x0', 05:13:05 INFO - 'XPC_SERVICE_NAME': '0', 05:13:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:06 INFO - Downloading/unpacking blobuploader==1.2.4 05:13:06 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 05:13:06 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 05:13:06 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 05:13:06 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 05:13:06 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 05:13:06 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 05:13:09 INFO - Downloading blobuploader-1.2.4.tar.gz 05:13:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:13:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:13:09 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 05:13:09 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 05:13:09 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 05:13:09 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 05:13:09 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 05:13:09 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 05:13:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:13:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:13:09 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 05:13:09 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 05:13:09 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 05:13:09 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 05:13:09 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 05:13:09 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 05:13:10 INFO - Downloading docopt-0.6.1.tar.gz 05:13:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:13:10 INFO - Installing collected packages: blobuploader, requests, docopt 05:13:10 INFO - Running setup.py install for blobuploader 05:13:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:13:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:13:10 INFO - Running setup.py install for requests 05:13:11 INFO - Running setup.py install for docopt 05:13:11 INFO - Successfully installed blobuploader requests docopt 05:13:11 INFO - Cleaning up... 05:13:11 INFO - Return code: 0 05:13:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:13:11 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')]} 05:13:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:11 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')]} 05:13:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:11 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 0x10550db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105510768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92b0dd2480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052ebd30>, '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 0x105212db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1052e0be8>, '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.5z2R3af1qq/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.mVuD114YGO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 05:13:11 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 05:13:11 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 05:13:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5z2R3af1qq/Render', 05:13:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:11 INFO - 'HOME': '/Users/cltbld', 05:13:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:11 INFO - 'LOGNAME': 'cltbld', 05:13:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:11 INFO - 'MOZ_NO_REMOTE': '1', 05:13:11 INFO - 'NO_EM_RESTART': '1', 05:13:11 INFO - 'PAGER': '/bin/cat', 05:13:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:11 INFO - 'PWD': '/builds/slave/test', 05:13:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:11 INFO - 'SHELL': '/bin/bash', 05:13:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mVuD114YGO/Listeners', 05:13:11 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 05:13:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:11 INFO - 'USER': 'cltbld', 05:13:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:11 INFO - 'XPC_FLAGS': '0x0', 05:13:11 INFO - 'XPC_SERVICE_NAME': '0', 05:13:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:13:11 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-KYzRoz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:13:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:13:11 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-XlZPnM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:13:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:13:11 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-c6z0sS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:13:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:13:11 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-IaExTk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:13:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:13:11 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-ec2JiH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:13:12 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-SW9EkE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:13:12 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-KWn7mb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:13:12 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-EeomhS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:13:12 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-hsEaCF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:13:12 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-7QMtJv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:13:12 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-wt1hG8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:13:12 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-esXpT3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:13:12 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-z31iNJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:13:12 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-1kVGrp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:13:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:13:13 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-vcNgdL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:13:13 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-gMwLBI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:13:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:13:13 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-FLdp6U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:13:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:13:13 INFO - Running setup.py install for manifestparser 05:13:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:13:13 INFO - Running setup.py install for mozcrash 05:13:13 INFO - Running setup.py install for mozdebug 05:13:13 INFO - Running setup.py install for mozdevice 05:13:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:13:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:13:13 INFO - Running setup.py install for mozfile 05:13:14 INFO - Running setup.py install for mozhttpd 05:13:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:13:14 INFO - Running setup.py install for mozinfo 05:13:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:13:14 INFO - Running setup.py install for mozInstall 05:13:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:13:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:13:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:13:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:13:14 INFO - Running setup.py install for mozleak 05:13:14 INFO - Running setup.py install for mozlog 05:13:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:13:14 INFO - Running setup.py install for moznetwork 05:13:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:13:14 INFO - Running setup.py install for mozprocess 05:13:15 INFO - Running setup.py install for mozprofile 05:13:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:13:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:13:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:13:15 INFO - Running setup.py install for mozrunner 05:13:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:13:15 INFO - Running setup.py install for mozscreenshot 05:13:15 INFO - Running setup.py install for moztest 05:13:15 INFO - Running setup.py install for mozversion 05:13:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:13:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:13:15 INFO - Cleaning up... 05:13:15 INFO - Return code: 0 05:13:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:13:15 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')]} 05:13:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:15 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')]} 05:13:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:15 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 0x10550db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105510768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92b0dd2480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052ebd30>, '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 0x105212db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1052e0be8>, '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.5z2R3af1qq/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.mVuD114YGO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 05:13:15 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 05:13:15 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 05:13:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5z2R3af1qq/Render', 05:13:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:15 INFO - 'HOME': '/Users/cltbld', 05:13:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:15 INFO - 'LOGNAME': 'cltbld', 05:13:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:15 INFO - 'MOZ_NO_REMOTE': '1', 05:13:15 INFO - 'NO_EM_RESTART': '1', 05:13:15 INFO - 'PAGER': '/bin/cat', 05:13:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:15 INFO - 'PWD': '/builds/slave/test', 05:13:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:15 INFO - 'SHELL': '/bin/bash', 05:13:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mVuD114YGO/Listeners', 05:13:15 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 05:13:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:15 INFO - 'USER': 'cltbld', 05:13:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:15 INFO - 'XPC_FLAGS': '0x0', 05:13:15 INFO - 'XPC_SERVICE_NAME': '0', 05:13:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:13:16 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-PqaxRA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:13:16 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)) 05:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:13:16 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-NvV_VT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:13:16 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)) 05:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:13:16 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-qinyE3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:13:16 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)) 05:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:13:16 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-jICPgs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:13:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 05:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:13:16 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-oHbLKr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:13:16 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)) 05:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:13:16 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-TV_nVK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:13:16 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)) 05:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:13:16 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-6Sv7gb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:13:16 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)) 05:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:13:16 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-u2ySZq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:13:17 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)) 05:13:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:13:17 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-axTnQZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:13:17 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)) 05:13:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:13:17 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-LN6TiN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:13:17 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)) 05:13:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:13:17 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-l_gA1r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:13:17 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)) 05:13:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:13:17 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-kX6UKj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:13:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 05:13:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:13:17 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-LsESdp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:13:17 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)) 05:13:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:13:17 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-ggbkSn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:13:17 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)) 05:13:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:13:17 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-tOOqXG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:13:17 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)) 05:13:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:13:17 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-GvMjx1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:13:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 05:13:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:13:17 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-WvpMxd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:13:17 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)) 05:13:17 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)) 05:13:17 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)) 05:13:17 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)) 05:13:17 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:13:17 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:13:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:13:17 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 05:13:17 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 05:13:17 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 05:13:17 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 05:13:17 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 05:13:17 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 05:13:20 INFO - Downloading blessings-1.6.tar.gz 05:13:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:13:20 INFO - Installing collected packages: blessings 05:13:20 INFO - Running setup.py install for blessings 05:13:20 INFO - Successfully installed blessings 05:13:20 INFO - Cleaning up... 05:13:20 INFO - Return code: 0 05:13:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:13:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:13:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:13:21 INFO - Reading from file tmpfile_stdout 05:13:21 INFO - Current package versions: 05:13:21 INFO - blessings == 1.6 05:13:21 INFO - blobuploader == 1.2.4 05:13:21 INFO - docopt == 0.6.1 05:13:21 INFO - functools32 == 3.2.3-2 05:13:21 INFO - jsonschema == 2.5.1 05:13:21 INFO - manifestparser == 1.1 05:13:21 INFO - mozInstall == 1.12 05:13:21 INFO - mozcrash == 1.0 05:13:21 INFO - mozdebug == 0.1 05:13:21 INFO - mozdevice == 0.49 05:13:21 INFO - mozfile == 1.2 05:13:21 INFO - mozhttpd == 0.7 05:13:21 INFO - mozinfo == 0.9 05:13:21 INFO - mozleak == 0.1 05:13:21 INFO - mozlog == 3.4 05:13:21 INFO - moznetwork == 0.27 05:13:21 INFO - mozprocess == 0.24 05:13:21 INFO - mozprofile == 0.28 05:13:21 INFO - mozrunner == 6.13 05:13:21 INFO - mozscreenshot == 0.1 05:13:21 INFO - mozsystemmonitor == 0.3 05:13:21 INFO - moztest == 0.8 05:13:21 INFO - mozversion == 1.4 05:13:21 INFO - psutil == 3.1.1 05:13:21 INFO - requests == 1.2.3 05:13:21 INFO - wsgiref == 0.1.2 05:13:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:13: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')]} 05:13:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13: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')]} 05:13:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:21 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 0x10550db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105510768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92b0dd2480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052ebd30>, '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 0x105212db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1052e0be8>, '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.5z2R3af1qq/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.mVuD114YGO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 05:13:21 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 05:13:21 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 05:13:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5z2R3af1qq/Render', 05:13:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:21 INFO - 'HOME': '/Users/cltbld', 05:13:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:21 INFO - 'LOGNAME': 'cltbld', 05:13:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:21 INFO - 'MOZ_NO_REMOTE': '1', 05:13:21 INFO - 'NO_EM_RESTART': '1', 05:13:21 INFO - 'PAGER': '/bin/cat', 05:13:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:21 INFO - 'PWD': '/builds/slave/test', 05:13:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:21 INFO - 'SHELL': '/bin/bash', 05:13:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mVuD114YGO/Listeners', 05:13:21 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 05:13:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:21 INFO - 'USER': 'cltbld', 05:13:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:21 INFO - 'XPC_FLAGS': '0x0', 05:13:21 INFO - 'XPC_SERVICE_NAME': '0', 05:13:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:21 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)) 05:13:21 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)) 05:13:21 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)) 05:13:21 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)) 05:13:21 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)) 05:13:21 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)) 05:13:21 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)) 05:13:21 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)) 05:13:21 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)) 05:13:21 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)) 05:13:21 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)) 05:13:21 INFO - Cleaning up... 05:13:21 INFO - Return code: 0 05:13:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:13: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')]} 05:13:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:13:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13: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')]} 05:13:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:13:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:13:22 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 0x10550db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105510768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92b0dd2480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052ebd30>, '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 0x105212db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1052e0be8>, '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.5z2R3af1qq/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.mVuD114YGO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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 05:13:22 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 05:13:22 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 05:13:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5z2R3af1qq/Render', 05:13:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:22 INFO - 'HOME': '/Users/cltbld', 05:13:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:22 INFO - 'LOGNAME': 'cltbld', 05:13:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:22 INFO - 'MOZ_NO_REMOTE': '1', 05:13:22 INFO - 'NO_EM_RESTART': '1', 05:13:22 INFO - 'PAGER': '/bin/cat', 05:13:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:22 INFO - 'PWD': '/builds/slave/test', 05:13:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:22 INFO - 'SHELL': '/bin/bash', 05:13:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mVuD114YGO/Listeners', 05:13:22 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 05:13:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:22 INFO - 'USER': 'cltbld', 05:13:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:22 INFO - 'XPC_FLAGS': '0x0', 05:13:22 INFO - 'XPC_SERVICE_NAME': '0', 05:13:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:13:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:13:22 INFO - Cleaning up... 05:13:22 INFO - Return code: 0 05:13:22 INFO - Running post-action listener: _resource_record_post_action 05:13:22 INFO - Running post-action listener: _start_resource_monitoring 05:13:22 INFO - Starting resource monitoring. 05:13:22 INFO - [mozharness: 2017-02-24 13:13:22.546662Z] Finished create-virtualenv step (success) 05:13:22 INFO - [mozharness: 2017-02-24 13:13:22.547549Z] Running install step. 05:13:22 INFO - Running pre-action listener: _resource_record_pre_action 05:13:22 INFO - Running main action method: install 05:13:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:13:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:13:22 INFO - Reading from file tmpfile_stdout 05:13:22 INFO - Detecting whether we're running mozinstall >=1.0... 05:13:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:13:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:13:22 INFO - Reading from file tmpfile_stdout 05:13:22 INFO - Output received: 05:13:22 INFO - Usage: mozinstall [options] installer 05:13:22 INFO - Options: 05:13:22 INFO - -h, --help show this help message and exit 05:13:22 INFO - -d DEST, --destination=DEST 05:13:22 INFO - Directory to install application into. [default: 05:13:22 INFO - "/builds/slave/test"] 05:13:22 INFO - --app=APP Application being installed. [default: firefox] 05:13:22 INFO - mkdir: /builds/slave/test/build/application 05:13:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:13:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 05:13:42 INFO - Reading from file tmpfile_stdout 05:13:42 INFO - Output received: 05:13:42 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 05:13:42 INFO - Running post-action listener: _resource_record_post_action 05:13:42 INFO - [mozharness: 2017-02-24 13:13:42.666543Z] Finished install step (success) 05:13:42 INFO - [mozharness: 2017-02-24 13:13:42.666683Z] Running run-tests step. 05:13:42 INFO - Running pre-action listener: _resource_record_pre_action 05:13:42 INFO - Running main action method: run_tests 05:13:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:13:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:13:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:13:42 INFO - 2017-02-24 05:13:42.681 screenresolution[1629:7326] starting screenresolution argv=screenresolution get 05:13:42 INFO - 2017-02-24 05:13:42.683 screenresolution[1629:7326] Display 0: 1600x1200x32@60 05:13:42 INFO - 2017-02-24 05:13:42.689 screenresolution[1630:7329] starting screenresolution argv=screenresolution list 05:13:42 INFO - Available Modes on Display 0 05:13:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:13:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:13:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:13:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:13:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:13:42 INFO - Intel Iris: 05:13:42 INFO - Chipset Model: Intel Iris 05:13:42 INFO - Type: GPU 05:13:42 INFO - Bus: Built-In 05:13:42 INFO - VRAM (Dynamic, Max): 1536 MB 05:13:42 INFO - Vendor: Intel (0x8086) 05:13:42 INFO - Device ID: 0x0a2e 05:13:42 INFO - Revision ID: 0x0009 05:13:42 INFO - Displays: 05:13:42 INFO - Display: 05:13:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:13:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:13:42 INFO - Display Serial Number: 1600x1200 60 05:13:42 INFO - Main Display: Yes 05:13:42 INFO - Mirror: Off 05:13:42 INFO - Online: Yes 05:13:42 INFO - Rotation: Supported 05:13:42 INFO - Return code: 0 05:13:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:13:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:13:42 INFO - Python 2.7.3 05:13:42 INFO - Return code: 0 05:13:42 INFO - grabbing minidump binary from tooltool 05:13:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:13:42 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 0x1052ebd30>, '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 0x105212db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1052e0be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:13:42 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 05:13:42 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 05:13:42 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 05:13:43 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:13:43 INFO - Return code: 0 05:13:43 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:13:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:13:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:13:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:13:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:13:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ddS3HRxjRi6lR80AtBF1rA/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0116', '--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 05:13:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ddS3HRxjRi6lR80AtBF1rA/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0116 --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 05:13:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5z2R3af1qq/Render', 05:13:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:13:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:13:43 INFO - 'HOME': '/Users/cltbld', 05:13:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:13:43 INFO - 'LOGNAME': 'cltbld', 05:13:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:13:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:13:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:13:43 INFO - 'MOZ_NO_REMOTE': '1', 05:13:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:13:43 INFO - 'NO_EM_RESTART': '1', 05:13:43 INFO - 'PAGER': '/bin/cat', 05:13:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:13:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:13:43 INFO - 'PWD': '/builds/slave/test', 05:13:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:13:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:13:43 INFO - 'SHELL': '/bin/bash', 05:13:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mVuD114YGO/Listeners', 05:13:43 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 05:13:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:13:43 INFO - 'USER': 'cltbld', 05:13:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:13:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:13:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:13:43 INFO - 'XPC_FLAGS': '0x0', 05:13:43 INFO - 'XPC_SERVICE_NAME': '0', 05:13:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:13:43 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ddS3HRxjRi6lR80AtBF1rA/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0116', '--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 05:13:43 INFO - mozversion application_buildid: 20170224033759 05:13:43 INFO - mozversion application_changeset: 66e711359af5799d9f133f59fab3d730cf8a20cb 05:13:43 INFO - mozversion application_display_name: FirefoxDeveloperEdition 05:13:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:13:43 INFO - mozversion application_name: Firefox 05:13:43 INFO - mozversion application_remotingname: firefox-dev 05:13:43 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:13:43 INFO - mozversion application_vendor: Mozilla 05:13:43 INFO - mozversion application_version: 53.0a2 05:13:43 INFO - mozversion platform_buildid: 20170224033759 05:13:43 INFO - mozversion platform_changeset: 66e711359af5799d9f133f59fab3d730cf8a20cb 05:13:43 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:13:43 INFO - mozversion platform_version: 53.0a2 05:13:43 INFO - using testdate: 1487942023 05:13:43 INFO - actual date: 1487942023 05:13:43 INFO - starting webserver on 'localhost:49243' 05:13:43 INFO - SUITE-START | Running 2 tests 05:13:43 INFO - TEST-START | dromaeo_css 05:13:43 INFO - operating with platform_type : mac_ 05:13:43 INFO - Initialising browser for dromaeo_css test... 05:13:43 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/tmp9gQ7fE/profile http://localhost:49243/getInfo.html) 05:13:43 INFO - PROCESS | 1640 | 1487942023965 addons.xpi WARN Error loading bootstrap.js for disableSHA1rollout@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/disableSHA1rollout@mozilla.org.xpi!/bootstrap.js 05:13:43 INFO - PROCESS | 1640 | 1487942023966 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method install 05:13:44 INFO - PROCESS | 1640 | 1487942024019 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method startup 05:13:45 INFO - PROCESS | 1640 | __metrics Screen width/height:1600/1200 05:13:45 INFO - PROCESS | 1640 | colorDepth:24 05:13:45 INFO - PROCESS | 1640 | Browser inner width/height: 1024/689 05:13:45 INFO - PROCESS | 1640 | __metrics 05:13:45 INFO - PROCESS | 1640 | 1487942025835 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method shutdown 05:13:45 INFO - PROCESS | 1640 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:13:45 INFO - PROCESS | 1640 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:13:46 INFO - TEST-INFO | 1640: exit 0 05:13:46 INFO - Browser initialized. 05:13:46 INFO - Running cycle 1/1 for dromaeo_css test... 05:13:46 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/tmp9gQ7fE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:13:46 INFO - PROCESS | 1642 | 1487942026947 addons.xpi WARN Error loading bootstrap.js for disableSHA1rollout@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/disableSHA1rollout@mozilla.org.xpi!/bootstrap.js 05:13:46 INFO - PROCESS | 1642 | 1487942026948 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method startup 05:13:58 INFO - PROCESS | 1642 | RSS: Main: 213217280 05:13:58 INFO - PROCESS | 1642 | 05:17:01 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 05:17:03 INFO - PROCESS | 1642 | RSS: Main: 417689600 05:17:03 INFO - PROCESS | 1642 | 05:18:48 INFO - PROCESS | 1642 | ************************* 05:18:48 INFO - PROCESS | 1642 | A coding exception was thrown and uncaught in a Task. 05:18:48 INFO - PROCESS | 1642 | 05:18:48 ERROR - PROCESS | 1642 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:18:48 INFO - PROCESS | 1642 | Full stack: 05:18:48 INFO - PROCESS | 1642 | ************************* 05:20:06 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 05:20:07 INFO - PROCESS | 1642 | RSS: Main: 352907264 05:20:07 INFO - PROCESS | 1642 | 05:23:10 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 05:23:10 INFO - PROCESS | 1642 | RSS: Main: 321773568 05:23:10 INFO - PROCESS | 1642 | 05:26:13 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 05:26:13 INFO - PROCESS | 1642 | RSS: Main: 337678336 05:26:13 INFO - PROCESS | 1642 | 05:29:17 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 05:29:18 INFO - PROCESS | 1642 | RSS: Main: 310685696 05:29:18 INFO - PROCESS | 1642 | 05:32:21 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 05:32:22 INFO - PROCESS | 1642 | RSS: Main: 363188224 05:32:22 INFO - PROCESS | 1642 | 05:32:22 INFO - PROCESS | 1642 | __start_tp_report 05:32:22 INFO - PROCESS | 1642 | _x_x_mozilla_page_load 05:32:22 INFO - PROCESS | 1642 | _x_x_mozilla_page_load_details 05:32:22 INFO - PROCESS | 1642 | |i|pagename|runs| 05:32:22 INFO - PROCESS | 1642 | |0;dojo.html;2894.5658151277307;3309.4208513510134;3349.916252093698;3391.287829555793;3391.542141810855;5679.829605111843;5706.7431965561545;5714.314282286121;5715.399883012281;5754.992876246656;5607.971960140194;5611.607187496867;5686.214063385673;5707.029804933161;5764.625299355549;5174.172132458817;5178.844634660947;5227.242049902781;5241.528262456378;5283.471652834724;4875.804967801301;5117.1556693145585;5118.027574760802;5292.629515933886;5343.492368225013;5183.17069268917;5184.8703782405555;5200.895982080356;5212.973935130319;5287.47125287472;4551.431071116111;5164.715940623246;5280.973595132038;5284.445133261029;5327.493888080626;5080.74596270185;5116.718580478072;5184.792608295664;5216.139337009389;5219.947800521984;5565.193047008202;5621.213030175779;5659.632123911973;5664.235328230678;5670.5747068969995;6195.256569211679;6218.19163508741;6263.812085637438;6266.342034086447;6278.37216278373;6231.283402408691;6305.68471576424;6308.842278943017;6325.019621958604;6326.620402775849;5658.405867383948;5719.19931209631;5733.397993210736;5828.300603927556;5836.6789826559525;6159.630422174594;6230.408111229426;6230.875382492325;6234;6267.843303917347;6179.474744646755;6188.164597779332;6188.257409110935;6198;6208.037754148114;5258.763355649005;6243.344448832898;6268.03958972112;6283.497320214372;6301.7164227608955;6074.848128796815;6107.938920610826;6114.633122012693;6145;6146.231721034871;5015.322931404214;6202.596831205957;6211.720472578745;6282.968585157137;6284.245890493079;4952.950470495249;4975.825846095369;4984.675996060317;4985.351904252424;5004;4424.159409712144;4439.178751930903;4456.509783923766;4462.732236065846;4485.282354823213;5657.688827114548;5712.6286791358425;5725.370209276976;5752.798652047159;5793;6251.187345645128;6271.122042913996;6279.874402511924;6286.371362863677;6309.905351419732;5647.971760141255;5782.710864456761;5822.165295269883;5830.53355731549;5888.234529511222;5328.3605967284175;5468.261784659099;5470.288862447856;5470.589705772083;5474.835754927359;6202.28673702521;6209.96895015522;6251.249850017936;6253.874922501615;6266.404691554295;6260.467860231749;6271.3728627136925;6281.937180628318;6290.685465726787;6303.747850085945;6239.688015599111;6310.936890631218;6325.810225693236;6341.8097457076365;6358.809235722936;6077.878442431215;6110.114033465034;6128.747575574743;6140.969295153495;6217.440430361289;6123.387661233841;6268.310485846644;6270.15352927349;6274.090256912813;6293.056041593798;6117.816465506042;6142.815715528542;6143.569950103629;6160.876782464416;6168.506519478341;5544.500994910316;5874.471297583238;5999.730012149551;6111;6129.4483496483745;5875.383084776037;5896.292444906639;5906.320773110975;5947.434993675594;5988.431099045583;5922.437368449991;6116.17431646722;6122.6938653068055;6128.724207410766;6152.938470615237;5802.535797136304;5816.098504731774;5831.241938547962;5889.587728858939;5895.970520147372 05:32:22 INFO - PROCESS | 1642 | |1;ext.html;443.65616647098494;448.1641738158336;469.36166813134133;513.7020528093703;531.688961957255;36567.817160914194;41813.79093104534;41834.7908260459;42022.7898860506;42064.57935420645;43088.922776930594;43538.911527211836;43572;43581.34627980575;43688.12623747523;8516.276116530102;9275.4434733916;9499.857502137473;9504.192143667762;9505.80988380232;8575.871361929576;11305;11541.538338466451;11581.131415143898;11744.706382340468;11431.42842857858;11516.193866429354;11528.596499122532;11529.135314851377;11535.711607209803;8527.488350698977;11511.48198331077;11551.884481155206;11557.422128893522;11599.94200029003;8545.871811922827;11129.443527823576;11388.202825802196;11403.42982850854;11532.365719885403;33063.66936330654;41292.58707412948;41316.79341603273;41361;41470.1705965882;3938.704597155196;3947.940780888289;3996.2806694794926;4008.518977722663;4021.416894550274;29328.560071598942;29420.8528957356;29563.26091847721;30367.392652147053;30698.386032279228;3108.751299896003;3294.8352582370785;3360.6135294441206;3380.0873764083804;3381.3575420669995;498916;499591.9999999964;502068;502080;502677;62720.686396567726;62963;63140;63151.6842415785;63373;66251;66322.66838665775;66559;66643.33356666469;66688;13889;16927;16962.23669934856;16962.321507139823;17016.234269457906;11753.588624398106;12125.636230913087;13004.479820807252;13051.412686428943;13064.934675326562;15115.093094414371;17777.878657194044;20079.594428390166;20495.077721502566;21567.56864862694;7387.704491820375;7429.8142546436775;7545.396368290633;8803.735887923373;8874.334424918157;867.1761826265074;890.2788741119584;892.1970226795936;908.4458480326995;916.5600511754395;30272.54591181134;30373.848130759205;30431.39137217243;30454.695453045188;30920.536191957137;958.2956526952685;1035.30634474902;1083.3012706804068;1105.9336439813476;1182.4147047211513;15122.319495622723;15789.921050394903;15810.36758529669;15961.44134955271;16150.950188237726;623920;630860;631664.3667126724;633316;634580;45184.870378240805;45490.77254613706;45607.31589026167;45630.54369456329;45677.771611142394;1215.501644325822;1441.466657336771;1460.247972294248;1463.1952426165778;1471.9043262187924;26110.73889261159;26956.191314260603;29780.851095744383;30382.84808575943;30457;51146.74426627992;52529.737351313;52543.474565253855;52883;55789.44210558005;615173;623240;624771;625994;628583;33307.00078997634;33669.66330336665;40530.56816591014;40998.92508061848;41231;41734.582654174286;41768.69331508497;42859;43466.91332716707;43535.78232108819;30252.24369390783;35333.646663533036;35592.46611300781;36892.077698057765;37083.629163708014;35038;35043.12392190216;35610.46584301289;36201.637983619825;36377.45433818442;34838.30323393569;34852.825735871156;35262.647373525935;35572.288554229286;35576;29800.850995744884;31510;31668.683313166573;31728.682713172573;31792.841035794674;23389.883050584638;23483.295501134995;23615.52768944646;24006.75993240045;24162.758372416753 05:32:22 INFO - PROCESS | 1642 | |2;jquery.html;361.09185398722235;365.7183968344375;383.4918732679199;395.40688966550175;420.18274456182735;14747.48383806567;15161.772573411408;16793.664126717475;20142;21002.474938126554;20298.492537686565;21119.472013199676;21236.36290911275;21402.357929262147;21483;4636.188666983281;5187.740612969355;5406.324209473816;5415.566754659618;5556.194351818984;5044.394672639289;5710.200571919934;5876.0598304271325;5879.76480940764;5880.852978675546;3849.8845034649007;4015.2973229684835;7467.514611550259;7808.76573702787;7889.605519724019;7615.80960475987;7753.418493612973;7828.295453409163;7939.364850811921;7939.682412703513;7155.497345557439;7726.0342457192855;7730.729424470116;7737.690492380354;7748;27308;27991;28023.43953120926;28732.85633571819;29110.27224319409;2874.798764086515;2894.348771526411;2913.6503619565574;2916.9270768230754;2933.559966005095;15697.90114691972;16478.6704265914;16782.496525104267;17166.399176028903;17735.024573648443;1590.705719441907;1937.476881242071;1945.445548018822;1984;1990.8506861985263;109548;118629;134109;135342.646573534;159393;19934.20263189456;23005.96473158712;23038.42403939915;23110.76889231086;23307.76692233056;33346.499802502985;38632;39098.413523797164;39991.40012899809;40181.598184017785;10255.7436064099;10780.730481738019;10815.621453249098;10876.238663293494;11462.59880904181;9023;9042.909570904207;9079.18287354128;9104.408213466104;9258.213051890665;8885.4668719877;10778.353298802096;10839.512221950032;11014.724631884266;11022.567066281492;3228.6448490666007;3418.9145271368416;3448.9482657760154;4070.9592904071174;4079.1637714268504;1007.5063219022773;1023.5240613114937;1035.668586052456;1040.331549575352;1047.4814966591587;21332.78667213308;21936.670949935764;21966.231181908875;22032.678039317692;22079.785611791183;927.2674587076134;956.6938579654444;1029.3823705776624;1073.849661047454;1085.3596378136779;10028.899711002943;10604.515367848508;10699.625513107005;10984.636871508445;11150.386728729998;690948;695390;702346;702479;727112;147746.52253477543;148775;149807;151773;156190.2190489063;976.9560369783377;981.916537094355;1049.9527521261714;1054.383185836279;1059.7986382587437;19293;20192.317263759414;20443.182272708924;20471.488212794204;20500.794992049887;60486.69756651189;61293.69353153206;61421.692891535255;61468;62966.99999999817;88537.55731221303;90348.09651903396;90470;91488;93401;7681.770916653309;8281.254687078192;8298.958505207434;8302.460340077876;8545.188207120314;10173.389596624227;12836.55072072473;13083.887869531196;13151.60545183646;13514.459421623025;9370.297227708086;11069.280496767684;11376.829347559958;11407.771844563229;11449.771004579696;5109.259157422227;7499.925000749922;9716.465594392377;10596.735081623021;10948.726281843017;11469.426528673699;11646.417679116179;11858.16992810495;11983.28100313984;12031.819522706992;5940.762369505344;7615.885761713689;8481.703140390056;8662.82674346497;8719.52042637661;9901.653442129491;11001.064909482784;11262.38056906878;11329.263597866113;11528.942355288169 05:32:22 INFO - PROCESS | 1642 | |3;mootools.html;1359.7620416427123;1381.440516590781;1384.5638623833493;1386.0159286906296;1392.157744564538;7151.463640226984;8511.446755960873;8531.530765807893;8537.615807288672;8560.10118937512;7479.065116860393;7663.310302072812;8078.474899131567;8339.916600834005;8352.874706879402;2303.3090072978125;2343.086196383412;2345.108858633717;2351.541449417364;2353.576356255873;2311.84972976756;2334.124703236286;2339.6724458575814;2355.705536807899;2357.7406485286606;2073;2148.9140434382607;2151.978480215201;2312.3987763181526;2345.0736958901225;2132.5521640455445;2141.2398598497543;2248.0895237428867;2314.4908120213695;2327.8254130940077;2351.094828491026;2351.8471299365656;2356.599378105726;2357.139644029934;2359.4809141988735;8413.789655258606;8484.957575212145;8486.53324067176;8493.36299777513;8507.31941444689;5096.719680417576;5244.423113457517;5251.212318152269;5267.341582302212;5274.077036518594;3207.615086189649;3342.866285348583;3504.754667173299;3525.735569832247;3526.9647303526895;1143.3540049871797;1229.569650622275;1264.7849865522883;1267.8161666558296;1700.6428649983395;10722.517486713117;10939.398333091676;10980.670579882537;10997.99999999992;11029.558817647285;4282.828686852491;4519.254323036728;4571.725696458223;4577.176108300538;4595.862124136282;8781.868271975925;8784.78038049054;8788.8681669775;8790.86813697795;8812.823743525094;5168.457311982263;5183.481651834862;5188;5190.740462976912;5205.687658740488;4255.319148936155;4469.061497085649;4766.404199475066;4770.833020844254;4861.927071093865;3592.1558433768046;3726.32926073304;3727.459518369821;3824.4836947011795;3826.5025546678753;1146.908247340211;1166.2244607336027;1178.216486036776;1179.9469023893946;1182.3024415594841;736.8710475666737;749.6176949755663;782.9686812527435;813.2924355810483;814.722994181981;4741.407324084489;5283.573435172559;5420.232938108056;5443.346798384219;5847.736851841677;873.1879352202512;966.415318732157;970.9174720148867;1002.3184234720314;1053.1890444357803;3089.7219250267585;3658.817059147032;3743.5694895087413;3744.3073031488716;3762.3039737648623;9825.4104753715;10120.139788117945;10177.033181847713;10200.43897585625;10200.693979180636;8489;10155.64455244063;10163.796724065625;10184.643537476153;10189.184865210913;7873.370130389555;8045.637946292314;8046.074701409296;8055.234752698484;8070.556119413372;5064.924026139537;5251.737413129405;5348.358197016383;5409.486098820606;5435.429279925709;9192.126747958933;9231.446113233227;9276.907230927603;9397.530123493934;9483.28875334339;9252.722418327461;9461.053894610484;9488.193503552;9555.283353748635;9586.424814510872;6322.747090116344;6527.05357723118;6810.659467026728;6947.478939079489;6951.409130223988;7619.657115429708;7821.491603045783;8015.7595272141925;8031.799205019687;8091.716789912325;7064.611446370293;7139.643017848983;7178.102737157856;7193.568385896863;7293.671784769592;7063.894041589278;7078.893816592652;7084.822879428056;7094.290570942864;7139.21468638439;6418.84460797061;6428.4857211423905;6541.378569035934;6546.967265163644;6549.901751473636;4720.339152518719;4926.852194434173;4999;5005.749712514287;5020.573251273537;4946.258061290691;4975.303457516023;4982.252662100714;5046.21783623539;5177.870553236199 05:32:22 INFO - PROCESS | 1642 | |4;prototype.html;348.1817728338405;350.7667401178216;360.9007522931193;363.25713915043764;366.91010702377923;4363.803628836702;4665.626749860012;4879.511748916579;5178.482151784829;5216.24364467152;5102.948970510294;5109.948900510994;5112.974435127829;5130.461301563338;5143.662647711594;3175.412548678502;4060.330045542482;4070.308047631895;4102.384642303663;4104.8768536943935;2878.6113874626867;3121.282105115825;3747.494088298086;3915.314819906519;4035.273650742865;3651.7808931464197;4003.819828107742;4004.339284018148;4011.4785077939828;4037.777922214277;3200.8239546824916;3538.716902647782;4028.315186418316;4041.969297829035;4058.939115913264;3185.7097875360514;3536.1159710072484;3578.159132603836;3578.818989733382;3580.7314451415987;4217.945513621595;4422.425084738996;4720.150372932871;4897.755112244409;4941.827036053721;4604.493505714368;4608.9308660370125;4611.377464042345;4634.953650463486;4661.88345291367;4575.748333841638;4653.162430762462;5131.384233891919;5178.533931946143;5469.261649677283;3409.5056216848666;3688.889333320005;3707.610700876396;3732.7760334379755;3950.2889479893606;4774.427068751772;4901.799059230476;5552.5280351170195;5580.553555715533;5607.383187849333;3208.4866421372935;3259.7229235515247;3771.0404075720385;3801.1827457096856;3851.345271303885;3858.2090671412852;3877.108265099043;3877.6122387760997;3896;3899.122697393064;4264.914701705949;4470.888227794266;4480.551944805494;4489.910201795946;4495;5208.166693329049;5431.918521222185;5436;5457.890842183134;5471.534919531884;1214.1666084212902;1224.9510019599295;1227.220714846079;1243.5088140184575;1244.807054906491;796.4385108498494;835.6796261906173;836.7322456813882;855.8018773716722;856.3534531428731;4411.911761764747;4608.377868987711;4757.762111894392;4801.255805350176;4814.325994360793;5055.266986286968;5216.165413533817;5246.790128394821;5267.341582302212;5298.337707786527;3866;4486.282194848874;4714.45783734868;4755.595774359149;4766.833160839354;2476.962845557318;2751.697313295536;2758.420876480497;2772.750452459288;2777.4583956128363;4607.9308810367875;5072.949270507322;5399.919001214985;5427.457254274541;5480.780768769284;5206.609504287194;5328.413874473805;5400.594955378362;5404.243405923174;5747.425257474303;5736.225609542658;5752.252207213036;5757.971210144006;5771.480566748929;5781.566382521243;4960.652754307308;5552.278203833475;5586.581006424616;5608.327000759935;5632.380438151882;4630.120277147331;4795.448523419712;5129.538341549279;5516.779328826802;5589.077802162597;5008.799648014185;5142.9456961323585;5436.891262174655;5716.914246286392;5763.991301522301;3287.736981041463;3552.3073000765635;3640.4903313536656;3715.461258117504;3811.9809400952818;5541.5289700375915;5594.104943209067;5600.451794433605;5611.831645050655;5631.408702086386;5089.447718445909;5210.27056212123;5342.973285133549;5393.595480338912;5424.779424629427;4735.857924262277;4908.730019848943;5240.449752775904;5292.947070529245;5409.945900541102;4013.2174226025645;4910.189818679878;5299;5312.734363281743;5337.905729325556;4337.652987761035;4907.803687852446;5396.977692492217;5413.971345444353;5421.295231619864;5285.577153827761;5313.2561441397465;5313.335833020872;5314.62797604164;5320.408724111132 05:32:22 INFO - PROCESS | 1642 | |5;yui.html;692.1693967239307;706.5478094019825;727.8253219227386;774.0595176860118;827.7516744976507;14360.281985900716;15342.539723808304;15381.846181538182;15397.923010384946;15418.46035388763;14062.226577538231;14263.643408914782;14265.358058887348;14280.78578821316;14294.785578216335;661.8610091880711;682.6825526130349;686.4679873098339;687.4328678839952;847.0301504777027;685.7051467868814;688.2704333406605;773.6325245508367;850.3664769746516;854.9529775862325;907.5054095517959;919.5402298850574;922.5848368234288;925.3337596930199;925.3476299209042;912.8813254276935;915.6108653822099;915.771057235691;915.9679411220642;916.8303863785221;521.8173639226278;536.3644081763123;661.4278648968656;683.480554778372;684.5926673629183;14403;15062.548123556311;15069.773953390708;15075.170865602387;15092.698146037128;13541.526046588322;13667.931660341636;13685.931570342085;13767.931160344135;13796.517121900786;10982.505787239514;12003.039756819433;12080.637580872588;12133.69665758363;12705.872941270562;4423.535528769497;4425.203463376623;4435.68950173488;4575.313702944585;4606.838760643395;103595.48202259016;107892;109910.90089099166;110701.78596428107;114721.42639286834;10285.53715082808;10722.571097156026;10943;11104.944475277733;11489.310641361544;22258.22096226624;22309.219177328716;22309.21917732904;22309.888450557642;22335.888320558293;7333.2666733327305;7691.538507689556;7706.845863082707;7758.06903171623;7839.059312882377;4201.4538110046105;4302.075053863435;4341.283688191476;4418.64650827933;4420.204363214652;3343.4316166251797;3442.4664177052596;3447.827608619559;3447.965610316945;3448.965310406868;623.7473823101651;635.2218532297898;641.2785616181795;652.0968458684756;655.9147310849559;631.3528633150966;649.7336092202174;653.1998302080009;653.3597074866562;654.8101050695245;8948.463092214488;10968.067714244218;10996.23026388161;11050.502727377287;11475.88524114765;550.8168137823066;597.1669521018156;601.0263373335225;601.2814686449738;704.4751660013322;4733.95266047342;4814.9518504815205;4933;4984.152694042022;4988.775505102207;82015.7697634536;95403.52298238465;99924;100436.4978175119;101574.49212754036;67871;73176.63411683014;78914;79484.6025769879;79603.20396796074;62973;68352.9241184148;73107.2689273111;74464;76873;10166.491675416348;10660;10807.5203576559;10834.783304333712;10930.125589952626;47567;48254.793630159525;48471;50803.98392032213;50837.474875752305;40980;62948;63306.73386532336;64291.3927151825;65193.67403163144;12293.323867187391;13196.538121166097;13265;13491.86508134906;13753.724925501234;14009.369578369202;14308.49920252786;14423.244706574618;14737.484188053795;14738.926305368404;12762.17045892014;13126.540571079966;13568.016192323403;13836.515721949683;13907.374168162254;12144.574939877062;12168.878311216775;12188.51245950152;12249.387530623611;12430.254184748945;10045.794504659487;10716.73208169802;10973.071451745674;11164.832527512257;11182.273152245078;10196.643117490852;11067.391293478937;11075.944620276847;11197;11490.080793536665;10798.514066866852;10850.837237441287;10943.562257509611;10964.835527466936;11005.064569511682 05:32:22 INFO - PROCESS | 1642 | __end_tp_report 05:32:22 INFO - PROCESS | 1642 | __start_cc_report 05:32:22 INFO - PROCESS | 1642 | _x_x_mozilla_cycle_collect,2470 05:32:22 INFO - PROCESS | 1642 | __end_cc_report 05:32:22 INFO - PROCESS | 1642 | __startTimestamp1487943142100__endTimestamp 05:32:22 INFO - PROCESS | 1642 | 05:32:22 INFO - PROCESS | 1642 | ------- Summary: start ------- 05:32:22 INFO - PROCESS | 1642 | Number of tests: 6 05:32:22 INFO - PROCESS | 1642 | 05:32:22 INFO - PROCESS | 1642 | [#0] dojo.html Cycles:165 Average:5724.18 Median:5888.23 stddev:652.80 (11.1%) stddev-sans-first:615.90 05:32:22 INFO - PROCESS | 1642 | Values: 2894.6 3309.4 3349.9 3391.3 3391.5 5679.8 5706.7 5714.3 5715.4 5755.0 5608.0 5611.6 5686.2 5707.0 5764.6 5174.2 5178.8 5227.2 5241.5 5283.5 4875.8 5117.2 5118.0 5292.6 5343.5 5183.2 5184.9 5200.9 5213.0 5287.5 4551.4 5164.7 5281.0 5284.4 5327.5 5080.7 5116.7 5184.8 5216.1 5219.9 5565.2 5621.2 5659.6 5664.2 5670.6 6195.3 6218.2 6263.8 6266.3 6278.4 6231.3 6305.7 6308.8 6325.0 6326.6 5658.4 5719.2 5733.4 5828.3 5836.7 6159.6 6230.4 6230.9 6234.0 6267.8 6179.5 6188.2 6188.3 6198.0 6208.0 5258.8 6243.3 6268.0 6283.5 6301.7 6074.8 6107.9 6114.6 6145.0 6146.2 5015.3 6202.6 6211.7 6283.0 6284.2 4953.0 4975.8 4984.7 4985.4 5004.0 4424.2 4439.2 4456.5 4462.7 4485.3 5657.7 5712.6 5725.4 5752.8 5793.0 6251.2 6271.1 6279.9 6286.4 6309.9 5648.0 5782.7 5822.2 5830.5 5888.2 5328.4 5468.3 5470.3 5470.6 5474.8 6202.3 6210.0 6251.2 6253.9 6266.4 6260.5 6271.4 6281.9 6290.7 6303.7 6239.7 6310.9 6325.8 6341.8 6358.8 6077.9 6110.1 6128.7 6141.0 6217.4 6123.4 6268.3 6270.2 6274.1 6293.1 6117.8 6142.8 6143.6 6160.9 6168.5 5544.5 5874.5 5999.7 6111.0 6129.4 5875.4 5896.3 5906.3 5947.4 5988.4 5922.4 6116.2 6122.7 6128.7 6152.9 5802.5 5816.1 5831.2 5889.6 5896.0 05:32:22 INFO - PROCESS | 1642 | 05:32:22 INFO - PROCESS | 1642 | [#1] ext.html Cycles:180 Average:71516.99 Median:36289.55 stddev:157241.81 (433.3%) stddev-sans-first:157592.37 05:32:22 INFO - PROCESS | 1642 | Values: 443.7 448.2 469.4 513.7 531.7 36567.8 41813.8 41834.8 42022.8 42064.6 43088.9 43538.9 43572.0 43581.3 43688.1 8516.3 9275.4 9499.9 9504.2 9505.8 8575.9 11305.0 11541.5 11581.1 11744.7 11431.4 11516.2 11528.6 11529.1 11535.7 8527.5 11511.5 11551.9 11557.4 11599.9 8545.9 11129.4 11388.2 11403.4 11532.4 33063.7 41292.6 41316.8 41361.0 41470.2 3938.7 3947.9 3996.3 4008.5 4021.4 29328.6 29420.9 29563.3 30367.4 30698.4 3108.8 3294.8 3360.6 3380.1 3381.4 498916.0 499592.0 502068.0 502080.0 502677.0 62720.7 62963.0 63140.0 63151.7 63373.0 66251.0 66322.7 66559.0 66643.3 66688.0 13889.0 16927.0 16962.2 16962.3 17016.2 11753.6 12125.6 13004.5 13051.4 13064.9 15115.1 17777.9 20079.6 20495.1 21567.6 7387.7 7429.8 7545.4 8803.7 8874.3 867.2 890.3 892.2 908.4 916.6 30272.5 30373.8 30431.4 30454.7 30920.5 958.3 1035.3 1083.3 1105.9 1182.4 15122.3 15789.9 15810.4 15961.4 16151.0 623920.0 630860.0 631664.4 633316.0 634580.0 45184.9 45490.8 45607.3 45630.5 45677.8 1215.5 1441.5 1460.2 1463.2 1471.9 26110.7 26956.2 29780.9 30382.8 30457.0 51146.7 52529.7 52543.5 52883.0 55789.4 615173.0 623240.0 624771.0 625994.0 628583.0 33307.0 33669.7 40530.6 40998.9 41231.0 41734.6 41768.7 42859.0 43466.9 43535.8 30252.2 35333.6 35592.5 36892.1 37083.6 35038.0 35043.1 35610.5 36201.6 36377.5 34838.3 34852.8 35262.6 35572.3 35576.0 29800.9 31510.0 31668.7 31728.7 31792.8 23389.9 23483.3 23615.5 24006.8 24162.8 05:32:22 INFO - PROCESS | 1642 | 05:32:22 INFO - PROCESS | 1642 | [#2] jquery.html Cycles:180 Average:41153.54 Median:27649.50 stddev:117327.52 (424.3%) stddev-sans-first:117616.68 05:32:22 INFO - PROCESS | 1642 | Values: 361.1 365.7 383.5 395.4 420.2 14747.5 15161.8 16793.7 20142.0 21002.5 20298.5 21119.5 21236.4 21402.4 21483.0 4636.2 5187.7 5406.3 5415.6 5556.2 5044.4 5710.2 5876.1 5879.8 5880.9 3849.9 4015.3 7467.5 7808.8 7889.6 7615.8 7753.4 7828.3 7939.4 7939.7 7155.5 7726.0 7730.7 7737.7 7748.0 27308.0 27991.0 28023.4 28732.9 29110.3 2874.8 2894.3 2913.7 2916.9 2933.6 15697.9 16478.7 16782.5 17166.4 17735.0 1590.7 1937.5 1945.4 1984.0 1990.9 109548.0 118629.0 134109.0 135342.6 159393.0 19934.2 23006.0 23038.4 23110.8 23307.8 33346.5 38632.0 39098.4 39991.4 40181.6 10255.7 10780.7 10815.6 10876.2 11462.6 9023.0 9042.9 9079.2 9104.4 9258.2 8885.5 10778.4 10839.5 11014.7 11022.6 3228.6 3418.9 3448.9 4071.0 4079.2 1007.5 1023.5 1035.7 1040.3 1047.5 21332.8 21936.7 21966.2 22032.7 22079.8 927.3 956.7 1029.4 1073.8 1085.4 10028.9 10604.5 10699.6 10984.6 11150.4 690948.0 695390.0 702346.0 702479.0 727112.0 147746.5 148775.0 149807.0 151773.0 156190.2 977.0 981.9 1050.0 1054.4 1059.8 19293.0 20192.3 20443.2 20471.5 20500.8 60486.7 61293.7 61421.7 61468.0 62967.0 88537.6 90348.1 90470.0 91488.0 93401.0 7681.8 8281.3 8299.0 8302.5 8545.2 10173.4 12836.6 13083.9 13151.6 13514.5 9370.3 11069.3 11376.8 11407.8 11449.8 5109.3 7499.9 9716.5 10596.7 10948.7 11469.4 11646.4 11858.2 11983.3 12031.8 5940.8 7615.9 8481.7 8662.8 8719.5 9901.7 11001.1 11262.4 11329.3 11528.9 05:32:22 INFO - PROCESS | 1642 | 05:32:22 INFO - PROCESS | 1642 | [#3] mootools.html Cycles:180 Average:5405.47 Median:5080.82 stddev:2967.77 (58.4%) stddev-sans-first:2960.52 05:32:22 INFO - PROCESS | 1642 | Values: 1359.8 1381.4 1384.6 1386.0 1392.2 7151.5 8511.4 8531.5 8537.6 8560.1 7479.1 7663.3 8078.5 8339.9 8352.9 2303.3 2343.1 2345.1 2351.5 2353.6 2311.8 2334.1 2339.7 2355.7 2357.7 2073.0 2148.9 2152.0 2312.4 2345.1 2132.6 2141.2 2248.1 2314.5 2327.8 2351.1 2351.8 2356.6 2357.1 2359.5 8413.8 8485.0 8486.5 8493.4 8507.3 5096.7 5244.4 5251.2 5267.3 5274.1 3207.6 3342.9 3504.8 3525.7 3527.0 1143.4 1229.6 1264.8 1267.8 1700.6 10722.5 10939.4 10980.7 10998.0 11029.6 4282.8 4519.3 4571.7 4577.2 4595.9 8781.9 8784.8 8788.9 8790.9 8812.8 5168.5 5183.5 5188.0 5190.7 5205.7 4255.3 4469.1 4766.4 4770.8 4861.9 3592.2 3726.3 3727.5 3824.5 3826.5 1146.9 1166.2 1178.2 1179.9 1182.3 736.9 749.6 783.0 813.3 814.7 4741.4 5283.6 5420.2 5443.3 5847.7 873.2 966.4 970.9 1002.3 1053.2 3089.7 3658.8 3743.6 3744.3 3762.3 9825.4 10120.1 10177.0 10200.4 10200.7 8489.0 10155.6 10163.8 10184.6 10189.2 7873.4 8045.6 8046.1 8055.2 8070.6 5064.9 5251.7 5348.4 5409.5 5435.4 9192.1 9231.4 9276.9 9397.5 9483.3 9252.7 9461.1 9488.2 9555.3 9586.4 6322.7 6527.1 6810.7 6947.5 6951.4 7619.7 7821.5 8015.8 8031.8 8091.7 7064.6 7139.6 7178.1 7193.6 7293.7 7063.9 7078.9 7084.8 7094.3 7139.2 6418.8 6428.5 6541.4 6547.0 6549.9 4720.3 4926.9 4999.0 5005.7 5020.6 4946.3 4975.3 4982.3 5046.2 5177.9 05:32:22 INFO - PROCESS | 1642 | 05:32:22 INFO - PROCESS | 1642 | [#4] prototype.html Cycles:180 Average:4336.14 Median:4784.94 stddev:1325.02 (27.7%) stddev-sans-first:1294.48 05:32:22 INFO - PROCESS | 1642 | Values: 348.2 350.8 360.9 363.3 366.9 4363.8 4665.6 4879.5 5178.5 5216.2 5102.9 5109.9 5113.0 5130.5 5143.7 3175.4 4060.3 4070.3 4102.4 4104.9 2878.6 3121.3 3747.5 3915.3 4035.3 3651.8 4003.8 4004.3 4011.5 4037.8 3200.8 3538.7 4028.3 4042.0 4058.9 3185.7 3536.1 3578.2 3578.8 3580.7 4217.9 4422.4 4720.2 4897.8 4941.8 4604.5 4608.9 4611.4 4635.0 4661.9 4575.7 4653.2 5131.4 5178.5 5469.3 3409.5 3688.9 3707.6 3732.8 3950.3 4774.4 4901.8 5552.5 5580.6 5607.4 3208.5 3259.7 3771.0 3801.2 3851.3 3858.2 3877.1 3877.6 3896.0 3899.1 4264.9 4470.9 4480.6 4489.9 4495.0 5208.2 5431.9 5436.0 5457.9 5471.5 1214.2 1225.0 1227.2 1243.5 1244.8 796.4 835.7 836.7 855.8 856.4 4411.9 4608.4 4757.8 4801.3 4814.3 5055.3 5216.2 5246.8 5267.3 5298.3 3866.0 4486.3 4714.5 4755.6 4766.8 2477.0 2751.7 2758.4 2772.8 2777.5 4607.9 5072.9 5399.9 5427.5 5480.8 5206.6 5328.4 5400.6 5404.2 5747.4 5736.2 5752.3 5758.0 5771.5 5781.6 4960.7 5552.3 5586.6 5608.3 5632.4 4630.1 4795.4 5129.5 5516.8 5589.1 5008.8 5142.9 5436.9 5716.9 5764.0 3287.7 3552.3 3640.5 3715.5 3812.0 5541.5 5594.1 5600.5 5611.8 5631.4 5089.4 5210.3 5343.0 5393.6 5424.8 4735.9 4908.7 5240.4 5292.9 5409.9 4013.2 4910.2 5299.0 5312.7 5337.9 4337.7 4907.8 5397.0 5414.0 5421.3 5285.6 5313.3 5313.3 5314.6 5320.4 05:32:22 INFO - PROCESS | 1642 | 05:32:22 INFO - PROCESS | 1642 | [#5] yui.html Cycles:180 Average:19521.93 Median:22214.48 stddev:27677.73 (124.6%) stddev-sans-first:27719.26 05:32:22 INFO - PROCESS | 1642 | Values: 692.2 706.5 727.8 774.1 827.8 14360.3 15342.5 15381.8 15397.9 15418.5 14062.2 14263.6 14265.4 14280.8 14294.8 661.9 682.7 686.5 687.4 847.0 685.7 688.3 773.6 850.4 855.0 907.5 919.5 922.6 925.3 925.3 912.9 915.6 915.8 916.0 916.8 521.8 536.4 661.4 683.5 684.6 14403.0 15062.5 15069.8 15075.2 15092.7 13541.5 13667.9 13685.9 13767.9 13796.5 10982.5 12003.0 12080.6 12133.7 12705.9 4423.5 4425.2 4435.7 4575.3 4606.8 103595.5 107892.0 109910.9 110701.8 114721.4 10285.5 10722.6 10943.0 11104.9 11489.3 22258.2 22309.2 22309.2 22309.9 22335.9 7333.3 7691.5 7706.8 7758.1 7839.1 4201.5 4302.1 4341.3 4418.6 4420.2 3343.4 3442.5 3447.8 3448.0 3449.0 623.7 635.2 641.3 652.1 655.9 631.4 649.7 653.2 653.4 654.8 8948.5 10968.1 10996.2 11050.5 11475.9 550.8 597.2 601.0 601.3 704.5 4734.0 4815.0 4933.0 4984.2 4988.8 82015.8 95403.5 99924.0 100436.5 101574.5 67871.0 73176.6 78914.0 79484.6 79603.2 62973.0 68352.9 73107.3 74464.0 76873.0 10166.5 10660.0 10807.5 10834.8 10930.1 47567.0 48254.8 48471.0 50804.0 50837.5 40980.0 62948.0 63306.7 64291.4 65193.7 12293.3 13196.5 13265.0 13491.9 13753.7 14009.4 14308.5 14423.2 14737.5 14738.9 12762.2 13126.5 13568.0 13836.5 13907.4 12144.6 12168.9 12188.5 12249.4 12430.3 10045.8 10716.7 10973.1 11164.8 11182.3 10196.6 11067.4 11075.9 11197.0 11490.1 10798.5 10850.8 10943.6 10964.8 11005.1 05:32:22 INFO - PROCESS | 1642 | -------- Summary: end -------- 05:32:22 INFO - PROCESS | 1642 | 05:32:22 INFO - PROCESS | 1642 | 1487943142123 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method shutdown 05:32:22 INFO - TEST-INFO | 1642: exit 0 05:32:22 INFO - TEST-OK | dromaeo_css | took 1119470ms 05:32:22 INFO - TEST-START | kraken 05:32:22 INFO - operating with platform_type : mac_ 05:32:22 INFO - Initialising browser for kraken test... 05:32:22 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/tmpv2SUTi/profile http://localhost:49243/getInfo.html) 05:32:23 INFO - PROCESS | 1653 | 1487943143237 addons.xpi WARN Error loading bootstrap.js for disableSHA1rollout@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/disableSHA1rollout@mozilla.org.xpi!/bootstrap.js 05:32:23 INFO - PROCESS | 1653 | 1487943143238 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method install 05:32:23 INFO - PROCESS | 1653 | 1487943143272 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method startup 05:32:25 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 05:32:25 INFO - PROCESS | 1653 | colorDepth:24 05:32:25 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 05:32:25 INFO - PROCESS | 1653 | __metrics 05:32:25 INFO - PROCESS | 1653 | 1487943145130 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method shutdown 05:32:25 INFO - PROCESS | 1653 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:32:25 INFO - PROCESS | 1653 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:32:25 INFO - TEST-INFO | 1653: exit 0 05:32:25 INFO - Browser initialized. 05:32:25 INFO - Running cycle 1/1 for kraken test... 05:32:25 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/tmpv2SUTi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:32:26 INFO - PROCESS | 1654 | 1487943146223 addons.xpi WARN Error loading bootstrap.js for disableSHA1rollout@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/disableSHA1rollout@mozilla.org.xpi!/bootstrap.js 05:32:26 INFO - PROCESS | 1654 | 1487943146224 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method startup 05:32:37 INFO - PROCESS | 1654 | RSS: Main: 213028864 05:32:37 INFO - PROCESS | 1654 | 05:32:40 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 05:32:41 INFO - PROCESS | 1654 | RSS: Main: 265994240 05:32:41 INFO - PROCESS | 1654 | 05:32:44 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 05:32:44 INFO - PROCESS | 1654 | RSS: Main: 275218432 05:32:44 INFO - PROCESS | 1654 | 05:32:47 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 05:32:48 INFO - PROCESS | 1654 | RSS: Main: 373399552 05:32:48 INFO - PROCESS | 1654 | 05:32:50 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 05:32:51 INFO - PROCESS | 1654 | RSS: Main: 277286912 05:32:51 INFO - PROCESS | 1654 | 05:32:53 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:32:53 INFO - PROCESS | 1654 | RSS: Main: 278999040 05:32:53 INFO - PROCESS | 1654 | 05:32:56 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 05:32:57 INFO - PROCESS | 1654 | RSS: Main: 318091264 05:32:57 INFO - PROCESS | 1654 | 05:33:00 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 05:33:00 INFO - PROCESS | 1654 | RSS: Main: 321409024 05:33:00 INFO - PROCESS | 1654 | 05:33:03 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 05:33:04 INFO - PROCESS | 1654 | RSS: Main: 318758912 05:33:04 INFO - PROCESS | 1654 | 05:33:05 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:33:06 INFO - PROCESS | 1654 | RSS: Main: 294797312 05:33:06 INFO - PROCESS | 1654 | 05:33:08 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:33:09 INFO - PROCESS | 1654 | RSS: Main: 296759296 05:33:09 INFO - PROCESS | 1654 | 05:33:11 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:33:11 INFO - PROCESS | 1654 | RSS: Main: 300326912 05:33:11 INFO - PROCESS | 1654 | 05:33:14 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:33:15 INFO - PROCESS | 1654 | RSS: Main: 313683968 05:33:15 INFO - PROCESS | 1654 | 05:33:18 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:33:19 INFO - PROCESS | 1654 | RSS: Main: 308236288 05:33:19 INFO - PROCESS | 1654 | 05:33:20 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 05:33:21 INFO - PROCESS | 1654 | RSS: Main: 352407552 05:33:21 INFO - PROCESS | 1654 | 05:33:21 INFO - PROCESS | 1654 | __start_tp_report 05:33:21 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 05:33:21 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 05:33:21 INFO - PROCESS | 1654 | |i|pagename|runs| 05:33:21 INFO - PROCESS | 1654 | |0;ai-astar;115;115;116;115;114;116;114;114;115;118 05:33:21 INFO - PROCESS | 1654 | |1;audio-beat-detection;138;109;108;141;110;110;109;108;128;110 05:33:21 INFO - PROCESS | 1654 | |2;audio-dft;187;183;183;186;185;185;183;186;183;183 05:33:21 INFO - PROCESS | 1654 | |3;audio-fft;80;59;74;112;65;58;59;58;73;57 05:33:21 INFO - PROCESS | 1654 | |4;audio-oscillator;87;69;84;71;67;70;83;89;100;112 05:33:21 INFO - PROCESS | 1654 | |5;imaging-gaussian-blur;88;85;87;98;133;110;89;86;87;84 05:33:21 INFO - PROCESS | 1654 | |6;imaging-darkroom;93;94;94;94;93;94;94;94;95;94 05:33:21 INFO - PROCESS | 1654 | |7;imaging-desaturate;86;85;87;88;87;86;88;85;87;85 05:33:21 INFO - PROCESS | 1654 | |8;json-parse-financial;57;53;53;54;54;56;55;56;53;54 05:33:21 INFO - PROCESS | 1654 | |9;json-stringify-tinderbox;63;71;79;73;72;74;68;68;69;74 05:33:21 INFO - PROCESS | 1654 | |10;stanford-crypto-aes;84;84;84;84;83;83;84;86;77;96 05:33:21 INFO - PROCESS | 1654 | |11;stanford-crypto-ccm;145;142;155;138;138;143;150;153;184;152 05:33:21 INFO - PROCESS | 1654 | |12;stanford-crypto-pbkdf2;170;174;172;225;194;166;168;169;166;170 05:33:21 INFO - PROCESS | 1654 | |13;stanford-crypto-sha256-iterative;66;67;66;64;65;63;65;66;65;65 05:33:21 INFO - PROCESS | 1654 | __end_tp_report 05:33:21 INFO - PROCESS | 1654 | __start_cc_report 05:33:21 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,2115 05:33:21 INFO - PROCESS | 1654 | __end_cc_report 05:33:21 INFO - PROCESS | 1654 | __startTimestamp1487943201135__endTimestamp 05:33:21 INFO - PROCESS | 1654 | 05:33:21 INFO - PROCESS | 1654 | ------- Summary: start ------- 05:33:21 INFO - PROCESS | 1654 | Number of tests: 14 05:33:21 INFO - PROCESS | 1654 | 05:33:21 INFO - PROCESS | 1654 | [#0] ai-astar Cycles:10 Average:115.20 Median:115.00 stddev:1.23 (1.1%) stddev-sans-first:1.30 05:33:21 INFO - PROCESS | 1654 | Values: 115.0 115.0 116.0 115.0 114.0 116.0 114.0 114.0 115.0 118.0 05:33:21 INFO - PROCESS | 1654 | 05:33:21 INFO - PROCESS | 1654 | [#1] audio-beat-detection Cycles:10 Average:117.10 Median:110.00 stddev:13.23 (12.0%) stddev-sans-first:11.67 05:33:21 INFO - PROCESS | 1654 | Values: 138.0 109.0 108.0 141.0 110.0 110.0 109.0 108.0 128.0 110.0 05:33:21 INFO - PROCESS | 1654 | 05:33:21 INFO - PROCESS | 1654 | [#2] audio-dft Cycles:10 Average:184.40 Median:185.00 stddev:1.58 (0.9%) stddev-sans-first:1.36 05:33:21 INFO - PROCESS | 1654 | Values: 187.0 183.0 183.0 186.0 185.0 185.0 183.0 186.0 183.0 183.0 05:33:21 INFO - PROCESS | 1654 | 05:33:21 INFO - PROCESS | 1654 | [#3] audio-fft Cycles:10 Average:69.50 Median:62.00 stddev:17.03 (27.5%) stddev-sans-first:17.64 05:33:21 INFO - PROCESS | 1654 | Values: 80.0 59.0 74.0 112.0 65.0 58.0 59.0 58.0 73.0 57.0 05:33:21 INFO - PROCESS | 1654 | 05:33:21 INFO - PROCESS | 1654 | [#4] audio-oscillator Cycles:10 Average:83.20 Median:77.00 stddev:14.71 (19.1%) stddev-sans-first:15.54 05:33:21 INFO - PROCESS | 1654 | Values: 87.0 69.0 84.0 71.0 67.0 70.0 83.0 89.0 100.0 112.0 05:33:21 INFO - PROCESS | 1654 | 05:33:21 INFO - PROCESS | 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:94.70 Median:87.00 stddev:15.61 (17.9%) stddev-sans-first:16.36 05:33:21 INFO - PROCESS | 1654 | Values: 88.0 85.0 87.0 98.0 133.0 110.0 89.0 86.0 87.0 84.0 05:33:21 INFO - PROCESS | 1654 | 05:33:21 INFO - PROCESS | 1654 | [#6] imaging-darkroom Cycles:10 Average:93.90 Median:94.00 stddev:0.57 (0.6%) stddev-sans-first:0.50 05:33:21 INFO - PROCESS | 1654 | Values: 93.0 94.0 94.0 94.0 93.0 94.0 94.0 94.0 95.0 94.0 05:33:21 INFO - PROCESS | 1654 | 05:33:21 INFO - PROCESS | 1654 | [#7] imaging-desaturate Cycles:10 Average:86.40 Median:87.00 stddev:1.17 (1.3%) stddev-sans-first:1.24 05:33:21 INFO - PROCESS | 1654 | Values: 86.0 85.0 87.0 88.0 87.0 86.0 88.0 85.0 87.0 85.0 05:33:21 INFO - PROCESS | 1654 | 05:33:21 INFO - PROCESS | 1654 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:54.50 stddev:1.43 (2.6%) stddev-sans-first:1.20 05:33:21 INFO - PROCESS | 1654 | Values: 57.0 53.0 53.0 54.0 54.0 56.0 55.0 56.0 53.0 54.0 05:33:21 INFO - PROCESS | 1654 | 05:33:21 INFO - PROCESS | 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:71.10 Median:72.50 stddev:4.38 (6.0%) stddev-sans-first:3.54 05:33:21 INFO - PROCESS | 1654 | Values: 63.0 71.0 79.0 73.0 72.0 74.0 68.0 68.0 69.0 74.0 05:33:21 INFO - PROCESS | 1654 | 05:33:21 INFO - PROCESS | 1654 | [#10] stanford-crypto-aes Cycles:10 Average:84.50 Median:84.00 stddev:4.67 (5.6%) stddev-sans-first:4.95 05:33:21 INFO - PROCESS | 1654 | Values: 84.0 84.0 84.0 84.0 83.0 83.0 84.0 86.0 77.0 96.0 05:33:21 INFO - PROCESS | 1654 | 05:33:21 INFO - PROCESS | 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:150.00 Median:151.00 stddev:13.42 (8.9%) stddev-sans-first:14.11 05:33:21 INFO - PROCESS | 1654 | Values: 145.0 142.0 155.0 138.0 138.0 143.0 150.0 153.0 184.0 152.0 05:33:21 INFO - PROCESS | 1654 | 05:33:21 INFO - PROCESS | 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.40 Median:171.00 stddev:18.59 (10.9%) stddev-sans-first:19.52 05:33:21 INFO - PROCESS | 1654 | Values: 170.0 174.0 172.0 225.0 194.0 166.0 168.0 169.0 166.0 170.0 05:33:21 INFO - PROCESS | 1654 | 05:33:21 INFO - PROCESS | 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.20 Median:65.50 stddev:1.14 (1.7%) stddev-sans-first:1.17 05:33:21 INFO - PROCESS | 1654 | Values: 66.0 67.0 66.0 64.0 65.0 63.0 65.0 66.0 65.0 65.0 05:33:21 INFO - PROCESS | 1654 | -------- Summary: end -------- 05:33:21 INFO - PROCESS | 1654 | 05:33:21 INFO - PROCESS | 1654 | 1487943201156 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method shutdown 05:33:21 INFO - TEST-INFO | 1654: exit 0 05:33:22 INFO - TEST-OK | kraken | took 59299ms 05:33:22 INFO - SUITE-END | took 1178s 05:33:22 INFO - Completed test suite (00:19:39) 05:33:22 INFO - javascript benchmark 05:33:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2894.5658151277307, 3309.4208513510134, 3349.916252093698, 3391.287829555793, 3391.542141810855, 5679.829605111843, 5706.7431965561545, 5714.314282286121, 5715.399883012281, 5754.992876246656, 5607.971960140194, 5611.607187496867, 5686.214063385673, 5707.029804933161, 5764.625299355549, 5174.172132458817, 5178.844634660947, 5227.242049902781, 5241.528262456378, 5283.471652834724, 4875.804967801301, 5117.1556693145585, 5118.027574760802, 5292.629515933886, 5343.492368225013, 5183.17069268917, 5184.8703782405555, 5200.895982080356, 5212.973935130319, 5287.47125287472, 4551.431071116111, 5164.715940623246, 5280.973595132038, 5284.445133261029, 5327.493888080626, 5080.74596270185, 5116.718580478072, 5184.792608295664, 5216.139337009389, 5219.947800521984, 5565.193047008202, 5621.213030175779, 5659.632123911973, 5664.235328230678, 5670.5747068969995, 6195.256569211679, 6218.19163508741, 6263.812085637438, 6266.342034086447, 6278.37216278373, 6231.283402408691, 6305.68471576424, 6308.842278943017, 6325.019621958604, 6326.620402775849, 5658.405867383948, 5719.19931209631, 5733.397993210736, 5828.300603927556, 5836.6789826559525, 6159.630422174594, 6230.408111229426, 6230.875382492325, 6234.0, 6267.843303917347, 6179.474744646755, 6188.164597779332, 6188.257409110935, 6198.0, 6208.037754148114, 5258.763355649005, 6243.344448832898, 6268.03958972112, 6283.497320214372, 6301.7164227608955, 6074.848128796815, 6107.938920610826, 6114.633122012693, 6145.0, 6146.231721034871, 5015.322931404214, 6202.596831205957, 6211.720472578745, 6282.968585157137, 6284.245890493079, 4952.950470495249, 4975.825846095369, 4984.675996060317, 4985.351904252424, 5004.0, 4424.159409712144, 4439.178751930903, 4456.509783923766, 4462.732236065846, 4485.282354823213, 5657.688827114548, 5712.6286791358425, 5725.370209276976, 5752.798652047159, 5793.0, 6251.187345645128, 6271.122042913996, 6279.874402511924, 6286.371362863677, 6309.905351419732, 5647.971760141255, 5782.710864456761, 5822.165295269883, 5830.53355731549, 5888.234529511222, 5328.3605967284175, 5468.261784659099, 5470.288862447856, 5470.589705772083, 5474.835754927359, 6202.28673702521, 6209.96895015522, 6251.249850017936, 6253.874922501615, 6266.404691554295, 6260.467860231749, 6271.3728627136925, 6281.937180628318, 6290.685465726787, 6303.747850085945, 6239.688015599111, 6310.936890631218, 6325.810225693236, 6341.8097457076365, 6358.809235722936, 6077.878442431215, 6110.114033465034, 6128.747575574743, 6140.969295153495, 6217.440430361289, 6123.387661233841, 6268.310485846644, 6270.15352927349, 6274.090256912813, 6293.056041593798, 6117.816465506042, 6142.815715528542, 6143.569950103629, 6160.876782464416, 6168.506519478341, 5544.500994910316, 5874.471297583238, 5999.730012149551, 6111.0, 6129.4483496483745, 5875.383084776037, 5896.292444906639, 5906.320773110975, 5947.434993675594, 5988.431099045583, 5922.437368449991, 6116.17431646722, 6122.6938653068055, 6128.724207410766, 6152.938470615237, 5802.535797136304, 5816.098504731774, 5831.241938547962, 5889.587728858939, 5895.970520147372], "value": 5681.551670939551, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [443.65616647098494, 448.1641738158336, 469.36166813134133, 513.7020528093703, 531.688961957255, 36567.817160914194, 41813.79093104534, 41834.7908260459, 42022.7898860506, 42064.57935420645, 43088.922776930594, 43538.911527211836, 43572.0, 43581.34627980575, 43688.12623747523, 8516.276116530102, 9275.4434733916, 9499.857502137473, 9504.192143667762, 9505.80988380232, 8575.871361929576, 11305.0, 11541.538338466451, 11581.131415143898, 11744.706382340468, 11431.42842857858, 11516.193866429354, 11528.596499122532, 11529.135314851377, 11535.711607209803, 8527.488350698977, 11511.48198331077, 11551.884481155206, 11557.422128893522, 11599.94200029003, 8545.871811922827, 11129.443527823576, 11388.202825802196, 11403.42982850854, 11532.365719885403, 33063.66936330654, 41292.58707412948, 41316.79341603273, 41361.0, 41470.1705965882, 3938.704597155196, 3947.940780888289, 3996.2806694794926, 4008.518977722663, 4021.416894550274, 29328.560071598942, 29420.8528957356, 29563.26091847721, 30367.392652147053, 30698.386032279228, 3108.751299896003, 3294.8352582370785, 3360.6135294441206, 3380.0873764083804, 3381.3575420669995, 498916.0, 499591.9999999964, 502068.0, 502080.0, 502677.0, 62720.686396567726, 62963.0, 63140.0, 63151.6842415785, 63373.0, 66251.0, 66322.66838665775, 66559.0, 66643.33356666469, 66688.0, 13889.0, 16927.0, 16962.23669934856, 16962.321507139823, 17016.234269457906, 11753.588624398106, 12125.636230913087, 13004.479820807252, 13051.412686428943, 13064.934675326562, 15115.093094414371, 17777.878657194044, 20079.594428390166, 20495.077721502566, 21567.56864862694, 7387.704491820375, 7429.8142546436775, 7545.396368290633, 8803.735887923373, 8874.334424918157, 867.1761826265074, 890.2788741119584, 892.1970226795936, 908.4458480326995, 916.5600511754395, 30272.54591181134, 30373.848130759205, 30431.39137217243, 30454.695453045188, 30920.536191957137, 958.2956526952685, 1035.30634474902, 1083.3012706804068, 1105.9336439813476, 1182.4147047211513, 15122.319495622723, 15789.921050394903, 15810.36758529669, 15961.44134955271, 16150.950188237726, 623920.0, 630860.0, 631664.3667126724, 633316.0, 634580.0, 45184.870378240805, 45490.77254613706, 45607.31589026167, 45630.54369456329, 45677.771611142394, 1215.501644325822, 1441.466657336771, 1460.247972294248, 1463.1952426165778, 1471.9043262187924, 26110.73889261159, 26956.191314260603, 29780.851095744383, 30382.84808575943, 30457.0, 51146.74426627992, 52529.737351313, 52543.474565253855, 52883.0, 55789.44210558005, 615173.0, 623240.0, 624771.0, 625994.0, 628583.0, 33307.00078997634, 33669.66330336665, 40530.56816591014, 40998.92508061848, 41231.0, 41734.582654174286, 41768.69331508497, 42859.0, 43466.91332716707, 43535.78232108819, 30252.24369390783, 35333.646663533036, 35592.46611300781, 36892.077698057765, 37083.629163708014, 35038.0, 35043.12392190216, 35610.46584301289, 36201.637983619825, 36377.45433818442, 34838.30323393569, 34852.825735871156, 35262.647373525935, 35572.288554229286, 35576.0, 29800.850995744884, 31510.0, 31668.683313166573, 31728.682713172573, 31792.841035794674, 23389.883050584638, 23483.295501134995, 23615.52768944646, 24006.75993240045, 24162.758372416753], "value": 20286.27828635144, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.09185398722235, 365.7183968344375, 383.4918732679199, 395.40688966550175, 420.18274456182735, 14747.48383806567, 15161.772573411408, 16793.664126717475, 20142.0, 21002.474938126554, 20298.492537686565, 21119.472013199676, 21236.36290911275, 21402.357929262147, 21483.0, 4636.188666983281, 5187.740612969355, 5406.324209473816, 5415.566754659618, 5556.194351818984, 5044.394672639289, 5710.200571919934, 5876.0598304271325, 5879.76480940764, 5880.852978675546, 3849.8845034649007, 4015.2973229684835, 7467.514611550259, 7808.76573702787, 7889.605519724019, 7615.80960475987, 7753.418493612973, 7828.295453409163, 7939.364850811921, 7939.682412703513, 7155.497345557439, 7726.0342457192855, 7730.729424470116, 7737.690492380354, 7748.0, 27308.0, 27991.0, 28023.43953120926, 28732.85633571819, 29110.27224319409, 2874.798764086515, 2894.348771526411, 2913.6503619565574, 2916.9270768230754, 2933.559966005095, 15697.90114691972, 16478.6704265914, 16782.496525104267, 17166.399176028903, 17735.024573648443, 1590.705719441907, 1937.476881242071, 1945.445548018822, 1984.0, 1990.8506861985263, 109548.0, 118629.0, 134109.0, 135342.646573534, 159393.0, 19934.20263189456, 23005.96473158712, 23038.42403939915, 23110.76889231086, 23307.76692233056, 33346.499802502985, 38632.0, 39098.413523797164, 39991.40012899809, 40181.598184017785, 10255.7436064099, 10780.730481738019, 10815.621453249098, 10876.238663293494, 11462.59880904181, 9023.0, 9042.909570904207, 9079.18287354128, 9104.408213466104, 9258.213051890665, 8885.4668719877, 10778.353298802096, 10839.512221950032, 11014.724631884266, 11022.567066281492, 3228.6448490666007, 3418.9145271368416, 3448.9482657760154, 4070.9592904071174, 4079.1637714268504, 1007.5063219022773, 1023.5240613114937, 1035.668586052456, 1040.331549575352, 1047.4814966591587, 21332.78667213308, 21936.670949935764, 21966.231181908875, 22032.678039317692, 22079.785611791183, 927.2674587076134, 956.6938579654444, 1029.3823705776624, 1073.849661047454, 1085.3596378136779, 10028.899711002943, 10604.515367848508, 10699.625513107005, 10984.636871508445, 11150.386728729998, 690948.0, 695390.0, 702346.0, 702479.0, 727112.0, 147746.52253477543, 148775.0, 149807.0, 151773.0, 156190.2190489063, 976.9560369783377, 981.916537094355, 1049.9527521261714, 1054.383185836279, 1059.7986382587437, 19293.0, 20192.317263759414, 20443.182272708924, 20471.488212794204, 20500.794992049887, 60486.69756651189, 61293.69353153206, 61421.692891535255, 61468.0, 62966.99999999817, 88537.55731221303, 90348.09651903396, 90470.0, 91488.0, 93401.0, 7681.770916653309, 8281.254687078192, 8298.958505207434, 8302.460340077876, 8545.188207120314, 10173.389596624227, 12836.55072072473, 13083.887869531196, 13151.60545183646, 13514.459421623025, 9370.297227708086, 11069.280496767684, 11376.829347559958, 11407.771844563229, 11449.771004579696, 5109.259157422227, 7499.925000749922, 9716.465594392377, 10596.735081623021, 10948.726281843017, 11469.426528673699, 11646.417679116179, 11858.16992810495, 11983.28100313984, 12031.819522706992, 5940.762369505344, 7615.885761713689, 8481.703140390056, 8662.82674346497, 8719.52042637661, 9901.653442129491, 11001.064909482784, 11262.38056906878, 11329.263597866113, 11528.942355288169], "value": 11168.408914530975, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1359.7620416427123, 1381.440516590781, 1384.5638623833493, 1386.0159286906296, 1392.157744564538, 7151.463640226984, 8511.446755960873, 8531.530765807893, 8537.615807288672, 8560.10118937512, 7479.065116860393, 7663.310302072812, 8078.474899131567, 8339.916600834005, 8352.874706879402, 2303.3090072978125, 2343.086196383412, 2345.108858633717, 2351.541449417364, 2353.576356255873, 2311.84972976756, 2334.124703236286, 2339.6724458575814, 2355.705536807899, 2357.7406485286606, 2073.0, 2148.9140434382607, 2151.978480215201, 2312.3987763181526, 2345.0736958901225, 2132.5521640455445, 2141.2398598497543, 2248.0895237428867, 2314.4908120213695, 2327.8254130940077, 2351.094828491026, 2351.8471299365656, 2356.599378105726, 2357.139644029934, 2359.4809141988735, 8413.789655258606, 8484.957575212145, 8486.53324067176, 8493.36299777513, 8507.31941444689, 5096.719680417576, 5244.423113457517, 5251.212318152269, 5267.341582302212, 5274.077036518594, 3207.615086189649, 3342.866285348583, 3504.754667173299, 3525.735569832247, 3526.9647303526895, 1143.3540049871797, 1229.569650622275, 1264.7849865522883, 1267.8161666558296, 1700.6428649983395, 10722.517486713117, 10939.398333091676, 10980.670579882537, 10997.99999999992, 11029.558817647285, 4282.828686852491, 4519.254323036728, 4571.725696458223, 4577.176108300538, 4595.862124136282, 8781.868271975925, 8784.78038049054, 8788.8681669775, 8790.86813697795, 8812.823743525094, 5168.457311982263, 5183.481651834862, 5188.0, 5190.740462976912, 5205.687658740488, 4255.319148936155, 4469.061497085649, 4766.404199475066, 4770.833020844254, 4861.927071093865, 3592.1558433768046, 3726.32926073304, 3727.459518369821, 3824.4836947011795, 3826.5025546678753, 1146.908247340211, 1166.2244607336027, 1178.216486036776, 1179.9469023893946, 1182.3024415594841, 736.8710475666737, 749.6176949755663, 782.9686812527435, 813.2924355810483, 814.722994181981, 4741.407324084489, 5283.573435172559, 5420.232938108056, 5443.346798384219, 5847.736851841677, 873.1879352202512, 966.415318732157, 970.9174720148867, 1002.3184234720314, 1053.1890444357803, 3089.7219250267585, 3658.817059147032, 3743.5694895087413, 3744.3073031488716, 3762.3039737648623, 9825.4104753715, 10120.139788117945, 10177.033181847713, 10200.43897585625, 10200.693979180636, 8489.0, 10155.64455244063, 10163.796724065625, 10184.643537476153, 10189.184865210913, 7873.370130389555, 8045.637946292314, 8046.074701409296, 8055.234752698484, 8070.556119413372, 5064.924026139537, 5251.737413129405, 5348.358197016383, 5409.486098820606, 5435.429279925709, 9192.126747958933, 9231.446113233227, 9276.907230927603, 9397.530123493934, 9483.28875334339, 9252.722418327461, 9461.053894610484, 9488.193503552, 9555.283353748635, 9586.424814510872, 6322.747090116344, 6527.05357723118, 6810.659467026728, 6947.478939079489, 6951.409130223988, 7619.657115429708, 7821.491603045783, 8015.7595272141925, 8031.799205019687, 8091.716789912325, 7064.611446370293, 7139.643017848983, 7178.102737157856, 7193.568385896863, 7293.671784769592, 7063.894041589278, 7078.893816592652, 7084.822879428056, 7094.290570942864, 7139.21468638439, 6418.84460797061, 6428.4857211423905, 6541.378569035934, 6546.967265163644, 6549.901751473636, 4720.339152518719, 4926.852194434173, 4999.0, 5005.749712514287, 5020.573251273537, 4946.258061290691, 4975.303457516023, 4982.252662100714, 5046.21783623539, 5177.870553236199], "value": 4381.399088837992, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.1817728338405, 350.7667401178216, 360.9007522931193, 363.25713915043764, 366.91010702377923, 4363.803628836702, 4665.626749860012, 4879.511748916579, 5178.482151784829, 5216.24364467152, 5102.948970510294, 5109.948900510994, 5112.974435127829, 5130.461301563338, 5143.662647711594, 3175.412548678502, 4060.330045542482, 4070.308047631895, 4102.384642303663, 4104.8768536943935, 2878.6113874626867, 3121.282105115825, 3747.494088298086, 3915.314819906519, 4035.273650742865, 3651.7808931464197, 4003.819828107742, 4004.339284018148, 4011.4785077939828, 4037.777922214277, 3200.8239546824916, 3538.716902647782, 4028.315186418316, 4041.969297829035, 4058.939115913264, 3185.7097875360514, 3536.1159710072484, 3578.159132603836, 3578.818989733382, 3580.7314451415987, 4217.945513621595, 4422.425084738996, 4720.150372932871, 4897.755112244409, 4941.827036053721, 4604.493505714368, 4608.9308660370125, 4611.377464042345, 4634.953650463486, 4661.88345291367, 4575.748333841638, 4653.162430762462, 5131.384233891919, 5178.533931946143, 5469.261649677283, 3409.5056216848666, 3688.889333320005, 3707.610700876396, 3732.7760334379755, 3950.2889479893606, 4774.427068751772, 4901.799059230476, 5552.5280351170195, 5580.553555715533, 5607.383187849333, 3208.4866421372935, 3259.7229235515247, 3771.0404075720385, 3801.1827457096856, 3851.345271303885, 3858.2090671412852, 3877.108265099043, 3877.6122387760997, 3896.0, 3899.122697393064, 4264.914701705949, 4470.888227794266, 4480.551944805494, 4489.910201795946, 4495.0, 5208.166693329049, 5431.918521222185, 5436.0, 5457.890842183134, 5471.534919531884, 1214.1666084212902, 1224.9510019599295, 1227.220714846079, 1243.5088140184575, 1244.807054906491, 796.4385108498494, 835.6796261906173, 836.7322456813882, 855.8018773716722, 856.3534531428731, 4411.911761764747, 4608.377868987711, 4757.762111894392, 4801.255805350176, 4814.325994360793, 5055.266986286968, 5216.165413533817, 5246.790128394821, 5267.341582302212, 5298.337707786527, 3866.0, 4486.282194848874, 4714.45783734868, 4755.595774359149, 4766.833160839354, 2476.962845557318, 2751.697313295536, 2758.420876480497, 2772.750452459288, 2777.4583956128363, 4607.9308810367875, 5072.949270507322, 5399.919001214985, 5427.457254274541, 5480.780768769284, 5206.609504287194, 5328.413874473805, 5400.594955378362, 5404.243405923174, 5747.425257474303, 5736.225609542658, 5752.252207213036, 5757.971210144006, 5771.480566748929, 5781.566382521243, 4960.652754307308, 5552.278203833475, 5586.581006424616, 5608.327000759935, 5632.380438151882, 4630.120277147331, 4795.448523419712, 5129.538341549279, 5516.779328826802, 5589.077802162597, 5008.799648014185, 5142.9456961323585, 5436.891262174655, 5716.914246286392, 5763.991301522301, 3287.736981041463, 3552.3073000765635, 3640.4903313536656, 3715.461258117504, 3811.9809400952818, 5541.5289700375915, 5594.104943209067, 5600.451794433605, 5611.831645050655, 5631.408702086386, 5089.447718445909, 5210.27056212123, 5342.973285133549, 5393.595480338912, 5424.779424629427, 4735.857924262277, 4908.730019848943, 5240.449752775904, 5292.947070529245, 5409.945900541102, 4013.2174226025645, 4910.189818679878, 5299.0, 5312.734363281743, 5337.905729325556, 4337.652987761035, 4907.803687852446, 5396.977692492217, 5413.971345444353, 5421.295231619864, 5285.577153827761, 5313.2561441397465, 5313.335833020872, 5314.62797604164, 5320.408724111132], "value": 3929.568597846173, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [692.1693967239307, 706.5478094019825, 727.8253219227386, 774.0595176860118, 827.7516744976507, 14360.281985900716, 15342.539723808304, 15381.846181538182, 15397.923010384946, 15418.46035388763, 14062.226577538231, 14263.643408914782, 14265.358058887348, 14280.78578821316, 14294.785578216335, 661.8610091880711, 682.6825526130349, 686.4679873098339, 687.4328678839952, 847.0301504777027, 685.7051467868814, 688.2704333406605, 773.6325245508367, 850.3664769746516, 854.9529775862325, 907.5054095517959, 919.5402298850574, 922.5848368234288, 925.3337596930199, 925.3476299209042, 912.8813254276935, 915.6108653822099, 915.771057235691, 915.9679411220642, 916.8303863785221, 521.8173639226278, 536.3644081763123, 661.4278648968656, 683.480554778372, 684.5926673629183, 14403.0, 15062.548123556311, 15069.773953390708, 15075.170865602387, 15092.698146037128, 13541.526046588322, 13667.931660341636, 13685.931570342085, 13767.931160344135, 13796.517121900786, 10982.505787239514, 12003.039756819433, 12080.637580872588, 12133.69665758363, 12705.872941270562, 4423.535528769497, 4425.203463376623, 4435.68950173488, 4575.313702944585, 4606.838760643395, 103595.48202259016, 107892.0, 109910.90089099166, 110701.78596428107, 114721.42639286834, 10285.53715082808, 10722.571097156026, 10943.0, 11104.944475277733, 11489.310641361544, 22258.22096226624, 22309.219177328716, 22309.21917732904, 22309.888450557642, 22335.888320558293, 7333.2666733327305, 7691.538507689556, 7706.845863082707, 7758.06903171623, 7839.059312882377, 4201.4538110046105, 4302.075053863435, 4341.283688191476, 4418.64650827933, 4420.204363214652, 3343.4316166251797, 3442.4664177052596, 3447.827608619559, 3447.965610316945, 3448.965310406868, 623.7473823101651, 635.2218532297898, 641.2785616181795, 652.0968458684756, 655.9147310849559, 631.3528633150966, 649.7336092202174, 653.1998302080009, 653.3597074866562, 654.8101050695245, 8948.463092214488, 10968.067714244218, 10996.23026388161, 11050.502727377287, 11475.88524114765, 550.8168137823066, 597.1669521018156, 601.0263373335225, 601.2814686449738, 704.4751660013322, 4733.95266047342, 4814.9518504815205, 4933.0, 4984.152694042022, 4988.775505102207, 82015.7697634536, 95403.52298238465, 99924.0, 100436.4978175119, 101574.49212754036, 67871.0, 73176.63411683014, 78914.0, 79484.6025769879, 79603.20396796074, 62973.0, 68352.9241184148, 73107.2689273111, 74464.0, 76873.0, 10166.491675416348, 10660.0, 10807.5203576559, 10834.783304333712, 10930.125589952626, 47567.0, 48254.793630159525, 48471.0, 50803.98392032213, 50837.474875752305, 40980.0, 62948.0, 63306.73386532336, 64291.3927151825, 65193.67403163144, 12293.323867187391, 13196.538121166097, 13265.0, 13491.86508134906, 13753.724925501234, 14009.369578369202, 14308.49920252786, 14423.244706574618, 14737.484188053795, 14738.926305368404, 12762.17045892014, 13126.540571079966, 13568.016192323403, 13836.515721949683, 13907.374168162254, 12144.574939877062, 12168.878311216775, 12188.51245950152, 12249.387530623611, 12430.254184748945, 10045.794504659487, 10716.73208169802, 10973.071451745674, 11164.832527512257, 11182.273152245078, 10196.643117490852, 11067.391293478937, 11075.944620276847, 11197.0, 11490.080793536665, 10798.514066866852, 10850.837237441287, 10943.562257509611, 10964.835527466936, 11005.064569511682], "value": 7324.436661184541, "unit": "score"}], "name": "dromaeo_css", "value": 7385.97051491477, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 116.0, 115.0, 114.0, 116.0, 114.0, 114.0, 115.0, 118.0], "value": 115.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 109.0, 108.0, 141.0, 110.0, 110.0, 109.0, 108.0, 128.0, 110.0], "value": 117.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 183.0, 183.0, 186.0, 185.0, 185.0, 183.0, 186.0, 183.0, 183.0], "value": 184.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 74.0, 112.0, 65.0, 58.0, 59.0, 58.0, 73.0, 57.0], "value": 69.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 69.0, 84.0, 71.0, 67.0, 70.0, 83.0, 89.0, 100.0, 112.0], "value": 83.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 87.0, 98.0, 133.0, 110.0, 89.0, 86.0, 87.0, 84.0], "value": 94.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 94.0, 93.0, 94.0, 94.0, 94.0, 95.0, 94.0], "value": 93.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 87.0, 88.0, 87.0, 86.0, 88.0, 85.0, 87.0, 85.0], "value": 86.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 53.0, 53.0, 54.0, 54.0, 56.0, 55.0, 56.0, 53.0, 54.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 71.0, 79.0, 73.0, 72.0, 74.0, 68.0, 68.0, 69.0, 74.0], "value": 71.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 84.0, 84.0, 83.0, 83.0, 84.0, 86.0, 77.0, 96.0], "value": 84.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 142.0, 155.0, 138.0, 138.0, 143.0, 150.0, 153.0, 184.0, 152.0], "value": 150.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 174.0, 172.0, 225.0, 194.0, 166.0, 168.0, 169.0, 166.0, 170.0], "value": 177.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 64.0, 65.0, 63.0, 65.0, 66.0, 65.0, 65.0], "value": 65.2, "unit": "score"}], "name": "kraken", "value": 1447.1000000000001, "alertThreshold": 2.0}]} 05:33:22 INFO - Return code: 0 05:33:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:33:22 ERROR - # TBPL SUCCESS # 05:33:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:33:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:33:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:33:22 INFO - 2017-02-24 05:33:22.435 screenresolution[1658:9787] starting screenresolution argv=screenresolution get 05:33:22 INFO - 2017-02-24 05:33:22.437 screenresolution[1658:9787] Display 0: 1600x1200x32@60 05:33:22 INFO - 2017-02-24 05:33:22.444 screenresolution[1659:9790] starting screenresolution argv=screenresolution list 05:33:22 INFO - Available Modes on Display 0 05:33:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:33:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:33:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:33:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:33:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:33:22 INFO - Intel Iris: 05:33:22 INFO - Chipset Model: Intel Iris 05:33:22 INFO - Type: GPU 05:33:22 INFO - Bus: Built-In 05:33:22 INFO - VRAM (Dynamic, Max): 1536 MB 05:33:22 INFO - Vendor: Intel (0x8086) 05:33:22 INFO - Device ID: 0x0a2e 05:33:22 INFO - Revision ID: 0x0009 05:33:22 INFO - Displays: 05:33:22 INFO - Display: 05:33:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:33:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:33:22 INFO - Display Serial Number: 1600x1200 60 05:33:22 INFO - Main Display: Yes 05:33:22 INFO - Mirror: Off 05:33:22 INFO - Online: Yes 05:33:22 INFO - Rotation: Supported 05:33:22 INFO - Return code: 0 05:33:22 INFO - Running post-action listener: _resource_record_post_action 05:33:22 INFO - [mozharness: 2017-02-24 13:33:22.681789Z] Finished run-tests step (success) 05:33:22 INFO - Running post-run listener: _resource_record_post_run 05:33:22 INFO - Total resource usage - Wall time: 1199s; CPU: 54.0%; Read bytes: 39097344; Write bytes: 508724736; Read time: 400; Write time: 1982 05:33:22 INFO - TinderboxPrint: CPU usage
53.7% 05:33:22 INFO - TinderboxPrint: I/O read bytes / time
39,097,344 / 400 05:33:22 INFO - TinderboxPrint: I/O write bytes / time
508,724,736 / 1,982 05:33:22 INFO - TinderboxPrint: CPU idle
2,222.5 (46.3%) 05:33:22 INFO - TinderboxPrint: CPU system
956.6 (19.9%) 05:33:22 INFO - TinderboxPrint: CPU user
1,618.5 (33.7%) 05:33:22 INFO - TinderboxPrint: Swap in / out
247,013,376 / 0 05:33:22 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 208949760; Write bytes: 205817344; Read time: 15958; Write time: 436 05:33:22 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 34386944; Write bytes: 297013248; Read time: 348; Write time: 1516 05:33:23 INFO - Running post-run listener: _upload_blobber_files 05:33:23 INFO - Blob upload gear active. 05:33:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:33:23 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 05:33:23 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:33:23 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:33:23 INFO - (blobuploader) - INFO - Open directory for files ... 05:33:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:33:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:33:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:33:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:33:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:33:24 INFO - (blobuploader) - INFO - Done attempting. 05:33:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:33:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:33:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:33:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:33:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:33:25 INFO - (blobuploader) - INFO - Done attempting. 05:33:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:33:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:33:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:33:27 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:33:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:33:27 INFO - (blobuploader) - INFO - Done attempting. 05:33:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:33:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:33:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:33:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:33:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:33:29 INFO - (blobuploader) - INFO - Done attempting. 05:33:29 INFO - (blobuploader) - INFO - Iteration through files over. 05:33:29 INFO - Return code: 0 05:33:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:33:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:33:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d323745e1fbdc3f25d8d799a7d4a47fe3a6fd4a88cac79916404554fe555428109fe0af07f08055f4db5c7cad2bd05abc53a4e4e31927ff82ee33482cb9463e2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/23e6cef0e8be0e9a5a57f02dc9a3fe010f7986726739fff492f29b4cb3cd33ef5fb57254ccc83200e57bd691866584a9d3912a7abb411ee7c338df53e6a4a9d8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0951c718c422446cf1ac24177f7bffed34713133eb9981314a341b55dbbff6faa0296f5ac10c0b62071a2ee2b0c14033149f9ff800637ea4ef415335b9edb52b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/30fdd6ca962c1612a2214ee6e03c52d0a00ea277e88ba2bf0c31ea2f4cd2697993fac594636d3c75ad393870f962306d18aedc4bbe9f30cb316621884eebf0ef"} 05:33:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:33:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:33:29 INFO - Contents: 05:33:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d323745e1fbdc3f25d8d799a7d4a47fe3a6fd4a88cac79916404554fe555428109fe0af07f08055f4db5c7cad2bd05abc53a4e4e31927ff82ee33482cb9463e2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/23e6cef0e8be0e9a5a57f02dc9a3fe010f7986726739fff492f29b4cb3cd33ef5fb57254ccc83200e57bd691866584a9d3912a7abb411ee7c338df53e6a4a9d8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0951c718c422446cf1ac24177f7bffed34713133eb9981314a341b55dbbff6faa0296f5ac10c0b62071a2ee2b0c14033149f9ff800637ea4ef415335b9edb52b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/30fdd6ca962c1612a2214ee6e03c52d0a00ea277e88ba2bf0c31ea2f4cd2697993fac594636d3c75ad393870f962306d18aedc4bbe9f30cb316621884eebf0ef"} 05:33:29 INFO - Running post-run listener: copy_logs_to_upload_dir 05:33:29 INFO - Copying logs to upload dir... 05:33:29 INFO - mkdir: /builds/slave/test/build/upload/logs 05:33:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.506583 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-02-24 05:33:29.323032) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-24 05:33:29.326993) ========= 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.5z2R3af1qq/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.mVuD114YGO/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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/Mozilla-Aurora/sha512/d323745e1fbdc3f25d8d799a7d4a47fe3a6fd4a88cac79916404554fe555428109fe0af07f08055f4db5c7cad2bd05abc53a4e4e31927ff82ee33482cb9463e2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/23e6cef0e8be0e9a5a57f02dc9a3fe010f7986726739fff492f29b4cb3cd33ef5fb57254ccc83200e57bd691866584a9d3912a7abb411ee7c338df53e6a4a9d8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0951c718c422446cf1ac24177f7bffed34713133eb9981314a341b55dbbff6faa0296f5ac10c0b62071a2ee2b0c14033149f9ff800637ea4ef415335b9edb52b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/30fdd6ca962c1612a2214ee6e03c52d0a00ea277e88ba2bf0c31ea2f4cd2697993fac594636d3c75ad393870f962306d18aedc4bbe9f30cb316621884eebf0ef"} build_url:https://queue.taskcluster.net/v1/task/ddS3HRxjRi6lR80AtBF1rA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011262 build_url: 'https://queue.taskcluster.net/v1/task/ddS3HRxjRi6lR80AtBF1rA/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d323745e1fbdc3f25d8d799a7d4a47fe3a6fd4a88cac79916404554fe555428109fe0af07f08055f4db5c7cad2bd05abc53a4e4e31927ff82ee33482cb9463e2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/23e6cef0e8be0e9a5a57f02dc9a3fe010f7986726739fff492f29b4cb3cd33ef5fb57254ccc83200e57bd691866584a9d3912a7abb411ee7c338df53e6a4a9d8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0951c718c422446cf1ac24177f7bffed34713133eb9981314a341b55dbbff6faa0296f5ac10c0b62071a2ee2b0c14033149f9ff800637ea4ef415335b9edb52b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/30fdd6ca962c1612a2214ee6e03c52d0a00ea277e88ba2bf0c31ea2f4cd2697993fac594636d3c75ad393870f962306d18aedc4bbe9f30cb316621884eebf0ef"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-24 05:33:29.360692) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-24 05:33:29.360998) ========= 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.5z2R3af1qq/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.mVuD114YGO/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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.005185 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-24 05:33:29.409170) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-24 05:33:29.409473) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-24 05:33:29.409801) ========= ========= Total master_lag: 0.18 =========