builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0263 starttime: 1480073076.5 results: success (0) buildid: 20161125004006 builduid: f6a669292bb14189aadc8562cd50e652 revision: 55b599ca3530c8286f6ad15405d99d2bcaecfbd1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-25 03:24:36.496793) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-25 03:24:36.497249) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-25 03:24:36.497526) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-25 03:24:36.525789) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 03:24:36.526165) ========= 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.fLMkiZGKe7/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.ASCwAxYeS3/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-25 03:24:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.02M=0.001s 2016-11-25 03:24:36 (8.02 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.091370 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 03:24:36.646179) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 03:24:36.646586) ========= 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.fLMkiZGKe7/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.ASCwAxYeS3/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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.019758 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 03:24:36.699523) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-25 03:24:36.701923) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 55b599ca3530c8286f6ad15405d99d2bcaecfbd1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 55b599ca3530c8286f6ad15405d99d2bcaecfbd1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.fLMkiZGKe7/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.ASCwAxYeS3/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-25 03:24:36,784 truncating revision to first 12 chars 2016-11-25 03:24:36,785 Setting DEBUG logging. 2016-11-25 03:24:36,785 attempt 1/10 2016-11-25 03:24:36,785 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/55b599ca3530?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-25 03:24:37,900 unpacking tar archive at: mozilla-aurora-55b599ca3530/testing/mozharness/ program finished with exit code 0 elapsedTime=1.383711 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-25 03:24:38.113323) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-25 03:24:38.113662) ========= script_repo_revision: 55b599ca3530c8286f6ad15405d99d2bcaecfbd1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-25 03:24:38.114081) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-25 03:24:38.114350) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-25 03:24:38.137271) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-25 03:24:38.137669) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.fLMkiZGKe7/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.ASCwAxYeS3/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 03:24:38 INFO - MultiFileLogger online at 20161125 03:24:38 in /builds/slave/test 03:24:38 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:24:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:24:38 INFO - {'append_to_log': False, 03:24:38 INFO - 'base_work_dir': '/builds/slave/test', 03:24:38 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:24:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:24:38 INFO - 'branch': 'Mozilla-Aurora', 03:24:38 INFO - 'buildbot_json_path': 'buildprops.json', 03:24:38 INFO - 'config_files': ('talos/mac_config.py',), 03:24:38 INFO - 'default_actions': ('clobber', 03:24:38 INFO - 'read-buildbot-config', 03:24:38 INFO - 'download-and-extract', 03:24:38 INFO - 'populate-webroot', 03:24:38 INFO - 'create-virtualenv', 03:24:38 INFO - 'install', 03:24:38 INFO - 'run-tests'), 03:24:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:24:38 INFO - 'download_minidump_stackwalk': True, 03:24:38 INFO - 'download_symbols': 'ondemand', 03:24:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:24:38 INFO - 'tooltool.py': '/tools/tooltool.py', 03:24:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:24:38 INFO - '/tools/misc-python/virtualenv.py')}, 03:24:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:24:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:24:38 INFO - 'installer_path': 'installer.exe', 03:24:38 INFO - 'log_level': 'info', 03:24:38 INFO - 'log_name': 'talos', 03:24:38 INFO - 'log_to_console': True, 03:24:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:24:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:24:38 INFO - 'opt_config_files': (), 03:24:38 INFO - 'pip_index': False, 03:24:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:24:38 INFO - 'cmd': ('bash', 03:24:38 INFO - '-c', 03:24:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:24:38 INFO - 'enabled': True, 03:24:38 INFO - 'halt_on_failure': False, 03:24:38 INFO - 'name': 'check_screen_resolution'},), 03:24:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:24:38 INFO - 'cmd': ('bash', 03:24:38 INFO - '-c', 03:24:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:24:38 INFO - 'enabled': True, 03:24:38 INFO - 'halt_on_failure': False, 03:24:38 INFO - 'name': 'check_screen_resolution'},), 03:24:38 INFO - 'run_cmd_checks_enabled': True, 03:24:38 INFO - 'sps_profile': False, 03:24:38 INFO - 'sps_profile_interval': 0, 03:24:38 INFO - 'suite': 'dromaeojs', 03:24:38 INFO - 'system_bits': '32', 03:24:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:24:38 INFO - 'title': 't-yosemite-r7-0263', 03:24:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:24:38 INFO - 'use_talos_json': True, 03:24:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:24:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:24:38 INFO - 'work_dir': 'build'} 03:24:38 INFO - [mozharness: 2016-11-25 11:24:38.337933Z] Running clobber step. 03:24:38 INFO - Running pre-action listener: _resource_record_pre_action 03:24:38 INFO - Running main action method: clobber 03:24:38 INFO - rmtree: /builds/slave/test/build 03:24:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:24:38 INFO - Running post-action listener: _resource_record_post_action 03:24:38 INFO - [mozharness: 2016-11-25 11:24:38.506858Z] Finished clobber step (success) 03:24:38 INFO - [mozharness: 2016-11-25 11:24:38.506979Z] Running read-buildbot-config step. 03:24:38 INFO - Running pre-action listener: _resource_record_pre_action 03:24:38 INFO - Running main action method: read_buildbot_config 03:24:38 INFO - Using buildbot properties: 03:24:38 INFO - { 03:24:38 INFO - "project": "", 03:24:38 INFO - "product": "firefox", 03:24:38 INFO - "script_repo_revision": "production", 03:24:38 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 03:24:38 INFO - "repository": "", 03:24:38 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 03:24:38 INFO - "buildid": "20161125004006", 03:24:38 INFO - "stage_platform": "macosx64", 03:24:38 INFO - "basedir": "/builds/slave/test", 03:24:38 INFO - "nightly_build": "True", 03:24:38 INFO - "buildnumber": 8, 03:24:38 INFO - "slavename": "t-yosemite-r7-0263", 03:24:38 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 03:24:38 INFO - "platform": "yosemite_r7", 03:24:38 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:24:38 INFO - "branch": "mozilla-aurora", 03:24:38 INFO - "repo_path": "releases/mozilla-aurora", 03:24:38 INFO - "revision": "55b599ca3530c8286f6ad15405d99d2bcaecfbd1", 03:24:38 INFO - "pgo_build": "False", 03:24:38 INFO - "builduid": "f6a669292bb14189aadc8562cd50e652", 03:24:38 INFO - "slavebuilddir": "test" 03:24:38 INFO - } 03:24:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/eSO68kuZT2ufbhU_aVk-LQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 03:24:38 INFO - Running post-action listener: _resource_record_post_action 03:24:38 INFO - [mozharness: 2016-11-25 11:24:38.510377Z] Finished read-buildbot-config step (success) 03:24:38 INFO - [mozharness: 2016-11-25 11:24:38.510486Z] Running download-and-extract step. 03:24:38 INFO - Running pre-action listener: _resource_record_pre_action 03:24:38 INFO - Running main action method: download_and_extract 03:24:38 INFO - mkdir: /builds/slave/test/build/tests 03:24:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:24:38 INFO - trying https://queue.taskcluster.net/v1/task/eSO68kuZT2ufbhU_aVk-LQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 03:24:38 INFO - Downloading https://queue.taskcluster.net/v1/task/eSO68kuZT2ufbhU_aVk-LQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 03:24:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eSO68kuZT2ufbhU_aVk-LQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 03:24:39 INFO - Downloaded 1199 bytes. 03:24:39 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 03:24:39 INFO - Using the following test package requirements: 03:24:39 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 03:24:39 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:24:39 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 03:24:39 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:24:39 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 03:24:39 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:24:39 INFO - u'jsshell-mac.zip'], 03:24:39 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:24:39 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 03:24:39 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 03:24:39 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:24:39 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 03:24:39 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:24:39 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 03:24:39 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:24:39 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 03:24:39 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:24:39 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 03:24:39 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:24:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eSO68kuZT2ufbhU_aVk-LQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 03:24:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eSO68kuZT2ufbhU_aVk-LQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:24:39 INFO - Fetch https://queue.taskcluster.net/v1/task/eSO68kuZT2ufbhU_aVk-LQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 03:24:40 INFO - Http code: 200 03:24:40 INFO - via: 1.1 b3bccc0e4c2cc0a33e7e8308d88cacdd.cloudfront.net (CloudFront) 03:24:40 INFO - x-amz-cf-id: Rdw9QgzkGRxXsq28h1HthJhiFm8psHWTfb6zkos9YpxLzCpu7YrI_g== 03:24:40 INFO - x-amz-version-id: Uypsr9Inp46_rdG5nJGa6F_9N0zHkjX5 03:24:40 INFO - Expected file size: 16279121 03:24:40 INFO - Obtained file size: 16279121 03:24:47 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 03:24:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eSO68kuZT2ufbhU_aVk-LQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 03:24:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eSO68kuZT2ufbhU_aVk-LQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:24:47 INFO - Fetch https://queue.taskcluster.net/v1/task/eSO68kuZT2ufbhU_aVk-LQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 03:24:47 INFO - Http code: 200 03:24:47 INFO - via: 1.1 b9a8ce441329f20cea895e08f19f1ec8.cloudfront.net (CloudFront) 03:24:47 INFO - x-amz-cf-id: J8pwOVCsHW7I8ynIRqED6beJSeC7QvlJoJBgaB0q0mWKNMo6NPwrww== 03:24:47 INFO - x-amz-version-id: Uypsr9Inp46_rdG5nJGa6F_9N0zHkjX5 03:24:48 INFO - Expected file size: 16279121 03:24:48 INFO - Obtained file size: 16279121 03:24:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eSO68kuZT2ufbhU_aVk-LQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 03:24:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eSO68kuZT2ufbhU_aVk-LQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 03:24:54 INFO - Fetch https://queue.taskcluster.net/v1/task/eSO68kuZT2ufbhU_aVk-LQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 03:24:55 INFO - Http code: 200 03:24:55 INFO - via: 1.1 b3bccc0e4c2cc0a33e7e8308d88cacdd.cloudfront.net (CloudFront) 03:24:55 INFO - x-amz-cf-id: 1bTzKjOQIB4gZ1tqiHZtiNMZdYIUlJ5pY3Znc6bWfYotEABmfF370w== 03:24:55 INFO - x-amz-version-id: XsR3cMESvlxnaFUgB7zAJAbWaq2F9nPV 03:24:55 INFO - Expected file size: 14409038 03:24:55 INFO - Obtained file size: 14409038 03:24:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:24:55 INFO - trying https://queue.taskcluster.net/v1/task/eSO68kuZT2ufbhU_aVk-LQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:24:55 INFO - Downloading https://queue.taskcluster.net/v1/task/eSO68kuZT2ufbhU_aVk-LQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 03:24:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eSO68kuZT2ufbhU_aVk-LQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 03:24:58 INFO - Downloaded 109721386 bytes. 03:24:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eSO68kuZT2ufbhU_aVk-LQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:24:58 INFO - mkdir: /builds/slave/test/properties 03:24:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:24:58 INFO - Writing to file /builds/slave/test/properties/build_url 03:24:58 INFO - Contents: 03:24:58 INFO - build_url:https://queue.taskcluster.net/v1/task/eSO68kuZT2ufbhU_aVk-LQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:24:59 INFO - Running post-action listener: _resource_record_post_action 03:24:59 INFO - Running post-action listener: set_extra_try_arguments 03:24:59 INFO - [mozharness: 2016-11-25 11:24:59.044198Z] Finished download-and-extract step (success) 03:24:59 INFO - [mozharness: 2016-11-25 11:24:59.044288Z] Running populate-webroot step. 03:24:59 INFO - Running pre-action listener: _resource_record_pre_action 03:24:59 INFO - Running main action method: populate_webroot 03:24:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:24:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:24:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:24:59 INFO - '--tppagecycles', 03:24:59 INFO - '7'], 03:24:59 INFO - 'tests': ['tsvgm']}}, 03:24:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:24:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:24:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:24:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:24:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:24:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:24:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:24:59 INFO - 'talos_options': ['--disable-e10s'], 03:24:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:24:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:24:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:24:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:24:59 INFO - 'talos_options': ['--disable-e10s'], 03:24:59 INFO - 'tests': ['damp', 'tps']}, 03:24:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:24:59 INFO - 'tests': ['damp', 'tps']}, 03:24:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:24:59 INFO - 'tests': ['dromaeo_dom']}, 03:24:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:24:59 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:24:59 INFO - 'tests': ['basic_compositor_video']}, 03:24:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:24:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:24:59 INFO - 'tests': ['a11yr', 03:24:59 INFO - 'ts_paint', 03:24:59 INFO - 'tpaint', 03:24:59 INFO - 'sessionrestore', 03:24:59 INFO - 'sessionrestore_no_auto_restore', 03:24:59 INFO - 'tabpaint']}, 03:24:59 INFO - 'other-e10s': {'tests': ['a11yr', 03:24:59 INFO - 'ts_paint', 03:24:59 INFO - 'tpaint', 03:24:59 INFO - 'sessionrestore', 03:24:59 INFO - 'sessionrestore_no_auto_restore', 03:24:59 INFO - 'tabpaint']}, 03:24:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:24:59 INFO - 'ts_paint', 03:24:59 INFO - 'tpaint', 03:24:59 INFO - 'sessionrestore', 03:24:59 INFO - 'sessionrestore_no_auto_restore', 03:24:59 INFO - 'tabpaint']}, 03:24:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:24:59 INFO - 'ts_paint', 03:24:59 INFO - 'tpaint', 03:24:59 INFO - 'sessionrestore', 03:24:59 INFO - 'sessionrestore_no_auto_restore', 03:24:59 INFO - 'tabpaint']}, 03:24:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:24:59 INFO - 'tests': ['a11yr', 03:24:59 INFO - 'ts_paint', 03:24:59 INFO - 'tpaint', 03:24:59 INFO - 'sessionrestore', 03:24:59 INFO - 'sessionrestore_no_auto_restore', 03:24:59 INFO - 'tabpaint']}, 03:24:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:24:59 INFO - 'tests': ['a11yr', 03:24:59 INFO - 'ts_paint', 03:24:59 INFO - 'tpaint', 03:24:59 INFO - 'sessionrestore', 03:24:59 INFO - 'sessionrestore_no_auto_restore', 03:24:59 INFO - 'tabpaint']}, 03:24:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:24:59 INFO - 'tests': ['tsvgx', 03:24:59 INFO - 'tsvgr_opacity', 03:24:59 INFO - 'tart', 03:24:59 INFO - 'tscrollx', 03:24:59 INFO - 'cart']}, 03:24:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:24:59 INFO - 'tsvgr_opacity', 03:24:59 INFO - 'tart', 03:24:59 INFO - 'tscrollx', 03:24:59 INFO - 'cart']}, 03:24:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:24:59 INFO - 'talos_options': ['--disable-e10s'], 03:24:59 INFO - 'tests': ['tp5o']}, 03:24:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:24:59 INFO - 'tests': ['tp5o']}, 03:24:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:24:59 INFO - 'talos_options': ['--disable-e10s', 03:24:59 INFO - '--xperf_path', 03:24:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:24:59 INFO - 'tests': ['tp5n']}, 03:24:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:24:59 INFO - 'talos_options': ['--xperf_path', 03:24:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:24:59 INFO - 'tests': ['tp5n']}}, 03:24:59 INFO - 'talos.zip': {'path': '', 03:24:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:24:59 INFO - Running post-action listener: _resource_record_post_action 03:24:59 INFO - [mozharness: 2016-11-25 11:24:59.063234Z] Finished populate-webroot step (success) 03:24:59 INFO - [mozharness: 2016-11-25 11:24:59.063329Z] Running create-virtualenv step. 03:24:59 INFO - Running pre-action listener: _resource_record_pre_action 03:24:59 INFO - Running main action method: create_virtualenv 03:24:59 INFO - Creating virtualenv /builds/slave/test/build/venv 03:24:59 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 03:24:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:24:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:24:59 INFO - Using real prefix '/tools/python27' 03:24:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:24:59 INFO - Installing distribute.............................................................................................................................................................................................done. 03:25:02 INFO - Installing pip.................done. 03:25:02 INFO - Return code: 0 03:25:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:25:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:25:02 INFO - Reading from file tmpfile_stdout 03:25:02 INFO - Output received: 03:25:02 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) 03:25:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:25:02 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')]} 03:25:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:02 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')]} 03:25:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:02 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 0x10b0a0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b016570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd33ad99d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeb4d30>, '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 0x10ad1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aeacb20>, '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.fLMkiZGKe7/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.ASCwAxYeS3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 03:25:02 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 03:25:02 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 03:25:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fLMkiZGKe7/Render', 03:25:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:25:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:25:02 INFO - 'HOME': '/Users/cltbld', 03:25:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:25:02 INFO - 'LOGNAME': 'cltbld', 03:25:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:02 INFO - 'MOZ_NO_REMOTE': '1', 03:25:02 INFO - 'NO_EM_RESTART': '1', 03:25:02 INFO - 'PAGER': '/bin/cat', 03:25:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:25:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:02 INFO - 'PWD': '/builds/slave/test', 03:25:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:25:03 INFO - 'SHELL': '/bin/bash', 03:25:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ASCwAxYeS3/Listeners', 03:25:03 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 03:25:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:25:03 INFO - 'USER': 'cltbld', 03:25:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:25:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:25:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:03 INFO - 'XPC_FLAGS': '0x0', 03:25:03 INFO - 'XPC_SERVICE_NAME': '0', 03:25:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:25:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:03 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 03:25:03 INFO - Cleaning up... 03:25:03 INFO - Return code: 0 03:25:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:25:03 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')]} 03:25:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:03 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')]} 03:25:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:03 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 0x10b0a0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b016570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd33ad99d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeb4d30>, '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 0x10ad1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aeacb20>, '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.fLMkiZGKe7/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.ASCwAxYeS3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 03:25:03 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 03:25:03 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 03:25:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fLMkiZGKe7/Render', 03:25:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:25:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:25:03 INFO - 'HOME': '/Users/cltbld', 03:25:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:25:03 INFO - 'LOGNAME': 'cltbld', 03:25:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:03 INFO - 'MOZ_NO_REMOTE': '1', 03:25:03 INFO - 'NO_EM_RESTART': '1', 03:25:03 INFO - 'PAGER': '/bin/cat', 03:25:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:25:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:03 INFO - 'PWD': '/builds/slave/test', 03:25:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:25:03 INFO - 'SHELL': '/bin/bash', 03:25:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ASCwAxYeS3/Listeners', 03:25:03 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 03:25:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:25:03 INFO - 'USER': 'cltbld', 03:25:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:25:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:25:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:03 INFO - 'XPC_FLAGS': '0x0', 03:25:03 INFO - 'XPC_SERVICE_NAME': '0', 03:25:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:25:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:03 INFO - Downloading/unpacking psutil>=3.1.1 03:25:03 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 03:25:03 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 03:25:03 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 03:25:03 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 03:25:03 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 03:25:03 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 03:25:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:25:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:25:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:25:07 INFO - Installing collected packages: psutil 03:25:07 INFO - Running setup.py install for psutil 03:25:07 INFO - building 'psutil._psutil_osx' extension 03:25:07 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 03:25:07 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 03:25:07 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 03:25:07 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 03:25:07 INFO - building 'psutil._psutil_posix' extension 03:25:07 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 03:25:07 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:25:07 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:25:07 INFO - ^ 03:25:07 INFO - 1 warning generated. 03:25:07 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 03:25:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:25:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:25:07 INFO - Successfully installed psutil 03:25:07 INFO - Cleaning up... 03:25:08 INFO - Return code: 0 03:25:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:25:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:25:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:25:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:08 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 0x10b0a0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b016570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd33ad99d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeb4d30>, '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 0x10ad1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aeacb20>, '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.fLMkiZGKe7/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.ASCwAxYeS3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 03:25:08 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 03:25:08 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 03:25:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fLMkiZGKe7/Render', 03:25:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:25:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:25:08 INFO - 'HOME': '/Users/cltbld', 03:25:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:25:08 INFO - 'LOGNAME': 'cltbld', 03:25:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:08 INFO - 'MOZ_NO_REMOTE': '1', 03:25:08 INFO - 'NO_EM_RESTART': '1', 03:25:08 INFO - 'PAGER': '/bin/cat', 03:25:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:25:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:08 INFO - 'PWD': '/builds/slave/test', 03:25:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:25:08 INFO - 'SHELL': '/bin/bash', 03:25:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ASCwAxYeS3/Listeners', 03:25:08 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 03:25:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:25:08 INFO - 'USER': 'cltbld', 03:25:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:25:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:25:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:08 INFO - 'XPC_FLAGS': '0x0', 03:25:08 INFO - 'XPC_SERVICE_NAME': '0', 03:25:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:25:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:25:08 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 03:25:08 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 03:25:08 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 03:25:08 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 03:25:08 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 03:25:08 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 03:25:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:25:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:25:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:25:10 INFO - Installing collected packages: mozsystemmonitor 03:25:10 INFO - Running setup.py install for mozsystemmonitor 03:25:11 INFO - Successfully installed mozsystemmonitor 03:25:11 INFO - Cleaning up... 03:25:11 INFO - Return code: 0 03:25:11 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:25: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')]} 03:25:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25: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')]} 03:25:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b0a0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b016570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd33ad99d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeb4d30>, '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 0x10ad1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aeacb20>, '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.fLMkiZGKe7/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.ASCwAxYeS3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 03:25:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:25:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:25:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fLMkiZGKe7/Render', 03:25:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:25:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:25:11 INFO - 'HOME': '/Users/cltbld', 03:25:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:25:11 INFO - 'LOGNAME': 'cltbld', 03:25:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:11 INFO - 'MOZ_NO_REMOTE': '1', 03:25:11 INFO - 'NO_EM_RESTART': '1', 03:25:11 INFO - 'PAGER': '/bin/cat', 03:25:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:25:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:11 INFO - 'PWD': '/builds/slave/test', 03:25:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:25:11 INFO - 'SHELL': '/bin/bash', 03:25:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ASCwAxYeS3/Listeners', 03:25:11 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 03:25:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:25:11 INFO - 'USER': 'cltbld', 03:25:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:25:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:25:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:11 INFO - 'XPC_FLAGS': '0x0', 03:25:11 INFO - 'XPC_SERVICE_NAME': '0', 03:25:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:25:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:11 INFO - Downloading/unpacking jsonschema==2.5.1 03:25:11 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 03:25:11 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 03:25:11 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 03:25:11 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 03:25:11 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 03:25:11 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 03:25:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:25:13 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:25:13 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 03:25:13 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 03:25:13 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 03:25:13 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 03:25:13 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 03:25:13 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 03:25:14 INFO - Downloading functools32-3.2.3-2.tar.gz 03:25:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:25:14 INFO - warning: no files found matching '*.txt' 03:25:14 INFO - no previously-included directories found matching 'build' 03:25:14 INFO - no previously-included directories found matching 'dist' 03:25:14 INFO - no previously-included directories found matching '.git*' 03:25:14 INFO - Installing collected packages: jsonschema, functools32 03:25:14 INFO - Running setup.py install for functools32 03:25:14 INFO - warning: no files found matching '*.txt' 03:25:14 INFO - no previously-included directories found matching 'build' 03:25:14 INFO - no previously-included directories found matching 'dist' 03:25:14 INFO - no previously-included directories found matching '.git*' 03:25:14 INFO - Successfully installed jsonschema functools32 03:25:14 INFO - Cleaning up... 03:25:14 INFO - Return code: 0 03:25:14 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:25:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:25:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:25:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:14 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 0x10b0a0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b016570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd33ad99d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeb4d30>, '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 0x10ad1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aeacb20>, '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.fLMkiZGKe7/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.ASCwAxYeS3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 03:25:14 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 03:25:14 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 03:25:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fLMkiZGKe7/Render', 03:25:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:25:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:25:14 INFO - 'HOME': '/Users/cltbld', 03:25:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:25:14 INFO - 'LOGNAME': 'cltbld', 03:25:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:14 INFO - 'MOZ_NO_REMOTE': '1', 03:25:14 INFO - 'NO_EM_RESTART': '1', 03:25:14 INFO - 'PAGER': '/bin/cat', 03:25:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:25:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:14 INFO - 'PWD': '/builds/slave/test', 03:25:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:25:14 INFO - 'SHELL': '/bin/bash', 03:25:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ASCwAxYeS3/Listeners', 03:25:14 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 03:25:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:25:14 INFO - 'USER': 'cltbld', 03:25:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:25:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:25:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:14 INFO - 'XPC_FLAGS': '0x0', 03:25:14 INFO - 'XPC_SERVICE_NAME': '0', 03:25:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:25:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:14 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:25:14 INFO - Cleaning up... 03:25:14 INFO - Return code: 0 03:25:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:25:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:25:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:25:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:14 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 0x10b0a0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b016570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd33ad99d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeb4d30>, '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 0x10ad1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aeacb20>, '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.fLMkiZGKe7/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.ASCwAxYeS3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 03:25:14 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 03:25:14 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 03:25:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fLMkiZGKe7/Render', 03:25:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:25:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:25:14 INFO - 'HOME': '/Users/cltbld', 03:25:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:25:14 INFO - 'LOGNAME': 'cltbld', 03:25:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:14 INFO - 'MOZ_NO_REMOTE': '1', 03:25:14 INFO - 'NO_EM_RESTART': '1', 03:25:14 INFO - 'PAGER': '/bin/cat', 03:25:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:25:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:14 INFO - 'PWD': '/builds/slave/test', 03:25:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:25:14 INFO - 'SHELL': '/bin/bash', 03:25:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ASCwAxYeS3/Listeners', 03:25:14 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 03:25:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:25:14 INFO - 'USER': 'cltbld', 03:25:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:25:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:25:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:14 INFO - 'XPC_FLAGS': '0x0', 03:25:14 INFO - 'XPC_SERVICE_NAME': '0', 03:25:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:25:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:15 INFO - Downloading/unpacking blobuploader==1.2.4 03:25:15 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 03:25:15 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 03:25:15 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 03:25:15 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 03:25:15 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 03:25:15 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 03:25:17 INFO - Downloading blobuploader-1.2.4.tar.gz 03:25:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:25:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:25:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:25:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:25:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:25:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:25:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:25:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:25:18 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 03:25:18 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 03:25:18 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 03:25:18 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 03:25:18 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 03:25:18 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 03:25:18 INFO - Downloading docopt-0.6.1.tar.gz 03:25:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:25:19 INFO - Installing collected packages: blobuploader, requests, docopt 03:25:19 INFO - Running setup.py install for blobuploader 03:25:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:25:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:25:19 INFO - Running setup.py install for requests 03:25:19 INFO - Running setup.py install for docopt 03:25:19 INFO - Successfully installed blobuploader requests docopt 03:25:19 INFO - Cleaning up... 03:25:20 INFO - Return code: 0 03:25:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:25: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')]} 03:25:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25: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')]} 03:25:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:20 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 0x10b0a0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b016570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd33ad99d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeb4d30>, '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 0x10ad1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aeacb20>, '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.fLMkiZGKe7/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.ASCwAxYeS3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 03:25:20 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 03:25:20 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 03:25:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fLMkiZGKe7/Render', 03:25:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:25:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:25:20 INFO - 'HOME': '/Users/cltbld', 03:25:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:25:20 INFO - 'LOGNAME': 'cltbld', 03:25:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:20 INFO - 'MOZ_NO_REMOTE': '1', 03:25:20 INFO - 'NO_EM_RESTART': '1', 03:25:20 INFO - 'PAGER': '/bin/cat', 03:25:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:25:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:20 INFO - 'PWD': '/builds/slave/test', 03:25:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:25:20 INFO - 'SHELL': '/bin/bash', 03:25:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ASCwAxYeS3/Listeners', 03:25:20 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 03:25:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:25:20 INFO - 'USER': 'cltbld', 03:25:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:25:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:25:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:20 INFO - 'XPC_FLAGS': '0x0', 03:25:20 INFO - 'XPC_SERVICE_NAME': '0', 03:25:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:25:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:25:20 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-lp7DZq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:25:20 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-qizAN9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:25:20 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-dxne_l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:25:20 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-0YNCqo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:25:20 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-U810D1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:25:20 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-3xoUTr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:25:20 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-Wo2l19-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:25:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:25:21 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-3XEEby-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:25:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:25:21 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-i70c4H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:25:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:25:21 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-54dMIv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:25:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:25:21 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-aorf94-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:25:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:25:21 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-HDlPFD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:25:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:25:21 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-UsTp56-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:25:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:25:21 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-NfC3P8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:25:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:25:21 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-RJRD3K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:25:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:25:21 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-BEcE39-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:25:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:25:21 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-Wokgbk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:25:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:25:22 INFO - Running setup.py install for manifestparser 03:25:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:25:22 INFO - Running setup.py install for mozcrash 03:25:22 INFO - Running setup.py install for mozdebug 03:25:22 INFO - Running setup.py install for mozdevice 03:25:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:25:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:25:22 INFO - Running setup.py install for mozfile 03:25:22 INFO - Running setup.py install for mozhttpd 03:25:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:25:22 INFO - Running setup.py install for mozinfo 03:25:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:25:23 INFO - Running setup.py install for mozInstall 03:25:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:25:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:25:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:25:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:25:23 INFO - Running setup.py install for mozleak 03:25:23 INFO - Running setup.py install for mozlog 03:25:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:25:23 INFO - Running setup.py install for moznetwork 03:25:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:25:23 INFO - Running setup.py install for mozprocess 03:25:23 INFO - Running setup.py install for mozprofile 03:25:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:25:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:25:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:25:24 INFO - Running setup.py install for mozrunner 03:25:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:25:24 INFO - Running setup.py install for mozscreenshot 03:25:24 INFO - Running setup.py install for moztest 03:25:24 INFO - Running setup.py install for mozversion 03:25:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:25:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:25:24 INFO - Cleaning up... 03:25:24 INFO - Return code: 0 03:25:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:25:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:25:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:25:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:24 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 0x10b0a0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b016570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd33ad99d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeb4d30>, '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 0x10ad1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aeacb20>, '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.fLMkiZGKe7/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.ASCwAxYeS3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 03:25:24 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 03:25:24 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 03:25:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fLMkiZGKe7/Render', 03:25:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:25:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:25:24 INFO - 'HOME': '/Users/cltbld', 03:25:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:25:24 INFO - 'LOGNAME': 'cltbld', 03:25:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:24 INFO - 'MOZ_NO_REMOTE': '1', 03:25:24 INFO - 'NO_EM_RESTART': '1', 03:25:24 INFO - 'PAGER': '/bin/cat', 03:25:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:25:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:24 INFO - 'PWD': '/builds/slave/test', 03:25:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:25:24 INFO - 'SHELL': '/bin/bash', 03:25:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ASCwAxYeS3/Listeners', 03:25:24 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 03:25:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:25:24 INFO - 'USER': 'cltbld', 03:25:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:25:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:25:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:24 INFO - 'XPC_FLAGS': '0x0', 03:25:24 INFO - 'XPC_SERVICE_NAME': '0', 03:25:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:25:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:25:24 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-EqXQTh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:25:25 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)) 03:25:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:25:25 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-JftdmY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:25:25 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)) 03:25:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:25:25 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-_zZbnB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:25:25 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)) 03:25:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:25:25 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-XPDgMy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:25:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:25:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:25:25 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-YYnnel-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:25:25 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)) 03:25:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:25:25 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-hFFWFJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:25:25 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)) 03:25:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:25:25 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-TqV1Zl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:25:25 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)) 03:25:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:25:25 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-t2AbMe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:25:25 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)) 03:25:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:25:25 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-UzyOUI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:25:25 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)) 03:25:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:25:25 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-4n_wyL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:25:25 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)) 03:25:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:25:25 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-0vHFBb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:25:26 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)) 03:25:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:25:26 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-scO_CW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:25:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:25:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:25:26 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-hWNzEW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:25:26 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)) 03:25:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:25:26 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-9qawgm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:25:26 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)) 03:25:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:25:26 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-kHSZ0h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:25:26 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)) 03:25:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:25:26 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-4lFIzi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:25:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:25:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:25:26 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-JQJngG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:25:26 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)) 03:25:26 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)) 03:25:26 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)) 03:25:26 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)) 03:25:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:25:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:25:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:25:26 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 03:25:26 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 03:25:26 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 03:25:26 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 03:25:26 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 03:25:26 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 03:25:29 INFO - Downloading blessings-1.6.tar.gz 03:25:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:25:29 INFO - Installing collected packages: blessings 03:25:29 INFO - Running setup.py install for blessings 03:25:29 INFO - Successfully installed blessings 03:25:29 INFO - Cleaning up... 03:25:29 INFO - Return code: 0 03:25:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:25:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:25:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:25:29 INFO - Reading from file tmpfile_stdout 03:25:29 INFO - Current package versions: 03:25:29 INFO - blessings == 1.6 03:25:29 INFO - blobuploader == 1.2.4 03:25:29 INFO - docopt == 0.6.1 03:25:29 INFO - functools32 == 3.2.3-2 03:25:29 INFO - jsonschema == 2.5.1 03:25:29 INFO - manifestparser == 1.1 03:25:29 INFO - mozInstall == 1.12 03:25:29 INFO - mozcrash == 1.0 03:25:29 INFO - mozdebug == 0.1 03:25:29 INFO - mozdevice == 0.48 03:25:29 INFO - mozfile == 1.2 03:25:29 INFO - mozhttpd == 0.7 03:25:29 INFO - mozinfo == 0.9 03:25:29 INFO - mozleak == 0.1 03:25:29 INFO - mozlog == 3.4 03:25:29 INFO - moznetwork == 0.27 03:25:29 INFO - mozprocess == 0.23 03:25:29 INFO - mozprofile == 0.28 03:25:29 INFO - mozrunner == 6.13 03:25:29 INFO - mozscreenshot == 0.1 03:25:29 INFO - mozsystemmonitor == 0.3 03:25:29 INFO - moztest == 0.7 03:25:29 INFO - mozversion == 1.4 03:25:29 INFO - psutil == 3.1.1 03:25:29 INFO - requests == 1.2.3 03:25:29 INFO - wsgiref == 0.1.2 03:25:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:25:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:25:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:30 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')]} 03:25:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:30 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 0x10b0a0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b016570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd33ad99d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeb4d30>, '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 0x10ad1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aeacb20>, '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.fLMkiZGKe7/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.ASCwAxYeS3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 03:25:30 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 03:25:30 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 03:25:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fLMkiZGKe7/Render', 03:25:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:25:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:25:30 INFO - 'HOME': '/Users/cltbld', 03:25:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:25:30 INFO - 'LOGNAME': 'cltbld', 03:25:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:30 INFO - 'MOZ_NO_REMOTE': '1', 03:25:30 INFO - 'NO_EM_RESTART': '1', 03:25:30 INFO - 'PAGER': '/bin/cat', 03:25:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:25:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:30 INFO - 'PWD': '/builds/slave/test', 03:25:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:25:30 INFO - 'SHELL': '/bin/bash', 03:25:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ASCwAxYeS3/Listeners', 03:25:30 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 03:25:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:25:30 INFO - 'USER': 'cltbld', 03:25:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:25:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:25:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:30 INFO - 'XPC_FLAGS': '0x0', 03:25:30 INFO - 'XPC_SERVICE_NAME': '0', 03:25:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:25:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:31 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)) 03:25:31 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)) 03:25:31 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)) 03:25:31 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)) 03:25:31 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)) 03:25:31 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)) 03:25:31 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)) 03:25:31 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)) 03:25:31 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)) 03:25:31 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)) 03:25:31 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)) 03:25:31 INFO - Cleaning up... 03:25:31 INFO - Return code: 0 03:25:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:25:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:25:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:25:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:25:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:25:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:25:31 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 0x10b0a0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b016570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd33ad99d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeb4d30>, '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 0x10ad1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aeacb20>, '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.fLMkiZGKe7/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.ASCwAxYeS3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 03:25:31 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 03:25:31 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 03:25:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fLMkiZGKe7/Render', 03:25:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:25:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:25:31 INFO - 'HOME': '/Users/cltbld', 03:25:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:25:31 INFO - 'LOGNAME': 'cltbld', 03:25:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:31 INFO - 'MOZ_NO_REMOTE': '1', 03:25:31 INFO - 'NO_EM_RESTART': '1', 03:25:31 INFO - 'PAGER': '/bin/cat', 03:25:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:25:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:31 INFO - 'PWD': '/builds/slave/test', 03:25:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:25:31 INFO - 'SHELL': '/bin/bash', 03:25:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ASCwAxYeS3/Listeners', 03:25:31 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 03:25:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:25:31 INFO - 'USER': 'cltbld', 03:25:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:25:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:25:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:31 INFO - 'XPC_FLAGS': '0x0', 03:25:31 INFO - 'XPC_SERVICE_NAME': '0', 03:25:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:25:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:25:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:25:31 INFO - Cleaning up... 03:25:31 INFO - Return code: 0 03:25:31 INFO - Running post-action listener: _resource_record_post_action 03:25:31 INFO - Running post-action listener: _start_resource_monitoring 03:25:31 INFO - Starting resource monitoring. 03:25:31 INFO - [mozharness: 2016-11-25 11:25:31.912846Z] Finished create-virtualenv step (success) 03:25:31 INFO - [mozharness: 2016-11-25 11:25:31.913878Z] Running install step. 03:25:31 INFO - Running pre-action listener: _resource_record_pre_action 03:25:31 INFO - Running main action method: install 03:25:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:25:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:25:32 INFO - Reading from file tmpfile_stdout 03:25:32 INFO - Detecting whether we're running mozinstall >=1.0... 03:25:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:25:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:25:32 INFO - Reading from file tmpfile_stdout 03:25:32 INFO - Output received: 03:25:32 INFO - Usage: mozinstall [options] installer 03:25:32 INFO - Options: 03:25:32 INFO - -h, --help show this help message and exit 03:25:32 INFO - -d DEST, --destination=DEST 03:25:32 INFO - Directory to install application into. [default: 03:25:32 INFO - "/builds/slave/test"] 03:25:32 INFO - --app=APP Application being installed. [default: firefox] 03:25:32 INFO - mkdir: /builds/slave/test/build/application 03:25:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:25:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 03:26:03 INFO - Reading from file tmpfile_stdout 03:26:03 INFO - Output received: 03:26:03 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:26:03 INFO - Running post-action listener: _resource_record_post_action 03:26:03 INFO - [mozharness: 2016-11-25 11:26:03.753786Z] Finished install step (success) 03:26:03 INFO - [mozharness: 2016-11-25 11:26:03.753932Z] Running run-tests step. 03:26:03 INFO - Running pre-action listener: _resource_record_pre_action 03:26:03 INFO - Running main action method: run_tests 03:26:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:26:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:26:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:26:03 INFO - 2016-11-25 03:26:03.768 screenresolution[1888:9013] starting screenresolution argv=screenresolution get 03:26:03 INFO - 2016-11-25 03:26:03.771 screenresolution[1888:9013] Display 0: 1600x1200x32@60 03:26:03 INFO - 2016-11-25 03:26:03.776 screenresolution[1889:9015] starting screenresolution argv=screenresolution list 03:26:03 INFO - Available Modes on Display 0 03:26:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:26:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:26:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:26:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:26:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:26:04 INFO - Intel Iris: 03:26:04 INFO - Chipset Model: Intel Iris 03:26:04 INFO - Type: GPU 03:26:04 INFO - Bus: Built-In 03:26:04 INFO - VRAM (Dynamic, Max): 1536 MB 03:26:04 INFO - Vendor: Intel (0x8086) 03:26:04 INFO - Device ID: 0x0a2e 03:26:04 INFO - Revision ID: 0x0009 03:26:04 INFO - Displays: 03:26:04 INFO - Display: 03:26:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:26:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:26:04 INFO - Display Serial Number: 1600x1200 60 03:26:04 INFO - Main Display: Yes 03:26:04 INFO - Mirror: Off 03:26:04 INFO - Online: Yes 03:26:04 INFO - Rotation: Supported 03:26:04 INFO - Return code: 0 03:26:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:26:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:26:04 INFO - Python 2.7.3 03:26:04 INFO - Return code: 0 03:26:04 INFO - grabbing minidump binary from tooltool 03:26:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:26:04 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 0x10aeb4d30>, '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 0x10ad1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aeacb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:26:04 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 03:26:04 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 03:26:04 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 03:26:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:26:04 INFO - Return code: 0 03:26:04 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:26:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:26:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:26:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:26:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:26:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eSO68kuZT2ufbhU_aVk-LQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0263', '--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 03:26:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/eSO68kuZT2ufbhU_aVk-LQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0263 --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 03:26:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fLMkiZGKe7/Render', 03:26:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:26:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:26:04 INFO - 'HOME': '/Users/cltbld', 03:26:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:26:04 INFO - 'LOGNAME': 'cltbld', 03:26:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:26:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:26:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:26:04 INFO - 'MOZ_NO_REMOTE': '1', 03:26:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:26:04 INFO - 'NO_EM_RESTART': '1', 03:26:04 INFO - 'PAGER': '/bin/cat', 03:26:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:26:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:26:04 INFO - 'PWD': '/builds/slave/test', 03:26:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:26:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:26:04 INFO - 'SHELL': '/bin/bash', 03:26:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ASCwAxYeS3/Listeners', 03:26:04 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 03:26:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:26:04 INFO - 'USER': 'cltbld', 03:26:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:26:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:26:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:26:04 INFO - 'XPC_FLAGS': '0x0', 03:26:04 INFO - 'XPC_SERVICE_NAME': '0', 03:26:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:26:04 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eSO68kuZT2ufbhU_aVk-LQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0263', '--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 03:26:04 INFO - mozversion application_buildid: 20161125004006 03:26:04 INFO - mozversion application_changeset: 55b599ca3530c8286f6ad15405d99d2bcaecfbd1 03:26:04 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:26:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:26:04 INFO - mozversion application_name: Firefox 03:26:04 INFO - mozversion application_remotingname: firefox-dev 03:26:04 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:26:04 INFO - mozversion application_vendor: Mozilla 03:26:04 INFO - mozversion application_version: 52.0a2 03:26:04 INFO - mozversion platform_buildid: 20161125004006 03:26:04 INFO - mozversion platform_changeset: 55b599ca3530c8286f6ad15405d99d2bcaecfbd1 03:26:04 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:26:04 INFO - mozversion platform_version: 52.0a2 03:26:04 INFO - using testdate: 1480073164 03:26:04 INFO - actual date: 1480073164 03:26:04 INFO - starting webserver on 'localhost:49374' 03:26:04 INFO - SUITE-START | Running 2 tests 03:26:04 INFO - TEST-START | dromaeo_css 03:26:04 INFO - operating with platform_type : mac_ 03:26:04 INFO - Initialising browser for dromaeo_css test... 03:26:04 INFO - TEST-INFO | started process 1899 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/tmpotm68b/profile http://localhost:49374/getInfo.html) 03:26:07 INFO - PROCESS | 1899 | __metrics Screen width/height:1600/1200 03:26:07 INFO - PROCESS | 1899 | colorDepth:24 03:26:07 INFO - PROCESS | 1899 | Browser inner width/height: 1024/689 03:26:07 INFO - PROCESS | 1899 | __metrics 03:26:07 INFO - PROCESS | 1899 | 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" 03:26:07 INFO - PROCESS | 1899 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:26:08 INFO - TEST-INFO | 1899: exit 0 03:26:08 INFO - Browser initialized. 03:26:08 INFO - Running cycle 1/1 for dromaeo_css test... 03:26:08 INFO - TEST-INFO | started process 1902 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/tmpotm68b/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:26:20 INFO - PROCESS | 1902 | RSS: Main: 222785536 03:26:20 INFO - PROCESS | 1902 | 03:27:11 INFO - PROCESS | 1902 | 1480073231680 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 03:29:24 INFO - PROCESS | 1902 | Cycle 1(1): loaded http://localhost:49374/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49374/tests/dromaeo/cssquery-ext.html) 03:29:26 INFO - PROCESS | 1902 | RSS: Main: 389083136 03:29:26 INFO - PROCESS | 1902 | 03:32:30 INFO - PROCESS | 1902 | Cycle 1(1): loaded http://localhost:49374/tests/dromaeo/cssquery-ext.html (next: http://localhost:49374/tests/dromaeo/cssquery-jquery.html) 03:32:30 INFO - PROCESS | 1902 | RSS: Main: 348258304 03:32:30 INFO - PROCESS | 1902 | 03:35:32 INFO - PROCESS | 1902 | Cycle 1(1): loaded http://localhost:49374/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49374/tests/dromaeo/cssquery-mootools.html) 03:35:33 INFO - PROCESS | 1902 | RSS: Main: 329908224 03:35:33 INFO - PROCESS | 1902 | 03:38:35 INFO - PROCESS | 1902 | Cycle 1(1): loaded http://localhost:49374/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49374/tests/dromaeo/cssquery-prototype.html) 03:38:36 INFO - PROCESS | 1902 | RSS: Main: 323383296 03:38:36 INFO - PROCESS | 1902 | 03:41:39 INFO - PROCESS | 1902 | Cycle 1(1): loaded http://localhost:49374/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49374/tests/dromaeo/cssquery-yui.html) 03:41:40 INFO - PROCESS | 1902 | RSS: Main: 315432960 03:41:40 INFO - PROCESS | 1902 | 03:44:43 INFO - PROCESS | 1902 | Cycle 1(1): loaded http://localhost:49374/tests/dromaeo/cssquery-yui.html (next: http://localhost:49374/tests/dromaeo/cssquery-dojo.html) 03:44:43 INFO - PROCESS | 1902 | RSS: Main: 397266944 03:44:43 INFO - PROCESS | 1902 | 03:44:43 INFO - PROCESS | 1902 | __start_tp_report 03:44:43 INFO - PROCESS | 1902 | _x_x_mozilla_page_load 03:44:43 INFO - PROCESS | 1902 | _x_x_mozilla_page_load_details 03:44:43 INFO - PROCESS | 1902 | |i|pagename|runs| 03:44:43 INFO - PROCESS | 1902 | |0;dojo.html;3328.4008878401887;3342.598888133424;3358.714509266714;3367.4275373186556;3406.8466918988643;5700.287464066992;5763.596548241625;5815.709214539267;5816.476517113459;5838.532917366611;5715.257016587838;5791.82624521264;5796.449337312959;5804.854878628036;5828.154917536955;5190.351206099238;5222.973885130588;5257.23770053342;5257.5005374489365;5292.285541451894;5211.635185537014;5243.6853788772605;5244.239585260135;5246.973765131169;5337.599680024014;5277.762500687459;5315;5322.600804939626;5336.493033161843;5383.4616538346245;5315;5339.199120131972;5339.759710813023;5353.491418315254;5372.247885296062;5059.772310246048;5173.922391164136;5232.110541208004;5247.291615631879;5273.657212281228;5676.205331253628;5687.800926967578;5742.28221472316;5771.538276937877;5792.710364481801;6155.907661385083;6209.006558950591;6229.626222426624;6248.3751624837605;6251.12484252205;6233.802549647199;6255.687215639246;6277.811665650038;6280.466160376373;6281.811545653638;5712.314522257356;5804.984127777622;5818.5054270386645;5844.503217226584;5933.643981361132;5298.70857102863;6259.092431597484;6275.843103922347;6326.652034138075;6332.430081292706;6182.536309776785;6192.696076226979;6195.597286176384;6203.596766210182;6213.875722485525;6129.064432475617;6214.844628884314;6231.40801623845;6240.032794916704;6255.718492667841;6223.00431930898;6279.215098112736;6283.120363149163;6285.717142728588;6309.053641953744;6183.5671502994355;6290.591111577733;6313.2424109107205;6319.684015799284;6334.904976425358;4867.148249056401;4944.060628480577;4987.12725273083;4998.800047998112;5014.573761230264;4394.890127746832;4445.73325600465;4450.3991961085485;4452.487963884195;4519.163954668397;5834.095715164156;5850.765969361263;5858.677772722456;5863.20846685692;5873.500752436091;6275.090111933744;6305.747770089145;6324.841878952971;6331.050342448578;6343.111964324999;5661.207430959669;5766.904288185231;5773.740181691834;5824.504917082011;5884.264466941632;5453.590980676465;5460.781568737286;5470.17947307907;5472;5497.285352904097;6365.522585806083;6367.777127800598;6369.20384951881;6401.487880969605;6417.743290268524;4686.976565117153;6236.688165591611;6266.091416744638;6315.747370105144;6316.431521163117;6227.439530442282;6327.209098862642;6330.1454303668415;6347.301796802256;6352.142460767737;6187.783427580013;6247.219097612799;6261.342559031418;6269.435750782452;6283.465905398092;6158.353372895782;6296.874062518816;6321.96839015802;6328.683565821782;6336.968315158395;5907.232059832194;6119.624418755002;6119.969400153149;6157.4458298753325;6215.285242197205;6175.752969881154;6190.442860142609;6207.596506227081;6209;6220.377962203925;6009.00654342789;6040.607360521552;6077.27072751255;6079.270487541523;6134.04922237054;6002.429769171922;6092.329843717099;6099.9390006099375;6104.4505994460715;6123.9693801532485;5932.317783454958;5933.851653708692;5943.732532036155;5949.988501954592;5954.9578823704815 03:44:43 INFO - PROCESS | 1902 | |1;ext.html;474.855169173402;477.3841744150047;482.74655805702;512.7769420302169;517.969241209992;29865.850670746644;31344.84327578359;31351.372972540536;31570.526442103386;39710.02899710028;40720.38919416204;41226.793866030624;41231.79384103083;41259.79370103153;41265.793671031606;8070.959645201766;9221.907780922173;9395.76510587234;9413.57638906251;9415.670451534197;8347.749567512985;8599.22606965373;9271.490068046254;11007.889921100767;11276.661700148969;11176;11226.607068752595;11238.438078096104;11245;11324.943375283112;11175.83236251457;11249.987501124939;11251.212415130944;11263.493142808553;11276.548938042468;7341;8384.916150838535;8584.957075214646;8615.827683446296;8676;38209.61790382116;38611.8069409654;38715.61284387148;39866;39925.40111898324;2737.671479422462;3133.81197128173;3158.494640857452;3216;3565.9108522286892;29869.850650746608;29966.55050174249;30075.548866767014;30544.54183187254;30859.22851928697;2902.259923719459;3125.7030582094662;3135.984320078408;3180.157258326545;3180.2367431816197;486232;487714;489159;489162;496512;49575.50424495781;50799.746001269756;50842.49157508452;53166.936661266554;53194.202086968726;59977;60446.395536044954;61303.693481532304;64023.67988160029;64166.67916660387;15544;15705.607359815867;15739.685206296039;15751.606209844846;15754.369825206863;11203.60787372438;12063.914247717572;12232.388380581113;12261.693457663452;12415.937920310522;14422.85577144236;14697.706045879022;16112.838871611133;16550.42073527421;16573.17134143288;6256.405641464054;6325.367463253638;6365.172527571386;6397.10440538325;6409.391107844747;855.2730179347504;880.5905254056767;974.7952929884804;989.6734077754323;993.145894530703;28241.858790706327;29180.416391672046;29194.41611167765;29696;29752.85123574368;1007.8286691262502;1074.5594306334365;1081.3457857995957;1106.773111512138;1125.358545629;14648.707025859423;15122.621934451507;15141.470048548248;15368.462103826312;15529.611759705871;194136;194642;194729;195561.02219489097;196890.03109969004;55662.721686391305;55732.72133639387;55919;55923.44076559264;55978.60053498695;1218.6587755428495;1261.035307989389;1329.6210579984659;1354.5800801751489;1368.0697125954048;29634.40731185321;30101.397972040875;30253.69746302597;30536.694633053386;30802.845985770826;48305;48836.511634883194;48899;48900.5109948896;48916.02167956692;542216;546092;547249;548478;656526;24214.273571792874;25235.495290094463;25292;25335.98656053811;25399.36501587475;42699;42807;44092.33861492144;44122.558774411846;44270;35936.82031589825;36275.63724362828;36590.63409365872;36652;36726.816365918;35952.28095438024;36463.817680912485;36575.817120914224;37634.62365376421;37761.2447751038;35447.468287975185;36030.63969360273;36518.08704782402;36688.26623467569;36693.08267293339;30985.225369365948;33238.00285991424;33318.666813331554;33424;33431.83284083564;24381.878090609433;24746.628800567647;24761.504769904142;25165.87417062903;25268.49463010693 03:44:43 INFO - PROCESS | 1902 | |2;jquery.html;352.7266368564361;372.194199557957;375.6018620262522;383.2603076063199;402.4566834773055;14997.42527034662;15009.099454032767;16771.580710482227;20220.898895505503;20425.387238382835;18915.810841891576;19172.137253823577;19502.609947801036;19578.412647620593;19801.207951681914;5501.559875209983;5736.483716465517;5767.826965191051;5770.624909380897;5775.249217601706;4416.86749397517;5575.745457272102;6285.465735412496;6305.527085468608;6400.743970241208;6042.697865106749;6269.279032911228;6332.1135041094285;6350.968245158768;6364.140840986477;5411.540019098381;6292.653904035276;6347.1114044033875;6350.872982540323;6355.491560675158;5288.312519372457;5483.451654834525;5507.284053073115;5526.392096869341;5542.362628297757;23735.525289494115;24132.63801042986;24153;24208.031678733005;24291.271261862174;2222.733272007353;2237.3735354100713;2256.7743225677464;2357.4695693469002;2816.774658027353;9475.336726429152;14719.411223551046;14756.852431475656;15302.387904483807;15304.693906121927;1431.4632012995128;1730.766346543213;1781.5189898727401;1781.7217361128376;1812.9728054079199;113035;117513;117960.41019794845;118057;118268;11229.27009744364;11253.943730281297;11491.425428728697;11492.770144597062;11619.477123529461;14357.78463323051;14362.994590378574;14368;14377.496787612383;14387.352569134415;8263.380246481538;8378.874316885252;8401.915980840113;8530.914690853137;8553.572321383906;8290.792730181673;8291.129431409736;8319.542425166674;8454.408191426664;8471.57642117904;4400.515943246241;4439.933400998988;4500.459944806644;4516.1419330327135;4561.931571026437;1659.3528523875698;1834.8256915592997;1887.6130393269348;1889.4520589029028;1893.8390236830023;398.94813674222166;402.3040140556853;799.3565180030132;802.558592773972;931.2503434735104;11050.060744836617;11407.48666310018;11526.36604986717;11575.76848463026;11968.341741204144;416.92286926918615;422.3284976886703;426.67146297350865;426.9509006464234;438.23746680775224;10260.897391026143;11608.24546404481;11679.240849344766;11681.88318116808;11705.414729263503;580033.6988205477;608502;612450;622503;627071;95114.04885951051;105625.94374056315;118926.4053679726;130865.9999999981;142976.14047719195;887.9344786256518;930.7440453875103;989.9455529945778;1009.3136667066318;1010.0858722855827;18010.18954147093;18201.635967280847;18431.447056588324;18524;18901.338453154072;41095.97260068522;55337.7233113848;56549;57298.42701572931;58047.4195258042;74788.8132125852;81440.59279703564;82209;85551;85874.57062714646;7449.81375465596;7786.259541984643;7870.803229919298;8022.9598852005365;8208;8675.219230269306;10742.8388574173;11923.82114268304;12275.877241227474;12299.262044277371;10205.285630005828;10430.739231519272;10643.521041553304;10693.89306106929;10922.726931826768;8481.40630155883;9870.555824988036;10384.792304154025;10685.091767199876;10905.945470272598;10366.948165259126;10713.94643026811;10899.836502452312;10938.56245750161;10984.176186786182;7106.289371063059;7598.924010760043;7613.961930190314;7934.325582325567;8059.597020149086;9222.815543689223;10336;10543.94728026355;10627.734306642396;10712 03:44:44 INFO - PROCESS | 1902 | |3;mootools.html;1294.7863602505588;1322.6627210061433;1459.708058388322;1569.9450519231823;1808.6111486030509;7527.849443011143;7928.722494712686;8234;8469.872951905727;8496.702615408461;8339.74980750577;8429.409941304111;8437.240648341649;8440.366972477059;8493.193146651074;2330.242671131885;2367.95264094718;2369.6327069304284;2371.3597328721235;2376.370261880603;2347.471818840764;2399.1003373734848;2402.4594466245044;2408.5664580375524;2412.7345991940874;1964.6463636545416;2325.476767727265;2366.2191476812695;2372.833901626887;2373.264288070695;1993.4518007547967;2306.9077236910503;2312.7918487336137;2313.0169677886997;2339.94150146246;2289.164454973939;2359.528094381131;2380.67860838788;2382.344855164826;2387.8209134315;7395.149557800814;8261.876071858927;8480.74557763268;8487;8494.575271236414;5144.485551444863;5350.9732451337495;5359.705216213107;5369.275147855029;5387.1111266640955;2652.7612514873754;3363.377775111612;3482.564679415073;3556.1643013891717;3565.7682250653625;870.7039606533746;897.824924139793;1056.073417507335;1071.8606581144402;1074.548689550383;10881.23831331807;10969.23215374924;11032.22774405792;11061.557537698482;11065.336079835235;3381.644927282649;4395.956040439554;4527.366168736379;4528.841490547881;4555.2711566149255;8779.429337093068;8782.736517904474;8790.120987901286;8804.075572064969;8806.95596522026;5144.382674079135;5154.175331946871;5168.173092305213;5170.45710200431;5175.637705360664;4101.692373072032;4325.091730736518;4466.352378905106;4466.441694788152;4849.2968519564465;3667.9083022924106;3700.4264339027495;3788.242351529705;3790.336691079105;3791.2038471920655;983.2085171437062;1143.188336281233;1146.94265286737;1152.302856771641;1164.126904821384;829.4318391901567;872.7687162901836;892.1747383670208;905.4114825363434;916.8585111536047;5650.378458369576;5756.133503265934;5851.970740146272;5900.940990590125;5946.345901950782;806.435495153395;841.869510225916;846.1876598465419;858.772425307294;927.2072378116748;3731.421629647409;3746.756460830037;3774;3864.8260828262796;3877.7673339599714;9637.614495420245;10285.640002599874;10307.175425965905;10365.533550990223;10496.527656255486;10247.641332553325;10317.226208034428;10419.843702344471;10545.420001899816;10558.947205263925;6573.934260657332;8053.194680532017;8225.259726624512;8228.177182281725;8231;5343.492368225013;5508.14623733322;5515.834524964257;5558.777648893999;5687.488126068673;9396.154346108882;9601.423914565148;9613.423194608346;9622.326437149612;9622.422654640744;8827.646894124164;9128.77178070527;9146.588403521724;9570.856437153308;9597.9040209597;6745.561538499982;7018.227994920452;7291.635418229173;7322.450816188701;7325.487215895049;6645.468362530889;8039.67841286342;8123.6750529978135;8152.470089444168;8155.062167850536;7132.322429369202;7228.132624085143;7236.131664200329;7284.453665974967;7380.114386273682;7089.291070892869;7160.283971603007;7171.533850299714;7191.748288809867;7198.7840364789145;6602.372774586406;6796.116504854337;6813.591184528944;6913.861722765617;6966.9651651741415;4871.561559459666;4900.313956046228;4901.09329773993;4932.654714169974;4988.002399520038;4232.470941132358;4791.688540244873;4844.660873738804;4851.51484851512;5095.796168153232 03:44:44 INFO - PROCESS | 1902 | |4;prototype.html;297.363641806534;304.13928582112624;306.8097779376787;313.21695760598504;316.4399013745669;4446.488194014035;4921.022781217365;5147.099257629914;5181.326427564416;5184.53339199472;4379.277419225832;4887.486813884544;5015.843187429404;5073.568746656539;5183.455737147592;3964.2071585682834;3966.6002068748426;3980.1840622672426;3992.421098940652;4026.047367026997;3518.771279866813;3705.2770461735286;3990.421388898608;3996.8800935971967;4003.339448990913;3882.2817778710887;3926.489556357684;3928.194720082376;3928.194720082376;3940.7044471664594;3901.4342920276545;3908.355121404993;3918.941215881764;3928.017995501125;3966.7619942803235;3060.9592738469028;3384.7969121852766;3412.931741365184;3425.4348032574594;3445.6898879101;4865.107913669086;4960.561437183213;4987.326710894019;5007.173816320303;5011.674241174312;4220.7548773112;4262.722923010026;4460.0633866887665;4475.686701930867;4488.236999710024;4161.646260067898;4682.016776476944;4929.482404347527;5438.53772429344;5442.183672449125;3686.189038411545;3734.3464893643777;3740.8129593520216;3743.8689645862264;3751.887443376676;4842.854714358575;4947.6536642434685;5100.710042022912;5543.184110404311;5727.455891690282;3363.9663603364143;3760.7555508891833;3814.6757525610633;3815.8664446744233;3831.2912111258947;3651.379265524867;3791.9241615167543;3815.664517418881;3821.8280177392085;3864.401017842239;3796.7342286040266;4371.0548805781755;4372.010735115515;4442.244818380883;4455.198064348384;5396.109641909119;5432.049391356576;5474.534664553478;5493.395726470085;5509.311336082989;1130.9038731707897;1132.9293817342698;1152.458344578047;1173.7769823733463;1223.7736018836545;704.2851505721696;834.4200780457523;839.7564706235246;842.5955541340192;856.1609622569916;3895.5325360956867;4322.7190232634775;4692;4744.691595046311;4831.3814872017565;4352.869413917588;5195.830543368542;5243.947560524346;5299.761510732026;5301.3373328333955;4028;4074.8166332515107;4668.195420732092;4745.454272758608;4805.438232574427;2203.889805509718;2655.800814938849;2710.5798601216843;2722.679500442284;2834.844083575423;4838.250071238964;4891.046245981999;4978.664294922338;5076.314697515862;5406.864828379322;4374.300111982131;4922.237053256751;5181.5077567631015;5187.771965847897;5330.307060082165;4928.507149285115;4935.975320123376;5332.493413125747;5535.58483113768;5547.389787123413;4493.236149854598;4919.557239848431;5326.334208223972;5352.223927530408;5360.231123729238;4368.082702632356;4430.778461076998;5193.4027586828;5216.008958297912;5227.372715274039;5847.538115471132;5895.882082358243;5917.289925209003;5925.970370148294;5929.940700592939;3814.6376094271;3815.7328986970647;3818.3508803502914;3827.5072721638026;3829.310724069694;5442.170071562844;5658.971705141448;5698.230738850202;5722.742476588647;5765.769369225184;4697.882552936204;4782.473927868002;5312.814051508179;5340.092184328596;5389.703566303734;4800.471948085638;5281.498257665516;5559.2217089606675;5571.9721401392735;5584.497395234448;5120.948790512196;5158.896822063462;5449.04641687711;5454.481824226692;5503.532199763065;4199.70602057853;5190.702324418895;5329.600279978939;5535.723213839211;5566.526845218202;5217.713025783467;5336.451259912115;5371.88430710195;5419.647722897999;5454.863628409322 03:44:44 INFO - PROCESS | 1902 | |5;yui.html;761.1703243464624;763.8892360607708;787.4409169489662;793.1315209845219;793.3851265269416;13926.025178237514;14395.78406323906;14440.133591984488;14441.42234310629;14444.783328250072;12790.744185116291;12882.549110781125;12897.355132243374;12905.354732263373;12907.096503244753;628.2931701835436;651.3453978751348;653.7058323754305;789.7670187294748;802.9718959836407;787.6258777080898;807.7132617920641;819.9508029518219;822.6298165825402;825.0965193113531;865.1046167216932;866.7096522664915;867.3494878840869;867.4578388507183;867.7873920889391;649.909012738217;661.5898143151229;687.1994126842222;862.9870551941726;865.1953683074738;511.10046318479374;514.0515748444114;604.2084868821858;628.8302158417201;629.9307076221612;13671.17972921628;13700.588982330546;13717.725645487035;13727.382267797973;13745;12692.492300307977;12829.101962862604;13066.150700204456;13066.803997940038;13157.671058223525;11365.488553015135;11800.01894704295;11927.880721192763;12128;12252.81620775689;4312.482502099768;4364.192624364503;4396.802143903532;4524.072565124142;4527.298268768346;86503;86770.56614716949;94359.52820235855;95888.52055739677;96287;10087.243456740774;10401.219908506891;10481.475926203659;10497.685069447929;10756.946215268874;19266.518337041685;19403.02984850752;19502;19553.511162221057;19596.314129005415;7381.188069312371;7442.627868606548;7462;7659.004329437137;7709.498882572615;4132.8052027192425;4172.061286210578;4238.766867822238;4259.914801703949;4300.2904520754355;3203.343314620497;3256.1859535116223;3308.520264561625;3310.1724568857785;3311.586051743532;597.3399393670019;605.9606125601822;614.4009590649171;614.7264467312093;637.101686621861;559.7593034994991;601.2274227617578;610.3499772742016;616.6454288783967;620.4663988969482;7564.773056808304;8107.716229931924;8577.828443431097;8619.888591911573;8668.8699669505;538.9003034438641;552.425477503392;589.7589161187556;594.4382558482192;596.1385797522538;4249.2988656871885;4264.104538046983;4270.197517742844;4327.675424343187;4362.934555981663;79923.20076799273;100651;113337;120464;122182;81251;82051.58974205091;82201.17798822054;91066;102063;56865.71567142221;59828.70085649631;60981;61218;88328;9319.906800931903;9634.421934683942;9765.414075155513;9789.314747967574;9796.216302695624;55727.88544229011;56361.43638563562;56487.43512564822;57706.7114664424;58604.12093818682;77328;77532.4493510138;79507;81008.18991810242;82483.58758206171;11905.047596192111;12025.518979240733;12036.217645852992;12348.197367171106;12398.81401778956;13097.607071787861;13460.528881489101;13522.661933451742;13873.514426995007;14206.573802785933;10860.511276992713;11639.825402618799;11689.415529223676;12242;12417.19288246261;10926.399048052115;11034.834477482684;11119.555217791521;11773.705657358634;12842.935785321013;12329.753404931671;12696;12940.8058879115;13047.478100875858;13591.980601454732;9999.60001599957;10168.186545076229;10227;10878.78242435131;11504;11128.054115400282;11257.437128143723;11340.773184536098;11393;11442.885571144181 03:44:44 INFO - PROCESS | 1902 | __end_tp_report 03:44:44 INFO - PROCESS | 1902 | __start_cc_report 03:44:44 INFO - PROCESS | 1902 | _x_x_mozilla_cycle_collect,2018 03:44:44 INFO - PROCESS | 1902 | __end_cc_report 03:44:44 INFO - PROCESS | 1902 | __startTimestamp1480074283989__endTimestamp 03:44:44 INFO - PROCESS | 1902 | 03:44:44 INFO - PROCESS | 1902 | ------- Summary: start ------- 03:44:44 INFO - PROCESS | 1902 | Number of tests: 6 03:44:44 INFO - PROCESS | 1902 | 03:44:44 INFO - PROCESS | 1902 | [#0] dojo.html Cycles:165 Average:5783.78 Median:5954.96 stddev:645.17 (10.8%) stddev-sans-first:617.72 03:44:44 INFO - PROCESS | 1902 | Values: 3328.4 3342.6 3358.7 3367.4 3406.8 5700.3 5763.6 5815.7 5816.5 5838.5 5715.3 5791.8 5796.4 5804.9 5828.2 5190.4 5223.0 5257.2 5257.5 5292.3 5211.6 5243.7 5244.2 5247.0 5337.6 5277.8 5315.0 5322.6 5336.5 5383.5 5315.0 5339.2 5339.8 5353.5 5372.2 5059.8 5173.9 5232.1 5247.3 5273.7 5676.2 5687.8 5742.3 5771.5 5792.7 6155.9 6209.0 6229.6 6248.4 6251.1 6233.8 6255.7 6277.8 6280.5 6281.8 5712.3 5805.0 5818.5 5844.5 5933.6 5298.7 6259.1 6275.8 6326.7 6332.4 6182.5 6192.7 6195.6 6203.6 6213.9 6129.1 6214.8 6231.4 6240.0 6255.7 6223.0 6279.2 6283.1 6285.7 6309.1 6183.6 6290.6 6313.2 6319.7 6334.9 4867.1 4944.1 4987.1 4998.8 5014.6 4394.9 4445.7 4450.4 4452.5 4519.2 5834.1 5850.8 5858.7 5863.2 5873.5 6275.1 6305.7 6324.8 6331.1 6343.1 5661.2 5766.9 5773.7 5824.5 5884.3 5453.6 5460.8 5470.2 5472.0 5497.3 6365.5 6367.8 6369.2 6401.5 6417.7 4687.0 6236.7 6266.1 6315.7 6316.4 6227.4 6327.2 6330.1 6347.3 6352.1 6187.8 6247.2 6261.3 6269.4 6283.5 6158.4 6296.9 6322.0 6328.7 6337.0 5907.2 6119.6 6120.0 6157.4 6215.3 6175.8 6190.4 6207.6 6209.0 6220.4 6009.0 6040.6 6077.3 6079.3 6134.0 6002.4 6092.3 6099.9 6104.5 6124.0 5932.3 5933.9 5943.7 5950.0 5955.0 03:44:44 INFO - PROCESS | 1902 | 03:44:44 INFO - PROCESS | 1902 | [#1] ext.html Cycles:180 Average:56514.78 Median:19506.69 stddev:120097.89 (615.7%) stddev-sans-first:120361.10 03:44:44 INFO - PROCESS | 1902 | Values: 474.9 477.4 482.7 512.8 518.0 29865.9 31344.8 31351.4 31570.5 39710.0 40720.4 41226.8 41231.8 41259.8 41265.8 8071.0 9221.9 9395.8 9413.6 9415.7 8347.7 8599.2 9271.5 11007.9 11276.7 11176.0 11226.6 11238.4 11245.0 11324.9 11175.8 11250.0 11251.2 11263.5 11276.5 7341.0 8384.9 8585.0 8615.8 8676.0 38209.6 38611.8 38715.6 39866.0 39925.4 2737.7 3133.8 3158.5 3216.0 3565.9 29869.9 29966.6 30075.5 30544.5 30859.2 2902.3 3125.7 3136.0 3180.2 3180.2 486232.0 487714.0 489159.0 489162.0 496512.0 49575.5 50799.7 50842.5 53166.9 53194.2 59977.0 60446.4 61303.7 64023.7 64166.7 15544.0 15705.6 15739.7 15751.6 15754.4 11203.6 12063.9 12232.4 12261.7 12415.9 14422.9 14697.7 16112.8 16550.4 16573.2 6256.4 6325.4 6365.2 6397.1 6409.4 855.3 880.6 974.8 989.7 993.1 28241.9 29180.4 29194.4 29696.0 29752.9 1007.8 1074.6 1081.3 1106.8 1125.4 14648.7 15122.6 15141.5 15368.5 15529.6 194136.0 194642.0 194729.0 195561.0 196890.0 55662.7 55732.7 55919.0 55923.4 55978.6 1218.7 1261.0 1329.6 1354.6 1368.1 29634.4 30101.4 30253.7 30536.7 30802.8 48305.0 48836.5 48899.0 48900.5 48916.0 542216.0 546092.0 547249.0 548478.0 656526.0 24214.3 25235.5 25292.0 25336.0 25399.4 42699.0 42807.0 44092.3 44122.6 44270.0 35936.8 36275.6 36590.6 36652.0 36726.8 35952.3 36463.8 36575.8 37634.6 37761.2 35447.5 36030.6 36518.1 36688.3 36693.1 30985.2 33238.0 33318.7 33424.0 33431.8 24381.9 24746.6 24761.5 25165.9 25268.5 03:44:44 INFO - PROCESS | 1902 | 03:44:44 INFO - PROCESS | 1902 | [#2] jquery.html Cycles:180 Average:34746.27 Median:1584.75 stddev:101690.65 (6416.8%) stddev-sans-first:101943.12 03:44:44 INFO - PROCESS | 1902 | Values: 352.7 372.2 375.6 383.3 402.5 14997.4 15009.1 16771.6 20220.9 20425.4 18915.8 19172.1 19502.6 19578.4 19801.2 5501.6 5736.5 5767.8 5770.6 5775.2 4416.9 5575.7 6285.5 6305.5 6400.7 6042.7 6269.3 6332.1 6351.0 6364.1 5411.5 6292.7 6347.1 6350.9 6355.5 5288.3 5483.5 5507.3 5526.4 5542.4 23735.5 24132.6 24153.0 24208.0 24291.3 2222.7 2237.4 2256.8 2357.5 2816.8 9475.3 14719.4 14756.9 15302.4 15304.7 1431.5 1730.8 1781.5 1781.7 1813.0 113035.0 117513.0 117960.4 118057.0 118268.0 11229.3 11253.9 11491.4 11492.8 11619.5 14357.8 14363.0 14368.0 14377.5 14387.4 8263.4 8378.9 8401.9 8530.9 8553.6 8290.8 8291.1 8319.5 8454.4 8471.6 4400.5 4439.9 4500.5 4516.1 4561.9 1659.4 1834.8 1887.6 1889.5 1893.8 398.9 402.3 799.4 802.6 931.3 11050.1 11407.5 11526.4 11575.8 11968.3 416.9 422.3 426.7 427.0 438.2 10260.9 11608.2 11679.2 11681.9 11705.4 580033.7 608502.0 612450.0 622503.0 627071.0 95114.0 105625.9 118926.4 130866.0 142976.1 887.9 930.7 989.9 1009.3 1010.1 18010.2 18201.6 18431.4 18524.0 18901.3 41096.0 55337.7 56549.0 57298.4 58047.4 74788.8 81440.6 82209.0 85551.0 85874.6 7449.8 7786.3 7870.8 8023.0 8208.0 8675.2 10742.8 11923.8 12275.9 12299.3 10205.3 10430.7 10643.5 10693.9 10922.7 8481.4 9870.6 10384.8 10685.1 10905.9 10366.9 10713.9 10899.8 10938.6 10984.2 7106.3 7598.9 7614.0 7934.3 8059.6 9222.8 10336.0 10543.9 10627.7 10712.0 03:44:44 INFO - PROCESS | 1902 | 03:44:44 INFO - PROCESS | 1902 | [#3] mootools.html Cycles:180 Average:5449.82 Median:5173.05 stddev:3024.16 (58.5%) stddev-sans-first:3016.52 03:44:44 INFO - PROCESS | 1902 | Values: 1294.8 1322.7 1459.7 1569.9 1808.6 7527.8 7928.7 8234.0 8469.9 8496.7 8339.7 8429.4 8437.2 8440.4 8493.2 2330.2 2368.0 2369.6 2371.4 2376.4 2347.5 2399.1 2402.5 2408.6 2412.7 1964.6 2325.5 2366.2 2372.8 2373.3 1993.5 2306.9 2312.8 2313.0 2339.9 2289.2 2359.5 2380.7 2382.3 2387.8 7395.1 8261.9 8480.7 8487.0 8494.6 5144.5 5351.0 5359.7 5369.3 5387.1 2652.8 3363.4 3482.6 3556.2 3565.8 870.7 897.8 1056.1 1071.9 1074.5 10881.2 10969.2 11032.2 11061.6 11065.3 3381.6 4396.0 4527.4 4528.8 4555.3 8779.4 8782.7 8790.1 8804.1 8807.0 5144.4 5154.2 5168.2 5170.5 5175.6 4101.7 4325.1 4466.4 4466.4 4849.3 3667.9 3700.4 3788.2 3790.3 3791.2 983.2 1143.2 1146.9 1152.3 1164.1 829.4 872.8 892.2 905.4 916.9 5650.4 5756.1 5852.0 5900.9 5946.3 806.4 841.9 846.2 858.8 927.2 3731.4 3746.8 3774.0 3864.8 3877.8 9637.6 10285.6 10307.2 10365.5 10496.5 10247.6 10317.2 10419.8 10545.4 10558.9 6573.9 8053.2 8225.3 8228.2 8231.0 5343.5 5508.1 5515.8 5558.8 5687.5 9396.2 9601.4 9613.4 9622.3 9622.4 8827.6 9128.8 9146.6 9570.9 9597.9 6745.6 7018.2 7291.6 7322.5 7325.5 6645.5 8039.7 8123.7 8152.5 8155.1 7132.3 7228.1 7236.1 7284.5 7380.1 7089.3 7160.3 7171.5 7191.7 7198.8 6602.4 6796.1 6813.6 6913.9 6967.0 4871.6 4900.3 4901.1 4932.7 4988.0 4232.5 4791.7 4844.7 4851.5 5095.8 03:44:44 INFO - PROCESS | 1902 | 03:44:44 INFO - PROCESS | 1902 | [#4] prototype.html Cycles:180 Average:4314.80 Median:4802.96 stddev:1316.91 (27.4%) stddev-sans-first:1285.61 03:44:44 INFO - PROCESS | 1902 | Values: 297.4 304.1 306.8 313.2 316.4 4446.5 4921.0 5147.1 5181.3 5184.5 4379.3 4887.5 5015.8 5073.6 5183.5 3964.2 3966.6 3980.2 3992.4 4026.0 3518.8 3705.3 3990.4 3996.9 4003.3 3882.3 3926.5 3928.2 3928.2 3940.7 3901.4 3908.4 3918.9 3928.0 3966.8 3061.0 3384.8 3412.9 3425.4 3445.7 4865.1 4960.6 4987.3 5007.2 5011.7 4220.8 4262.7 4460.1 4475.7 4488.2 4161.6 4682.0 4929.5 5438.5 5442.2 3686.2 3734.3 3740.8 3743.9 3751.9 4842.9 4947.7 5100.7 5543.2 5727.5 3364.0 3760.8 3814.7 3815.9 3831.3 3651.4 3791.9 3815.7 3821.8 3864.4 3796.7 4371.1 4372.0 4442.2 4455.2 5396.1 5432.0 5474.5 5493.4 5509.3 1130.9 1132.9 1152.5 1173.8 1223.8 704.3 834.4 839.8 842.6 856.2 3895.5 4322.7 4692.0 4744.7 4831.4 4352.9 5195.8 5243.9 5299.8 5301.3 4028.0 4074.8 4668.2 4745.5 4805.4 2203.9 2655.8 2710.6 2722.7 2834.8 4838.3 4891.0 4978.7 5076.3 5406.9 4374.3 4922.2 5181.5 5187.8 5330.3 4928.5 4936.0 5332.5 5535.6 5547.4 4493.2 4919.6 5326.3 5352.2 5360.2 4368.1 4430.8 5193.4 5216.0 5227.4 5847.5 5895.9 5917.3 5926.0 5929.9 3814.6 3815.7 3818.4 3827.5 3829.3 5442.2 5659.0 5698.2 5722.7 5765.8 4697.9 4782.5 5312.8 5340.1 5389.7 4800.5 5281.5 5559.2 5572.0 5584.5 5120.9 5158.9 5449.0 5454.5 5503.5 4199.7 5190.7 5329.6 5535.7 5566.5 5217.7 5336.5 5371.9 5419.6 5454.9 03:44:44 INFO - PROCESS | 1902 | 03:44:44 INFO - PROCESS | 1902 | [#5] yui.html Cycles:180 Average:19904.10 Median:4380.50 stddev:29137.74 (665.2%) stddev-sans-first:29184.02 03:44:44 INFO - PROCESS | 1902 | Values: 761.2 763.9 787.4 793.1 793.4 13926.0 14395.8 14440.1 14441.4 14444.8 12790.7 12882.5 12897.4 12905.4 12907.1 628.3 651.3 653.7 789.8 803.0 787.6 807.7 820.0 822.6 825.1 865.1 866.7 867.3 867.5 867.8 649.9 661.6 687.2 863.0 865.2 511.1 514.1 604.2 628.8 629.9 13671.2 13700.6 13717.7 13727.4 13745.0 12692.5 12829.1 13066.2 13066.8 13157.7 11365.5 11800.0 11927.9 12128.0 12252.8 4312.5 4364.2 4396.8 4524.1 4527.3 86503.0 86770.6 94359.5 95888.5 96287.0 10087.2 10401.2 10481.5 10497.7 10756.9 19266.5 19403.0 19502.0 19553.5 19596.3 7381.2 7442.6 7462.0 7659.0 7709.5 4132.8 4172.1 4238.8 4259.9 4300.3 3203.3 3256.2 3308.5 3310.2 3311.6 597.3 606.0 614.4 614.7 637.1 559.8 601.2 610.3 616.6 620.5 7564.8 8107.7 8577.8 8619.9 8668.9 538.9 552.4 589.8 594.4 596.1 4249.3 4264.1 4270.2 4327.7 4362.9 79923.2 100651.0 113337.0 120464.0 122182.0 81251.0 82051.6 82201.2 91066.0 102063.0 56865.7 59828.7 60981.0 61218.0 88328.0 9319.9 9634.4 9765.4 9789.3 9796.2 55727.9 56361.4 56487.4 57706.7 58604.1 77328.0 77532.4 79507.0 81008.2 82483.6 11905.0 12025.5 12036.2 12348.2 12398.8 13097.6 13460.5 13522.7 13873.5 14206.6 10860.5 11639.8 11689.4 12242.0 12417.2 10926.4 11034.8 11119.6 11773.7 12842.9 12329.8 12696.0 12940.8 13047.5 13592.0 9999.6 10168.2 10227.0 10878.8 11504.0 11128.1 11257.4 11340.8 11393.0 11442.9 03:44:44 INFO - PROCESS | 1902 | -------- Summary: end -------- 03:44:44 INFO - PROCESS | 1902 | 03:44:44 INFO - TEST-INFO | 1902: exit 0 03:44:44 INFO - TEST-OK | dromaeo_css | took 1120289ms 03:44:44 INFO - TEST-START | kraken 03:44:44 INFO - operating with platform_type : mac_ 03:44:44 INFO - Initialising browser for kraken test... 03:44:44 INFO - TEST-INFO | started process 1930 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/tmpGKxpTl/profile http://localhost:49374/getInfo.html) 03:44:47 INFO - PROCESS | 1930 | __metrics Screen width/height:1600/1200 03:44:47 INFO - PROCESS | 1930 | colorDepth:24 03:44:47 INFO - PROCESS | 1930 | Browser inner width/height: 1024/689 03:44:47 INFO - PROCESS | 1930 | __metrics 03:44:47 INFO - PROCESS | 1930 | 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" 03:44:47 INFO - PROCESS | 1930 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:44:48 INFO - TEST-INFO | 1930: exit 0 03:44:48 INFO - Browser initialized. 03:44:48 INFO - Running cycle 1/1 for kraken test... 03:44:48 INFO - TEST-INFO | started process 1932 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/tmpGKxpTl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:45:00 INFO - PROCESS | 1932 | RSS: Main: 222986240 03:45:00 INFO - PROCESS | 1932 | 03:45:03 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49374/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49374/tests/kraken/driver.html?testName=audio-beat-detection) 03:45:04 INFO - PROCESS | 1932 | RSS: Main: 271921152 03:45:04 INFO - PROCESS | 1932 | 03:45:07 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49374/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49374/tests/kraken/driver.html?testName=audio-dft) 03:45:07 INFO - PROCESS | 1932 | RSS: Main: 284983296 03:45:07 INFO - PROCESS | 1932 | 03:45:11 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49374/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49374/tests/kraken/driver.html?testName=audio-fft) 03:45:11 INFO - PROCESS | 1932 | RSS: Main: 379551744 03:45:11 INFO - PROCESS | 1932 | 03:45:13 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49374/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49374/tests/kraken/driver.html?testName=audio-oscillator) 03:45:14 INFO - PROCESS | 1932 | RSS: Main: 284475392 03:45:14 INFO - PROCESS | 1932 | 03:45:16 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49374/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49374/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:45:17 INFO - PROCESS | 1932 | RSS: Main: 285384704 03:45:17 INFO - PROCESS | 1932 | 03:45:19 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49374/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49374/tests/kraken/driver.html?testName=imaging-darkroom) 03:45:20 INFO - PROCESS | 1932 | RSS: Main: 326795264 03:45:20 INFO - PROCESS | 1932 | 03:45:23 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49374/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49374/tests/kraken/driver.html?testName=imaging-desaturate) 03:45:24 INFO - PROCESS | 1932 | RSS: Main: 324726784 03:45:24 INFO - PROCESS | 1932 | 03:45:26 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49374/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49374/tests/kraken/driver.html?testName=json-parse-financial) 03:45:27 INFO - PROCESS | 1932 | RSS: Main: 321998848 03:45:27 INFO - PROCESS | 1932 | 03:45:29 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49374/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49374/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:45:29 INFO - PROCESS | 1932 | RSS: Main: 301015040 03:45:29 INFO - PROCESS | 1932 | 03:45:31 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49374/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49374/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:45:32 INFO - PROCESS | 1932 | RSS: Main: 302145536 03:45:32 INFO - PROCESS | 1932 | 03:45:34 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49374/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49374/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:45:35 INFO - PROCESS | 1932 | RSS: Main: 305184768 03:45:35 INFO - PROCESS | 1932 | 03:45:38 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49374/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49374/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:45:38 INFO - PROCESS | 1932 | RSS: Main: 319987712 03:45:38 INFO - PROCESS | 1932 | 03:45:41 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49374/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49374/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:45:42 INFO - PROCESS | 1932 | RSS: Main: 314093568 03:45:42 INFO - PROCESS | 1932 | 03:45:44 INFO - PROCESS | 1932 | Cycle 1(1): loaded http://localhost:49374/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49374/tests/kraken/driver.html?testName=ai-astar) 03:45:44 INFO - PROCESS | 1932 | RSS: Main: 358072320 03:45:44 INFO - PROCESS | 1932 | 03:45:44 INFO - PROCESS | 1932 | __start_tp_report 03:45:44 INFO - PROCESS | 1932 | _x_x_mozilla_page_load 03:45:44 INFO - PROCESS | 1932 | _x_x_mozilla_page_load_details 03:45:44 INFO - PROCESS | 1932 | |i|pagename|runs| 03:45:44 INFO - PROCESS | 1932 | |0;ai-astar;105;113;116;114;118;161;168;114;115;115 03:45:44 INFO - PROCESS | 1932 | |1;audio-beat-detection;149;111;114;146;118;140;113;113;143;113 03:45:44 INFO - PROCESS | 1932 | |2;audio-dft;185;186;184;187;187;188;257;225;196;262 03:45:44 INFO - PROCESS | 1932 | |3;audio-fft;80;60;60;91;63;60;60;58;83;60 03:45:44 INFO - PROCESS | 1932 | |4;audio-oscillator;87;71;96;69;69;70;90;68;74;125 03:45:44 INFO - PROCESS | 1932 | |5;imaging-gaussian-blur;89;91;135;130;88;84;93;85;89;84 03:45:44 INFO - PROCESS | 1932 | |6;imaging-darkroom;95;94;94;95;94;94;94;94;94;94 03:45:44 INFO - PROCESS | 1932 | |7;imaging-desaturate;85;84;79;80;91;126;134;83;85;83 03:45:44 INFO - PROCESS | 1932 | |8;json-parse-financial;56;53;55;54;58;56;57;56;57;57 03:45:44 INFO - PROCESS | 1932 | |9;json-stringify-tinderbox;72;74;67;79;73;71;74;75;76;73 03:45:44 INFO - PROCESS | 1932 | |10;stanford-crypto-aes;86;85;84;85;83;92;97;85;89;85 03:45:44 INFO - PROCESS | 1932 | |11;stanford-crypto-ccm;159;158;139;147;145;151;152;149;200;148 03:45:44 INFO - PROCESS | 1932 | |12;stanford-crypto-pbkdf2;167;170;171;170;170;171;169;168;168;233 03:45:44 INFO - PROCESS | 1932 | |13;stanford-crypto-sha256-iterative;67;67;69;66;68;70;85;90;66;67 03:45:44 INFO - PROCESS | 1932 | __end_tp_report 03:45:44 INFO - PROCESS | 1932 | __start_cc_report 03:45:44 INFO - PROCESS | 1932 | _x_x_mozilla_cycle_collect,1993 03:45:44 INFO - PROCESS | 1932 | __end_cc_report 03:45:44 INFO - PROCESS | 1932 | __startTimestamp1480074344488__endTimestamp 03:45:44 INFO - PROCESS | 1932 | 03:45:44 INFO - PROCESS | 1932 | ------- Summary: start ------- 03:45:44 INFO - PROCESS | 1932 | Number of tests: 14 03:45:44 INFO - PROCESS | 1932 | 03:45:44 INFO - PROCESS | 1932 | [#0] ai-astar Cycles:10 Average:123.90 Median:115.50 stddev:21.73 (18.8%) stddev-sans-first:21.94 03:45:44 INFO - PROCESS | 1932 | Values: 105.0 113.0 116.0 114.0 118.0 161.0 168.0 114.0 115.0 115.0 03:45:44 INFO - PROCESS | 1932 | 03:45:44 INFO - PROCESS | 1932 | [#1] audio-beat-detection Cycles:10 Average:126.00 Median:129.00 stddev:16.17 (12.5%) stddev-sans-first:14.86 03:45:44 INFO - PROCESS | 1932 | Values: 149.0 111.0 114.0 146.0 118.0 140.0 113.0 113.0 143.0 113.0 03:45:44 INFO - PROCESS | 1932 | 03:45:44 INFO - PROCESS | 1932 | [#2] audio-dft Cycles:10 Average:205.70 Median:192.00 stddev:30.85 (16.1%) stddev-sans-first:31.80 03:45:44 INFO - PROCESS | 1932 | Values: 185.0 186.0 184.0 187.0 187.0 188.0 257.0 225.0 196.0 262.0 03:45:44 INFO - PROCESS | 1932 | 03:45:44 INFO - PROCESS | 1932 | [#3] audio-fft Cycles:10 Average:67.50 Median:61.50 stddev:12.20 (19.8%) stddev-sans-first:12.08 03:45:44 INFO - PROCESS | 1932 | Values: 80.0 60.0 60.0 91.0 63.0 60.0 60.0 58.0 83.0 60.0 03:45:44 INFO - PROCESS | 1932 | 03:45:44 INFO - PROCESS | 1932 | [#4] audio-oscillator Cycles:10 Average:81.90 Median:72.50 stddev:18.25 (25.2%) stddev-sans-first:19.26 03:45:44 INFO - PROCESS | 1932 | Values: 87.0 71.0 96.0 69.0 69.0 70.0 90.0 68.0 74.0 125.0 03:45:44 INFO - PROCESS | 1932 | 03:45:44 INFO - PROCESS | 1932 | [#5] imaging-gaussian-blur Cycles:10 Average:96.80 Median:88.50 stddev:19.08 (21.6%) stddev-sans-first:20.02 03:45:44 INFO - PROCESS | 1932 | Values: 89.0 91.0 135.0 130.0 88.0 84.0 93.0 85.0 89.0 84.0 03:45:44 INFO - PROCESS | 1932 | 03:45:44 INFO - PROCESS | 1932 | [#6] imaging-darkroom Cycles:10 Average:94.20 Median:94.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 03:45:44 INFO - PROCESS | 1932 | Values: 95.0 94.0 94.0 95.0 94.0 94.0 94.0 94.0 94.0 94.0 03:45:44 INFO - PROCESS | 1932 | 03:45:44 INFO - PROCESS | 1932 | [#7] imaging-desaturate Cycles:10 Average:93.00 Median:83.50 stddev:19.86 (23.8%) stddev-sans-first:20.85 03:45:44 INFO - PROCESS | 1932 | Values: 85.0 84.0 79.0 80.0 91.0 126.0 134.0 83.0 85.0 83.0 03:45:44 INFO - PROCESS | 1932 | 03:45:44 INFO - PROCESS | 1932 | [#8] json-parse-financial Cycles:10 Average:55.90 Median:56.50 stddev:1.52 (2.7%) stddev-sans-first:1.62 03:45:44 INFO - PROCESS | 1932 | Values: 56.0 53.0 55.0 54.0 58.0 56.0 57.0 56.0 57.0 57.0 03:45:44 INFO - PROCESS | 1932 | 03:45:44 INFO - PROCESS | 1932 | [#9] json-stringify-tinderbox Cycles:10 Average:73.40 Median:74.00 stddev:3.17 (4.3%) stddev-sans-first:3.32 03:45:44 INFO - PROCESS | 1932 | Values: 72.0 74.0 67.0 79.0 73.0 71.0 74.0 75.0 76.0 73.0 03:45:44 INFO - PROCESS | 1932 | 03:45:44 INFO - PROCESS | 1932 | [#10] stanford-crypto-aes Cycles:10 Average:87.10 Median:85.50 stddev:4.36 (5.1%) stddev-sans-first:4.60 03:45:44 INFO - PROCESS | 1932 | Values: 86.0 85.0 84.0 85.0 83.0 92.0 97.0 85.0 89.0 85.0 03:45:44 INFO - PROCESS | 1932 | 03:45:44 INFO - PROCESS | 1932 | [#11] stanford-crypto-ccm Cycles:10 Average:154.80 Median:151.50 stddev:16.93 (11.2%) stddev-sans-first:17.89 03:45:44 INFO - PROCESS | 1932 | Values: 159.0 158.0 139.0 147.0 145.0 151.0 152.0 149.0 200.0 148.0 03:45:44 INFO - PROCESS | 1932 | 03:45:44 INFO - PROCESS | 1932 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.70 Median:170.00 stddev:20.18 (11.9%) stddev-sans-first:21.15 03:45:44 INFO - PROCESS | 1932 | Values: 167.0 170.0 171.0 170.0 170.0 171.0 169.0 168.0 168.0 233.0 03:45:44 INFO - PROCESS | 1932 | 03:45:44 INFO - PROCESS | 1932 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.50 Median:68.50 stddev:8.61 (12.6%) stddev-sans-first:8.97 03:45:44 INFO - PROCESS | 1932 | Values: 67.0 67.0 69.0 66.0 68.0 70.0 85.0 90.0 66.0 67.0 03:45:44 INFO - PROCESS | 1932 | -------- Summary: end -------- 03:45:44 INFO - PROCESS | 1932 | 03:45:45 INFO - TEST-INFO | 1932: exit 0 03:45:45 INFO - TEST-OK | kraken | took 60978ms 03:45:45 INFO - SUITE-END | took 1181s 03:45:45 INFO - Completed test suite (00:19:41) 03:45:45 INFO - javascript benchmark 03:45:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3328.4008878401887, 3342.598888133424, 3358.714509266714, 3367.4275373186556, 3406.8466918988643, 5700.287464066992, 5763.596548241625, 5815.709214539267, 5816.476517113459, 5838.532917366611, 5715.257016587838, 5791.82624521264, 5796.449337312959, 5804.854878628036, 5828.154917536955, 5190.351206099238, 5222.973885130588, 5257.23770053342, 5257.5005374489365, 5292.285541451894, 5211.635185537014, 5243.6853788772605, 5244.239585260135, 5246.973765131169, 5337.599680024014, 5277.762500687459, 5315.0, 5322.600804939626, 5336.493033161843, 5383.4616538346245, 5315.0, 5339.199120131972, 5339.759710813023, 5353.491418315254, 5372.247885296062, 5059.772310246048, 5173.922391164136, 5232.110541208004, 5247.291615631879, 5273.657212281228, 5676.205331253628, 5687.800926967578, 5742.28221472316, 5771.538276937877, 5792.710364481801, 6155.907661385083, 6209.006558950591, 6229.626222426624, 6248.3751624837605, 6251.12484252205, 6233.802549647199, 6255.687215639246, 6277.811665650038, 6280.466160376373, 6281.811545653638, 5712.314522257356, 5804.984127777622, 5818.5054270386645, 5844.503217226584, 5933.643981361132, 5298.70857102863, 6259.092431597484, 6275.843103922347, 6326.652034138075, 6332.430081292706, 6182.536309776785, 6192.696076226979, 6195.597286176384, 6203.596766210182, 6213.875722485525, 6129.064432475617, 6214.844628884314, 6231.40801623845, 6240.032794916704, 6255.718492667841, 6223.00431930898, 6279.215098112736, 6283.120363149163, 6285.717142728588, 6309.053641953744, 6183.5671502994355, 6290.591111577733, 6313.2424109107205, 6319.684015799284, 6334.904976425358, 4867.148249056401, 4944.060628480577, 4987.12725273083, 4998.800047998112, 5014.573761230264, 4394.890127746832, 4445.73325600465, 4450.3991961085485, 4452.487963884195, 4519.163954668397, 5834.095715164156, 5850.765969361263, 5858.677772722456, 5863.20846685692, 5873.500752436091, 6275.090111933744, 6305.747770089145, 6324.841878952971, 6331.050342448578, 6343.111964324999, 5661.207430959669, 5766.904288185231, 5773.740181691834, 5824.504917082011, 5884.264466941632, 5453.590980676465, 5460.781568737286, 5470.17947307907, 5472.0, 5497.285352904097, 6365.522585806083, 6367.777127800598, 6369.20384951881, 6401.487880969605, 6417.743290268524, 4686.976565117153, 6236.688165591611, 6266.091416744638, 6315.747370105144, 6316.431521163117, 6227.439530442282, 6327.209098862642, 6330.1454303668415, 6347.301796802256, 6352.142460767737, 6187.783427580013, 6247.219097612799, 6261.342559031418, 6269.435750782452, 6283.465905398092, 6158.353372895782, 6296.874062518816, 6321.96839015802, 6328.683565821782, 6336.968315158395, 5907.232059832194, 6119.624418755002, 6119.969400153149, 6157.4458298753325, 6215.285242197205, 6175.752969881154, 6190.442860142609, 6207.596506227081, 6209.0, 6220.377962203925, 6009.00654342789, 6040.607360521552, 6077.27072751255, 6079.270487541523, 6134.04922237054, 6002.429769171922, 6092.329843717099, 6099.9390006099375, 6104.4505994460715, 6123.9693801532485, 5932.317783454958, 5933.851653708692, 5943.732532036155, 5949.988501954592, 5954.9578823704815], "value": 5742.636670463266, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [474.855169173402, 477.3841744150047, 482.74655805702, 512.7769420302169, 517.969241209992, 29865.850670746644, 31344.84327578359, 31351.372972540536, 31570.526442103386, 39710.02899710028, 40720.38919416204, 41226.793866030624, 41231.79384103083, 41259.79370103153, 41265.793671031606, 8070.959645201766, 9221.907780922173, 9395.76510587234, 9413.57638906251, 9415.670451534197, 8347.749567512985, 8599.22606965373, 9271.490068046254, 11007.889921100767, 11276.661700148969, 11176.0, 11226.607068752595, 11238.438078096104, 11245.0, 11324.943375283112, 11175.83236251457, 11249.987501124939, 11251.212415130944, 11263.493142808553, 11276.548938042468, 7341.0, 8384.916150838535, 8584.957075214646, 8615.827683446296, 8676.0, 38209.61790382116, 38611.8069409654, 38715.61284387148, 39866.0, 39925.40111898324, 2737.671479422462, 3133.81197128173, 3158.494640857452, 3216.0, 3565.9108522286892, 29869.850650746608, 29966.55050174249, 30075.548866767014, 30544.54183187254, 30859.22851928697, 2902.259923719459, 3125.7030582094662, 3135.984320078408, 3180.157258326545, 3180.2367431816197, 486232.0, 487714.0, 489159.0, 489162.0, 496512.0, 49575.50424495781, 50799.746001269756, 50842.49157508452, 53166.936661266554, 53194.202086968726, 59977.0, 60446.395536044954, 61303.693481532304, 64023.67988160029, 64166.67916660387, 15544.0, 15705.607359815867, 15739.685206296039, 15751.606209844846, 15754.369825206863, 11203.60787372438, 12063.914247717572, 12232.388380581113, 12261.693457663452, 12415.937920310522, 14422.85577144236, 14697.706045879022, 16112.838871611133, 16550.42073527421, 16573.17134143288, 6256.405641464054, 6325.367463253638, 6365.172527571386, 6397.10440538325, 6409.391107844747, 855.2730179347504, 880.5905254056767, 974.7952929884804, 989.6734077754323, 993.145894530703, 28241.858790706327, 29180.416391672046, 29194.41611167765, 29696.0, 29752.85123574368, 1007.8286691262502, 1074.5594306334365, 1081.3457857995957, 1106.773111512138, 1125.358545629, 14648.707025859423, 15122.621934451507, 15141.470048548248, 15368.462103826312, 15529.611759705871, 194136.0, 194642.0, 194729.0, 195561.02219489097, 196890.03109969004, 55662.721686391305, 55732.72133639387, 55919.0, 55923.44076559264, 55978.60053498695, 1218.6587755428495, 1261.035307989389, 1329.6210579984659, 1354.5800801751489, 1368.0697125954048, 29634.40731185321, 30101.397972040875, 30253.69746302597, 30536.694633053386, 30802.845985770826, 48305.0, 48836.511634883194, 48899.0, 48900.5109948896, 48916.02167956692, 542216.0, 546092.0, 547249.0, 548478.0, 656526.0, 24214.273571792874, 25235.495290094463, 25292.0, 25335.98656053811, 25399.36501587475, 42699.0, 42807.0, 44092.33861492144, 44122.558774411846, 44270.0, 35936.82031589825, 36275.63724362828, 36590.63409365872, 36652.0, 36726.816365918, 35952.28095438024, 36463.817680912485, 36575.817120914224, 37634.62365376421, 37761.2447751038, 35447.468287975185, 36030.63969360273, 36518.08704782402, 36688.26623467569, 36693.08267293339, 30985.225369365948, 33238.00285991424, 33318.666813331554, 33424.0, 33431.83284083564, 24381.878090609433, 24746.628800567647, 24761.504769904142, 25165.87417062903, 25268.49463010693], "value": 18590.498681834342, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.7266368564361, 372.194199557957, 375.6018620262522, 383.2603076063199, 402.4566834773055, 14997.42527034662, 15009.099454032767, 16771.580710482227, 20220.898895505503, 20425.387238382835, 18915.810841891576, 19172.137253823577, 19502.609947801036, 19578.412647620593, 19801.207951681914, 5501.559875209983, 5736.483716465517, 5767.826965191051, 5770.624909380897, 5775.249217601706, 4416.86749397517, 5575.745457272102, 6285.465735412496, 6305.527085468608, 6400.743970241208, 6042.697865106749, 6269.279032911228, 6332.1135041094285, 6350.968245158768, 6364.140840986477, 5411.540019098381, 6292.653904035276, 6347.1114044033875, 6350.872982540323, 6355.491560675158, 5288.312519372457, 5483.451654834525, 5507.284053073115, 5526.392096869341, 5542.362628297757, 23735.525289494115, 24132.63801042986, 24153.0, 24208.031678733005, 24291.271261862174, 2222.733272007353, 2237.3735354100713, 2256.7743225677464, 2357.4695693469002, 2816.774658027353, 9475.336726429152, 14719.411223551046, 14756.852431475656, 15302.387904483807, 15304.693906121927, 1431.4632012995128, 1730.766346543213, 1781.5189898727401, 1781.7217361128376, 1812.9728054079199, 113035.0, 117513.0, 117960.41019794845, 118057.0, 118268.0, 11229.27009744364, 11253.943730281297, 11491.425428728697, 11492.770144597062, 11619.477123529461, 14357.78463323051, 14362.994590378574, 14368.0, 14377.496787612383, 14387.352569134415, 8263.380246481538, 8378.874316885252, 8401.915980840113, 8530.914690853137, 8553.572321383906, 8290.792730181673, 8291.129431409736, 8319.542425166674, 8454.408191426664, 8471.57642117904, 4400.515943246241, 4439.933400998988, 4500.459944806644, 4516.1419330327135, 4561.931571026437, 1659.3528523875698, 1834.8256915592997, 1887.6130393269348, 1889.4520589029028, 1893.8390236830023, 398.94813674222166, 402.3040140556853, 799.3565180030132, 802.558592773972, 931.2503434735104, 11050.060744836617, 11407.48666310018, 11526.36604986717, 11575.76848463026, 11968.341741204144, 416.92286926918615, 422.3284976886703, 426.67146297350865, 426.9509006464234, 438.23746680775224, 10260.897391026143, 11608.24546404481, 11679.240849344766, 11681.88318116808, 11705.414729263503, 580033.6988205477, 608502.0, 612450.0, 622503.0, 627071.0, 95114.04885951051, 105625.94374056315, 118926.4053679726, 130865.9999999981, 142976.14047719195, 887.9344786256518, 930.7440453875103, 989.9455529945778, 1009.3136667066318, 1010.0858722855827, 18010.18954147093, 18201.635967280847, 18431.447056588324, 18524.0, 18901.338453154072, 41095.97260068522, 55337.7233113848, 56549.0, 57298.42701572931, 58047.4195258042, 74788.8132125852, 81440.59279703564, 82209.0, 85551.0, 85874.57062714646, 7449.81375465596, 7786.259541984643, 7870.803229919298, 8022.9598852005365, 8208.0, 8675.219230269306, 10742.8388574173, 11923.82114268304, 12275.877241227474, 12299.262044277371, 10205.285630005828, 10430.739231519272, 10643.521041553304, 10693.89306106929, 10922.726931826768, 8481.40630155883, 9870.555824988036, 10384.792304154025, 10685.091767199876, 10905.945470272598, 10366.948165259126, 10713.94643026811, 10899.836502452312, 10938.56245750161, 10984.176186786182, 7106.289371063059, 7598.924010760043, 7613.961930190314, 7934.325582325567, 8059.597020149086, 9222.815543689223, 10336.0, 10543.94728026355, 10627.734306642396, 10712.0], "value": 9004.152508144336, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1294.7863602505588, 1322.6627210061433, 1459.708058388322, 1569.9450519231823, 1808.6111486030509, 7527.849443011143, 7928.722494712686, 8234.0, 8469.872951905727, 8496.702615408461, 8339.74980750577, 8429.409941304111, 8437.240648341649, 8440.366972477059, 8493.193146651074, 2330.242671131885, 2367.95264094718, 2369.6327069304284, 2371.3597328721235, 2376.370261880603, 2347.471818840764, 2399.1003373734848, 2402.4594466245044, 2408.5664580375524, 2412.7345991940874, 1964.6463636545416, 2325.476767727265, 2366.2191476812695, 2372.833901626887, 2373.264288070695, 1993.4518007547967, 2306.9077236910503, 2312.7918487336137, 2313.0169677886997, 2339.94150146246, 2289.164454973939, 2359.528094381131, 2380.67860838788, 2382.344855164826, 2387.8209134315, 7395.149557800814, 8261.876071858927, 8480.74557763268, 8487.0, 8494.575271236414, 5144.485551444863, 5350.9732451337495, 5359.705216213107, 5369.275147855029, 5387.1111266640955, 2652.7612514873754, 3363.377775111612, 3482.564679415073, 3556.1643013891717, 3565.7682250653625, 870.7039606533746, 897.824924139793, 1056.073417507335, 1071.8606581144402, 1074.548689550383, 10881.23831331807, 10969.23215374924, 11032.22774405792, 11061.557537698482, 11065.336079835235, 3381.644927282649, 4395.956040439554, 4527.366168736379, 4528.841490547881, 4555.2711566149255, 8779.429337093068, 8782.736517904474, 8790.120987901286, 8804.075572064969, 8806.95596522026, 5144.382674079135, 5154.175331946871, 5168.173092305213, 5170.45710200431, 5175.637705360664, 4101.692373072032, 4325.091730736518, 4466.352378905106, 4466.441694788152, 4849.2968519564465, 3667.9083022924106, 3700.4264339027495, 3788.242351529705, 3790.336691079105, 3791.2038471920655, 983.2085171437062, 1143.188336281233, 1146.94265286737, 1152.302856771641, 1164.126904821384, 829.4318391901567, 872.7687162901836, 892.1747383670208, 905.4114825363434, 916.8585111536047, 5650.378458369576, 5756.133503265934, 5851.970740146272, 5900.940990590125, 5946.345901950782, 806.435495153395, 841.869510225916, 846.1876598465419, 858.772425307294, 927.2072378116748, 3731.421629647409, 3746.756460830037, 3774.0, 3864.8260828262796, 3877.7673339599714, 9637.614495420245, 10285.640002599874, 10307.175425965905, 10365.533550990223, 10496.527656255486, 10247.641332553325, 10317.226208034428, 10419.843702344471, 10545.420001899816, 10558.947205263925, 6573.934260657332, 8053.194680532017, 8225.259726624512, 8228.177182281725, 8231.0, 5343.492368225013, 5508.14623733322, 5515.834524964257, 5558.777648893999, 5687.488126068673, 9396.154346108882, 9601.423914565148, 9613.423194608346, 9622.326437149612, 9622.422654640744, 8827.646894124164, 9128.77178070527, 9146.588403521724, 9570.856437153308, 9597.9040209597, 6745.561538499982, 7018.227994920452, 7291.635418229173, 7322.450816188701, 7325.487215895049, 6645.468362530889, 8039.67841286342, 8123.6750529978135, 8152.470089444168, 8155.062167850536, 7132.322429369202, 7228.132624085143, 7236.131664200329, 7284.453665974967, 7380.114386273682, 7089.291070892869, 7160.283971603007, 7171.533850299714, 7191.748288809867, 7198.7840364789145, 6602.372774586406, 6796.116504854337, 6813.591184528944, 6913.861722765617, 6966.9651651741415, 4871.561559459666, 4900.313956046228, 4901.09329773993, 4932.654714169974, 4988.002399520038, 4232.470941132358, 4791.688540244873, 4844.660873738804, 4851.51484851512, 5095.796168153232], "value": 4384.301665795707, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.363641806534, 304.13928582112624, 306.8097779376787, 313.21695760598504, 316.4399013745669, 4446.488194014035, 4921.022781217365, 5147.099257629914, 5181.326427564416, 5184.53339199472, 4379.277419225832, 4887.486813884544, 5015.843187429404, 5073.568746656539, 5183.455737147592, 3964.2071585682834, 3966.6002068748426, 3980.1840622672426, 3992.421098940652, 4026.047367026997, 3518.771279866813, 3705.2770461735286, 3990.421388898608, 3996.8800935971967, 4003.339448990913, 3882.2817778710887, 3926.489556357684, 3928.194720082376, 3928.194720082376, 3940.7044471664594, 3901.4342920276545, 3908.355121404993, 3918.941215881764, 3928.017995501125, 3966.7619942803235, 3060.9592738469028, 3384.7969121852766, 3412.931741365184, 3425.4348032574594, 3445.6898879101, 4865.107913669086, 4960.561437183213, 4987.326710894019, 5007.173816320303, 5011.674241174312, 4220.7548773112, 4262.722923010026, 4460.0633866887665, 4475.686701930867, 4488.236999710024, 4161.646260067898, 4682.016776476944, 4929.482404347527, 5438.53772429344, 5442.183672449125, 3686.189038411545, 3734.3464893643777, 3740.8129593520216, 3743.8689645862264, 3751.887443376676, 4842.854714358575, 4947.6536642434685, 5100.710042022912, 5543.184110404311, 5727.455891690282, 3363.9663603364143, 3760.7555508891833, 3814.6757525610633, 3815.8664446744233, 3831.2912111258947, 3651.379265524867, 3791.9241615167543, 3815.664517418881, 3821.8280177392085, 3864.401017842239, 3796.7342286040266, 4371.0548805781755, 4372.010735115515, 4442.244818380883, 4455.198064348384, 5396.109641909119, 5432.049391356576, 5474.534664553478, 5493.395726470085, 5509.311336082989, 1130.9038731707897, 1132.9293817342698, 1152.458344578047, 1173.7769823733463, 1223.7736018836545, 704.2851505721696, 834.4200780457523, 839.7564706235246, 842.5955541340192, 856.1609622569916, 3895.5325360956867, 4322.7190232634775, 4692.0, 4744.691595046311, 4831.3814872017565, 4352.869413917588, 5195.830543368542, 5243.947560524346, 5299.761510732026, 5301.3373328333955, 4028.0, 4074.8166332515107, 4668.195420732092, 4745.454272758608, 4805.438232574427, 2203.889805509718, 2655.800814938849, 2710.5798601216843, 2722.679500442284, 2834.844083575423, 4838.250071238964, 4891.046245981999, 4978.664294922338, 5076.314697515862, 5406.864828379322, 4374.300111982131, 4922.237053256751, 5181.5077567631015, 5187.771965847897, 5330.307060082165, 4928.507149285115, 4935.975320123376, 5332.493413125747, 5535.58483113768, 5547.389787123413, 4493.236149854598, 4919.557239848431, 5326.334208223972, 5352.223927530408, 5360.231123729238, 4368.082702632356, 4430.778461076998, 5193.4027586828, 5216.008958297912, 5227.372715274039, 5847.538115471132, 5895.882082358243, 5917.289925209003, 5925.970370148294, 5929.940700592939, 3814.6376094271, 3815.7328986970647, 3818.3508803502914, 3827.5072721638026, 3829.310724069694, 5442.170071562844, 5658.971705141448, 5698.230738850202, 5722.742476588647, 5765.769369225184, 4697.882552936204, 4782.473927868002, 5312.814051508179, 5340.092184328596, 5389.703566303734, 4800.471948085638, 5281.498257665516, 5559.2217089606675, 5571.9721401392735, 5584.497395234448, 5120.948790512196, 5158.896822063462, 5449.04641687711, 5454.481824226692, 5503.532199763065, 4199.70602057853, 5190.702324418895, 5329.600279978939, 5535.723213839211, 5566.526845218202, 5217.713025783467, 5336.451259912115, 5371.88430710195, 5419.647722897999, 5454.863628409322], "value": 3892.5189682910236, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [761.1703243464624, 763.8892360607708, 787.4409169489662, 793.1315209845219, 793.3851265269416, 13926.025178237514, 14395.78406323906, 14440.133591984488, 14441.42234310629, 14444.783328250072, 12790.744185116291, 12882.549110781125, 12897.355132243374, 12905.354732263373, 12907.096503244753, 628.2931701835436, 651.3453978751348, 653.7058323754305, 789.7670187294748, 802.9718959836407, 787.6258777080898, 807.7132617920641, 819.9508029518219, 822.6298165825402, 825.0965193113531, 865.1046167216932, 866.7096522664915, 867.3494878840869, 867.4578388507183, 867.7873920889391, 649.909012738217, 661.5898143151229, 687.1994126842222, 862.9870551941726, 865.1953683074738, 511.10046318479374, 514.0515748444114, 604.2084868821858, 628.8302158417201, 629.9307076221612, 13671.17972921628, 13700.588982330546, 13717.725645487035, 13727.382267797973, 13745.0, 12692.492300307977, 12829.101962862604, 13066.150700204456, 13066.803997940038, 13157.671058223525, 11365.488553015135, 11800.01894704295, 11927.880721192763, 12128.0, 12252.81620775689, 4312.482502099768, 4364.192624364503, 4396.802143903532, 4524.072565124142, 4527.298268768346, 86503.0, 86770.56614716949, 94359.52820235855, 95888.52055739677, 96287.0, 10087.243456740774, 10401.219908506891, 10481.475926203659, 10497.685069447929, 10756.946215268874, 19266.518337041685, 19403.02984850752, 19502.0, 19553.511162221057, 19596.314129005415, 7381.188069312371, 7442.627868606548, 7462.0, 7659.004329437137, 7709.498882572615, 4132.8052027192425, 4172.061286210578, 4238.766867822238, 4259.914801703949, 4300.2904520754355, 3203.343314620497, 3256.1859535116223, 3308.520264561625, 3310.1724568857785, 3311.586051743532, 597.3399393670019, 605.9606125601822, 614.4009590649171, 614.7264467312093, 637.101686621861, 559.7593034994991, 601.2274227617578, 610.3499772742016, 616.6454288783967, 620.4663988969482, 7564.773056808304, 8107.716229931924, 8577.828443431097, 8619.888591911573, 8668.8699669505, 538.9003034438641, 552.425477503392, 589.7589161187556, 594.4382558482192, 596.1385797522538, 4249.2988656871885, 4264.104538046983, 4270.197517742844, 4327.675424343187, 4362.934555981663, 79923.20076799273, 100651.0, 113337.0, 120464.0, 122182.0, 81251.0, 82051.58974205091, 82201.17798822054, 91066.0, 102063.0, 56865.71567142221, 59828.70085649631, 60981.0, 61218.0, 88328.0, 9319.906800931903, 9634.421934683942, 9765.414075155513, 9789.314747967574, 9796.216302695624, 55727.88544229011, 56361.43638563562, 56487.43512564822, 57706.7114664424, 58604.12093818682, 77328.0, 77532.4493510138, 79507.0, 81008.18991810242, 82483.58758206171, 11905.047596192111, 12025.518979240733, 12036.217645852992, 12348.197367171106, 12398.81401778956, 13097.607071787861, 13460.528881489101, 13522.661933451742, 13873.514426995007, 14206.573802785933, 10860.511276992713, 11639.825402618799, 11689.415529223676, 12242.0, 12417.19288246261, 10926.399048052115, 11034.834477482684, 11119.555217791521, 11773.705657358634, 12842.935785321013, 12329.753404931671, 12696.0, 12940.8058879115, 13047.478100875858, 13591.980601454732, 9999.60001599957, 10168.186545076229, 10227.0, 10878.78242435131, 11504.0, 11128.054115400282, 11257.437128143723, 11340.773184536098, 11393.0, 11442.885571144181], "value": 7081.210712602576, "unit": "score"}], "name": "dromaeo_css", "value": 6985.352709854196, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 113.0, 116.0, 114.0, 118.0, 161.0, 168.0, 114.0, 115.0, 115.0], "value": 123.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 111.0, 114.0, 146.0, 118.0, 140.0, 113.0, 113.0, 143.0, 113.0], "value": 126.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 186.0, 184.0, 187.0, 187.0, 188.0, 257.0, 225.0, 196.0, 262.0], "value": 205.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 60.0, 60.0, 91.0, 63.0, 60.0, 60.0, 58.0, 83.0, 60.0], "value": 67.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 96.0, 69.0, 69.0, 70.0, 90.0, 68.0, 74.0, 125.0], "value": 81.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 91.0, 135.0, 130.0, 88.0, 84.0, 93.0, 85.0, 89.0, 84.0], "value": 96.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 95.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 79.0, 80.0, 91.0, 126.0, 134.0, 83.0, 85.0, 83.0], "value": 93.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 53.0, 55.0, 54.0, 58.0, 56.0, 57.0, 56.0, 57.0, 57.0], "value": 55.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 74.0, 67.0, 79.0, 73.0, 71.0, 74.0, 75.0, 76.0, 73.0], "value": 73.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 84.0, 85.0, 83.0, 92.0, 97.0, 85.0, 89.0, 85.0], "value": 87.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 158.0, 139.0, 147.0, 145.0, 151.0, 152.0, 149.0, 200.0, 148.0], "value": 154.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 170.0, 171.0, 170.0, 170.0, 171.0, 169.0, 168.0, 168.0, 233.0], "value": 175.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 69.0, 66.0, 68.0, 70.0, 85.0, 90.0, 66.0, 67.0], "value": 71.5, "unit": "score"}], "name": "kraken", "value": 1507.3999999999999, "alertThreshold": 2.0}]} 03:45:45 INFO - Return code: 0 03:45:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:45:45 INFO - # TBPL SUCCESS # 03:45:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:45:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:45:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:45:45 INFO - 2016-11-25 03:45:45.832 screenresolution[1936:11189] starting screenresolution argv=screenresolution get 03:45:45 INFO - 2016-11-25 03:45:45.835 screenresolution[1936:11189] Display 0: 1600x1200x32@60 03:45:45 INFO - 2016-11-25 03:45:45.842 screenresolution[1937:11192] starting screenresolution argv=screenresolution list 03:45:45 INFO - Available Modes on Display 0 03:45:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:45:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:45:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:45:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:45:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:45:46 INFO - Intel Iris: 03:45:46 INFO - Chipset Model: Intel Iris 03:45:46 INFO - Type: GPU 03:45:46 INFO - Bus: Built-In 03:45:46 INFO - VRAM (Dynamic, Max): 1536 MB 03:45:46 INFO - Vendor: Intel (0x8086) 03:45:46 INFO - Device ID: 0x0a2e 03:45:46 INFO - Revision ID: 0x0009 03:45:46 INFO - Displays: 03:45:46 INFO - Display: 03:45:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:45:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:45:46 INFO - Display Serial Number: 1600x1200 60 03:45:46 INFO - Main Display: Yes 03:45:46 INFO - Mirror: Off 03:45:46 INFO - Online: Yes 03:45:46 INFO - Rotation: Supported 03:45:46 INFO - Return code: 0 03:45:46 INFO - Running post-action listener: _resource_record_post_action 03:45:46 INFO - [mozharness: 2016-11-25 11:45:46.087742Z] Finished run-tests step (success) 03:45:46 INFO - Running post-run listener: _resource_record_post_run 03:45:46 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 2428928; Write bytes: 652212736; Read time: 24; Write time: 1876 03:45:46 INFO - TinderboxPrint: CPU usage
53.3% 03:45:46 INFO - TinderboxPrint: I/O read bytes / time
2,428,928 / 24 03:45:46 INFO - TinderboxPrint: I/O write bytes / time
652,212,736 / 1,876 03:45:46 INFO - TinderboxPrint: CPU idle
2,268.5 (46.7%) 03:45:46 INFO - TinderboxPrint: CPU system
970.2 (20.0%) 03:45:46 INFO - TinderboxPrint: CPU user
1,615.0 (33.3%) 03:45:46 INFO - TinderboxPrint: Swap in / out
350,916,608 / 0 03:45:46 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 337777152; Write bytes: 334190592; Read time: 26478; Write time: 706 03:45:46 INFO - run-tests - Wall time: 1182s; CPU: 53.0%; Read bytes: 2428928; Write bytes: 300290560; Read time: 24; Write time: 1113 03:45:46 INFO - Running post-run listener: _upload_blobber_files 03:45:46 INFO - Blob upload gear active. 03:45:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:45:46 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 03:45:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:45:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:45:46 INFO - (blobuploader) - INFO - Open directory for files ... 03:45:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:45:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:45:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:45:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:45:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:45:47 INFO - (blobuploader) - INFO - Done attempting. 03:45:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:45:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:45:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:45:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:45:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:45:48 INFO - (blobuploader) - INFO - Done attempting. 03:45:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:45:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:45:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:45:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:45:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:45:51 INFO - (blobuploader) - INFO - Done attempting. 03:45:51 INFO - (blobuploader) - INFO - Iteration through files over. 03:45:51 INFO - Return code: 0 03:45:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:45:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:45:51 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/552ebbc5e1bf355db3b1b62bf6b32cb505f65ff4f525700b0b3e517654d231d70502600771b706d05a85242f5d40ebfe6d204ecdaa4eb90cedbd412f1c150c4d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7362ae2b33b546317a8067dd29b3a529a0d940f83d8bd3fe0844eb75e262ad245dcb5c518b90e1e3f013962753a7ca11426c9312471661bb7454b0b558d33c13", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/10cbecebbbaceba4676282acb4dc89af1d1adbd1bef48a0cfdc37bbe6432218934c364ec45b817c702d6c7fc0f5e97c3d392d980441054f322766856de382496"} 03:45:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:45:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:45:51 INFO - Contents: 03:45:51 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/552ebbc5e1bf355db3b1b62bf6b32cb505f65ff4f525700b0b3e517654d231d70502600771b706d05a85242f5d40ebfe6d204ecdaa4eb90cedbd412f1c150c4d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7362ae2b33b546317a8067dd29b3a529a0d940f83d8bd3fe0844eb75e262ad245dcb5c518b90e1e3f013962753a7ca11426c9312471661bb7454b0b558d33c13", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/10cbecebbbaceba4676282acb4dc89af1d1adbd1bef48a0cfdc37bbe6432218934c364ec45b817c702d6c7fc0f5e97c3d392d980441054f322766856de382496"} 03:45:51 INFO - Running post-run listener: copy_logs_to_upload_dir 03:45:51 INFO - Copying logs to upload dir... 03:45:51 INFO - mkdir: /builds/slave/test/build/upload/logs 03:45:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.057159 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-25 03:45:51.251844) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-25 03:45:51.259256) ========= 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.fLMkiZGKe7/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.ASCwAxYeS3/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/552ebbc5e1bf355db3b1b62bf6b32cb505f65ff4f525700b0b3e517654d231d70502600771b706d05a85242f5d40ebfe6d204ecdaa4eb90cedbd412f1c150c4d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7362ae2b33b546317a8067dd29b3a529a0d940f83d8bd3fe0844eb75e262ad245dcb5c518b90e1e3f013962753a7ca11426c9312471661bb7454b0b558d33c13", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/10cbecebbbaceba4676282acb4dc89af1d1adbd1bef48a0cfdc37bbe6432218934c364ec45b817c702d6c7fc0f5e97c3d392d980441054f322766856de382496"} build_url:https://queue.taskcluster.net/v1/task/eSO68kuZT2ufbhU_aVk-LQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011093 build_url: 'https://queue.taskcluster.net/v1/task/eSO68kuZT2ufbhU_aVk-LQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/552ebbc5e1bf355db3b1b62bf6b32cb505f65ff4f525700b0b3e517654d231d70502600771b706d05a85242f5d40ebfe6d204ecdaa4eb90cedbd412f1c150c4d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7362ae2b33b546317a8067dd29b3a529a0d940f83d8bd3fe0844eb75e262ad245dcb5c518b90e1e3f013962753a7ca11426c9312471661bb7454b0b558d33c13", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/10cbecebbbaceba4676282acb4dc89af1d1adbd1bef48a0cfdc37bbe6432218934c364ec45b817c702d6c7fc0f5e97c3d392d980441054f322766856de382496"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-25 03:45:51.297044) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 03:45:51.297413) ========= 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.fLMkiZGKe7/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.ASCwAxYeS3/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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.004472 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 03:45:51.348679) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-25 03:45:51.352089) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-25 03:45:51.352502) ========= ========= Total master_lag: 0.22 =========