builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0163 starttime: 1486332888.37 results: success (0) buildid: 20170205125708 builduid: 3ec68f53e8174fe4a95e59c32c47f674 revision: 6c998914bad21a65c7fe2ee7e8c1c7f5269c750d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-05 14:14:48.366591) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-05 14:14:48.366999) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-05 14:14:48.367290) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-05 14:14:48.391180) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-05 14:14:48.391449) ========= 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.SWyf8R7mo5/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.5Utl4sTHQG/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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-05 14:14:48-- 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% 13.3M=0.001s 2017-02-05 14:14:48 (13.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.128431 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-05 14:14:48.546279) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-05 14:14:48.546588) ========= 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.SWyf8R7mo5/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.5Utl4sTHQG/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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.021620 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-05 14:14:48.594807) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-05 14:14:48.595131) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 6c998914bad21a65c7fe2ee7e8c1c7f5269c750d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 6c998914bad21a65c7fe2ee7e8c1c7f5269c750d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SWyf8R7mo5/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.5Utl4sTHQG/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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-05 14:14:48,682 truncating revision to first 12 chars 2017-02-05 14:14:48,682 Setting DEBUG logging. 2017-02-05 14:14:48,682 attempt 1/10 2017-02-05 14:14:48,682 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6c998914bad2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-05 14:14:49,413 unpacking tar archive at: mozilla-central-6c998914bad2/testing/mozharness/ program finished with exit code 0 elapsedTime=0.978786 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-05 14:14:49.599974) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-05 14:14:49.603319) ========= script_repo_revision: 6c998914bad21a65c7fe2ee7e8c1c7f5269c750d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-05 14:14:49.603695) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-05 14:14:49.603957) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-05 14:14:49.623392) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-02-05 14:14:49.623667) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SWyf8R7mo5/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.5Utl4sTHQG/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 14:14:49 INFO - MultiFileLogger online at 20170205 14:14:49 in /builds/slave/test 14:14:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 14:14:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:14:49 INFO - {'append_to_log': False, 14:14:49 INFO - 'base_work_dir': '/builds/slave/test', 14:14:49 INFO - 'blob_upload_branch': 'Firefox', 14:14:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:14:49 INFO - 'branch': 'Firefox', 14:14:49 INFO - 'buildbot_json_path': 'buildprops.json', 14:14:49 INFO - 'config_files': ('talos/mac_config.py',), 14:14:49 INFO - 'default_actions': ('clobber', 14:14:49 INFO - 'read-buildbot-config', 14:14:49 INFO - 'download-and-extract', 14:14:49 INFO - 'populate-webroot', 14:14:49 INFO - 'create-virtualenv', 14:14:49 INFO - 'install', 14:14:49 INFO - 'run-tests'), 14:14:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:14:49 INFO - 'download_minidump_stackwalk': True, 14:14:49 INFO - 'download_symbols': 'ondemand', 14:14:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:14:49 INFO - 'tooltool.py': '/tools/tooltool.py', 14:14:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:14:49 INFO - '/tools/misc-python/virtualenv.py')}, 14:14:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:14:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:14:49 INFO - 'installer_path': 'installer.exe', 14:14:49 INFO - 'log_level': 'info', 14:14:49 INFO - 'log_name': 'talos', 14:14:49 INFO - 'log_to_console': True, 14:14:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:14:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:14:49 INFO - 'opt_config_files': (), 14:14:49 INFO - 'pip_index': False, 14:14:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:14:49 INFO - 'cmd': ('bash', 14:14:49 INFO - '-c', 14:14:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:14:49 INFO - 'enabled': True, 14:14:49 INFO - 'halt_on_failure': False, 14:14:49 INFO - 'name': 'check_screen_resolution'},), 14:14:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:14:49 INFO - 'cmd': ('bash', 14:14:49 INFO - '-c', 14:14:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:14:49 INFO - 'enabled': True, 14:14:49 INFO - 'halt_on_failure': False, 14:14:49 INFO - 'name': 'check_screen_resolution'},), 14:14:49 INFO - 'run_cmd_checks_enabled': True, 14:14:49 INFO - 'sps_profile': False, 14:14:49 INFO - 'sps_profile_interval': 0, 14:14:49 INFO - 'suite': 'dromaeojs', 14:14:49 INFO - 'system_bits': '32', 14:14:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:14:49 INFO - 'title': 't-yosemite-r7-0163', 14:14:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:14:49 INFO - 'use_talos_json': True, 14:14:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:14:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:14:49 INFO - 'work_dir': 'build'} 14:14:49 INFO - [mozharness: 2017-02-05 22:14:49.834549Z] Running clobber step. 14:14:49 INFO - Running pre-action listener: _resource_record_pre_action 14:14:49 INFO - Running main action method: clobber 14:14:49 INFO - rmtree: /builds/slave/test/build 14:14:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:14:51 INFO - Running post-action listener: _resource_record_post_action 14:14:51 INFO - [mozharness: 2017-02-05 22:14:51.832811Z] Finished clobber step (success) 14:14:51 INFO - [mozharness: 2017-02-05 22:14:51.832975Z] Running read-buildbot-config step. 14:14:51 INFO - Running pre-action listener: _resource_record_pre_action 14:14:51 INFO - Running main action method: read_buildbot_config 14:14:51 INFO - Using buildbot properties: 14:14:51 INFO - { 14:14:51 INFO - "project": "", 14:14:51 INFO - "product": "firefox", 14:14:51 INFO - "script_repo_revision": "production", 14:14:51 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 14:14:51 INFO - "repository": "", 14:14:51 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 14:14:51 INFO - "buildid": "20170205125708", 14:14:51 INFO - "pgo_build": "False", 14:14:51 INFO - "basedir": "/builds/slave/test", 14:14:51 INFO - "buildnumber": 67, 14:14:51 INFO - "slavename": "t-yosemite-r7-0163", 14:14:51 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 14:14:51 INFO - "platform": "yosemite_r7", 14:14:51 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 14:14:51 INFO - "branch": "mozilla-central", 14:14:51 INFO - "repo_path": "mozilla-central", 14:14:51 INFO - "revision": "6c998914bad21a65c7fe2ee7e8c1c7f5269c750d", 14:14:51 INFO - "stage_platform": "macosx64", 14:14:51 INFO - "builduid": "3ec68f53e8174fe4a95e59c32c47f674", 14:14:51 INFO - "slavebuilddir": "test" 14:14:51 INFO - } 14:14:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/GmTt9U59QleKKFMwB6xn6Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 14:14:51 INFO - Running post-action listener: _resource_record_post_action 14:14:51 INFO - [mozharness: 2017-02-05 22:14:51.838877Z] Finished read-buildbot-config step (success) 14:14:51 INFO - [mozharness: 2017-02-05 22:14:51.839073Z] Running download-and-extract step. 14:14:51 INFO - Running pre-action listener: _resource_record_pre_action 14:14:51 INFO - Running main action method: download_and_extract 14:14:51 INFO - mkdir: /builds/slave/test/build/tests 14:14:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:14:51 INFO - trying https://queue.taskcluster.net/v1/task/GmTt9U59QleKKFMwB6xn6Q/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 14:14:51 INFO - Downloading https://queue.taskcluster.net/v1/task/GmTt9U59QleKKFMwB6xn6Q/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 14:14:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GmTt9U59QleKKFMwB6xn6Q/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 14:14:53 INFO - Downloaded 1199 bytes. 14:14:53 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 14:14:53 INFO - Using the following test package requirements: 14:14:53 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 14:14:53 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 14:14:53 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 14:14:53 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 14:14:53 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 14:14:53 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 14:14:53 INFO - u'jsshell-mac.zip'], 14:14:53 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 14:14:53 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 14:14:53 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 14:14:53 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 14:14:53 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 14:14:53 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 14:14:53 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 14:14:53 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 14:14:53 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 14:14:53 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 14:14:53 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 14:14:53 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:14:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GmTt9U59QleKKFMwB6xn6Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 14:14:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GmTt9U59QleKKFMwB6xn6Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:14:53 INFO - Fetch https://queue.taskcluster.net/v1/task/GmTt9U59QleKKFMwB6xn6Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 14:14:55 INFO - Expected file size: 34851075 14:14:55 INFO - Obtained file size: 34851075 14:15:02 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 14:15:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GmTt9U59QleKKFMwB6xn6Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 14:15:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GmTt9U59QleKKFMwB6xn6Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:15:02 INFO - Fetch https://queue.taskcluster.net/v1/task/GmTt9U59QleKKFMwB6xn6Q/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 14:15:04 INFO - Expected file size: 34851075 14:15:04 INFO - Obtained file size: 34851075 14:15:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GmTt9U59QleKKFMwB6xn6Q/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 14:15:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GmTt9U59QleKKFMwB6xn6Q/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 14:15:10 INFO - Fetch https://queue.taskcluster.net/v1/task/GmTt9U59QleKKFMwB6xn6Q/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 14:15:12 INFO - Expected file size: 14403454 14:15:12 INFO - Obtained file size: 14403454 14:15:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:15:12 INFO - trying https://queue.taskcluster.net/v1/task/GmTt9U59QleKKFMwB6xn6Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 14:15:12 INFO - Downloading https://queue.taskcluster.net/v1/task/GmTt9U59QleKKFMwB6xn6Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 14:15:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GmTt9U59QleKKFMwB6xn6Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 14:15:15 INFO - Downloaded 70288893 bytes. 14:15:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GmTt9U59QleKKFMwB6xn6Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 14:15:15 INFO - mkdir: /builds/slave/test/properties 14:15:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:15:15 INFO - Writing to file /builds/slave/test/properties/build_url 14:15:15 INFO - Contents: 14:15:15 INFO - build_url:https://queue.taskcluster.net/v1/task/GmTt9U59QleKKFMwB6xn6Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 14:15:16 INFO - Running post-action listener: _resource_record_post_action 14:15:16 INFO - Running post-action listener: set_extra_try_arguments 14:15:16 INFO - [mozharness: 2017-02-05 22:15:16.240068Z] Finished download-and-extract step (success) 14:15:16 INFO - [mozharness: 2017-02-05 22:15:16.240203Z] Running populate-webroot step. 14:15:16 INFO - Running pre-action listener: _resource_record_pre_action 14:15:16 INFO - Running main action method: populate_webroot 14:15:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:15:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:15:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:15:16 INFO - '--tppagecycles', 14:15:16 INFO - '7'], 14:15:16 INFO - 'tests': ['tsvgm']}}, 14:15:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:15:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:15:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:15:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:15:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:15:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:15:16 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:15:16 INFO - 'talos_options': ['--disable-e10s'], 14:15:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:15:16 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:15:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:15:16 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:15:16 INFO - 'talos_options': ['--disable-e10s'], 14:15:16 INFO - 'tests': ['damp', 'tps']}, 14:15:16 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:15:16 INFO - 'tests': ['damp', 'tps']}, 14:15:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:15:16 INFO - 'tests': ['dromaeo_dom']}, 14:15:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:15:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:15:16 INFO - 'tests': ['basic_compositor_video']}, 14:15:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:15:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:15:16 INFO - 'tests': ['a11yr', 14:15:16 INFO - 'ts_paint', 14:15:16 INFO - 'tpaint', 14:15:16 INFO - 'sessionrestore', 14:15:16 INFO - 'sessionrestore_no_auto_restore', 14:15:16 INFO - 'tabpaint']}, 14:15:16 INFO - 'other-e10s': {'tests': ['a11yr', 14:15:16 INFO - 'ts_paint', 14:15:16 INFO - 'tpaint', 14:15:16 INFO - 'sessionrestore', 14:15:16 INFO - 'sessionrestore_no_auto_restore', 14:15:16 INFO - 'tabpaint']}, 14:15:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:15:16 INFO - 'ts_paint', 14:15:16 INFO - 'tpaint', 14:15:16 INFO - 'sessionrestore', 14:15:16 INFO - 'sessionrestore_no_auto_restore', 14:15:16 INFO - 'tabpaint']}, 14:15:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:15:16 INFO - 'ts_paint', 14:15:16 INFO - 'tpaint', 14:15:16 INFO - 'sessionrestore', 14:15:16 INFO - 'sessionrestore_no_auto_restore', 14:15:16 INFO - 'tabpaint']}, 14:15:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:15:16 INFO - 'tests': ['a11yr', 14:15:16 INFO - 'ts_paint', 14:15:16 INFO - 'tpaint', 14:15:16 INFO - 'sessionrestore', 14:15:16 INFO - 'sessionrestore_no_auto_restore', 14:15:16 INFO - 'tabpaint']}, 14:15:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:15:16 INFO - 'tests': ['a11yr', 14:15:16 INFO - 'ts_paint', 14:15:16 INFO - 'tpaint', 14:15:16 INFO - 'sessionrestore', 14:15:16 INFO - 'sessionrestore_no_auto_restore', 14:15:16 INFO - 'tabpaint']}, 14:15:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:15:16 INFO - 'tests': ['tsvgx', 14:15:16 INFO - 'tsvgr_opacity', 14:15:16 INFO - 'tart', 14:15:16 INFO - 'tscrollx', 14:15:16 INFO - 'cart', 14:15:16 INFO - 'tsvg_static']}, 14:15:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:15:16 INFO - 'tsvgr_opacity', 14:15:16 INFO - 'tart', 14:15:16 INFO - 'tscrollx', 14:15:16 INFO - 'cart', 14:15:16 INFO - 'tsvg_static']}, 14:15:16 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:15:16 INFO - 'talos_options': ['--disable-e10s'], 14:15:16 INFO - 'tests': ['tp5o']}, 14:15:16 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:15:16 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:15:16 INFO - 'talos_options': ['--disable-e10s', 14:15:16 INFO - '--xperf_path', 14:15:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:15:16 INFO - 'tests': ['tp5n']}, 14:15:16 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:15:16 INFO - 'talos_options': ['--xperf_path', 14:15:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:15:16 INFO - 'tests': ['tp5n']}}, 14:15:16 INFO - 'talos.zip': {'path': '', 14:15:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:15:16 INFO - Running post-action listener: _resource_record_post_action 14:15:16 INFO - [mozharness: 2017-02-05 22:15:16.259310Z] Finished populate-webroot step (success) 14:15:16 INFO - [mozharness: 2017-02-05 22:15:16.259429Z] Running create-virtualenv step. 14:15:16 INFO - Running pre-action listener: _resource_record_pre_action 14:15:16 INFO - Running main action method: create_virtualenv 14:15:16 INFO - Creating virtualenv /builds/slave/test/build/venv 14:15:16 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 14:15:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:15:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:15:16 INFO - Using real prefix '/tools/python27' 14:15:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:15:17 INFO - Installing distribute.............................................................................................................................................................................................done. 14:15:20 INFO - Installing pip.................done. 14:15:20 INFO - Return code: 0 14:15:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:15:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:15:20 INFO - Reading from file tmpfile_stdout 14:15:20 INFO - Output received: 14:15:20 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 14:15:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:15:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:15:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:15:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:15:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:15:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110617b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11061a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7feb6d56d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1103f5d30>, '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 0x11031cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1103eabe8>, '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.SWyf8R7mo5/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.5Utl4sTHQG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 14:15:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:15:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:15:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SWyf8R7mo5/Render', 14:15:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:15:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:15:20 INFO - 'HOME': '/Users/cltbld', 14:15:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:15:20 INFO - 'LOGNAME': 'cltbld', 14:15:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:15:20 INFO - 'MOZ_NO_REMOTE': '1', 14:15:20 INFO - 'NO_EM_RESTART': '1', 14:15:20 INFO - 'PAGER': '/bin/cat', 14:15:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:15:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:15:20 INFO - 'PWD': '/builds/slave/test', 14:15:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:15:20 INFO - 'SHELL': '/bin/bash', 14:15:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5Utl4sTHQG/Listeners', 14:15:20 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 14:15:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:15:20 INFO - 'USER': 'cltbld', 14:15:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:15:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:15:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:15:20 INFO - 'XPC_FLAGS': '0x0', 14:15:20 INFO - 'XPC_SERVICE_NAME': '0', 14:15:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:15:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:15:20 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 14:15:20 INFO - Cleaning up... 14:15:20 INFO - Return code: 0 14:15:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:15:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:15:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:15:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:15:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:15:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110617b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11061a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7feb6d56d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1103f5d30>, '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 0x11031cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1103eabe8>, '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.SWyf8R7mo5/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.5Utl4sTHQG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 14:15:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:15:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:15:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SWyf8R7mo5/Render', 14:15:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:15:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:15:20 INFO - 'HOME': '/Users/cltbld', 14:15:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:15:20 INFO - 'LOGNAME': 'cltbld', 14:15:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:15:20 INFO - 'MOZ_NO_REMOTE': '1', 14:15:20 INFO - 'NO_EM_RESTART': '1', 14:15:20 INFO - 'PAGER': '/bin/cat', 14:15:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:15:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:15:20 INFO - 'PWD': '/builds/slave/test', 14:15:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:15:20 INFO - 'SHELL': '/bin/bash', 14:15:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5Utl4sTHQG/Listeners', 14:15:20 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 14:15:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:15:20 INFO - 'USER': 'cltbld', 14:15:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:15:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:15:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:15:20 INFO - 'XPC_FLAGS': '0x0', 14:15:20 INFO - 'XPC_SERVICE_NAME': '0', 14:15:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:15:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:15:20 INFO - Downloading/unpacking psutil>=3.1.1 14:15:20 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 14:15:20 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 14:15:20 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 14:15:20 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 14:15:20 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 14:15:20 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 14:15:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:15:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:15:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:15:24 INFO - Installing collected packages: psutil 14:15:24 INFO - Running setup.py install for psutil 14:15:24 INFO - building 'psutil._psutil_osx' extension 14:15:24 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 14:15:24 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 14:15:24 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 14:15:24 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 14:15:24 INFO - building 'psutil._psutil_posix' extension 14:15:24 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 14:15:24 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:15:24 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:15:24 INFO - ^ 14:15:25 INFO - 1 warning generated. 14:15:25 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 14:15:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:15:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:15:25 INFO - Successfully installed psutil 14:15:25 INFO - Cleaning up... 14:15:25 INFO - Return code: 0 14:15:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:15:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:15:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:15:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:15:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:15:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110617b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11061a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7feb6d56d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1103f5d30>, '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 0x11031cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1103eabe8>, '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.SWyf8R7mo5/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.5Utl4sTHQG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 14:15:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:15:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:15:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SWyf8R7mo5/Render', 14:15:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:15:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:15:25 INFO - 'HOME': '/Users/cltbld', 14:15:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:15:25 INFO - 'LOGNAME': 'cltbld', 14:15:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:15:25 INFO - 'MOZ_NO_REMOTE': '1', 14:15:25 INFO - 'NO_EM_RESTART': '1', 14:15:25 INFO - 'PAGER': '/bin/cat', 14:15:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:15:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:15:25 INFO - 'PWD': '/builds/slave/test', 14:15:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:15:25 INFO - 'SHELL': '/bin/bash', 14:15:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5Utl4sTHQG/Listeners', 14:15:25 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 14:15:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:15:25 INFO - 'USER': 'cltbld', 14:15:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:15:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:15:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:15:25 INFO - 'XPC_FLAGS': '0x0', 14:15:25 INFO - 'XPC_SERVICE_NAME': '0', 14:15:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:15:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:15:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:15:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:15:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:15:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:15:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:15:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:15:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:15:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:15:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:15:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:15:28 INFO - Installing collected packages: mozsystemmonitor 14:15:28 INFO - Running setup.py install for mozsystemmonitor 14:15:28 INFO - Successfully installed mozsystemmonitor 14:15:28 INFO - Cleaning up... 14:15:28 INFO - Return code: 0 14:15:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:15:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:15:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:15:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:15:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:15:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x110617b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11061a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7feb6d56d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1103f5d30>, '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 0x11031cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1103eabe8>, '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.SWyf8R7mo5/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.5Utl4sTHQG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 14:15:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:15:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:15:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SWyf8R7mo5/Render', 14:15:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:15:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:15:28 INFO - 'HOME': '/Users/cltbld', 14:15:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:15:28 INFO - 'LOGNAME': 'cltbld', 14:15:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:15:28 INFO - 'MOZ_NO_REMOTE': '1', 14:15:28 INFO - 'NO_EM_RESTART': '1', 14:15:28 INFO - 'PAGER': '/bin/cat', 14:15:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:15:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:15:28 INFO - 'PWD': '/builds/slave/test', 14:15:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:15:28 INFO - 'SHELL': '/bin/bash', 14:15:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5Utl4sTHQG/Listeners', 14:15:28 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 14:15:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:15:28 INFO - 'USER': 'cltbld', 14:15:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:15:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:15:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:15:28 INFO - 'XPC_FLAGS': '0x0', 14:15:28 INFO - 'XPC_SERVICE_NAME': '0', 14:15:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:15:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:15:28 INFO - Downloading/unpacking jsonschema==2.5.1 14:15:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:15:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:15:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:15:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:15:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:15:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:15:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:15:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:15:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:15:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:15:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:15:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:15:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:15:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:15:31 INFO - Downloading functools32-3.2.3-2.tar.gz 14:15:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:15:31 INFO - warning: no files found matching '*.txt' 14:15:31 INFO - no previously-included directories found matching 'build' 14:15:31 INFO - no previously-included directories found matching 'dist' 14:15:31 INFO - no previously-included directories found matching '.git*' 14:15:31 INFO - Installing collected packages: jsonschema, functools32 14:15:31 INFO - Running setup.py install for functools32 14:15:31 INFO - warning: no files found matching '*.txt' 14:15:31 INFO - no previously-included directories found matching 'build' 14:15:31 INFO - no previously-included directories found matching 'dist' 14:15:31 INFO - no previously-included directories found matching '.git*' 14:15:31 INFO - Successfully installed jsonschema functools32 14:15:31 INFO - Cleaning up... 14:15:32 INFO - Return code: 0 14:15:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:15:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:15:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:15:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:15:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:15:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110617b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11061a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7feb6d56d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1103f5d30>, '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 0x11031cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1103eabe8>, '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.SWyf8R7mo5/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.5Utl4sTHQG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 14:15:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:15:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 14:15:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SWyf8R7mo5/Render', 14:15:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:15:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:15:32 INFO - 'HOME': '/Users/cltbld', 14:15:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:15:32 INFO - 'LOGNAME': 'cltbld', 14:15:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:15:32 INFO - 'MOZ_NO_REMOTE': '1', 14:15:32 INFO - 'NO_EM_RESTART': '1', 14:15:32 INFO - 'PAGER': '/bin/cat', 14:15:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:15:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:15:32 INFO - 'PWD': '/builds/slave/test', 14:15:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:15:32 INFO - 'SHELL': '/bin/bash', 14:15:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5Utl4sTHQG/Listeners', 14:15:32 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 14:15:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:15:32 INFO - 'USER': 'cltbld', 14:15:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:15:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:15:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:15:32 INFO - 'XPC_FLAGS': '0x0', 14:15:32 INFO - 'XPC_SERVICE_NAME': '0', 14:15:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:15:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:15:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:15:32 INFO - Cleaning up... 14:15:32 INFO - Return code: 0 14:15:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:15:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:15:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:15:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:15:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:15:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x110617b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11061a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7feb6d56d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1103f5d30>, '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 0x11031cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1103eabe8>, '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.SWyf8R7mo5/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.5Utl4sTHQG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 14:15:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:15:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:15:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SWyf8R7mo5/Render', 14:15:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:15:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:15:32 INFO - 'HOME': '/Users/cltbld', 14:15:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:15:32 INFO - 'LOGNAME': 'cltbld', 14:15:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:15:32 INFO - 'MOZ_NO_REMOTE': '1', 14:15:32 INFO - 'NO_EM_RESTART': '1', 14:15:32 INFO - 'PAGER': '/bin/cat', 14:15:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:15:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:15:32 INFO - 'PWD': '/builds/slave/test', 14:15:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:15:32 INFO - 'SHELL': '/bin/bash', 14:15:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5Utl4sTHQG/Listeners', 14:15:32 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 14:15:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:15:32 INFO - 'USER': 'cltbld', 14:15:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:15:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:15:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:15:32 INFO - 'XPC_FLAGS': '0x0', 14:15:32 INFO - 'XPC_SERVICE_NAME': '0', 14:15:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:15:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:15:32 INFO - Downloading/unpacking blobuploader==1.2.4 14:15:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:15:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:15:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:15:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:15:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:15:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:15:35 INFO - Downloading blobuploader-1.2.4.tar.gz 14:15:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:15:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:15:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:15:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:15:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:15:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:15:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:15:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:15:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:15:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:15:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:15:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:15:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:15:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:15:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:15:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:15:36 INFO - Downloading docopt-0.6.1.tar.gz 14:15:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:15:36 INFO - Installing collected packages: blobuploader, requests, docopt 14:15:36 INFO - Running setup.py install for blobuploader 14:15:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:15:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:15:36 INFO - Running setup.py install for requests 14:15:37 INFO - Running setup.py install for docopt 14:15:37 INFO - Successfully installed blobuploader requests docopt 14:15:37 INFO - Cleaning up... 14:15:37 INFO - Return code: 0 14:15:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:15:37 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')]} 14:15:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:15:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:37 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')]} 14:15:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:15:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:37 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 0x110617b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11061a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7feb6d56d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1103f5d30>, '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 0x11031cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1103eabe8>, '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.SWyf8R7mo5/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.5Utl4sTHQG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 14:15:37 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 14:15:37 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 14:15:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SWyf8R7mo5/Render', 14:15:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:15:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:15:37 INFO - 'HOME': '/Users/cltbld', 14:15:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:15:37 INFO - 'LOGNAME': 'cltbld', 14:15:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:15:37 INFO - 'MOZ_NO_REMOTE': '1', 14:15:37 INFO - 'NO_EM_RESTART': '1', 14:15:37 INFO - 'PAGER': '/bin/cat', 14:15:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:15:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:15:37 INFO - 'PWD': '/builds/slave/test', 14:15:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:15:37 INFO - 'SHELL': '/bin/bash', 14:15:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5Utl4sTHQG/Listeners', 14:15:37 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 14:15:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:15:37 INFO - 'USER': 'cltbld', 14:15:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:15:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:15:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:15:37 INFO - 'XPC_FLAGS': '0x0', 14:15:37 INFO - 'XPC_SERVICE_NAME': '0', 14:15:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:15:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:15:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:15:37 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-qlwC3R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:15:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:15:37 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-l4XNts-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:15:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:15:37 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-7Kc6Pm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:15:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:15:37 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-jSMZgI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:15:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:15:38 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-b3FGbD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:15:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:15:38 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-TJUlkj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:15:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:15:38 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-DLQ33p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:15:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:15:38 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-aCdmh7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:15:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:15:38 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-u_otFy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:15:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:15:38 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-PO6UkH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:15:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:15:38 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-x0Y50v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:15:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:15:38 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-dXV_FB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:15:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:15:38 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-S0x9UB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:15:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:15:39 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-TZw_z2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:15:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:15:39 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-UxzHha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:15:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:15:39 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-K_LpGW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:15:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:15:39 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-0XeI1t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:15:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:15:39 INFO - Running setup.py install for manifestparser 14:15:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:15:39 INFO - Running setup.py install for mozcrash 14:15:39 INFO - Running setup.py install for mozdebug 14:15:39 INFO - Running setup.py install for mozdevice 14:15:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:15:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:15:40 INFO - Running setup.py install for mozfile 14:15:40 INFO - Running setup.py install for mozhttpd 14:15:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:15:40 INFO - Running setup.py install for mozinfo 14:15:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:15:40 INFO - Running setup.py install for mozInstall 14:15:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:15:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:15:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:15:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:15:40 INFO - Running setup.py install for mozleak 14:15:40 INFO - Running setup.py install for mozlog 14:15:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:15:40 INFO - Running setup.py install for moznetwork 14:15:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:15:41 INFO - Running setup.py install for mozprocess 14:15:41 INFO - Running setup.py install for mozprofile 14:15:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:15:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:15:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:15:41 INFO - Running setup.py install for mozrunner 14:15:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:15:41 INFO - Running setup.py install for mozscreenshot 14:15:41 INFO - Running setup.py install for moztest 14:15:41 INFO - Running setup.py install for mozversion 14:15:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:15:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:15:42 INFO - Cleaning up... 14:15:42 INFO - Return code: 0 14:15:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:15: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')]} 14:15:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:15:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15: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')]} 14:15:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:15:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110617b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11061a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7feb6d56d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1103f5d30>, '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 0x11031cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1103eabe8>, '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.SWyf8R7mo5/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.5Utl4sTHQG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 14:15:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:15:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:15:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SWyf8R7mo5/Render', 14:15:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:15:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:15:42 INFO - 'HOME': '/Users/cltbld', 14:15:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:15:42 INFO - 'LOGNAME': 'cltbld', 14:15:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:15:42 INFO - 'MOZ_NO_REMOTE': '1', 14:15:42 INFO - 'NO_EM_RESTART': '1', 14:15:42 INFO - 'PAGER': '/bin/cat', 14:15:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:15:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:15:42 INFO - 'PWD': '/builds/slave/test', 14:15:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:15:42 INFO - 'SHELL': '/bin/bash', 14:15:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5Utl4sTHQG/Listeners', 14:15:42 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 14:15:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:15:42 INFO - 'USER': 'cltbld', 14:15:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:15:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:15:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:15:42 INFO - 'XPC_FLAGS': '0x0', 14:15:42 INFO - 'XPC_SERVICE_NAME': '0', 14:15:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:15:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:15:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:15:43 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-gcc_7u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:15:43 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 14:15:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:15:43 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-LEvtK8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:15:43 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)) 14:15:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:15:43 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-KuwuDa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:15:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:15:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:15:43 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-Czi1Fb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:15:43 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)) 14:15:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:15:43 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-HtCNoH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:15:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 14:15:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:15:43 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-eYy3V6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:15:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 14:15:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:15:43 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-43GHMl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:15:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 14:15:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:15:43 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-re7jQI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:15:44 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)) 14:15:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:15:44 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-U1cipi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:15:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 14:15:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:15:44 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-Zdm25n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:15:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:15:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:15:44 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-V0Ikqj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:15:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 14:15:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:15:44 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-RClA5d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:15:44 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)) 14:15:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:15:44 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-EKZAsN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:15:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 14:15:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:15:44 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-whzAbs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:15:44 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)) 14:15:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:15:44 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-mraUFR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:15:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 14:15:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:15:44 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-DCuqoP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:15:44 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)) 14:15:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:15:44 INFO - Running setup.py (path:/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/pip-ePhNAA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:15:45 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)) 14:15:45 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)) 14:15:45 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)) 14:15:45 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)) 14:15:45 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)) 14:15:45 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)) 14:15:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:15:45 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 14:15:45 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 14:15:45 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 14:15:45 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 14:15:45 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 14:15:45 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 14:15:47 INFO - Downloading blessings-1.6.tar.gz 14:15:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:15:47 INFO - Installing collected packages: blessings 14:15:47 INFO - Running setup.py install for blessings 14:15:47 INFO - Successfully installed blessings 14:15:47 INFO - Cleaning up... 14:15:48 INFO - Return code: 0 14:15:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:15:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:15:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:15:48 INFO - Reading from file tmpfile_stdout 14:15:48 INFO - Current package versions: 14:15:48 INFO - blessings == 1.6 14:15:48 INFO - blobuploader == 1.2.4 14:15:48 INFO - docopt == 0.6.1 14:15:48 INFO - functools32 == 3.2.3-2 14:15:48 INFO - jsonschema == 2.5.1 14:15:48 INFO - manifestparser == 1.1 14:15:48 INFO - mozInstall == 1.12 14:15:48 INFO - mozcrash == 1.0 14:15:48 INFO - mozdebug == 0.1 14:15:48 INFO - mozdevice == 0.49 14:15:48 INFO - mozfile == 1.2 14:15:48 INFO - mozhttpd == 0.7 14:15:48 INFO - mozinfo == 0.9 14:15:48 INFO - mozleak == 0.1 14:15:48 INFO - mozlog == 3.4 14:15:48 INFO - moznetwork == 0.27 14:15:48 INFO - mozprocess == 0.24 14:15:48 INFO - mozprofile == 0.28 14:15:48 INFO - mozrunner == 6.13 14:15:48 INFO - mozscreenshot == 0.1 14:15:48 INFO - mozsystemmonitor == 0.3 14:15:48 INFO - moztest == 0.8 14:15:48 INFO - mozversion == 1.4 14:15:48 INFO - psutil == 3.1.1 14:15:48 INFO - requests == 1.2.3 14:15:48 INFO - wsgiref == 0.1.2 14:15:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:15:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:15:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:15:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:15:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:15:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110617b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11061a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7feb6d56d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1103f5d30>, '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 0x11031cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1103eabe8>, '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.SWyf8R7mo5/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.5Utl4sTHQG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 14:15:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 14:15:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:15:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SWyf8R7mo5/Render', 14:15:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:15:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:15:48 INFO - 'HOME': '/Users/cltbld', 14:15:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:15:48 INFO - 'LOGNAME': 'cltbld', 14:15:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:15:48 INFO - 'MOZ_NO_REMOTE': '1', 14:15:48 INFO - 'NO_EM_RESTART': '1', 14:15:48 INFO - 'PAGER': '/bin/cat', 14:15:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:15:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:15:48 INFO - 'PWD': '/builds/slave/test', 14:15:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:15:48 INFO - 'SHELL': '/bin/bash', 14:15:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5Utl4sTHQG/Listeners', 14:15:48 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 14:15:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:15:48 INFO - 'USER': 'cltbld', 14:15:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:15:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:15:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:15:48 INFO - 'XPC_FLAGS': '0x0', 14:15:48 INFO - 'XPC_SERVICE_NAME': '0', 14:15:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:15:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:15:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:15:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 14:15:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 14:15:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:15:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 14:15:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 14:15:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 14:15:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 14:15:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 14:15:48 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:15:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:15:48 INFO - Cleaning up... 14:15:48 INFO - Return code: 0 14:15:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:15:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:15:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:15:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:15:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:15:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:15:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x110617b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11061a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7feb6d56d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1103f5d30>, '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 0x11031cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1103eabe8>, '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.SWyf8R7mo5/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.5Utl4sTHQG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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 14:15:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:15:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:15:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SWyf8R7mo5/Render', 14:15:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:15:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:15:48 INFO - 'HOME': '/Users/cltbld', 14:15:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:15:48 INFO - 'LOGNAME': 'cltbld', 14:15:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:15:48 INFO - 'MOZ_NO_REMOTE': '1', 14:15:48 INFO - 'NO_EM_RESTART': '1', 14:15:48 INFO - 'PAGER': '/bin/cat', 14:15:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:15:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:15:48 INFO - 'PWD': '/builds/slave/test', 14:15:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:15:48 INFO - 'SHELL': '/bin/bash', 14:15:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5Utl4sTHQG/Listeners', 14:15:48 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 14:15:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:15:48 INFO - 'USER': 'cltbld', 14:15:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:15:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:15:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:15:48 INFO - 'XPC_FLAGS': '0x0', 14:15:48 INFO - 'XPC_SERVICE_NAME': '0', 14:15:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:15:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:15:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:15:48 INFO - Cleaning up... 14:15:48 INFO - Return code: 0 14:15:48 INFO - Running post-action listener: _resource_record_post_action 14:15:48 INFO - Running post-action listener: _start_resource_monitoring 14:15:48 INFO - Starting resource monitoring. 14:15:48 INFO - [mozharness: 2017-02-05 22:15:48.889934Z] Finished create-virtualenv step (success) 14:15:48 INFO - [mozharness: 2017-02-05 22:15:48.891002Z] Running install step. 14:15:48 INFO - Running pre-action listener: _resource_record_pre_action 14:15:48 INFO - Running main action method: install 14:15:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:15:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:15:49 INFO - Reading from file tmpfile_stdout 14:15:49 INFO - Detecting whether we're running mozinstall >=1.0... 14:15:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:15:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:15:49 INFO - Reading from file tmpfile_stdout 14:15:49 INFO - Output received: 14:15:49 INFO - Usage: mozinstall [options] installer 14:15:49 INFO - Options: 14:15:49 INFO - -h, --help show this help message and exit 14:15:49 INFO - -d DEST, --destination=DEST 14:15:49 INFO - Directory to install application into. [default: 14:15:49 INFO - "/builds/slave/test"] 14:15:49 INFO - --app=APP Application being installed. [default: firefox] 14:15:49 INFO - mkdir: /builds/slave/test/build/application 14:15:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:15:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 14:16:11 INFO - Reading from file tmpfile_stdout 14:16:11 INFO - Output received: 14:16:11 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:16:11 INFO - Running post-action listener: _resource_record_post_action 14:16:11 INFO - [mozharness: 2017-02-05 22:16:11.097805Z] Finished install step (success) 14:16:11 INFO - [mozharness: 2017-02-05 22:16:11.097966Z] Running run-tests step. 14:16:11 INFO - Running pre-action listener: _resource_record_pre_action 14:16:11 INFO - Running main action method: run_tests 14:16:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:16:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:16:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:16:11 INFO - 2017-02-05 14:16:11.113 screenresolution[6834:125559] starting screenresolution argv=screenresolution get 14:16:11 INFO - 2017-02-05 14:16:11.115 screenresolution[6834:125559] Display 0: 1600x1200x32@60 14:16:11 INFO - 2017-02-05 14:16:11.121 screenresolution[6835:125561] starting screenresolution argv=screenresolution list 14:16:11 INFO - Available Modes on Display 0 14:16:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:16:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:16:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:16:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:16:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:16:11 INFO - Intel Iris: 14:16:11 INFO - Chipset Model: Intel Iris 14:16:11 INFO - Type: GPU 14:16:11 INFO - Bus: Built-In 14:16:11 INFO - VRAM (Dynamic, Max): 1536 MB 14:16:11 INFO - Vendor: Intel (0x8086) 14:16:11 INFO - Device ID: 0x0a2e 14:16:11 INFO - Revision ID: 0x0009 14:16:11 INFO - Displays: 14:16:11 INFO - Display: 14:16:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:16:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:16:11 INFO - Display Serial Number: 1600x1200 60 14:16:11 INFO - Main Display: Yes 14:16:11 INFO - Mirror: Off 14:16:11 INFO - Online: Yes 14:16:11 INFO - Rotation: Supported 14:16:11 INFO - Return code: 0 14:16:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:16:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:16:11 INFO - Python 2.7.3 14:16:11 INFO - Return code: 0 14:16:11 INFO - grabbing minidump binary from tooltool 14:16: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')]} 14:16:11 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 0x1103f5d30>, '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 0x11031cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1103eabe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:16:11 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 14:16:11 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 14:16:11 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 14:16:11 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:16:11 INFO - Return code: 0 14:16:11 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:16:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:16:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:16:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:16:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:16:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GmTt9U59QleKKFMwB6xn6Q/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0163', '--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 14:16:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GmTt9U59QleKKFMwB6xn6Q/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0163 --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 14:16:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SWyf8R7mo5/Render', 14:16:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:16:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:16:11 INFO - 'HOME': '/Users/cltbld', 14:16:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:16:11 INFO - 'LOGNAME': 'cltbld', 14:16:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:16:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:16:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:16:11 INFO - 'MOZ_NO_REMOTE': '1', 14:16:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:16:11 INFO - 'NO_EM_RESTART': '1', 14:16:11 INFO - 'PAGER': '/bin/cat', 14:16:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:16:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:16:11 INFO - 'PWD': '/builds/slave/test', 14:16:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:16:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:16:11 INFO - 'SHELL': '/bin/bash', 14:16:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5Utl4sTHQG/Listeners', 14:16:11 INFO - 'TMPDIR': '/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/', 14:16:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:16:11 INFO - 'USER': 'cltbld', 14:16:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:16:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:16:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:16:11 INFO - 'XPC_FLAGS': '0x0', 14:16:11 INFO - 'XPC_SERVICE_NAME': '0', 14:16:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:16:11 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GmTt9U59QleKKFMwB6xn6Q/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0163', '--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 14:16:11 INFO - mozversion application_buildid: 20170205125708 14:16:11 INFO - mozversion application_changeset: 6c998914bad21a65c7fe2ee7e8c1c7f5269c750d 14:16:11 INFO - mozversion application_display_name: Nightly 14:16:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:16:11 INFO - mozversion application_name: Firefox 14:16:11 INFO - mozversion application_remotingname: firefox 14:16:11 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:16:11 INFO - mozversion application_vendor: Mozilla 14:16:11 INFO - mozversion application_version: 54.0a1 14:16:11 INFO - mozversion platform_buildid: 20170205125708 14:16:11 INFO - mozversion platform_changeset: 6c998914bad21a65c7fe2ee7e8c1c7f5269c750d 14:16:11 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:16:11 INFO - mozversion platform_version: 54.0a1 14:16:11 INFO - using testdate: 1486332971 14:16:11 INFO - actual date: 1486332971 14:16:11 INFO - starting webserver on 'localhost:61292' 14:16:11 INFO - SUITE-START | Running 2 tests 14:16:11 INFO - TEST-START | dromaeo_css 14:16:11 INFO - operating with platform_type : mac_ 14:16:11 INFO - Initialising browser for dromaeo_css test... 14:16:11 INFO - TEST-INFO | started process 6845 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/tmpnnqzd1/profile http://localhost:61292/getInfo.html) 14:16:13 INFO - PROCESS | 6845 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 14:16:14 INFO - PROCESS | 6845 | __metrics Screen width/height:1600/1200 14:16:14 INFO - PROCESS | 6845 | colorDepth:24 14:16:14 INFO - PROCESS | 6845 | Browser inner width/height: 1024/689 14:16:14 INFO - PROCESS | 6845 | __metrics 14:16:14 INFO - PROCESS | 6845 | 1486332974147 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 14:16:14 INFO - PROCESS | 6845 | 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" 14:16:14 INFO - PROCESS | 6845 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:16:14 INFO - TEST-INFO | 6845: exit 0 14:16:14 INFO - Browser initialized. 14:16:14 INFO - Running cycle 1/1 for dromaeo_css test... 14:16:14 INFO - TEST-INFO | started process 6847 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/tmpnnqzd1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:16:27 INFO - PROCESS | 6847 | RSS: Main: 255492096 14:16:27 INFO - PROCESS | 6847 | 14:19:29 INFO - PROCESS | 6847 | Cycle 1(1): loaded http://localhost:61292/tests/dromaeo/cssquery-dojo.html (next: http://localhost:61292/tests/dromaeo/cssquery-ext.html) 14:19:31 INFO - PROCESS | 6847 | RSS: Main: 420188160 14:19:31 INFO - PROCESS | 6847 | 14:21:16 INFO - PROCESS | 6847 | ************************* 14:21:16 INFO - PROCESS | 6847 | A coding exception was thrown and uncaught in a Task. 14:21:16 INFO - PROCESS | 6847 | 14:21:16 ERROR - PROCESS | 6847 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:21:16 INFO - PROCESS | 6847 | Full stack: 14:21:16 INFO - PROCESS | 6847 | ************************* 14:22:34 INFO - PROCESS | 6847 | Cycle 1(1): loaded http://localhost:61292/tests/dromaeo/cssquery-ext.html (next: http://localhost:61292/tests/dromaeo/cssquery-jquery.html) 14:22:35 INFO - PROCESS | 6847 | RSS: Main: 391503872 14:22:35 INFO - PROCESS | 6847 | 14:25:37 INFO - PROCESS | 6847 | Cycle 1(1): loaded http://localhost:61292/tests/dromaeo/cssquery-jquery.html (next: http://localhost:61292/tests/dromaeo/cssquery-mootools.html) 14:25:38 INFO - PROCESS | 6847 | RSS: Main: 347512832 14:25:38 INFO - PROCESS | 6847 | 14:28:40 INFO - PROCESS | 6847 | Cycle 1(1): loaded http://localhost:61292/tests/dromaeo/cssquery-mootools.html (next: http://localhost:61292/tests/dromaeo/cssquery-prototype.html) 14:28:41 INFO - PROCESS | 6847 | RSS: Main: 363446272 14:28:41 INFO - PROCESS | 6847 | 14:31:44 INFO - PROCESS | 6847 | Cycle 1(1): loaded http://localhost:61292/tests/dromaeo/cssquery-prototype.html (next: http://localhost:61292/tests/dromaeo/cssquery-yui.html) 14:31:45 INFO - PROCESS | 6847 | RSS: Main: 341262336 14:31:45 INFO - PROCESS | 6847 | 14:34:48 INFO - PROCESS | 6847 | Cycle 1(1): loaded http://localhost:61292/tests/dromaeo/cssquery-yui.html (next: http://localhost:61292/tests/dromaeo/cssquery-dojo.html) 14:34:49 INFO - PROCESS | 6847 | RSS: Main: 429613056 14:34:49 INFO - PROCESS | 6847 | 14:34:49 INFO - PROCESS | 6847 | __start_tp_report 14:34:49 INFO - PROCESS | 6847 | _x_x_mozilla_page_load 14:34:49 INFO - PROCESS | 6847 | _x_x_mozilla_page_load_details 14:34:49 INFO - PROCESS | 6847 | |i|pagename|runs| 14:34:49 INFO - PROCESS | 6847 | |0;dojo.html;2686.5835795451703;3238.206639373354;3238.4656531672276;3256.446404111301;3282.0482060202544;5293.100172970595;5426.1046927257;5439.945600543993;5481.671099734019;5492.780288788454;5401.891962160765;5419.187121931712;5433.157860531624;5439.673619582828;5441.646292990963;4940.827071052514;4966.404031516223;5029.823956161535;5067;5091.974540127294;5009.749512524378;5070.670406423589;5119.104156772569;5122.487751224885;5122.487751224885;246.89440189727586;4991.900161996739;5069.771860266297;5108.744562771865;5126.71803050832;5060.51925067118;5075.746212689388;5089.898202035939;5105.055564720538;5137.229415587654;5026.723530205837;5030.069437154101;5049.1164046292115;5052.4189718182515;5078.21287700407;5342.305500284977;5366.436524164985;5524.640898341635;5539.086050801613;5563.443655634404;6010.519158467312;6022.608530445507;6071.271447426293;6125.123972708545;6126.426068710472;6101.511879049665;6140.355262697397;6150.569460137792;6180.907286390663;6200.069989501566;4540.318952157203;5597.944020559783;5693.829185124453;5713.400092990218;5721.170430287627;5061.670991385548;6050.606710563799;6131.101423540838;6140.232470941132;6215.285242197115;5976.103584462366;5978.073398623221;5984.132300816357;5991.550633702403;6019;5826.883462330729;5897.970510147507;6089.786857460056;6125.785597504066;6133.601315914451;5851.502622277069;5871.031279838864;5887.0580707086665;5899.587028907935;5935.614185077956;5903.144044113579;5912.615679980788;5940.584159108907;5947.791827286024;5959.0763431668165;3984.2828290907914;4782.641301902371;4784.880377990578;4796.2805579162705;4837.879053023703;3623.7282203834816;4323.805428755713;4350.825966961286;4363.716358436692;4415.028693687384;5542.11326187808;5543.584231182677;5554.611177217556;5558.888822223533;5577.079781836032;5916.26046744157;5978.910316345258;6004.789832355934;6142.969285153546;6164.106204600308;5535.280413546213;5578.497935185853;5591.720413979285;5660.688662123623;5674.631148975386;5325.973370133124;5331.1470164773455;5336.226247194135;5345.946540534623;5349.010433069894;6230.7507699691505;6235.158253635701;6239.500839932794;6244.250689917239;6250.281217660027;5961.046232602763;5994.31065427464;6005.429484198994;6105.633661980295;6151.507879369729;6230.470410015018;6236.6258024518675;6247.531435142291;6263.091851681572;6264.185655864709;6066.878662426639;6067.575269731076;6070.939290607214;6080.300765411857;6081.239845019372;5854.209681692917;5926.199963005111;6106.908396374147;6166.167567378347;6175.382461753788;5841.824745257479;5848.093545500455;5864.149698293809;5951.702414879325;5962.791302304572;5913.674747888907;5921.052631578927;5925.05199168131;5927.496162826035;5932.940670593239;5574.777008919597;5728.341240757366;5785.132230165509;5786.681732504752;5802.984477716467;4640.93038604428;5849.122631605123;5849.3858144894175;5879.529637629065;5929;5717.285339332583;5728.885422291614;5729.598928074995;5747.8563035924435;5756.827295180984 14:34:49 INFO - PROCESS | 6847 | |1;ext.html;437.4728452215081;470.99293510597334;493.99965070731764;517.3507248403254;518.1269560790068;29520.557191642143;39522.209555808906;40539.59460405395;40770.184596308056;40999.18001639965;36936;37019.259614807685;37156;37250.25499490008;37258.81370593143;9179.678711245124;9392.24862011041;9400.952995235015;9404.200642945323;9418;7941.245581669761;8179.959100204491;10842.078423334026;11097;11117;11272.154588405903;11313.660590182308;11345.035671967893;11359.1480638952;11360.829587556193;7990.12108668046;11187.049100826398;11196.104311655048;11252.943735281313;11291.604793832259;7960.323372513285;8992.46045237288;11071.612493562767;11106.278091924;11212.383318917457;39323.803380982914;39646.20707585861;39714.80142599298;39731;39765.40351894724;3898.610138986107;3918.431827385013;3918.6277303656107;3941.073847645801;3962.1085255817497;29705;29865.25336866596;30283.242918926982;30372.240693982607;30795.846020769975;3102.860371283275;3103.4724096903356;3103.922401939947;3137.4666306727913;3214.758893083028;445873;447852;448104;448982;451823;51310.230346544835;61437.69281153655;61674;61790;61803.454913627516;52938;55009.724951375785;68039;68298;68407.63184736278;14324.283785810876;14663.56009319722;14673.486427974969;14714.779278310834;14855.405783768745;10910.072643825206;10988.780224395467;11000.174986876016;11008.449577521093;11019.449027548591;17262.482125536255;17279.481615551555;17285.13574321304;17352.47942561725;17378.913105434392;6338.239411270585;7108.8933665995055;7346.061575369895;7467.1039475263315;7496.6251687415415;781.6482582837746;846.4328899637258;846.4498076250363;862.3920136303891;863.9934476335125;28634.713652863622;29078.7092129076;29136.708632913822;29371.55942660862;30257.546136807967;1023.539407266733;1029.5161274201114;1047.4239168457318;1076.1444651502075;1076.2896488317829;13331.866681333257;14630.707385852224;14762.261886905611;14847.70304593902;15483.5354939352;498130.5093474509;509935;510075;510394;513142;59761.701191494634;60003.099953500736;60537;60641;60694;1387.0983860490762;1432.792245124451;1446.5732608880617;1448.0877047460244;1454.7745099509382;29012;29145.854270728512;29404.852975734986;30203.848980754956;30292;53379.39861804152;53957.651058723844;54037.72981135069;54109.18836217381;54126.72936635291;473031;477480;477837;478365;478811;39707.60292397039;39951.60048399595;40720.77837664875;40896.1820763589;40914.590854091075;45469.54530454653;46209.30686039645;46378.76810615925;46883.29675054945;47039.764801177145;37299;37753.433698493995;37784;38906.416403754534;39154.80422597869;36065.639343607276;36692.266154676225;37328.62671373251;38006.619933800306;38231.808840955615;36677.63322366742;37142.44286335761;37490.62509374871;37776.055598610255;37855.81072094622;32051.679483205804;33430.66569334276;33490.33019339648;33643.15892102717;34131;21146.577068458853;23973.400664983514;24375.51248974975;24917.750822491544;24984.750152498244 14:34:49 INFO - PROCESS | 6847 | |2;jquery.html;361.9485394927735;366.5601278465842;386.53229592193446;394.3201959651882;403.61962089653383;14123.576292711223;14456.78314825277;19531.5117122072;20539.794602053975;21008.89495552524;20383.796162038376;20509;21031.894840525776;21065.789342106575;21176.682349764727;5725.08398656214;5981.28224613047;6016.608920420181;6020.367861374559;6051.12258722485;5823.38854420284;5883.852903677421;5916.704164791765;5920.348761636216;5971.940280597182;5944;5944.554158438134;5947.910781338283;5955.821325360246;5958.523318134538;5789.494731369832;5795.072788353864;5875.236219291507;5944.881102377928;5957.91063134049;5143.7912090658665;5146.6911985281;5209.270702101709;5215.426303106655;5216.113260745682;24981;25449.618255726178;25527.48945021089;25677.486450271077;26015.869920650464;2646.5765477523696;2662.121499905046;2676.47808677308;2702.7162147974573;2719.29298382422;14614.561563153122;14973;15113.093214407061;15118.319675614624;15769.290381932726;1737.1053907237783;1773.6807374672553;1780.1544266473452;1790.6687262856283;1792.9910350448295;128065;132100;134264.65735342618;134624;136600.63399365978;10787.568497260021;11167.944160279309;11212.775744485229;11295.717607059725;11385.601503947322;12698.555550555686;13353.73292534144;13393.397297121654;13656.658583535293;13676.726465470634;6199.938000620026;7356.558606483628;7500.324970752658;7784.119875646047;7842.157093083121;6014.729337179838;6050.880587091402;6463.06285588587;6565.835854103686;7266.382357499671;5883.441073098049;6198.318184999646;6834;6913.792586222421;6964.477664175207;1616.4584864070669;1875.5311172206948;1912.722655214986;1913.87559808612;1921.1258877210835;928.2666693312343;1020.7088033637455;1044.4151275285865;1046.6598355534481;1109.5187924121228;21624.78375216228;22122.446938826335;22746.203882864353;23392.415189620395;23994.52010959771;1004.3672486333709;1028.7582418131733;1050.1336397472116;1068.2308737708838;1071.266182664877;10378.636747713794;10647.989127999794;11222.418843666002;11241.435025882041;11370.772584548262;571605;640455;642340;715107;719734;136114.31942840223;139229.823105307;144410;144763.2761836205;148325.07661006215;452.7374123008647;473.3822361817861;475.1328824894581;476.2617942189592;477.45331595323853;11260.324380537195;11302.604408845651;11305.547778088785;11562.891371490536;11780.528778849093;52920.470795291556;52920.470795291556;53010;53461;53843.46156538541;71556.64221678858;75311;75557.62221188859;75876;76044;8375.497470151811;8423;8470.915290847013;8562.272206862486;8636.611352489028;10924.453777311262;11458;11713.765724685629;13294.939284410932;13346.733065338833;11039.282446640942;11265.324080555192;11277.379744114152;11389.316641001566;11585.304881707125;11074.778504430027;11196.83204751913;11208.719782005515;11458.656240312805;11550.94224528872;9154.450732955778;10494.632687855888;11759.882401175879;11773.646790596296;11789.882101178879;7298;8356.289715374262;8575.356848236283;8642.586178459598;8898.199162075443;9987.550560224952;10485.266031378036;10673.359598423809;11764.176507644725;11938.806119387991 14:34:49 INFO - PROCESS | 6847 | |3;mootools.html;1258.546923107681;1396.5321617258219;1415.0801978713841;1424.159745750008;1427.3148888533508;8077.030756309244;8460.57697115145;8579;8599;8637.481751094938;8168.673253069885;8405.117462666409;8427.115152908947;8587.098354672748;8587.270082043018;2259.9774002259974;2263.2757517594378;2265.275111964172;2271.6933214016144;2287.7254729432493;1999.2302963359139;2255.12050300383;2307.273208939187;2310.295359915225;2318.316096751454;1995.0623207092642;2008.8794672319634;2024.4028011736573;2028.2596852148934;2200.6478963365867;2253.7070180876462;2261.31030035839;2264.784845439689;2265.1392470861133;2267.4444761033487;1963.9607207855906;2214.1475531920164;2267.5691618592414;2276.203328834911;2279.8176145908287;8503.107173746792;8557.315414766803;8561.229489345926;8563.272121869648;8564.957175214084;5030.119729047402;5201.167813149916;5204.713740744258;5207.4792520748;5217.034848553029;3282.720968717662;3413.6245013048547;3456.084137703511;3474.5656792900886;3539.3275277697153;1140.236041851961;1227.9508819647203;1247.2516490105954;1689.206073145632;1737.4961261234228;10942.343459392461;10986.780264394747;11066.833997490045;11087.279326843729;11100;4211.515675697334;4401.22978478765;4461.598456138963;4469.977650111794;4475.373447717322;8623;8702.390832641655;8748.781280467912;8759;8799.648014079494;5082.974585127125;5087.109755792721;5092.719900405513;5093.821716239914;5106.668066575735;4640.32715256286;4651.790669419816;4672.906541869212;4706;4753.405824271966;3582.982085089558;3608.1340478285547;3631.4915911772373;3659.2315613720884;3666.4866918631415;1130.8473356096986;1138.7494751154732;1142.4287856071965;1147.7761836441814;1150.2178518607427;698.7065432518253;806.5281810141133;854.6111519258723;875.2472873328932;913.0504275553483;4381.452318460192;5279.028666713214;5368.1679339702405;5600.52395546375;5612.831615051555;858.9563680128674;904.9502277374676;909.7816524034184;951.9952400237954;960.6061514779046;3138.59198304219;3603.0992251937014;3763.096856754359;3825.3114439401174;3835.424686297022;9475;9754.122129008281;10017.849732254022;10146.289759716896;10155.39067655943;10317.690469285782;10317.968203179622;10319.690409287734;10330.638427654996;10342.741431464274;7519.8496030079095;7779.988601481771;7838.568878711611;8004.63979120941;8005.839883202303;4729.146192560848;5150.4592017837585;5543.1685247213245;5638.238837757014;5933.080372542263;9130.589123489326;9319.297403752837;9325.114114159143;9337.673181438617;9376.57805398745;8973.102689730975;9344.906550934404;9346.672866449642;9469.00575439569;9510.334276600572;5775.480206781242;7161.785146445615;7250.60121693312;7259.056322678018;7260.636968151465;7826.5695386751995;7831.84336313282;7879.921200787919;7925.643346049557;7955.761327160194;7208.56748595065;7273.818154546178;7307.26927307265;7337.889931651136;7404.92595074064;6973.232944376013;7096.396806271318;7135.357817796424;7189.2451292615615;7373.705051797868;6104.145419641342;6319.368063193644;6409.775657851953;6422.839429014312;6425.228972523327;4856.878578035577;4903.41159060915;4917;5005.524475174852;5140.126178549581;4927.433345165208;4943.555080042814;5027.572656324253;5046.419661738947;5128.923066154085 14:34:49 INFO - PROCESS | 6847 | |4;prototype.html;312.66076307206686;350.58980992239094;363.7124578990374;368.7013519049568;372.9757565758224;4369.934450983238;4760.714357138574;4906.460289368171;5135.717535535544;5162.845114646562;4637.304404339351;4961.255811628249;5040;5059.382541482839;5068.5438310552045;3862.613738626143;3899.78551179685;3930.351492003816;3935.744176628524;3957.8324394303677;3431.6053653829754;3825.005498570364;3915.6671682906845;3919.1377896862646;3954.8220330085214;3099.581556489879;3482.843272052751;3862.208247309295;3869.342211823997;3891.4941057662463;3255.9348813023844;3499.3701133795907;3600.5859326177433;3867.4198870169416;3873.9661677619333;2679.3837417394107;2989.9850500747575;3225.9676903390923;3347.7991320520605;3397.405454045557;3807.1053302473897;4882.248471222549;4891.706497610119;4980.435075935896;5078.339815823919;4427.653343727829;4450.421445212101;4452.973896876913;4463.955360446386;4481.865544033684;4714.4106986626675;5337.759800808973;5379.735762095905;5427.592930530226;5450.645708029005;3760.623937606245;3760.924781504382;3767.5855655878104;3768.4347347898033;3768.8680896168503;4256.489221293434;4306.7631280279575;5073.847784566469;5132.563732082777;5215.426303106655;3198.504231844068;3322.551455553518;3724.1248306647917;3750.587435382106;3773.962260377416;3823.9426408603895;3831.233753249361;3837.4627552142724;3850.249201405699;3860.9034774130873;3989.145047552902;4206.726562773471;4321.719088259253;4472.948857018598;4586.334981427674;4889.902201955941;5436;5485.807996720096;5503.284573005563;5556.77772889088;1224.8958838498827;1257.88050135237;1304.1848844472204;1352.7970804379422;1369.6370461827623;845.4293351987384;847.3517758914436;859.3640705877731;898.7327867706503;977.2572844637984;4189.97905010473;4353.129374125125;4396.362527433568;4459.44256967879;4961.677490963076;4301.354796780508;4668.902807840155;5007.499250074963;5376.865578360494;5399.37907140676;4484.461864576272;4877.707337559757;4892.969417691689;4893.846707448711;4910.680805747615;2723.6323096381734;2786.9098176367725;2804.321872611651;2812.0579605832277;2845.9146225613263;4541.750203738826;4777.256301449946;4924.60603151747;5471.452854714496;5542.75057622408;4977.929745104748;5272.841814745487;5295.867603309871;5501.587380946365;5554.944450555524;4438.866833994985;4440.533743956903;5324.0150572144275;5841.474267316047;5852.824415267549;5652.349979752381;5662.518685911744;5678.630888992202;5687.857803554779;5709.200711900255;5076.746162691777;5233.869153271046;5594.524465420485;5603.467670571289;5625.578081643975;4779.378680771617;5349.4753995111205;5471.753771080391;5643.802466913638;5809.244798176379;3652.543432070991;3657.6956517394933;3670.871519496912;3837.9253808933545;3839.251345987506;5082.720450375265;5108.412532558803;5644;5668.433156684464;5693.4021927696995;4293.957060429356;4875.268709693432;5038.47096054909;5106.106431374569;5509.256250406143;4779.4742578317055;4832.8791780205775;5466.207399926909;5495.450454954473;5511.366192887869;4883.584895283941;4993.27597498368;5380.730963451733;5403.000444917703;5405.189221616789;5028.823991160292;5397.057059458513;5477.616566840283;5478.671279723229;5516.917246241229;4491.755291249869;4770.952290477051;5022.874428139326;5340.546053585489;5372.301600791872 14:34:49 INFO - PROCESS | 6847 | |5;yui.html;712.6294326949983;737.9188289288178;766.8888011238367;824.5671022713075;836.172189532363;14049.929750351259;14624.415023399079;14637.048591841523;14653.267336633166;14667.193304368255;13300.733985320288;13399.799003014963;13416;13443.731125377486;13468.326583670827;856.6659002988823;865.5758678247645;873.4540911930044;876.4040452492303;888.8711136885148;851.5486792000249;880.7401816464127;886.1227384888978;886.6630680341493;891.2825175733532;932.7761337279038;933.3093510802025;934.4253284230183;936.7892224249523;938.943663380196;927.9884925431312;928.7956649537091;929.0848514213494;929.2844509727472;932.5197523275518;658.9703463344113;674.7402250133684;695.3255342317943;696.4184905603827;697.3653974882883;14302.856971430361;14494.855051449456;14508.78236826438;14594.70810583793;15215.61960950974;13548.729025419436;13945.860541394659;13987.580372588838;14041.71916561663;14046.367913443919;11505.424728763612;11511.769764604662;11662.533498660043;11691.649250522498;12146.331951742739;3615.5842078161086;3630.6006339302658;3681.208540163878;3779.433085037239;3786.753860999026;102150;104171.9165616691;104510.95489045164;105135;105454.94545054527;9526.90473095274;10033.849492257623;10117.291789574807;10676;10982.945085274683;17896.91051544734;22347.66478502824;22405.43986400353;22416.32751017472;22434.214802481834;7410.592417417094;7411.6664750086375;7433.182349941502;7466.402687784964;7482.176960534337;4151.750894946313;4197.727147735387;4253.532111467736;4304.913901721948;4344.500382455994;3584.1577229351083;3713.7400381973002;3719.9442008369897;3806;3979.1643754811234;718.3857801579678;734.2804052029041;738.7894450081701;768.7847402727245;769.4536878815992;511.51406164144214;540.9215663728737;566.2610293566852;587.3539106982286;593.7209511529575;9879.950600246953;10128.493575321352;10219.284650074424;10355.896441035644;10386.688399348031;576.3400905962733;611.2298503885048;671.8924972004456;694.5034300475185;727.1492353945896;3661.6704496595435;4892.09496243189;4915.714192900318;4955.5044495550155;4957.540963999487;89233;89423;92560.53719731358;93270;94771.52614237023;53727.46272537303;56816;60658;61047.694761525905;79615;64767;70764.64617676879;74272.08160256606;74413;75220.62389688125;10136.746581335525;10399.27205095636;10761.246712730346;10805.45972701346;10852.348859068481;34919;37338.319775610704;42245.788771055944;48494.515054849;50447.74776126096;64291.49959502977;65809.34190658032;65983;66016;66458.66770666116;11378.601748938747;11954.103442241692;12634.494620215088;13098.083134180515;13290.933545332211;14132;14207.786883196966;14234.217118058607;14278.928605356907;14934.668946005537;12872.806907896576;13126.868731312947;13291.468341266242;13325.733485330045;13897.583072507841;12770.553030643883;13087.607371778862;13229.735405292033;13241.80137297922;13621.318934053455;12827.54931196051;12998.090133690552;13139.802902956655;13631.931840340734;13681.863181368059;10335.22485813552;10511;10698.358098514114;10760.838587421038;10773.407462589634;8740.519271439878;10033.1973442123;10035.24735644815;10056.64801731936;10112.79774404493 14:34:49 INFO - PROCESS | 6847 | __end_tp_report 14:34:49 INFO - PROCESS | 6847 | __start_cc_report 14:34:49 INFO - PROCESS | 6847 | _x_x_mozilla_cycle_collect,1954 14:34:49 INFO - PROCESS | 6847 | __end_cc_report 14:34:49 INFO - PROCESS | 6847 | __startTimestamp1486334089096__endTimestamp 14:34:49 INFO - PROCESS | 6847 | 14:34:49 INFO - PROCESS | 6847 | ------- Summary: start ------- 14:34:49 INFO - PROCESS | 6847 | Number of tests: 6 14:34:49 INFO - PROCESS | 6847 | 14:34:49 INFO - PROCESS | 6847 | [#0] dojo.html Cycles:165 Average:5521.51 Median:5747.86 stddev:780.38 (13.6%) stddev-sans-first:750.41 14:34:49 INFO - PROCESS | 6847 | Values: 2686.6 3238.2 3238.5 3256.4 3282.0 5293.1 5426.1 5439.9 5481.7 5492.8 5401.9 5419.2 5433.2 5439.7 5441.6 4940.8 4966.4 5029.8 5067.0 5092.0 5009.7 5070.7 5119.1 5122.5 5122.5 246.9 4991.9 5069.8 5108.7 5126.7 5060.5 5075.7 5089.9 5105.1 5137.2 5026.7 5030.1 5049.1 5052.4 5078.2 5342.3 5366.4 5524.6 5539.1 5563.4 6010.5 6022.6 6071.3 6125.1 6126.4 6101.5 6140.4 6150.6 6180.9 6200.1 4540.3 5597.9 5693.8 5713.4 5721.2 5061.7 6050.6 6131.1 6140.2 6215.3 5976.1 5978.1 5984.1 5991.6 6019.0 5826.9 5898.0 6089.8 6125.8 6133.6 5851.5 5871.0 5887.1 5899.6 5935.6 5903.1 5912.6 5940.6 5947.8 5959.1 3984.3 4782.6 4784.9 4796.3 4837.9 3623.7 4323.8 4350.8 4363.7 4415.0 5542.1 5543.6 5554.6 5558.9 5577.1 5916.3 5978.9 6004.8 6143.0 6164.1 5535.3 5578.5 5591.7 5660.7 5674.6 5326.0 5331.1 5336.2 5345.9 5349.0 6230.8 6235.2 6239.5 6244.3 6250.3 5961.0 5994.3 6005.4 6105.6 6151.5 6230.5 6236.6 6247.5 6263.1 6264.2 6066.9 6067.6 6070.9 6080.3 6081.2 5854.2 5926.2 6106.9 6166.2 6175.4 5841.8 5848.1 5864.1 5951.7 5962.8 5913.7 5921.1 5925.1 5927.5 5932.9 5574.8 5728.3 5785.1 5786.7 5803.0 4640.9 5849.1 5849.4 5879.5 5929.0 5717.3 5728.9 5729.6 5747.9 5756.8 14:34:49 INFO - PROCESS | 6847 | 14:34:49 INFO - PROCESS | 6847 | [#1] ext.html Cycles:180 Average:62726.45 Median:37819.91 stddev:127057.23 (336.0%) stddev-sans-first:127327.58 14:34:49 INFO - PROCESS | 6847 | Values: 437.5 471.0 494.0 517.4 518.1 29520.6 39522.2 40539.6 40770.2 40999.2 36936.0 37019.3 37156.0 37250.3 37258.8 9179.7 9392.2 9401.0 9404.2 9418.0 7941.2 8180.0 10842.1 11097.0 11117.0 11272.2 11313.7 11345.0 11359.1 11360.8 7990.1 11187.0 11196.1 11252.9 11291.6 7960.3 8992.5 11071.6 11106.3 11212.4 39323.8 39646.2 39714.8 39731.0 39765.4 3898.6 3918.4 3918.6 3941.1 3962.1 29705.0 29865.3 30283.2 30372.2 30795.8 3102.9 3103.5 3103.9 3137.5 3214.8 445873.0 447852.0 448104.0 448982.0 451823.0 51310.2 61437.7 61674.0 61790.0 61803.5 52938.0 55009.7 68039.0 68298.0 68407.6 14324.3 14663.6 14673.5 14714.8 14855.4 10910.1 10988.8 11000.2 11008.4 11019.4 17262.5 17279.5 17285.1 17352.5 17378.9 6338.2 7108.9 7346.1 7467.1 7496.6 781.6 846.4 846.4 862.4 864.0 28634.7 29078.7 29136.7 29371.6 30257.5 1023.5 1029.5 1047.4 1076.1 1076.3 13331.9 14630.7 14762.3 14847.7 15483.5 498130.5 509935.0 510075.0 510394.0 513142.0 59761.7 60003.1 60537.0 60641.0 60694.0 1387.1 1432.8 1446.6 1448.1 1454.8 29012.0 29145.9 29404.9 30203.8 30292.0 53379.4 53957.7 54037.7 54109.2 54126.7 473031.0 477480.0 477837.0 478365.0 478811.0 39707.6 39951.6 40720.8 40896.2 40914.6 45469.5 46209.3 46378.8 46883.3 47039.8 37299.0 37753.4 37784.0 38906.4 39154.8 36065.6 36692.3 37328.6 38006.6 38231.8 36677.6 37142.4 37490.6 37776.1 37855.8 32051.7 33430.7 33490.3 33643.2 34131.0 21146.6 23973.4 24375.5 24917.8 24984.8 14:34:49 INFO - PROCESS | 6847 | 14:34:49 INFO - PROCESS | 6847 | [#2] jquery.html Cycles:180 Average:37300.49 Median:23693.47 stddev:110363.17 (465.8%) stddev-sans-first:110637.92 14:34:49 INFO - PROCESS | 6847 | Values: 361.9 366.6 386.5 394.3 403.6 14123.6 14456.8 19531.5 20539.8 21008.9 20383.8 20509.0 21031.9 21065.8 21176.7 5725.1 5981.3 6016.6 6020.4 6051.1 5823.4 5883.9 5916.7 5920.3 5971.9 5944.0 5944.6 5947.9 5955.8 5958.5 5789.5 5795.1 5875.2 5944.9 5957.9 5143.8 5146.7 5209.3 5215.4 5216.1 24981.0 25449.6 25527.5 25677.5 26015.9 2646.6 2662.1 2676.5 2702.7 2719.3 14614.6 14973.0 15113.1 15118.3 15769.3 1737.1 1773.7 1780.2 1790.7 1793.0 128065.0 132100.0 134264.7 134624.0 136600.6 10787.6 11167.9 11212.8 11295.7 11385.6 12698.6 13353.7 13393.4 13656.7 13676.7 6199.9 7356.6 7500.3 7784.1 7842.2 6014.7 6050.9 6463.1 6565.8 7266.4 5883.4 6198.3 6834.0 6913.8 6964.5 1616.5 1875.5 1912.7 1913.9 1921.1 928.3 1020.7 1044.4 1046.7 1109.5 21624.8 22122.4 22746.2 23392.4 23994.5 1004.4 1028.8 1050.1 1068.2 1071.3 10378.6 10648.0 11222.4 11241.4 11370.8 571605.0 640455.0 642340.0 715107.0 719734.0 136114.3 139229.8 144410.0 144763.3 148325.1 452.7 473.4 475.1 476.3 477.5 11260.3 11302.6 11305.5 11562.9 11780.5 52920.5 52920.5 53010.0 53461.0 53843.5 71556.6 75311.0 75557.6 75876.0 76044.0 8375.5 8423.0 8470.9 8562.3 8636.6 10924.5 11458.0 11713.8 13294.9 13346.7 11039.3 11265.3 11277.4 11389.3 11585.3 11074.8 11196.8 11208.7 11458.7 11550.9 9154.5 10494.6 11759.9 11773.6 11789.9 7298.0 8356.3 8575.4 8642.6 8898.2 9987.6 10485.3 10673.4 11764.2 11938.8 14:34:49 INFO - PROCESS | 6847 | 14:34:49 INFO - PROCESS | 6847 | [#3] mootools.html Cycles:180 Average:5414.23 Median:5089.91 stddev:3009.54 (59.1%) stddev-sans-first:3001.78 14:34:49 INFO - PROCESS | 6847 | Values: 1258.5 1396.5 1415.1 1424.2 1427.3 8077.0 8460.6 8579.0 8599.0 8637.5 8168.7 8405.1 8427.1 8587.1 8587.3 2260.0 2263.3 2265.3 2271.7 2287.7 1999.2 2255.1 2307.3 2310.3 2318.3 1995.1 2008.9 2024.4 2028.3 2200.6 2253.7 2261.3 2264.8 2265.1 2267.4 1964.0 2214.1 2267.6 2276.2 2279.8 8503.1 8557.3 8561.2 8563.3 8565.0 5030.1 5201.2 5204.7 5207.5 5217.0 3282.7 3413.6 3456.1 3474.6 3539.3 1140.2 1228.0 1247.3 1689.2 1737.5 10942.3 10986.8 11066.8 11087.3 11100.0 4211.5 4401.2 4461.6 4470.0 4475.4 8623.0 8702.4 8748.8 8759.0 8799.6 5083.0 5087.1 5092.7 5093.8 5106.7 4640.3 4651.8 4672.9 4706.0 4753.4 3583.0 3608.1 3631.5 3659.2 3666.5 1130.8 1138.7 1142.4 1147.8 1150.2 698.7 806.5 854.6 875.2 913.1 4381.5 5279.0 5368.2 5600.5 5612.8 859.0 905.0 909.8 952.0 960.6 3138.6 3603.1 3763.1 3825.3 3835.4 9475.0 9754.1 10017.8 10146.3 10155.4 10317.7 10318.0 10319.7 10330.6 10342.7 7519.8 7780.0 7838.6 8004.6 8005.8 4729.1 5150.5 5543.2 5638.2 5933.1 9130.6 9319.3 9325.1 9337.7 9376.6 8973.1 9344.9 9346.7 9469.0 9510.3 5775.5 7161.8 7250.6 7259.1 7260.6 7826.6 7831.8 7879.9 7925.6 7955.8 7208.6 7273.8 7307.3 7337.9 7404.9 6973.2 7096.4 7135.4 7189.2 7373.7 6104.1 6319.4 6409.8 6422.8 6425.2 4856.9 4903.4 4917.0 5005.5 5140.1 4927.4 4943.6 5027.6 5046.4 5128.9 14:34:49 INFO - PROCESS | 6847 | 14:34:49 INFO - PROCESS | 6847 | [#4] prototype.html Cycles:180 Average:4294.76 Median:4778.32 stddev:1305.15 (27.3%) stddev-sans-first:1274.13 14:34:49 INFO - PROCESS | 6847 | Values: 312.7 350.6 363.7 368.7 373.0 4369.9 4760.7 4906.5 5135.7 5162.8 4637.3 4961.3 5040.0 5059.4 5068.5 3862.6 3899.8 3930.4 3935.7 3957.8 3431.6 3825.0 3915.7 3919.1 3954.8 3099.6 3482.8 3862.2 3869.3 3891.5 3255.9 3499.4 3600.6 3867.4 3874.0 2679.4 2990.0 3226.0 3347.8 3397.4 3807.1 4882.2 4891.7 4980.4 5078.3 4427.7 4450.4 4453.0 4464.0 4481.9 4714.4 5337.8 5379.7 5427.6 5450.6 3760.6 3760.9 3767.6 3768.4 3768.9 4256.5 4306.8 5073.8 5132.6 5215.4 3198.5 3322.6 3724.1 3750.6 3774.0 3823.9 3831.2 3837.5 3850.2 3860.9 3989.1 4206.7 4321.7 4472.9 4586.3 4889.9 5436.0 5485.8 5503.3 5556.8 1224.9 1257.9 1304.2 1352.8 1369.6 845.4 847.4 859.4 898.7 977.3 4190.0 4353.1 4396.4 4459.4 4961.7 4301.4 4668.9 5007.5 5376.9 5399.4 4484.5 4877.7 4893.0 4893.8 4910.7 2723.6 2786.9 2804.3 2812.1 2845.9 4541.8 4777.3 4924.6 5471.5 5542.8 4977.9 5272.8 5295.9 5501.6 5554.9 4438.9 4440.5 5324.0 5841.5 5852.8 5652.3 5662.5 5678.6 5687.9 5709.2 5076.7 5233.9 5594.5 5603.5 5625.6 4779.4 5349.5 5471.8 5643.8 5809.2 3652.5 3657.7 3670.9 3837.9 3839.3 5082.7 5108.4 5644.0 5668.4 5693.4 4294.0 4875.3 5038.5 5106.1 5509.3 4779.5 4832.9 5466.2 5495.5 5511.4 4883.6 4993.3 5380.7 5403.0 5405.2 5028.8 5397.1 5477.6 5478.7 5516.9 4491.8 4771.0 5022.9 5340.5 5372.3 14:34:49 INFO - PROCESS | 6847 | 14:34:49 INFO - PROCESS | 6847 | [#5] yui.html Cycles:180 Average:18816.05 Median:20558.88 stddev:26348.99 (128.2%) stddev-sans-first:26387.84 14:34:49 INFO - PROCESS | 6847 | Values: 712.6 737.9 766.9 824.6 836.2 14049.9 14624.4 14637.0 14653.3 14667.2 13300.7 13399.8 13416.0 13443.7 13468.3 856.7 865.6 873.5 876.4 888.9 851.5 880.7 886.1 886.7 891.3 932.8 933.3 934.4 936.8 938.9 928.0 928.8 929.1 929.3 932.5 659.0 674.7 695.3 696.4 697.4 14302.9 14494.9 14508.8 14594.7 15215.6 13548.7 13945.9 13987.6 14041.7 14046.4 11505.4 11511.8 11662.5 11691.6 12146.3 3615.6 3630.6 3681.2 3779.4 3786.8 102150.0 104171.9 104511.0 105135.0 105454.9 9526.9 10033.8 10117.3 10676.0 10982.9 17896.9 22347.7 22405.4 22416.3 22434.2 7410.6 7411.7 7433.2 7466.4 7482.2 4151.8 4197.7 4253.5 4304.9 4344.5 3584.2 3713.7 3719.9 3806.0 3979.2 718.4 734.3 738.8 768.8 769.5 511.5 540.9 566.3 587.4 593.7 9880.0 10128.5 10219.3 10355.9 10386.7 576.3 611.2 671.9 694.5 727.1 3661.7 4892.1 4915.7 4955.5 4957.5 89233.0 89423.0 92560.5 93270.0 94771.5 53727.5 56816.0 60658.0 61047.7 79615.0 64767.0 70764.6 74272.1 74413.0 75220.6 10136.7 10399.3 10761.2 10805.5 10852.3 34919.0 37338.3 42245.8 48494.5 50447.7 64291.5 65809.3 65983.0 66016.0 66458.7 11378.6 11954.1 12634.5 13098.1 13290.9 14132.0 14207.8 14234.2 14278.9 14934.7 12872.8 13126.9 13291.5 13325.7 13897.6 12770.6 13087.6 13229.7 13241.8 13621.3 12827.5 12998.1 13139.8 13631.9 13681.9 10335.2 10511.0 10698.4 10760.8 10773.4 8740.5 10033.2 10035.2 10056.6 10112.8 14:34:49 INFO - PROCESS | 6847 | -------- Summary: end -------- 14:34:49 INFO - PROCESS | 6847 | 14:34:49 INFO - TEST-INFO | 6847: exit 0 14:34:49 INFO - TEST-OK | dromaeo_css | took 1118071ms 14:34:49 INFO - TEST-START | kraken 14:34:49 INFO - operating with platform_type : mac_ 14:34:49 INFO - Initialising browser for kraken test... 14:34:49 INFO - TEST-INFO | started process 6850 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/tmpoiq74L/profile http://localhost:61292/getInfo.html) 14:34:52 INFO - PROCESS | 6850 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 14:34:52 INFO - PROCESS | 6850 | __metrics Screen width/height:1600/1200 14:34:52 INFO - PROCESS | 6850 | colorDepth:24 14:34:52 INFO - PROCESS | 6850 | Browser inner width/height: 1024/689 14:34:52 INFO - PROCESS | 6850 | __metrics 14:34:52 INFO - PROCESS | 6850 | 1486334092498 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 14:34:52 INFO - PROCESS | 6850 | 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" 14:34:52 INFO - PROCESS | 6850 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:34:53 INFO - TEST-INFO | 6850: exit 0 14:34:53 INFO - Browser initialized. 14:34:53 INFO - Running cycle 1/1 for kraken test... 14:34:53 INFO - TEST-INFO | started process 6851 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/tmpoiq74L/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:35:05 INFO - PROCESS | 6851 | RSS: Main: 252825600 14:35:05 INFO - PROCESS | 6851 | 14:35:08 INFO - PROCESS | 6851 | Cycle 1(1): loaded http://localhost:61292/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:61292/tests/kraken/driver.html?testName=audio-beat-detection) 14:35:09 INFO - PROCESS | 6851 | RSS: Main: 302043136 14:35:09 INFO - PROCESS | 6851 | 14:35:11 INFO - PROCESS | 6851 | Cycle 1(1): loaded http://localhost:61292/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:61292/tests/kraken/driver.html?testName=audio-dft) 14:35:12 INFO - PROCESS | 6851 | RSS: Main: 303431680 14:35:12 INFO - PROCESS | 6851 | 14:35:15 INFO - PROCESS | 6851 | Cycle 1(1): loaded http://localhost:61292/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:61292/tests/kraken/driver.html?testName=audio-fft) 14:35:16 INFO - PROCESS | 6851 | RSS: Main: 392200192 14:35:16 INFO - PROCESS | 6851 | 14:35:18 INFO - PROCESS | 6851 | Cycle 1(1): loaded http://localhost:61292/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:61292/tests/kraken/driver.html?testName=audio-oscillator) 14:35:19 INFO - PROCESS | 6851 | RSS: Main: 294305792 14:35:19 INFO - PROCESS | 6851 | 14:35:21 INFO - PROCESS | 6851 | Cycle 1(1): loaded http://localhost:61292/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:61292/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:35:21 INFO - PROCESS | 6851 | RSS: Main: 297431040 14:35:21 INFO - PROCESS | 6851 | 14:35:24 INFO - PROCESS | 6851 | Cycle 1(1): loaded http://localhost:61292/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:61292/tests/kraken/driver.html?testName=imaging-darkroom) 14:35:25 INFO - PROCESS | 6851 | RSS: Main: 339226624 14:35:25 INFO - PROCESS | 6851 | 14:35:28 INFO - PROCESS | 6851 | Cycle 1(1): loaded http://localhost:61292/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:61292/tests/kraken/driver.html?testName=imaging-desaturate) 14:35:28 INFO - PROCESS | 6851 | RSS: Main: 340086784 14:35:28 INFO - PROCESS | 6851 | 14:35:31 INFO - PROCESS | 6851 | Cycle 1(1): loaded http://localhost:61292/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:61292/tests/kraken/driver.html?testName=json-parse-financial) 14:35:32 INFO - PROCESS | 6851 | RSS: Main: 337719296 14:35:32 INFO - PROCESS | 6851 | 14:35:33 INFO - PROCESS | 6851 | Cycle 1(1): loaded http://localhost:61292/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:61292/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:35:34 INFO - PROCESS | 6851 | RSS: Main: 316416000 14:35:34 INFO - PROCESS | 6851 | 14:35:36 INFO - PROCESS | 6851 | Cycle 1(1): loaded http://localhost:61292/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:61292/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:35:37 INFO - PROCESS | 6851 | RSS: Main: 321310720 14:35:37 INFO - PROCESS | 6851 | 14:35:39 INFO - PROCESS | 6851 | Cycle 1(1): loaded http://localhost:61292/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:61292/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:35:40 INFO - PROCESS | 6851 | RSS: Main: 323407872 14:35:40 INFO - PROCESS | 6851 | 14:35:42 INFO - PROCESS | 6851 | Cycle 1(1): loaded http://localhost:61292/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:61292/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:35:43 INFO - PROCESS | 6851 | RSS: Main: 339443712 14:35:43 INFO - PROCESS | 6851 | 14:35:46 INFO - PROCESS | 6851 | Cycle 1(1): loaded http://localhost:61292/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:61292/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:35:47 INFO - PROCESS | 6851 | RSS: Main: 333099008 14:35:47 INFO - PROCESS | 6851 | 14:35:49 INFO - PROCESS | 6851 | Cycle 1(1): loaded http://localhost:61292/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:61292/tests/kraken/driver.html?testName=ai-astar) 14:35:49 INFO - PROCESS | 6851 | RSS: Main: 378552320 14:35:49 INFO - PROCESS | 6851 | 14:35:49 INFO - PROCESS | 6851 | __start_tp_report 14:35:49 INFO - PROCESS | 6851 | _x_x_mozilla_page_load 14:35:49 INFO - PROCESS | 6851 | _x_x_mozilla_page_load_details 14:35:49 INFO - PROCESS | 6851 | |i|pagename|runs| 14:35:49 INFO - PROCESS | 6851 | |0;ai-astar;114;114;113;114;112;113;112;113;112;113 14:35:49 INFO - PROCESS | 6851 | |1;audio-beat-detection;142;110;110;148;112;110;110;110;130;109 14:35:49 INFO - PROCESS | 6851 | |2;audio-dft;248;247;185;186;187;184;182;185;183;185 14:35:49 INFO - PROCESS | 6851 | |3;audio-fft;84;59;59;89;60;59;60;76;97;64 14:35:49 INFO - PROCESS | 6851 | |4;audio-oscillator;90;70;86;70;68;69;85;69;70;84 14:35:49 INFO - PROCESS | 6851 | |5;imaging-gaussian-blur;88;85;85;84;86;83;87;83;86;83 14:35:49 INFO - PROCESS | 6851 | |6;imaging-darkroom;95;100;98;96;96;98;115;117;99;97 14:35:49 INFO - PROCESS | 6851 | |7;imaging-desaturate;85;86;85;85;87;85;84;95;134;85 14:35:49 INFO - PROCESS | 6851 | |8;json-parse-financial;62;60;60;60;61;60;59;58;59;60 14:35:49 INFO - PROCESS | 6851 | |9;json-stringify-tinderbox;72;73;64;77;73;68;75;70;71;72 14:35:49 INFO - PROCESS | 6851 | |10;stanford-crypto-aes;86;86;93;87;85;87;75;99;98;103 14:35:49 INFO - PROCESS | 6851 | |11;stanford-crypto-ccm;141;140;137;137;132;153;151;150;184;144 14:35:49 INFO - PROCESS | 6851 | |12;stanford-crypto-pbkdf2;172;174;173;172;179;229;200;170;171;171 14:35:49 INFO - PROCESS | 6851 | |13;stanford-crypto-sha256-iterative;68;68;67;68;67;66;66;66;65;65 14:35:49 INFO - PROCESS | 6851 | __end_tp_report 14:35:49 INFO - PROCESS | 6851 | __start_cc_report 14:35:49 INFO - PROCESS | 6851 | _x_x_mozilla_cycle_collect,2054 14:35:49 INFO - PROCESS | 6851 | __end_cc_report 14:35:49 INFO - PROCESS | 6851 | __startTimestamp1486334149311__endTimestamp 14:35:49 INFO - PROCESS | 6851 | 14:35:49 INFO - PROCESS | 6851 | ------- Summary: start ------- 14:35:49 INFO - PROCESS | 6851 | Number of tests: 14 14:35:49 INFO - PROCESS | 6851 | 14:35:49 INFO - PROCESS | 6851 | [#0] ai-astar Cycles:10 Average:113.00 Median:113.00 stddev:0.82 (0.7%) stddev-sans-first:0.78 14:35:49 INFO - PROCESS | 6851 | Values: 114.0 114.0 113.0 114.0 112.0 113.0 112.0 113.0 112.0 113.0 14:35:49 INFO - PROCESS | 6851 | 14:35:49 INFO - PROCESS | 6851 | [#1] audio-beat-detection Cycles:10 Average:119.10 Median:111.00 stddev:15.07 (13.6%) stddev-sans-first:13.52 14:35:49 INFO - PROCESS | 6851 | Values: 142.0 110.0 110.0 148.0 112.0 110.0 110.0 110.0 130.0 109.0 14:35:49 INFO - PROCESS | 6851 | 14:35:49 INFO - PROCESS | 6851 | [#2] audio-dft Cycles:10 Average:197.20 Median:185.50 stddev:26.55 (14.3%) stddev-sans-first:20.85 14:35:49 INFO - PROCESS | 6851 | Values: 248.0 247.0 185.0 186.0 187.0 184.0 182.0 185.0 183.0 185.0 14:35:49 INFO - PROCESS | 6851 | 14:35:49 INFO - PROCESS | 6851 | [#3] audio-fft Cycles:10 Average:70.70 Median:70.00 stddev:14.59 (20.8%) stddev-sans-first:14.66 14:35:49 INFO - PROCESS | 6851 | Values: 84.0 59.0 59.0 89.0 60.0 59.0 60.0 76.0 97.0 64.0 14:35:49 INFO - PROCESS | 6851 | 14:35:49 INFO - PROCESS | 6851 | [#4] audio-oscillator Cycles:10 Average:76.10 Median:77.00 stddev:8.89 (11.5%) stddev-sans-first:7.88 14:35:49 INFO - PROCESS | 6851 | Values: 90.0 70.0 86.0 70.0 68.0 69.0 85.0 69.0 70.0 84.0 14:35:49 INFO - PROCESS | 6851 | 14:35:49 INFO - PROCESS | 6851 | [#5] imaging-gaussian-blur Cycles:10 Average:85.00 Median:85.50 stddev:1.76 (2.1%) stddev-sans-first:1.50 14:35:49 INFO - PROCESS | 6851 | Values: 88.0 85.0 85.0 84.0 86.0 83.0 87.0 83.0 86.0 83.0 14:35:49 INFO - PROCESS | 6851 | 14:35:49 INFO - PROCESS | 6851 | [#6] imaging-darkroom Cycles:10 Average:101.10 Median:96.50 stddev:8.01 (8.3%) stddev-sans-first:8.18 14:35:49 INFO - PROCESS | 6851 | Values: 95.0 100.0 98.0 96.0 96.0 98.0 115.0 117.0 99.0 97.0 14:35:49 INFO - PROCESS | 6851 | 14:35:49 INFO - PROCESS | 6851 | [#7] imaging-desaturate Cycles:10 Average:91.10 Median:85.00 stddev:15.40 (18.1%) stddev-sans-first:16.18 14:35:49 INFO - PROCESS | 6851 | Values: 85.0 86.0 85.0 85.0 87.0 85.0 84.0 95.0 134.0 85.0 14:35:49 INFO - PROCESS | 6851 | 14:35:49 INFO - PROCESS | 6851 | [#8] json-parse-financial Cycles:10 Average:59.90 Median:60.00 stddev:1.10 (1.8%) stddev-sans-first:0.87 14:35:49 INFO - PROCESS | 6851 | Values: 62.0 60.0 60.0 60.0 61.0 60.0 59.0 58.0 59.0 60.0 14:35:49 INFO - PROCESS | 6851 | 14:35:49 INFO - PROCESS | 6851 | [#9] json-stringify-tinderbox Cycles:10 Average:71.50 Median:72.50 stddev:3.63 (5.0%) stddev-sans-first:3.84 14:35:49 INFO - PROCESS | 6851 | Values: 72.0 73.0 64.0 77.0 73.0 68.0 75.0 70.0 71.0 72.0 14:35:49 INFO - PROCESS | 6851 | 14:35:49 INFO - PROCESS | 6851 | [#10] stanford-crypto-aes Cycles:10 Average:89.90 Median:87.00 stddev:8.32 (9.6%) stddev-sans-first:8.70 14:35:49 INFO - PROCESS | 6851 | Values: 86.0 86.0 93.0 87.0 85.0 87.0 75.0 99.0 98.0 103.0 14:35:49 INFO - PROCESS | 6851 | 14:35:49 INFO - PROCESS | 6851 | [#11] stanford-crypto-ccm Cycles:10 Average:146.90 Median:147.00 stddev:14.72 (10.0%) stddev-sans-first:15.45 14:35:49 INFO - PROCESS | 6851 | Values: 141.0 140.0 137.0 137.0 132.0 153.0 151.0 150.0 184.0 144.0 14:35:49 INFO - PROCESS | 6851 | 14:35:49 INFO - PROCESS | 6851 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.10 Median:173.50 stddev:19.05 (11.0%) stddev-sans-first:19.92 14:35:49 INFO - PROCESS | 6851 | Values: 172.0 174.0 173.0 172.0 179.0 229.0 200.0 170.0 171.0 171.0 14:35:49 INFO - PROCESS | 6851 | 14:35:49 INFO - PROCESS | 6851 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:67.00 stddev:1.17 (1.8%) stddev-sans-first:1.13 14:35:49 INFO - PROCESS | 6851 | Values: 68.0 68.0 67.0 68.0 67.0 66.0 66.0 66.0 65.0 65.0 14:35:49 INFO - PROCESS | 6851 | -------- Summary: end -------- 14:35:49 INFO - PROCESS | 6851 | 14:35:50 INFO - TEST-INFO | 6851: exit 0 14:35:50 INFO - TEST-OK | kraken | took 60487ms 14:35:50 INFO - SUITE-END | took 1178s 14:35:50 INFO - Completed test suite (00:19:38) 14:35:50 INFO - javascript benchmark 14:35:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7087.661969308025, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2686.5835795451703, 3238.206639373354, 3238.4656531672276, 3256.446404111301, 3282.0482060202544, 5293.100172970595, 5426.1046927257, 5439.945600543993, 5481.671099734019, 5492.780288788454, 5401.891962160765, 5419.187121931712, 5433.157860531624, 5439.673619582828, 5441.646292990963, 4940.827071052514, 4966.404031516223, 5029.823956161535, 5067.0, 5091.974540127294, 5009.749512524378, 5070.670406423589, 5119.104156772569, 5122.487751224885, 5122.487751224885, 246.89440189727586, 4991.900161996739, 5069.771860266297, 5108.744562771865, 5126.71803050832, 5060.51925067118, 5075.746212689388, 5089.898202035939, 5105.055564720538, 5137.229415587654, 5026.723530205837, 5030.069437154101, 5049.1164046292115, 5052.4189718182515, 5078.21287700407, 5342.305500284977, 5366.436524164985, 5524.640898341635, 5539.086050801613, 5563.443655634404, 6010.519158467312, 6022.608530445507, 6071.271447426293, 6125.123972708545, 6126.426068710472, 6101.511879049665, 6140.355262697397, 6150.569460137792, 6180.907286390663, 6200.069989501566, 4540.318952157203, 5597.944020559783, 5693.829185124453, 5713.400092990218, 5721.170430287627, 5061.670991385548, 6050.606710563799, 6131.101423540838, 6140.232470941132, 6215.285242197115, 5976.103584462366, 5978.073398623221, 5984.132300816357, 5991.550633702403, 6019.0, 5826.883462330729, 5897.970510147507, 6089.786857460056, 6125.785597504066, 6133.601315914451, 5851.502622277069, 5871.031279838864, 5887.0580707086665, 5899.587028907935, 5935.614185077956, 5903.144044113579, 5912.615679980788, 5940.584159108907, 5947.791827286024, 5959.0763431668165, 3984.2828290907914, 4782.641301902371, 4784.880377990578, 4796.2805579162705, 4837.879053023703, 3623.7282203834816, 4323.805428755713, 4350.825966961286, 4363.716358436692, 4415.028693687384, 5542.11326187808, 5543.584231182677, 5554.611177217556, 5558.888822223533, 5577.079781836032, 5916.26046744157, 5978.910316345258, 6004.789832355934, 6142.969285153546, 6164.106204600308, 5535.280413546213, 5578.497935185853, 5591.720413979285, 5660.688662123623, 5674.631148975386, 5325.973370133124, 5331.1470164773455, 5336.226247194135, 5345.946540534623, 5349.010433069894, 6230.7507699691505, 6235.158253635701, 6239.500839932794, 6244.250689917239, 6250.281217660027, 5961.046232602763, 5994.31065427464, 6005.429484198994, 6105.633661980295, 6151.507879369729, 6230.470410015018, 6236.6258024518675, 6247.531435142291, 6263.091851681572, 6264.185655864709, 6066.878662426639, 6067.575269731076, 6070.939290607214, 6080.300765411857, 6081.239845019372, 5854.209681692917, 5926.199963005111, 6106.908396374147, 6166.167567378347, 6175.382461753788, 5841.824745257479, 5848.093545500455, 5864.149698293809, 5951.702414879325, 5962.791302304572, 5913.674747888907, 5921.052631578927, 5925.05199168131, 5927.496162826035, 5932.940670593239, 5574.777008919597, 5728.341240757366, 5785.132230165509, 5786.681732504752, 5802.984477716467, 4640.93038604428, 5849.122631605123, 5849.3858144894175, 5879.529637629065, 5929.0, 5717.285339332583, 5728.885422291614, 5729.598928074995, 5747.8563035924435, 5756.827295180984], "value": 5471.391128811667, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [437.4728452215081, 470.99293510597334, 493.99965070731764, 517.3507248403254, 518.1269560790068, 29520.557191642143, 39522.209555808906, 40539.59460405395, 40770.184596308056, 40999.18001639965, 36936.0, 37019.259614807685, 37156.0, 37250.25499490008, 37258.81370593143, 9179.678711245124, 9392.24862011041, 9400.952995235015, 9404.200642945323, 9418.0, 7941.245581669761, 8179.959100204491, 10842.078423334026, 11097.0, 11117.0, 11272.154588405903, 11313.660590182308, 11345.035671967893, 11359.1480638952, 11360.829587556193, 7990.12108668046, 11187.049100826398, 11196.104311655048, 11252.943735281313, 11291.604793832259, 7960.323372513285, 8992.46045237288, 11071.612493562767, 11106.278091924, 11212.383318917457, 39323.803380982914, 39646.20707585861, 39714.80142599298, 39731.0, 39765.40351894724, 3898.610138986107, 3918.431827385013, 3918.6277303656107, 3941.073847645801, 3962.1085255817497, 29705.0, 29865.25336866596, 30283.242918926982, 30372.240693982607, 30795.846020769975, 3102.860371283275, 3103.4724096903356, 3103.922401939947, 3137.4666306727913, 3214.758893083028, 445873.0, 447852.0, 448104.0, 448982.0, 451823.0, 51310.230346544835, 61437.69281153655, 61674.0, 61790.0, 61803.454913627516, 52938.0, 55009.724951375785, 68039.0, 68298.0, 68407.63184736278, 14324.283785810876, 14663.56009319722, 14673.486427974969, 14714.779278310834, 14855.405783768745, 10910.072643825206, 10988.780224395467, 11000.174986876016, 11008.449577521093, 11019.449027548591, 17262.482125536255, 17279.481615551555, 17285.13574321304, 17352.47942561725, 17378.913105434392, 6338.239411270585, 7108.8933665995055, 7346.061575369895, 7467.1039475263315, 7496.6251687415415, 781.6482582837746, 846.4328899637258, 846.4498076250363, 862.3920136303891, 863.9934476335125, 28634.713652863622, 29078.7092129076, 29136.708632913822, 29371.55942660862, 30257.546136807967, 1023.539407266733, 1029.5161274201114, 1047.4239168457318, 1076.1444651502075, 1076.2896488317829, 13331.866681333257, 14630.707385852224, 14762.261886905611, 14847.70304593902, 15483.5354939352, 498130.5093474509, 509935.0, 510075.0, 510394.0, 513142.0, 59761.701191494634, 60003.099953500736, 60537.0, 60641.0, 60694.0, 1387.0983860490762, 1432.792245124451, 1446.5732608880617, 1448.0877047460244, 1454.7745099509382, 29012.0, 29145.854270728512, 29404.852975734986, 30203.848980754956, 30292.0, 53379.39861804152, 53957.651058723844, 54037.72981135069, 54109.18836217381, 54126.72936635291, 473031.0, 477480.0, 477837.0, 478365.0, 478811.0, 39707.60292397039, 39951.60048399595, 40720.77837664875, 40896.1820763589, 40914.590854091075, 45469.54530454653, 46209.30686039645, 46378.76810615925, 46883.29675054945, 47039.764801177145, 37299.0, 37753.433698493995, 37784.0, 38906.416403754534, 39154.80422597869, 36065.639343607276, 36692.266154676225, 37328.62671373251, 38006.619933800306, 38231.808840955615, 36677.63322366742, 37142.44286335761, 37490.62509374871, 37776.055598610255, 37855.81072094622, 32051.679483205804, 33430.66569334276, 33490.33019339648, 33643.15892102717, 34131.0, 21146.577068458853, 23973.400664983514, 24375.51248974975, 24917.750822491544, 24984.750152498244], "value": 19676.159336799934, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.9485394927735, 366.5601278465842, 386.53229592193446, 394.3201959651882, 403.61962089653383, 14123.576292711223, 14456.78314825277, 19531.5117122072, 20539.794602053975, 21008.89495552524, 20383.796162038376, 20509.0, 21031.894840525776, 21065.789342106575, 21176.682349764727, 5725.08398656214, 5981.28224613047, 6016.608920420181, 6020.367861374559, 6051.12258722485, 5823.38854420284, 5883.852903677421, 5916.704164791765, 5920.348761636216, 5971.940280597182, 5944.0, 5944.554158438134, 5947.910781338283, 5955.821325360246, 5958.523318134538, 5789.494731369832, 5795.072788353864, 5875.236219291507, 5944.881102377928, 5957.91063134049, 5143.7912090658665, 5146.6911985281, 5209.270702101709, 5215.426303106655, 5216.113260745682, 24981.0, 25449.618255726178, 25527.48945021089, 25677.486450271077, 26015.869920650464, 2646.5765477523696, 2662.121499905046, 2676.47808677308, 2702.7162147974573, 2719.29298382422, 14614.561563153122, 14973.0, 15113.093214407061, 15118.319675614624, 15769.290381932726, 1737.1053907237783, 1773.6807374672553, 1780.1544266473452, 1790.6687262856283, 1792.9910350448295, 128065.0, 132100.0, 134264.65735342618, 134624.0, 136600.63399365978, 10787.568497260021, 11167.944160279309, 11212.775744485229, 11295.717607059725, 11385.601503947322, 12698.555550555686, 13353.73292534144, 13393.397297121654, 13656.658583535293, 13676.726465470634, 6199.938000620026, 7356.558606483628, 7500.324970752658, 7784.119875646047, 7842.157093083121, 6014.729337179838, 6050.880587091402, 6463.06285588587, 6565.835854103686, 7266.382357499671, 5883.441073098049, 6198.318184999646, 6834.0, 6913.792586222421, 6964.477664175207, 1616.4584864070669, 1875.5311172206948, 1912.722655214986, 1913.87559808612, 1921.1258877210835, 928.2666693312343, 1020.7088033637455, 1044.4151275285865, 1046.6598355534481, 1109.5187924121228, 21624.78375216228, 22122.446938826335, 22746.203882864353, 23392.415189620395, 23994.52010959771, 1004.3672486333709, 1028.7582418131733, 1050.1336397472116, 1068.2308737708838, 1071.266182664877, 10378.636747713794, 10647.989127999794, 11222.418843666002, 11241.435025882041, 11370.772584548262, 571605.0, 640455.0, 642340.0, 715107.0, 719734.0, 136114.31942840223, 139229.823105307, 144410.0, 144763.2761836205, 148325.07661006215, 452.7374123008647, 473.3822361817861, 475.1328824894581, 476.2617942189592, 477.45331595323853, 11260.324380537195, 11302.604408845651, 11305.547778088785, 11562.891371490536, 11780.528778849093, 52920.470795291556, 52920.470795291556, 53010.0, 53461.0, 53843.46156538541, 71556.64221678858, 75311.0, 75557.62221188859, 75876.0, 76044.0, 8375.497470151811, 8423.0, 8470.915290847013, 8562.272206862486, 8636.611352489028, 10924.453777311262, 11458.0, 11713.765724685629, 13294.939284410932, 13346.733065338833, 11039.282446640942, 11265.324080555192, 11277.379744114152, 11389.316641001566, 11585.304881707125, 11074.778504430027, 11196.83204751913, 11208.719782005515, 11458.656240312805, 11550.94224528872, 9154.450732955778, 10494.632687855888, 11759.882401175879, 11773.646790596296, 11789.882101178879, 7298.0, 8356.289715374262, 8575.356848236283, 8642.586178459598, 8898.199162075443, 9987.550560224952, 10485.266031378036, 10673.359598423809, 11764.176507644725, 11938.806119387991], "value": 9465.879425462274, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1258.546923107681, 1396.5321617258219, 1415.0801978713841, 1424.159745750008, 1427.3148888533508, 8077.030756309244, 8460.57697115145, 8579.0, 8599.0, 8637.481751094938, 8168.673253069885, 8405.117462666409, 8427.115152908947, 8587.098354672748, 8587.270082043018, 2259.9774002259974, 2263.2757517594378, 2265.275111964172, 2271.6933214016144, 2287.7254729432493, 1999.2302963359139, 2255.12050300383, 2307.273208939187, 2310.295359915225, 2318.316096751454, 1995.0623207092642, 2008.8794672319634, 2024.4028011736573, 2028.2596852148934, 2200.6478963365867, 2253.7070180876462, 2261.31030035839, 2264.784845439689, 2265.1392470861133, 2267.4444761033487, 1963.9607207855906, 2214.1475531920164, 2267.5691618592414, 2276.203328834911, 2279.8176145908287, 8503.107173746792, 8557.315414766803, 8561.229489345926, 8563.272121869648, 8564.957175214084, 5030.119729047402, 5201.167813149916, 5204.713740744258, 5207.4792520748, 5217.034848553029, 3282.720968717662, 3413.6245013048547, 3456.084137703511, 3474.5656792900886, 3539.3275277697153, 1140.236041851961, 1227.9508819647203, 1247.2516490105954, 1689.206073145632, 1737.4961261234228, 10942.343459392461, 10986.780264394747, 11066.833997490045, 11087.279326843729, 11100.0, 4211.515675697334, 4401.22978478765, 4461.598456138963, 4469.977650111794, 4475.373447717322, 8623.0, 8702.390832641655, 8748.781280467912, 8759.0, 8799.648014079494, 5082.974585127125, 5087.109755792721, 5092.719900405513, 5093.821716239914, 5106.668066575735, 4640.32715256286, 4651.790669419816, 4672.906541869212, 4706.0, 4753.405824271966, 3582.982085089558, 3608.1340478285547, 3631.4915911772373, 3659.2315613720884, 3666.4866918631415, 1130.8473356096986, 1138.7494751154732, 1142.4287856071965, 1147.7761836441814, 1150.2178518607427, 698.7065432518253, 806.5281810141133, 854.6111519258723, 875.2472873328932, 913.0504275553483, 4381.452318460192, 5279.028666713214, 5368.1679339702405, 5600.52395546375, 5612.831615051555, 858.9563680128674, 904.9502277374676, 909.7816524034184, 951.9952400237954, 960.6061514779046, 3138.59198304219, 3603.0992251937014, 3763.096856754359, 3825.3114439401174, 3835.424686297022, 9475.0, 9754.122129008281, 10017.849732254022, 10146.289759716896, 10155.39067655943, 10317.690469285782, 10317.968203179622, 10319.690409287734, 10330.638427654996, 10342.741431464274, 7519.8496030079095, 7779.988601481771, 7838.568878711611, 8004.63979120941, 8005.839883202303, 4729.146192560848, 5150.4592017837585, 5543.1685247213245, 5638.238837757014, 5933.080372542263, 9130.589123489326, 9319.297403752837, 9325.114114159143, 9337.673181438617, 9376.57805398745, 8973.102689730975, 9344.906550934404, 9346.672866449642, 9469.00575439569, 9510.334276600572, 5775.480206781242, 7161.785146445615, 7250.60121693312, 7259.056322678018, 7260.636968151465, 7826.5695386751995, 7831.84336313282, 7879.921200787919, 7925.643346049557, 7955.761327160194, 7208.56748595065, 7273.818154546178, 7307.26927307265, 7337.889931651136, 7404.92595074064, 6973.232944376013, 7096.396806271318, 7135.357817796424, 7189.2451292615615, 7373.705051797868, 6104.145419641342, 6319.368063193644, 6409.775657851953, 6422.839429014312, 6425.228972523327, 4856.878578035577, 4903.41159060915, 4917.0, 5005.524475174852, 5140.126178549581, 4927.433345165208, 4943.555080042814, 5027.572656324253, 5046.419661738947, 5128.923066154085], "value": 4369.219795588378, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [312.66076307206686, 350.58980992239094, 363.7124578990374, 368.7013519049568, 372.9757565758224, 4369.934450983238, 4760.714357138574, 4906.460289368171, 5135.717535535544, 5162.845114646562, 4637.304404339351, 4961.255811628249, 5040.0, 5059.382541482839, 5068.5438310552045, 3862.613738626143, 3899.78551179685, 3930.351492003816, 3935.744176628524, 3957.8324394303677, 3431.6053653829754, 3825.005498570364, 3915.6671682906845, 3919.1377896862646, 3954.8220330085214, 3099.581556489879, 3482.843272052751, 3862.208247309295, 3869.342211823997, 3891.4941057662463, 3255.9348813023844, 3499.3701133795907, 3600.5859326177433, 3867.4198870169416, 3873.9661677619333, 2679.3837417394107, 2989.9850500747575, 3225.9676903390923, 3347.7991320520605, 3397.405454045557, 3807.1053302473897, 4882.248471222549, 4891.706497610119, 4980.435075935896, 5078.339815823919, 4427.653343727829, 4450.421445212101, 4452.973896876913, 4463.955360446386, 4481.865544033684, 4714.4106986626675, 5337.759800808973, 5379.735762095905, 5427.592930530226, 5450.645708029005, 3760.623937606245, 3760.924781504382, 3767.5855655878104, 3768.4347347898033, 3768.8680896168503, 4256.489221293434, 4306.7631280279575, 5073.847784566469, 5132.563732082777, 5215.426303106655, 3198.504231844068, 3322.551455553518, 3724.1248306647917, 3750.587435382106, 3773.962260377416, 3823.9426408603895, 3831.233753249361, 3837.4627552142724, 3850.249201405699, 3860.9034774130873, 3989.145047552902, 4206.726562773471, 4321.719088259253, 4472.948857018598, 4586.334981427674, 4889.902201955941, 5436.0, 5485.807996720096, 5503.284573005563, 5556.77772889088, 1224.8958838498827, 1257.88050135237, 1304.1848844472204, 1352.7970804379422, 1369.6370461827623, 845.4293351987384, 847.3517758914436, 859.3640705877731, 898.7327867706503, 977.2572844637984, 4189.97905010473, 4353.129374125125, 4396.362527433568, 4459.44256967879, 4961.677490963076, 4301.354796780508, 4668.902807840155, 5007.499250074963, 5376.865578360494, 5399.37907140676, 4484.461864576272, 4877.707337559757, 4892.969417691689, 4893.846707448711, 4910.680805747615, 2723.6323096381734, 2786.9098176367725, 2804.321872611651, 2812.0579605832277, 2845.9146225613263, 4541.750203738826, 4777.256301449946, 4924.60603151747, 5471.452854714496, 5542.75057622408, 4977.929745104748, 5272.841814745487, 5295.867603309871, 5501.587380946365, 5554.944450555524, 4438.866833994985, 4440.533743956903, 5324.0150572144275, 5841.474267316047, 5852.824415267549, 5652.349979752381, 5662.518685911744, 5678.630888992202, 5687.857803554779, 5709.200711900255, 5076.746162691777, 5233.869153271046, 5594.524465420485, 5603.467670571289, 5625.578081643975, 4779.378680771617, 5349.4753995111205, 5471.753771080391, 5643.802466913638, 5809.244798176379, 3652.543432070991, 3657.6956517394933, 3670.871519496912, 3837.9253808933545, 3839.251345987506, 5082.720450375265, 5108.412532558803, 5644.0, 5668.433156684464, 5693.4021927696995, 4293.957060429356, 4875.268709693432, 5038.47096054909, 5106.106431374569, 5509.256250406143, 4779.4742578317055, 4832.8791780205775, 5466.207399926909, 5495.450454954473, 5511.366192887869, 4883.584895283941, 4993.27597498368, 5380.730963451733, 5403.000444917703, 5405.189221616789, 5028.823991160292, 5397.057059458513, 5477.616566840283, 5478.671279723229, 5516.917246241229, 4491.755291249869, 4770.952290477051, 5022.874428139326, 5340.546053585489, 5372.301600791872], "value": 3903.395699347989, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [712.6294326949983, 737.9188289288178, 766.8888011238367, 824.5671022713075, 836.172189532363, 14049.929750351259, 14624.415023399079, 14637.048591841523, 14653.267336633166, 14667.193304368255, 13300.733985320288, 13399.799003014963, 13416.0, 13443.731125377486, 13468.326583670827, 856.6659002988823, 865.5758678247645, 873.4540911930044, 876.4040452492303, 888.8711136885148, 851.5486792000249, 880.7401816464127, 886.1227384888978, 886.6630680341493, 891.2825175733532, 932.7761337279038, 933.3093510802025, 934.4253284230183, 936.7892224249523, 938.943663380196, 927.9884925431312, 928.7956649537091, 929.0848514213494, 929.2844509727472, 932.5197523275518, 658.9703463344113, 674.7402250133684, 695.3255342317943, 696.4184905603827, 697.3653974882883, 14302.856971430361, 14494.855051449456, 14508.78236826438, 14594.70810583793, 15215.61960950974, 13548.729025419436, 13945.860541394659, 13987.580372588838, 14041.71916561663, 14046.367913443919, 11505.424728763612, 11511.769764604662, 11662.533498660043, 11691.649250522498, 12146.331951742739, 3615.5842078161086, 3630.6006339302658, 3681.208540163878, 3779.433085037239, 3786.753860999026, 102150.0, 104171.9165616691, 104510.95489045164, 105135.0, 105454.94545054527, 9526.90473095274, 10033.849492257623, 10117.291789574807, 10676.0, 10982.945085274683, 17896.91051544734, 22347.66478502824, 22405.43986400353, 22416.32751017472, 22434.214802481834, 7410.592417417094, 7411.6664750086375, 7433.182349941502, 7466.402687784964, 7482.176960534337, 4151.750894946313, 4197.727147735387, 4253.532111467736, 4304.913901721948, 4344.500382455994, 3584.1577229351083, 3713.7400381973002, 3719.9442008369897, 3806.0, 3979.1643754811234, 718.3857801579678, 734.2804052029041, 738.7894450081701, 768.7847402727245, 769.4536878815992, 511.51406164144214, 540.9215663728737, 566.2610293566852, 587.3539106982286, 593.7209511529575, 9879.950600246953, 10128.493575321352, 10219.284650074424, 10355.896441035644, 10386.688399348031, 576.3400905962733, 611.2298503885048, 671.8924972004456, 694.5034300475185, 727.1492353945896, 3661.6704496595435, 4892.09496243189, 4915.714192900318, 4955.5044495550155, 4957.540963999487, 89233.0, 89423.0, 92560.53719731358, 93270.0, 94771.52614237023, 53727.46272537303, 56816.0, 60658.0, 61047.694761525905, 79615.0, 64767.0, 70764.64617676879, 74272.08160256606, 74413.0, 75220.62389688125, 10136.746581335525, 10399.27205095636, 10761.246712730346, 10805.45972701346, 10852.348859068481, 34919.0, 37338.319775610704, 42245.788771055944, 48494.515054849, 50447.74776126096, 64291.49959502977, 65809.34190658032, 65983.0, 66016.0, 66458.66770666116, 11378.601748938747, 11954.103442241692, 12634.494620215088, 13098.083134180515, 13290.933545332211, 14132.0, 14207.786883196966, 14234.217118058607, 14278.928605356907, 14934.668946005537, 12872.806907896576, 13126.868731312947, 13291.468341266242, 13325.733485330045, 13897.583072507841, 12770.553030643883, 13087.607371778862, 13229.735405292033, 13241.80137297922, 13621.318934053455, 12827.54931196051, 12998.090133690552, 13139.802902956655, 13631.931840340734, 13681.863181368059, 10335.22485813552, 10511.0, 10698.358098514114, 10760.838587421038, 10773.407462589634, 8740.519271439878, 10033.1973442123, 10035.24735644815, 10056.64801731936, 10112.79774404493], "value": 7293.274759732492, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1469.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 113.0, 114.0, 112.0, 113.0, 112.0, 113.0, 112.0, 113.0], "value": 113.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 110.0, 110.0, 148.0, 112.0, 110.0, 110.0, 110.0, 130.0, 109.0], "value": 119.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [248.0, 247.0, 185.0, 186.0, 187.0, 184.0, 182.0, 185.0, 183.0, 185.0], "value": 197.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 59.0, 59.0, 89.0, 60.0, 59.0, 60.0, 76.0, 97.0, 64.0], "value": 70.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 70.0, 86.0, 70.0, 68.0, 69.0, 85.0, 69.0, 70.0, 84.0], "value": 76.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 85.0, 84.0, 86.0, 83.0, 87.0, 83.0, 86.0, 83.0], "value": 85.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 100.0, 98.0, 96.0, 96.0, 98.0, 115.0, 117.0, 99.0, 97.0], "value": 101.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 85.0, 85.0, 87.0, 85.0, 84.0, 95.0, 134.0, 85.0], "value": 91.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 60.0, 60.0, 60.0, 61.0, 60.0, 59.0, 58.0, 59.0, 60.0], "value": 59.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 73.0, 64.0, 77.0, 73.0, 68.0, 75.0, 70.0, 71.0, 72.0], "value": 71.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 93.0, 87.0, 85.0, 87.0, 75.0, 99.0, 98.0, 103.0], "value": 89.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 140.0, 137.0, 137.0, 132.0, 153.0, 151.0, 150.0, 184.0, 144.0], "value": 146.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 174.0, 173.0, 172.0, 179.0, 229.0, 200.0, 170.0, 171.0, 171.0], "value": 181.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 67.0, 68.0, 67.0, 66.0, 66.0, 66.0, 65.0, 65.0], "value": 66.6, "unit": "score"}]}]} 14:35:50 INFO - Return code: 0 14:35:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:35:50 ERROR - # TBPL SUCCESS # 14:35:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:35:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:35:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:35:50 INFO - 2017-02-05 14:35:50.618 screenresolution[6855:127489] starting screenresolution argv=screenresolution get 14:35:50 INFO - 2017-02-05 14:35:50.621 screenresolution[6855:127489] Display 0: 1600x1200x32@60 14:35:50 INFO - 2017-02-05 14:35:50.628 screenresolution[6856:127492] starting screenresolution argv=screenresolution list 14:35:50 INFO - Available Modes on Display 0 14:35:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:35:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:35:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:35:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:35:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:35:50 INFO - Intel Iris: 14:35:50 INFO - Chipset Model: Intel Iris 14:35:50 INFO - Type: GPU 14:35:50 INFO - Bus: Built-In 14:35:50 INFO - VRAM (Dynamic, Max): 1536 MB 14:35:50 INFO - Vendor: Intel (0x8086) 14:35:50 INFO - Device ID: 0x0a2e 14:35:50 INFO - Revision ID: 0x0009 14:35:50 INFO - Displays: 14:35:50 INFO - Display: 14:35:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:35:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:35:50 INFO - Display Serial Number: 1600x1200 60 14:35:50 INFO - Main Display: Yes 14:35:50 INFO - Mirror: Off 14:35:50 INFO - Online: Yes 14:35:50 INFO - Rotation: Supported 14:35:50 INFO - Return code: 0 14:35:50 INFO - Running post-action listener: _resource_record_post_action 14:35:50 INFO - [mozharness: 2017-02-05 22:35:50.867777Z] Finished run-tests step (success) 14:35:50 INFO - Running post-run listener: _resource_record_post_run 14:35:50 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 352768; Write bytes: 553302016; Read time: 4; Write time: 1838 14:35:50 INFO - TinderboxPrint: CPU usage
53.8% 14:35:50 INFO - TinderboxPrint: I/O read bytes / time
352,768 / 4 14:35:50 INFO - TinderboxPrint: I/O write bytes / time
553,302,016 / 1,838 14:35:50 INFO - TinderboxPrint: CPU idle
2,220.9 (46.2%) 14:35:50 INFO - TinderboxPrint: CPU system
964.7 (20.1%) 14:35:50 INFO - TinderboxPrint: CPU user
1,620.1 (33.7%) 14:35:50 INFO - TinderboxPrint: Swap in / out
251,645,952 / 0 14:35:50 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 250143232; Write bytes: 245794816; Read time: 18065; Write time: 600 14:35:50 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 270336; Write bytes: 296169472; Read time: 2; Write time: 1200 14:35:51 INFO - Running post-run listener: _upload_blobber_files 14:35:51 INFO - Blob upload gear active. 14:35:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:35:51 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 14:35:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:35:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:35:51 INFO - (blobuploader) - INFO - Open directory for files ... 14:35:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:35:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:35:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:35:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:35:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:35:52 INFO - (blobuploader) - INFO - Done attempting. 14:35:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:35:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:35:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:35:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:35:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:35:53 INFO - (blobuploader) - INFO - Done attempting. 14:35:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:35:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:35:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:35:54 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:35:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:35:54 INFO - (blobuploader) - INFO - Done attempting. 14:35:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:35:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:35:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:35:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:35:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:35:56 INFO - (blobuploader) - INFO - Done attempting. 14:35:56 INFO - (blobuploader) - INFO - Iteration through files over. 14:35:56 INFO - Return code: 0 14:35:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:35:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:35:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/26a8e0228f0fa17143c2227bf8bd8a729a7f8b5f8996498b3a2012b68599ed576ce89648b1674f97cb309edec6ff06b94eb850a1fb16da585a48473bf8d565d4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fb24fb7fd729b63a77d11171d7cd47e6194f203ea469682899ecfc4c8d78f1073895dd9db33d48067aba517fef8ce3136f6a8cb24a201c31aafa15427ed4bf80", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7617790ef59d7ac2011871e1f9c1141dade8350e1847e0715b4359c4945883e68eac504c6d317b4eaf16bec320db78cdae41f332533177befb0158c04347e863", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e581239a33733328f74c2227d2b87ffc6a6ca5ba2d4aa1641bcb1de7fd974f9fd47b38c41f1ad73b51bf1c2300dee174b9065da0cea29feec03f7fd36d9ed73"} 14:35:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:35:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:35:56 INFO - Contents: 14:35:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/26a8e0228f0fa17143c2227bf8bd8a729a7f8b5f8996498b3a2012b68599ed576ce89648b1674f97cb309edec6ff06b94eb850a1fb16da585a48473bf8d565d4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fb24fb7fd729b63a77d11171d7cd47e6194f203ea469682899ecfc4c8d78f1073895dd9db33d48067aba517fef8ce3136f6a8cb24a201c31aafa15427ed4bf80", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7617790ef59d7ac2011871e1f9c1141dade8350e1847e0715b4359c4945883e68eac504c6d317b4eaf16bec320db78cdae41f332533177befb0158c04347e863", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e581239a33733328f74c2227d2b87ffc6a6ca5ba2d4aa1641bcb1de7fd974f9fd47b38c41f1ad73b51bf1c2300dee174b9065da0cea29feec03f7fd36d9ed73"} 14:35:56 INFO - Running post-run listener: copy_logs_to_upload_dir 14:35:56 INFO - Copying logs to upload dir... 14:35:56 INFO - mkdir: /builds/slave/test/build/upload/logs 14:35:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.343116 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-02-05 14:35:57.019795) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-05 14:35:57.027590) ========= 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.SWyf8R7mo5/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.5Utl4sTHQG/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/26a8e0228f0fa17143c2227bf8bd8a729a7f8b5f8996498b3a2012b68599ed576ce89648b1674f97cb309edec6ff06b94eb850a1fb16da585a48473bf8d565d4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fb24fb7fd729b63a77d11171d7cd47e6194f203ea469682899ecfc4c8d78f1073895dd9db33d48067aba517fef8ce3136f6a8cb24a201c31aafa15427ed4bf80", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7617790ef59d7ac2011871e1f9c1141dade8350e1847e0715b4359c4945883e68eac504c6d317b4eaf16bec320db78cdae41f332533177befb0158c04347e863", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e581239a33733328f74c2227d2b87ffc6a6ca5ba2d4aa1641bcb1de7fd974f9fd47b38c41f1ad73b51bf1c2300dee174b9065da0cea29feec03f7fd36d9ed73"} build_url:https://queue.taskcluster.net/v1/task/GmTt9U59QleKKFMwB6xn6Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011317 build_url: 'https://queue.taskcluster.net/v1/task/GmTt9U59QleKKFMwB6xn6Q/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/26a8e0228f0fa17143c2227bf8bd8a729a7f8b5f8996498b3a2012b68599ed576ce89648b1674f97cb309edec6ff06b94eb850a1fb16da585a48473bf8d565d4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fb24fb7fd729b63a77d11171d7cd47e6194f203ea469682899ecfc4c8d78f1073895dd9db33d48067aba517fef8ce3136f6a8cb24a201c31aafa15427ed4bf80", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7617790ef59d7ac2011871e1f9c1141dade8350e1847e0715b4359c4945883e68eac504c6d317b4eaf16bec320db78cdae41f332533177befb0158c04347e863", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e581239a33733328f74c2227d2b87ffc6a6ca5ba2d4aa1641bcb1de7fd974f9fd47b38c41f1ad73b51bf1c2300dee174b9065da0cea29feec03f7fd36d9ed73"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-05 14:35:57.064834) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-05 14:35:57.065135) ========= 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.SWyf8R7mo5/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.5Utl4sTHQG/Listeners TMPDIR=/var/folders/bj/hz090k0x51d88nc483dscv7m00000w/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.005428 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-05 14:35:57.113950) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-05 14:35:57.117268) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-05 14:35:57.117573) ========= ========= Total master_lag: 0.20 =========