builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0292 starttime: 1479315576.28 results: success (0) buildid: 20161116062633 builduid: 5e76dae4226445a5977768f6f03ad329 revision: d86e4e3d92b082d1e86160ac182f8c73cd80597a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-16 08:59:36.280086) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-16 08:59:36.280548) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-16 08:59:36.280831) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-16 08:59:36.306218) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 08:59:36.306623) ========= 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.x9GuvOyBOd/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.ctQCZbsW2Y/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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-16 08:59:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 164M=0s 2016-11-16 08:59:36 (164 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.139786 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 08:59:36.477876) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 08:59:36.478205) ========= 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.x9GuvOyBOd/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.ctQCZbsW2Y/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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.024171 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 08:59:36.526431) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 08:59:36.526838) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d86e4e3d92b082d1e86160ac182f8c73cd80597a --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 d86e4e3d92b082d1e86160ac182f8c73cd80597a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.x9GuvOyBOd/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.ctQCZbsW2Y/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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-16 08:59:36,631 truncating revision to first 12 chars 2016-11-16 08:59:36,631 Setting DEBUG logging. 2016-11-16 08:59:36,631 attempt 1/10 2016-11-16 08:59:36,631 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/d86e4e3d92b0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-16 08:59:37,019 unpacking tar archive at: mozilla-aurora-d86e4e3d92b0/testing/mozharness/ program finished with exit code 0 elapsedTime=0.642664 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 08:59:37.191403) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-16 08:59:37.191739) ========= script_repo_revision: d86e4e3d92b082d1e86160ac182f8c73cd80597a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-16 08:59:37.192123) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-16 08:59:37.192391) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-16 08:59:37.210733) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-16 08:59:37.211097) ========= /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.x9GuvOyBOd/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.ctQCZbsW2Y/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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 08:59:37 INFO - MultiFileLogger online at 20161116 08:59:37 in /builds/slave/test 08:59:37 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 08:59:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:59:37 INFO - {'append_to_log': False, 08:59:37 INFO - 'base_work_dir': '/builds/slave/test', 08:59:37 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:59:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:59:37 INFO - 'branch': 'Mozilla-Aurora', 08:59:37 INFO - 'buildbot_json_path': 'buildprops.json', 08:59:37 INFO - 'config_files': ('talos/mac_config.py',), 08:59:37 INFO - 'default_actions': ('clobber', 08:59:37 INFO - 'read-buildbot-config', 08:59:37 INFO - 'download-and-extract', 08:59:37 INFO - 'populate-webroot', 08:59:37 INFO - 'create-virtualenv', 08:59:37 INFO - 'install', 08:59:37 INFO - 'run-tests'), 08:59:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:59:37 INFO - 'download_minidump_stackwalk': True, 08:59:37 INFO - 'download_symbols': 'ondemand', 08:59:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:59:37 INFO - 'tooltool.py': '/tools/tooltool.py', 08:59:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:59:37 INFO - '/tools/misc-python/virtualenv.py')}, 08:59:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:59:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:59:37 INFO - 'installer_path': 'installer.exe', 08:59:37 INFO - 'log_level': 'info', 08:59:37 INFO - 'log_name': 'talos', 08:59:37 INFO - 'log_to_console': True, 08:59:37 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:59:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:59:37 INFO - 'opt_config_files': (), 08:59:37 INFO - 'pip_index': False, 08:59:37 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:59:37 INFO - 'cmd': ('bash', 08:59:37 INFO - '-c', 08:59:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:59:37 INFO - 'enabled': True, 08:59:37 INFO - 'halt_on_failure': False, 08:59:37 INFO - 'name': 'check_screen_resolution'},), 08:59:37 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:59:37 INFO - 'cmd': ('bash', 08:59:37 INFO - '-c', 08:59:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:59:37 INFO - 'enabled': True, 08:59:37 INFO - 'halt_on_failure': False, 08:59:37 INFO - 'name': 'check_screen_resolution'},), 08:59:37 INFO - 'run_cmd_checks_enabled': True, 08:59:37 INFO - 'sps_profile': False, 08:59:37 INFO - 'sps_profile_interval': 0, 08:59:37 INFO - 'suite': 'dromaeojs', 08:59:37 INFO - 'system_bits': '32', 08:59:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:59:37 INFO - 'title': 't-yosemite-r7-0292', 08:59:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:59:37 INFO - 'use_talos_json': True, 08:59:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:59:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:59:37 INFO - 'work_dir': 'build'} 08:59:37 INFO - [mozharness: 2016-11-16 16:59:37.428381Z] Running clobber step. 08:59:37 INFO - Running pre-action listener: _resource_record_pre_action 08:59:37 INFO - Running main action method: clobber 08:59:37 INFO - rmtree: /builds/slave/test/build 08:59:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:59:39 INFO - Running post-action listener: _resource_record_post_action 08:59:39 INFO - [mozharness: 2016-11-16 16:59:39.776732Z] Finished clobber step (success) 08:59:39 INFO - [mozharness: 2016-11-16 16:59:39.776897Z] Running read-buildbot-config step. 08:59:39 INFO - Running pre-action listener: _resource_record_pre_action 08:59:39 INFO - Running main action method: read_buildbot_config 08:59:39 INFO - Using buildbot properties: 08:59:39 INFO - { 08:59:39 INFO - "project": "", 08:59:39 INFO - "product": "firefox", 08:59:39 INFO - "script_repo_revision": "production", 08:59:39 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 08:59:39 INFO - "repository": "", 08:59:39 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 08:59:39 INFO - "buildid": "20161116062633", 08:59:39 INFO - "pgo_build": "False", 08:59:39 INFO - "basedir": "/builds/slave/test", 08:59:39 INFO - "buildnumber": 40, 08:59:39 INFO - "slavename": "t-yosemite-r7-0292", 08:59:39 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 08:59:39 INFO - "platform": "yosemite_r7", 08:59:39 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:59:39 INFO - "branch": "mozilla-aurora", 08:59:39 INFO - "repo_path": "releases/mozilla-aurora", 08:59:39 INFO - "revision": "d86e4e3d92b082d1e86160ac182f8c73cd80597a", 08:59:39 INFO - "stage_platform": "macosx64", 08:59:39 INFO - "builduid": "5e76dae4226445a5977768f6f03ad329", 08:59:39 INFO - "slavebuilddir": "test" 08:59:39 INFO - } 08:59:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/AMrsAB-XTzeRmXfTwtEONQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 08:59:39 INFO - Running post-action listener: _resource_record_post_action 08:59:39 INFO - [mozharness: 2016-11-16 16:59:39.780272Z] Finished read-buildbot-config step (success) 08:59:39 INFO - [mozharness: 2016-11-16 16:59:39.780431Z] Running download-and-extract step. 08:59:39 INFO - Running pre-action listener: _resource_record_pre_action 08:59:39 INFO - Running main action method: download_and_extract 08:59:39 INFO - mkdir: /builds/slave/test/build/tests 08:59:39 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')]} 08:59:39 INFO - trying https://queue.taskcluster.net/v1/task/AMrsAB-XTzeRmXfTwtEONQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 08:59:39 INFO - Downloading https://queue.taskcluster.net/v1/task/AMrsAB-XTzeRmXfTwtEONQ/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 08:59:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AMrsAB-XTzeRmXfTwtEONQ/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 08:59:41 INFO - Downloaded 1199 bytes. 08:59:41 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:59:41 INFO - Using the following test package requirements: 08:59:41 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:59:41 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:59:41 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 08:59:41 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:59:41 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 08:59:41 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:59:41 INFO - u'jsshell-mac.zip'], 08:59:41 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:59:41 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 08:59:41 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:59:41 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:59:41 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 08:59:41 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:59:41 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 08:59:41 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:59:41 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 08:59:41 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:59:41 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 08:59:41 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:59:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AMrsAB-XTzeRmXfTwtEONQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:59:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AMrsAB-XTzeRmXfTwtEONQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:59:41 INFO - Fetch https://queue.taskcluster.net/v1/task/AMrsAB-XTzeRmXfTwtEONQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:59:41 INFO - Http code: 200 08:59:41 INFO - via: 1.1 6e65abb04cb818a6ec78111935b507f7.cloudfront.net (CloudFront) 08:59:41 INFO - x-amz-cf-id: FLYe8elRJMM7-3YfbPocSO4RSuvazF4Oizs7KrNfwiuQb5XWvXhCAg== 08:59:41 INFO - x-amz-version-id: SmjnOdjmIiHa59fv1Mkdg26FLBog5gn8 08:59:42 INFO - Expected file size: 16327495 08:59:42 INFO - Obtained file size: 16327495 08:59:48 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 08:59:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AMrsAB-XTzeRmXfTwtEONQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:59:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AMrsAB-XTzeRmXfTwtEONQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:59:48 INFO - Fetch https://queue.taskcluster.net/v1/task/AMrsAB-XTzeRmXfTwtEONQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:59:49 INFO - Http code: 200 08:59:49 INFO - via: 1.1 9767e262e4af15c8d83a726bb0f44fa5.cloudfront.net (CloudFront) 08:59:49 INFO - x-amz-cf-id: qaHjOUrp63bP_BHYUewFjASXPpuKXJ09bVDSEkHT8OcQ1mLeH0sOUQ== 08:59:49 INFO - x-amz-version-id: SmjnOdjmIiHa59fv1Mkdg26FLBog5gn8 08:59:49 INFO - Expected file size: 16327495 08:59:49 INFO - Obtained file size: 16327495 08:59:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AMrsAB-XTzeRmXfTwtEONQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 08:59:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AMrsAB-XTzeRmXfTwtEONQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:59:56 INFO - Fetch https://queue.taskcluster.net/v1/task/AMrsAB-XTzeRmXfTwtEONQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 08:59:56 INFO - Http code: 200 08:59:56 INFO - via: 1.1 b04a4cffa8fb4f524ff7edcab1b5ae31.cloudfront.net (CloudFront) 08:59:56 INFO - x-amz-cf-id: G4bbT2dUAg6FSSzrTvCVhOtBOJNBTKRDqJgvHrtw0qNB6J5Gcd1bnA== 08:59:56 INFO - x-amz-version-id: UFVZD9q6IM.1lRh1JF4krwEDCtCikVnL 08:59:56 INFO - Expected file size: 14409038 08:59:56 INFO - Obtained file size: 14409038 08:59:57 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')]} 08:59:57 INFO - trying https://queue.taskcluster.net/v1/task/AMrsAB-XTzeRmXfTwtEONQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:59:57 INFO - Downloading https://queue.taskcluster.net/v1/task/AMrsAB-XTzeRmXfTwtEONQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 08:59:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AMrsAB-XTzeRmXfTwtEONQ/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 09:00:00 INFO - Downloaded 114367670 bytes. 09:00:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AMrsAB-XTzeRmXfTwtEONQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:00:00 INFO - mkdir: /builds/slave/test/properties 09:00:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:00:00 INFO - Writing to file /builds/slave/test/properties/build_url 09:00:00 INFO - Contents: 09:00:00 INFO - build_url:https://queue.taskcluster.net/v1/task/AMrsAB-XTzeRmXfTwtEONQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:00:01 INFO - Running post-action listener: _resource_record_post_action 09:00:01 INFO - Running post-action listener: set_extra_try_arguments 09:00:01 INFO - [mozharness: 2016-11-16 17:00:01.262869Z] Finished download-and-extract step (success) 09:00:01 INFO - [mozharness: 2016-11-16 17:00:01.263001Z] Running populate-webroot step. 09:00:01 INFO - Running pre-action listener: _resource_record_pre_action 09:00:01 INFO - Running main action method: populate_webroot 09:00:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:00:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:00:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:00:01 INFO - '--tppagecycles', 09:00:01 INFO - '7'], 09:00:01 INFO - 'tests': ['tsvgm']}}, 09:00:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:00:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:00:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:00:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:00:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:00:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:00:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:01 INFO - 'talos_options': ['--disable-e10s'], 09:00:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:00:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:00:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:01 INFO - 'talos_options': ['--disable-e10s'], 09:00:01 INFO - 'tests': ['damp', 'tps']}, 09:00:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:01 INFO - 'tests': ['damp', 'tps']}, 09:00:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:00:01 INFO - 'tests': ['dromaeo_dom']}, 09:00:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:00:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:00:01 INFO - 'tests': ['basic_compositor_video']}, 09:00:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:00:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:00:01 INFO - 'tests': ['a11yr', 09:00:01 INFO - 'ts_paint', 09:00:01 INFO - 'tpaint', 09:00:01 INFO - 'sessionrestore', 09:00:01 INFO - 'sessionrestore_no_auto_restore', 09:00:01 INFO - 'tabpaint']}, 09:00:01 INFO - 'other-e10s': {'tests': ['a11yr', 09:00:01 INFO - 'ts_paint', 09:00:01 INFO - 'tpaint', 09:00:01 INFO - 'sessionrestore', 09:00:01 INFO - 'sessionrestore_no_auto_restore', 09:00:01 INFO - 'tabpaint']}, 09:00:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:00:01 INFO - 'ts_paint', 09:00:01 INFO - 'tpaint', 09:00:01 INFO - 'sessionrestore', 09:00:01 INFO - 'sessionrestore_no_auto_restore', 09:00:01 INFO - 'tabpaint']}, 09:00:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:00:01 INFO - 'ts_paint', 09:00:01 INFO - 'tpaint', 09:00:01 INFO - 'sessionrestore', 09:00:01 INFO - 'sessionrestore_no_auto_restore', 09:00:01 INFO - 'tabpaint']}, 09:00:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:00:01 INFO - 'tests': ['a11yr', 09:00:01 INFO - 'ts_paint', 09:00:01 INFO - 'tpaint', 09:00:01 INFO - 'sessionrestore', 09:00:01 INFO - 'sessionrestore_no_auto_restore', 09:00:01 INFO - 'tabpaint']}, 09:00:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:00:01 INFO - 'tests': ['a11yr', 09:00:01 INFO - 'ts_paint', 09:00:01 INFO - 'tpaint', 09:00:01 INFO - 'sessionrestore', 09:00:01 INFO - 'sessionrestore_no_auto_restore', 09:00:01 INFO - 'tabpaint']}, 09:00:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:00:01 INFO - 'tests': ['tsvgx', 09:00:01 INFO - 'tsvgr_opacity', 09:00:01 INFO - 'tart', 09:00:01 INFO - 'tscrollx', 09:00:01 INFO - 'cart']}, 09:00:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:00:01 INFO - 'tsvgr_opacity', 09:00:01 INFO - 'tart', 09:00:01 INFO - 'tscrollx', 09:00:01 INFO - 'cart']}, 09:00:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:01 INFO - 'talos_options': ['--disable-e10s'], 09:00:01 INFO - 'tests': ['tp5o']}, 09:00:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:01 INFO - 'tests': ['tp5o']}, 09:00:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:01 INFO - 'talos_options': ['--disable-e10s', 09:00:01 INFO - '--xperf_path', 09:00:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:00:01 INFO - 'tests': ['tp5n']}, 09:00:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:01 INFO - 'talos_options': ['--xperf_path', 09:00:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:00:01 INFO - 'tests': ['tp5n']}}, 09:00:01 INFO - 'talos.zip': {'path': '', 09:00:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:00:01 INFO - Running post-action listener: _resource_record_post_action 09:00:01 INFO - [mozharness: 2016-11-16 17:00:01.281730Z] Finished populate-webroot step (success) 09:00:01 INFO - [mozharness: 2016-11-16 17:00:01.281858Z] Running create-virtualenv step. 09:00:01 INFO - Running pre-action listener: _resource_record_pre_action 09:00:01 INFO - Running main action method: create_virtualenv 09:00:01 INFO - Creating virtualenv /builds/slave/test/build/venv 09:00:01 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:00:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:00:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:00:01 INFO - Using real prefix '/tools/python27' 09:00:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:00:02 INFO - Installing distribute.............................................................................................................................................................................................done. 09:00:05 INFO - Installing pip.................done. 09:00:05 INFO - Return code: 0 09:00:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:00:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:00:05 INFO - Reading from file tmpfile_stdout 09:00:05 INFO - Output received: 09:00:05 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:00:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:00:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1014ec490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101462570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a99d60560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101300d30>, '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 0x101166db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1012f8b20>, '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.x9GuvOyBOd/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.ctQCZbsW2Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:00:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:00:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:00:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x9GuvOyBOd/Render', 09:00:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:05 INFO - 'HOME': '/Users/cltbld', 09:00:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:05 INFO - 'LOGNAME': 'cltbld', 09:00:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:05 INFO - 'MOZ_NO_REMOTE': '1', 09:00:05 INFO - 'NO_EM_RESTART': '1', 09:00:05 INFO - 'PAGER': '/bin/cat', 09:00:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:05 INFO - 'PWD': '/builds/slave/test', 09:00:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:05 INFO - 'SHELL': '/bin/bash', 09:00:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ctQCZbsW2Y/Listeners', 09:00:05 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 09:00:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:05 INFO - 'USER': 'cltbld', 09:00:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:05 INFO - 'XPC_FLAGS': '0x0', 09:00:05 INFO - 'XPC_SERVICE_NAME': '0', 09:00:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:05 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:00:05 INFO - Cleaning up... 09:00:05 INFO - Return code: 0 09:00:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:00:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1014ec490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101462570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a99d60560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101300d30>, '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 0x101166db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1012f8b20>, '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.x9GuvOyBOd/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.ctQCZbsW2Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:00:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:00:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:00:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x9GuvOyBOd/Render', 09:00:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:05 INFO - 'HOME': '/Users/cltbld', 09:00:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:05 INFO - 'LOGNAME': 'cltbld', 09:00:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:05 INFO - 'MOZ_NO_REMOTE': '1', 09:00:05 INFO - 'NO_EM_RESTART': '1', 09:00:05 INFO - 'PAGER': '/bin/cat', 09:00:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:05 INFO - 'PWD': '/builds/slave/test', 09:00:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:05 INFO - 'SHELL': '/bin/bash', 09:00:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ctQCZbsW2Y/Listeners', 09:00:05 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 09:00:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:05 INFO - 'USER': 'cltbld', 09:00:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:05 INFO - 'XPC_FLAGS': '0x0', 09:00:05 INFO - 'XPC_SERVICE_NAME': '0', 09:00:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:05 INFO - Downloading/unpacking psutil>=3.1.1 09:00:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:00:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:00:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:00:09 INFO - Installing collected packages: psutil 09:00:09 INFO - Running setup.py install for psutil 09:00:09 INFO - building 'psutil._psutil_osx' extension 09:00:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:00:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:00:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:00:09 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:00:10 INFO - building 'psutil._psutil_posix' extension 09:00:10 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:00:10 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:00:10 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:00:10 INFO - ^ 09:00:10 INFO - 1 warning generated. 09:00:10 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:00:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:00:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:00:10 INFO - Successfully installed psutil 09:00:10 INFO - Cleaning up... 09:00:10 INFO - Return code: 0 09:00:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:00:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:10 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 0x1014ec490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101462570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a99d60560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101300d30>, '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 0x101166db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1012f8b20>, '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.x9GuvOyBOd/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.ctQCZbsW2Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:00:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:00:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:00:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x9GuvOyBOd/Render', 09:00:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:10 INFO - 'HOME': '/Users/cltbld', 09:00:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:10 INFO - 'LOGNAME': 'cltbld', 09:00:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:10 INFO - 'MOZ_NO_REMOTE': '1', 09:00:10 INFO - 'NO_EM_RESTART': '1', 09:00:10 INFO - 'PAGER': '/bin/cat', 09:00:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:10 INFO - 'PWD': '/builds/slave/test', 09:00:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:10 INFO - 'SHELL': '/bin/bash', 09:00:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ctQCZbsW2Y/Listeners', 09:00:10 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 09:00:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:10 INFO - 'USER': 'cltbld', 09:00:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:10 INFO - 'XPC_FLAGS': '0x0', 09:00:10 INFO - 'XPC_SERVICE_NAME': '0', 09:00:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:00:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:00:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:00:13 INFO - Installing collected packages: mozsystemmonitor 09:00:13 INFO - Running setup.py install for mozsystemmonitor 09:00:13 INFO - Successfully installed mozsystemmonitor 09:00:13 INFO - Cleaning up... 09:00:13 INFO - Return code: 0 09:00:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:00:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:13 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 0x1014ec490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101462570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a99d60560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101300d30>, '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 0x101166db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1012f8b20>, '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.x9GuvOyBOd/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.ctQCZbsW2Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:00:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:00:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:00:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x9GuvOyBOd/Render', 09:00:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:13 INFO - 'HOME': '/Users/cltbld', 09:00:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:13 INFO - 'LOGNAME': 'cltbld', 09:00:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:13 INFO - 'MOZ_NO_REMOTE': '1', 09:00:13 INFO - 'NO_EM_RESTART': '1', 09:00:13 INFO - 'PAGER': '/bin/cat', 09:00:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:13 INFO - 'PWD': '/builds/slave/test', 09:00:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:13 INFO - 'SHELL': '/bin/bash', 09:00:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ctQCZbsW2Y/Listeners', 09:00:13 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 09:00:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:13 INFO - 'USER': 'cltbld', 09:00:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:13 INFO - 'XPC_FLAGS': '0x0', 09:00:13 INFO - 'XPC_SERVICE_NAME': '0', 09:00:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:13 INFO - Downloading/unpacking jsonschema==2.5.1 09:00: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 09:00: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 09:00: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 09:00: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 09:00: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 09:00: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 09:00:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:00:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:00:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:16 INFO - Downloading functools32-3.2.3-2.tar.gz 09:00:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:00:16 INFO - warning: no files found matching '*.txt' 09:00:16 INFO - no previously-included directories found matching 'build' 09:00:16 INFO - no previously-included directories found matching 'dist' 09:00:16 INFO - no previously-included directories found matching '.git*' 09:00:16 INFO - Installing collected packages: jsonschema, functools32 09:00:16 INFO - Running setup.py install for functools32 09:00:16 INFO - warning: no files found matching '*.txt' 09:00:16 INFO - no previously-included directories found matching 'build' 09:00:16 INFO - no previously-included directories found matching 'dist' 09:00:16 INFO - no previously-included directories found matching '.git*' 09:00:17 INFO - Successfully installed jsonschema functools32 09:00:17 INFO - Cleaning up... 09:00:17 INFO - Return code: 0 09:00:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:00:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:17 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 0x1014ec490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101462570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a99d60560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101300d30>, '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 0x101166db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1012f8b20>, '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.x9GuvOyBOd/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.ctQCZbsW2Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:00:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:00:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:00:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x9GuvOyBOd/Render', 09:00:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:17 INFO - 'HOME': '/Users/cltbld', 09:00:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:17 INFO - 'LOGNAME': 'cltbld', 09:00:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:17 INFO - 'MOZ_NO_REMOTE': '1', 09:00:17 INFO - 'NO_EM_RESTART': '1', 09:00:17 INFO - 'PAGER': '/bin/cat', 09:00:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:17 INFO - 'PWD': '/builds/slave/test', 09:00:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:17 INFO - 'SHELL': '/bin/bash', 09:00:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ctQCZbsW2Y/Listeners', 09:00:17 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 09:00:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:17 INFO - 'USER': 'cltbld', 09:00:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:17 INFO - 'XPC_FLAGS': '0x0', 09:00:17 INFO - 'XPC_SERVICE_NAME': '0', 09:00:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:00:17 INFO - Cleaning up... 09:00:17 INFO - Return code: 0 09:00:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:00:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:17 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 0x1014ec490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101462570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a99d60560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101300d30>, '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 0x101166db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1012f8b20>, '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.x9GuvOyBOd/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.ctQCZbsW2Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:00:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:00:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:00:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x9GuvOyBOd/Render', 09:00:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:17 INFO - 'HOME': '/Users/cltbld', 09:00:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:17 INFO - 'LOGNAME': 'cltbld', 09:00:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:17 INFO - 'MOZ_NO_REMOTE': '1', 09:00:17 INFO - 'NO_EM_RESTART': '1', 09:00:17 INFO - 'PAGER': '/bin/cat', 09:00:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:17 INFO - 'PWD': '/builds/slave/test', 09:00:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:17 INFO - 'SHELL': '/bin/bash', 09:00:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ctQCZbsW2Y/Listeners', 09:00:17 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 09:00:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:17 INFO - 'USER': 'cltbld', 09:00:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:17 INFO - 'XPC_FLAGS': '0x0', 09:00:17 INFO - 'XPC_SERVICE_NAME': '0', 09:00:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:17 INFO - Downloading/unpacking blobuploader==1.2.4 09:00: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 09:00: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 09:00: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 09:00: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 09:00: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 09:00: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 09:00:20 INFO - Downloading blobuploader-1.2.4.tar.gz 09:00:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:00:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:00:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:00:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:00:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:21 INFO - Downloading docopt-0.6.1.tar.gz 09:00:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:00:21 INFO - Installing collected packages: blobuploader, requests, docopt 09:00:21 INFO - Running setup.py install for blobuploader 09:00:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:00:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:00:21 INFO - Running setup.py install for requests 09:00:22 INFO - Running setup.py install for docopt 09:00:22 INFO - Successfully installed blobuploader requests docopt 09:00:22 INFO - Cleaning up... 09:00:22 INFO - Return code: 0 09:00:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:00:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:22 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 0x1014ec490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101462570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a99d60560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101300d30>, '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 0x101166db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1012f8b20>, '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.x9GuvOyBOd/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.ctQCZbsW2Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:00:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:00:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:00:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x9GuvOyBOd/Render', 09:00:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:22 INFO - 'HOME': '/Users/cltbld', 09:00:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:22 INFO - 'LOGNAME': 'cltbld', 09:00:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:22 INFO - 'MOZ_NO_REMOTE': '1', 09:00:22 INFO - 'NO_EM_RESTART': '1', 09:00:22 INFO - 'PAGER': '/bin/cat', 09:00:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:22 INFO - 'PWD': '/builds/slave/test', 09:00:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:22 INFO - 'SHELL': '/bin/bash', 09:00:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ctQCZbsW2Y/Listeners', 09:00:22 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 09:00:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:22 INFO - 'USER': 'cltbld', 09:00:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:22 INFO - 'XPC_FLAGS': '0x0', 09:00:22 INFO - 'XPC_SERVICE_NAME': '0', 09:00:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:00:22 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-kuu8wc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:00:22 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-G69M6I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:00:22 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-3DqMb_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:00:22 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-KRX1UE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:00:23 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-ZHProf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:00:23 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-x19pAw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:00:23 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-qx9Zhe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:00:23 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-dXvSWg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:00:23 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-_gwifH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:00:23 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-vY8X3v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:00:23 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-M6f92E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:00:23 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-fIGrrX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:00:23 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-nkpkkQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:00:23 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-5lhSLA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:24 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-Yz5bEA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:00:24 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-8SreXT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:00:24 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-jjjPwN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:00:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:00:24 INFO - Running setup.py install for manifestparser 09:00:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:00:24 INFO - Running setup.py install for mozcrash 09:00:24 INFO - Running setup.py install for mozdebug 09:00:24 INFO - Running setup.py install for mozdevice 09:00:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:00:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:00:24 INFO - Running setup.py install for mozfile 09:00:25 INFO - Running setup.py install for mozhttpd 09:00:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:00:25 INFO - Running setup.py install for mozinfo 09:00:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:00:25 INFO - Running setup.py install for mozInstall 09:00:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:00:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:00:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:00:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:00:25 INFO - Running setup.py install for mozleak 09:00:25 INFO - Running setup.py install for mozlog 09:00:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:00:25 INFO - Running setup.py install for moznetwork 09:00:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:00:26 INFO - Running setup.py install for mozprocess 09:00:26 INFO - Running setup.py install for mozprofile 09:00:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:00:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:00:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:00:26 INFO - Running setup.py install for mozrunner 09:00:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:00:26 INFO - Running setup.py install for mozscreenshot 09:00:26 INFO - Running setup.py install for moztest 09:00:26 INFO - Running setup.py install for mozversion 09:00:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:00:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:00:26 INFO - Cleaning up... 09:00:26 INFO - Return code: 0 09:00:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:00:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:26 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 0x1014ec490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101462570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a99d60560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101300d30>, '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 0x101166db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1012f8b20>, '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.x9GuvOyBOd/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.ctQCZbsW2Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:00:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:00:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:00:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x9GuvOyBOd/Render', 09:00:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:26 INFO - 'HOME': '/Users/cltbld', 09:00:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:26 INFO - 'LOGNAME': 'cltbld', 09:00:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:26 INFO - 'MOZ_NO_REMOTE': '1', 09:00:26 INFO - 'NO_EM_RESTART': '1', 09:00:26 INFO - 'PAGER': '/bin/cat', 09:00:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:26 INFO - 'PWD': '/builds/slave/test', 09:00:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:26 INFO - 'SHELL': '/bin/bash', 09:00:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ctQCZbsW2Y/Listeners', 09:00:26 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 09:00:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:26 INFO - 'USER': 'cltbld', 09:00:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:26 INFO - 'XPC_FLAGS': '0x0', 09:00:26 INFO - 'XPC_SERVICE_NAME': '0', 09:00:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:00:27 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-Mecd59-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:00:27 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:00:27 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-BztN6q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:00:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:00:27 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-BzPJqX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:00:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:00:27 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-D38mRj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:00:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:00:27 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-Mf8CAR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:00:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:00:27 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-b0JP4m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:00:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:00:27 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-voSPEp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:00:28 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-m_8FI8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:00:28 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-gHk7ce-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:00:28 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-7K3GRU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:00:28 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-1hvOV4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:00:28 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-LoVs2r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:00:28 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-ZUWj1R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:00:28 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-ubL5u2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:28 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-OKLQIu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:00:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:00:28 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-JHUxxS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:00:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:00:29 INFO - Running setup.py (path:/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/pip-QBp7cg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:00:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:00:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:31 INFO - Downloading blessings-1.6.tar.gz 09:00:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:00:31 INFO - Installing collected packages: blessings 09:00:31 INFO - Running setup.py install for blessings 09:00:31 INFO - Successfully installed blessings 09:00:31 INFO - Cleaning up... 09:00:32 INFO - Return code: 0 09:00:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:00:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:00:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:00:32 INFO - Reading from file tmpfile_stdout 09:00:32 INFO - Current package versions: 09:00:32 INFO - blessings == 1.6 09:00:32 INFO - blobuploader == 1.2.4 09:00:32 INFO - docopt == 0.6.1 09:00:32 INFO - functools32 == 3.2.3-2 09:00:32 INFO - jsonschema == 2.5.1 09:00:32 INFO - manifestparser == 1.1 09:00:32 INFO - mozInstall == 1.12 09:00:32 INFO - mozcrash == 1.0 09:00:32 INFO - mozdebug == 0.1 09:00:32 INFO - mozdevice == 0.48 09:00:32 INFO - mozfile == 1.2 09:00:32 INFO - mozhttpd == 0.7 09:00:32 INFO - mozinfo == 0.9 09:00:32 INFO - mozleak == 0.1 09:00:32 INFO - mozlog == 3.4 09:00:32 INFO - moznetwork == 0.27 09:00:32 INFO - mozprocess == 0.23 09:00:32 INFO - mozprofile == 0.28 09:00:32 INFO - mozrunner == 6.13 09:00:32 INFO - mozscreenshot == 0.1 09:00:32 INFO - mozsystemmonitor == 0.3 09:00:32 INFO - moztest == 0.7 09:00:32 INFO - mozversion == 1.4 09:00:32 INFO - psutil == 3.1.1 09:00:32 INFO - requests == 1.2.3 09:00:32 INFO - wsgiref == 0.1.2 09:00:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:00:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:33 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 0x1014ec490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101462570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a99d60560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101300d30>, '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 0x101166db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1012f8b20>, '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.x9GuvOyBOd/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.ctQCZbsW2Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:00:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:00:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:00:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x9GuvOyBOd/Render', 09:00:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:33 INFO - 'HOME': '/Users/cltbld', 09:00:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:33 INFO - 'LOGNAME': 'cltbld', 09:00:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:33 INFO - 'MOZ_NO_REMOTE': '1', 09:00:33 INFO - 'NO_EM_RESTART': '1', 09:00:33 INFO - 'PAGER': '/bin/cat', 09:00:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:33 INFO - 'PWD': '/builds/slave/test', 09:00:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:33 INFO - 'SHELL': '/bin/bash', 09:00:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ctQCZbsW2Y/Listeners', 09:00:33 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 09:00:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:33 INFO - 'USER': 'cltbld', 09:00:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:33 INFO - 'XPC_FLAGS': '0x0', 09:00:33 INFO - 'XPC_SERVICE_NAME': '0', 09:00:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:00:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:00:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:00:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:00:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:00:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:00:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:00:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:00:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:00:33 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:00:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:00:33 INFO - Cleaning up... 09:00:33 INFO - Return code: 0 09:00:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:00:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:00:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:33 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 0x1014ec490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101462570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a99d60560>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101300d30>, '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 0x101166db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1012f8b20>, '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.x9GuvOyBOd/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.ctQCZbsW2Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:00:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:00:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:00:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x9GuvOyBOd/Render', 09:00:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:00:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:00:33 INFO - 'HOME': '/Users/cltbld', 09:00:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:00:33 INFO - 'LOGNAME': 'cltbld', 09:00:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:33 INFO - 'MOZ_NO_REMOTE': '1', 09:00:33 INFO - 'NO_EM_RESTART': '1', 09:00:33 INFO - 'PAGER': '/bin/cat', 09:00:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:00:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:33 INFO - 'PWD': '/builds/slave/test', 09:00:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:00:33 INFO - 'SHELL': '/bin/bash', 09:00:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ctQCZbsW2Y/Listeners', 09:00:33 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 09:00:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:00:33 INFO - 'USER': 'cltbld', 09:00:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:00:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:00:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:33 INFO - 'XPC_FLAGS': '0x0', 09:00:33 INFO - 'XPC_SERVICE_NAME': '0', 09:00:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:00:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:00:34 INFO - Cleaning up... 09:00:34 INFO - Return code: 0 09:00:34 INFO - Running post-action listener: _resource_record_post_action 09:00:34 INFO - Running post-action listener: _start_resource_monitoring 09:00:34 INFO - Starting resource monitoring. 09:00:34 INFO - [mozharness: 2016-11-16 17:00:34.072409Z] Finished create-virtualenv step (success) 09:00:34 INFO - [mozharness: 2016-11-16 17:00:34.073460Z] Running install step. 09:00:34 INFO - Running pre-action listener: _resource_record_pre_action 09:00:34 INFO - Running main action method: install 09:00:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:00:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:00:34 INFO - Reading from file tmpfile_stdout 09:00:34 INFO - Detecting whether we're running mozinstall >=1.0... 09:00:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:00:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:00:34 INFO - Reading from file tmpfile_stdout 09:00:34 INFO - Output received: 09:00:34 INFO - Usage: mozinstall [options] installer 09:00:34 INFO - Options: 09:00:34 INFO - -h, --help show this help message and exit 09:00:34 INFO - -d DEST, --destination=DEST 09:00:34 INFO - Directory to install application into. [default: 09:00:34 INFO - "/builds/slave/test"] 09:00:34 INFO - --app=APP Application being installed. [default: firefox] 09:00:34 INFO - mkdir: /builds/slave/test/build/application 09:00:34 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'] 09:00:34 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 09:01:06 INFO - Reading from file tmpfile_stdout 09:01:06 INFO - Output received: 09:01:06 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:01:06 INFO - Running post-action listener: _resource_record_post_action 09:01:06 INFO - [mozharness: 2016-11-16 17:01:06.203330Z] Finished install step (success) 09:01:06 INFO - [mozharness: 2016-11-16 17:01:06.203475Z] Running run-tests step. 09:01:06 INFO - Running pre-action listener: _resource_record_pre_action 09:01:06 INFO - Running main action method: run_tests 09:01:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:01:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:01:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:01:06 INFO - 2016-11-16 09:01:06.218 screenresolution[1623:7598] starting screenresolution argv=screenresolution get 09:01:06 INFO - 2016-11-16 09:01:06.221 screenresolution[1623:7598] Display 0: 1600x1200x32@60 09:01:06 INFO - 2016-11-16 09:01:06.227 screenresolution[1624:7601] starting screenresolution argv=screenresolution list 09:01:06 INFO - Available Modes on Display 0 09:01:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:01:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:01:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:01:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:01:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:01:06 INFO - Intel Iris: 09:01:06 INFO - Chipset Model: Intel Iris 09:01:06 INFO - Type: GPU 09:01:06 INFO - Bus: Built-In 09:01:06 INFO - VRAM (Dynamic, Max): 1536 MB 09:01:06 INFO - Vendor: Intel (0x8086) 09:01:06 INFO - Device ID: 0x0a2e 09:01:06 INFO - Revision ID: 0x0009 09:01:06 INFO - Displays: 09:01:06 INFO - Display: 09:01:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:01:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:01:06 INFO - Display Serial Number: 1600x1200 60 09:01:06 INFO - Main Display: Yes 09:01:06 INFO - Mirror: Off 09:01:06 INFO - Online: Yes 09:01:06 INFO - Rotation: Supported 09:01:06 INFO - Return code: 0 09:01:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:01:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:01:06 INFO - Python 2.7.3 09:01:06 INFO - Return code: 0 09:01:06 INFO - grabbing minidump binary from tooltool 09:01:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:06 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 0x101300d30>, '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 0x101166db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1012f8b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:01:06 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:01:06 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:01:06 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:01:06 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:01:06 INFO - Return code: 0 09:01:06 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:01:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:01:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:01:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:01:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:01:06 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/AMrsAB-XTzeRmXfTwtEONQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0292', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:01:06 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/AMrsAB-XTzeRmXfTwtEONQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0292 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:01:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x9GuvOyBOd/Render', 09:01:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:01:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:01:06 INFO - 'HOME': '/Users/cltbld', 09:01:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:01:06 INFO - 'LOGNAME': 'cltbld', 09:01:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:01:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:01:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:01:06 INFO - 'MOZ_NO_REMOTE': '1', 09:01:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:01:06 INFO - 'NO_EM_RESTART': '1', 09:01:06 INFO - 'PAGER': '/bin/cat', 09:01:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:01:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:01:06 INFO - 'PWD': '/builds/slave/test', 09:01:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:01:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:01:06 INFO - 'SHELL': '/bin/bash', 09:01:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ctQCZbsW2Y/Listeners', 09:01:06 INFO - 'TMPDIR': '/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/', 09:01:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:01:06 INFO - 'USER': 'cltbld', 09:01:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:01:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:01:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:01:06 INFO - 'XPC_FLAGS': '0x0', 09:01:06 INFO - 'XPC_SERVICE_NAME': '0', 09:01:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:01:06 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/AMrsAB-XTzeRmXfTwtEONQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0292', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:01:06 INFO - mozversion application_buildid: 20161116062633 09:01:06 INFO - mozversion application_changeset: d86e4e3d92b082d1e86160ac182f8c73cd80597a 09:01:06 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:01:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:01:06 INFO - mozversion application_name: Firefox 09:01:06 INFO - mozversion application_remotingname: firefox-dev 09:01:06 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:01:06 INFO - mozversion application_vendor: Mozilla 09:01:06 INFO - mozversion application_version: 52.0a2 09:01:06 INFO - mozversion platform_buildid: 20161116062633 09:01:06 INFO - mozversion platform_changeset: d86e4e3d92b082d1e86160ac182f8c73cd80597a 09:01:06 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:01:06 INFO - mozversion platform_version: 52.0a2 09:01:06 INFO - using testdate: 1479315666 09:01:06 INFO - actual date: 1479315666 09:01:06 INFO - starting webserver on 'localhost:49242' 09:01:06 INFO - SUITE-START | Running 2 tests 09:01:06 INFO - TEST-START | dromaeo_css 09:01:06 INFO - operating with platform_type : mac_ 09:01:06 INFO - Initialising browser for dromaeo_css test... 09:01:06 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/tmpfqrNYF/profile http://localhost:49242/getInfo.html) 09:01:08 ERROR - PROCESS | 1634 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 09:01:09 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 09:01:09 INFO - PROCESS | 1634 | colorDepth:24 09:01:09 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 09:01:09 INFO - PROCESS | 1634 | __metrics 09:01:09 INFO - PROCESS | 1634 | 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" 09:01:09 INFO - PROCESS | 1634 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:01:09 INFO - TEST-INFO | 1634: exit 0 09:01:09 INFO - Browser initialized. 09:01:09 INFO - Running cycle 1/1 for dromaeo_css test... 09:01:09 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/tmpfqrNYF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:01:22 INFO - PROCESS | 1636 | RSS: Main: 237092864 09:01:22 INFO - PROCESS | 1636 | 09:02:13 INFO - PROCESS | 1636 | 1479315733294 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:04:25 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 09:04:27 INFO - PROCESS | 1636 | RSS: Main: 399998976 09:04:27 INFO - PROCESS | 1636 | 09:07:30 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 09:07:30 INFO - PROCESS | 1636 | RSS: Main: 370876416 09:07:30 INFO - PROCESS | 1636 | 09:10:33 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 09:10:33 INFO - PROCESS | 1636 | RSS: Main: 366825472 09:10:33 INFO - PROCESS | 1636 | 09:13:36 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 09:13:36 INFO - PROCESS | 1636 | RSS: Main: 333357056 09:13:36 INFO - PROCESS | 1636 | 09:16:40 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 09:16:40 INFO - PROCESS | 1636 | RSS: Main: 327651328 09:16:40 INFO - PROCESS | 1636 | 09:19:43 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 09:19:44 INFO - PROCESS | 1636 | RSS: Main: 458354688 09:19:44 INFO - PROCESS | 1636 | 09:19:44 INFO - PROCESS | 1636 | __start_tp_report 09:19:44 INFO - PROCESS | 1636 | _x_x_mozilla_page_load 09:19:44 INFO - PROCESS | 1636 | _x_x_mozilla_page_load_details 09:19:44 INFO - PROCESS | 1636 | |i|pagename|runs| 09:19:44 INFO - PROCESS | 1636 | |0;dojo.html;3295.5715756951595;3307.6196237432696;3308.917277068073;3313.8177400242976;3358.596968363797;4726.787294571744;5592.692401917893;5625;5641.2384328115695;5650.039493286141;5566.610337276382;5650.49145576898;5669.574781891364;5678.261825962619;5709.800156994506;5126.154184559543;5147.794088236466;5163.277141200235;5206.245094461301;5267.394249661281;5085.288059671649;5113.718745468997;5134.768935397898;5251.317328747257;5262;5150.845474635767;5177.508136727005;5200.115980283342;5222.764975576109;5225.346831646044;5153.871153221162;5183.170692689152;5190.92213616796;5216.739163041832;5235.816746413857;4406.810161256442;5118.385793704742;5173.146430838907;5191.402988656278;5197.636165468419;5371.838844834661;5408.540274076709;5419.241306217133;5574.637648552831;5613.4386561343945;6093.390660933916;6175;6185.16500272462;6188.504919606421;6194.039923811816;6204.6587437690905;6250.312465628777;6255.812325630239;6256.374362563753;6260.060990851363;5647.830565083054;5676.432356764373;5708.800191993343;5756;5782.88434231313;6235.781747638902;6315.115883776275;6320.083587879732;6323.52573556985;6347.206599175103;6038.5169186465855;6047.697615119227;6070.089486577049;6078.908816367758;6100.511959043266;6043.3050199227655;6126.203166017066;6135.018397056539;6225.066240064027;6256.499480041586;6029.60807547508;6050.273967123885;6090.543209259324;6123.601965872204;6125.877482450415;6127.691046834749;6142.508599312045;6143.709820937564;6147.538934579924;6159.137720719103;4863.927041094386;4867.196912509467;4878.121938051114;4898.632602554822;4904.55858972687;4334.718243314174;4350.73895566267;4353.303471444554;4372.125574885035;4380.233459144637;5640.210370548127;5681.261436013292;5703.771849125988;5705.857353566194;5727.226824378656;6119.357467465941;6188.721507532172;6190.380961903774;6198.318184999646;6201.596896201822;5532.363778165482;5566.2207290979295;5604.579656525777;5672.659640421505;5679.687617181012;5284.497972692588;5297.073012222845;5302.2046692996355;5306.655067420683;5307.256984022163;5181.766820493087;6291.653959032297;6295.464885484785;6296.464800492009;6318.241810982528;6084.14821924922;6113.637041974642;6118.7714669719035;6122.97760420812;6143.569950103629;6185.659788711484;6250.3749625037135;6260.530460215411;6277.497800175884;6300.9684951574945;6166.506679465721;6169.321374648695;6174.104754810617;6176.7838125665385;6188.907166392418;6237.469815065589;6239.719212635351;6255.218097737783;6261.530385221035;6273.560850740587;5684.459976302244;5807.303123625192;5934.643921364732;5956.910646340221;5988.131720900358;4968;5991.970040149771;6004.639721616717;6024.728887199999;6027.30685971119;5755.251817263729;5829.387914268941;5845.590808643354;5854.912176317273;5863.885861686266;5842.123681447817;5908.05471124618;5940.970295148497;5947.1079338099635;5980.1328807321825;5841.970790146022;5858.091995740667;5862;5913.379095194942;5916.763329466773 09:19:44 INFO - PROCESS | 1636 | |1;ext.html;465.4809886976023;467.8245657878298;501.53106845099813;508.8397154896208;519.3222844188335;27573.58639620407;39364.01589960249;40414.39378409323;40432.78701638956;40443;40719.59280407195;41119.79440102795;41131.58868411315;41137;41357.7932110339;9134.497602631836;9313.975462699098;9317.673881414152;9319.254459643213;9321.580528876217;9283.025282345357;9384.202342800836;9390.154886060252;9393.248540116809;9399.248060155205;9356.204722598553;9420.905790942106;9424.858627120599;9425.387349822275;9474.573644185994;9320.067993200659;9359.812803743922;9380.24958003358;9380.71857844259;9381;9079.954600227024;9135.954320228357;9156.908430915671;9177.954110229473;9178.357514973955;38775.80612096922;39051.60948390508;39110.022249443704;39130.80434597837;39266.80366598149;3215;3288.128645908836;3301.636819949804;3331.833408329574;3341.1814105544054;29174.70825291741;29980.250493737614;30187.245318866986;30271.69728302733;30297.848510757525;3180.80915145092;3189.8086114833186;3197.4084794313126;3205.8397080146133;3302.9925872608846;425365;425984;426085;426191.9999999969;427121;32384.514232286536;33209.00372988814;57835;58400.415995839496;58424.41575584275;33706.831465843;34133.48799768005;34133.634654614034;34140.65859341424;34183.48724769131;12393.814092788616;12697.412823397075;13719.382627781773;14450;14490;12142;12253.75492490163;12254.877451225373;12264.509419623293;12271.956883664989;7689.3464055555905;7697.307242348215;7738.4969976951315;7773.067231932205;7788.026496687914;3150.7951983121025;3203.6796320368244;3214.6463888972194;3228.886988955422;3245.918852028718;883.6774577279247;899.2895612466209;927.823713494434;970.2432102959702;982.7444864335181;27420;28396.432071358457;29171.416571668447;29477.768247192493;29882.954096606514;989.8812142542942;1045.6706137566641;1055.714956961616;1088.7822435512928;1119.9496022679;12368.392109025948;15077.472288469851;15283.770743438858;15401.306941187673;15703.764443533357;618775;619607;622090;622249;629612;56412;57046;57105.14342284869;57192;57218.141727874114;1339.04258455205;1363.795430685405;1384.0795870746042;1387.1191793211383;1391.589481103077;26201.951921922908;26701.46597068009;29327.266818329714;30116.09651710452;30237;50184.74907625438;50431.49568504268;50456.49543504518;50487;50559.49440505548;440443;443216;446413;448365;448639;25623.615645764956;26593.601095983187;26613.866930665998;26645;26655;42487.787561061996;45187.32219016783;45345.77327113343;45411.31883021823;45773.94017269297;33377.49933750947;36806.44790328201;36904.81547592245;37786.62213377831;37874.43188352122;37087.25825483421;37242;37381;37589.43615845819;37607.68373106928;36928.52285908534;36929.26141477209;37056.814715926244;37364.879053628436;37448.812755936044;31663.050108496784;34019.82990085034;34134.65865341414;34176;34291.4856277161;21128.260510882046;21891.343259702233;22543.9565658634;24908.750912490643;25786.613200802378 09:19:44 INFO - PROCESS | 1636 | |2;jquery.html;330.39867441256916;351.91378112362474;376.82430815854536;380.20537077508004;402.43056075652953;14503.492377766781;15086.47197348093;15460.5361839145;19501.609967800672;20026.699599505984;19483.512912177168;19534.31629892954;20211.898940505314;20280.087396067174;20460.28389006385;5684.175794509793;5980.461758441739;5987.042073268278;5987.520998320124;5988.730507127168;5486.341639003325;5855.531557475413;5911.793087241947;5923.378045305246;5924.940750592482;5705;5820.970895145519;5857.092150716646;5857.970710146444;5868.354481007107;5586.273784408021;5830.825075247707;5832.212651292064;5832.941670583282;5836.5914385993;4883.633727470454;5043.84868453947;5066.758644132175;5103.2855400244;5107.668001579886;19612.803871961383;24144.63783043256;24226.03095876163;24254.87872560626;24533.423990480922;2331.2773040357542;2343;2480.193936970492;2724.495968245861;2748.134337683643;14986.775198372034;15180.544583662508;15539;15649.904506684537;15733.763993540106;1714.1943286655253;1757.6484703059439;1809.5928416106271;1858.9163487642954;1890.574620710329;87472.56263718705;94823.52588237084;99590.5020474893;125043.37478312642;129031.35484322612;9303.674371396968;10095.495225238708;11081.501332440059;11152.60965866207;11168.218224724355;12098.213616114925;12518.624441266777;12520;12533.811992820116;12558.058145639114;6218.00511918091;6274.1843560336865;7267.745628903069;8044.919550804417;8113.269805717514;8312.127226641236;8325.95837020811;8366.037905640807;8487.448315859448;8489.066202717697;3842.4620553122586;3859.0931131184147;3907.0427745202605;3930.4104384342004;3985.980070099631;1831.4505648305455;1880.4734674291221;1929.8456123510086;1932.3816378758663;1943.7861835198116;734.6510407556368;924.542351535994;929.8558723397884;942.8821397325335;961.5721004153085;20086.39740807778;21302.467438313855;21322.36032919015;21330.253441129487;21438.785612143678;924.4822899176482;947.9336446448821;963.0465838819518;965.4303960663242;983.4885859353096;10407.479626018669;11124.666260012213;11221.326720396639;11222.046126079375;11271.323720576791;513122;597548;614236;615414;616208;129669;130257;134557;135228.32385838203;135594.28811423716;954.4225743425267;955.8183945050557;985.4678473624306;985.7289245457556;988.367444835306;18503.259869605605;18647.533811654273;18954.715679265097;18982.525436864187;19013.71479427782;49283.76790580264;53766.73116634548;55344.44655553393;56266.71866640641;57314.7134264326;79925;81408.59295703721;81614.59192703999;83615;83836;5310.212138627252;5443.074677304947;7963.362930965393;8077.434579579608;8234.752957411287;8400.579971001547;8826.823463530822;9474.431534107976;11643;13105.082644214814;6906.447484201151;7558.773236802905;7902.130765615892;7926.533591285631;7999.520028798291;7776.766696999099;7808.063032436144;7947.403944704055;8058.073321568095;8202.466839655403;7783.688652454065;7922.286994170322;8045.436819422584;8609.526476043879;8618.224359807647;11853.34806585646;12422.656350869584;12710.809337860124;12749.745005100032;12817.871821281668;10684.519196636014;11359.375234361858;11735.882641173479;11819.290842549475;12082.697932551757 09:19:44 INFO - PROCESS | 1636 | |3;mootools.html;1322.094365359728;1470.4632809024708;1485.4281101775816;1643.819179890212;1814.0294942205915;7558.924410755891;7996.84006319874;8082.555459449735;8429.662813487454;8443.577821108951;8250.092489826115;8266.504009759403;8369.790755231123;8370.288525475342;8387.077421483647;2292.713410823643;2317.652352147183;2320.408295884547;2323.4191452136965;2326.9767302327014;2298.8275879309035;2333.57995560799;2339.6841426407386;2361;2364.3025307534317;2289.5306462175213;2298.4483723906224;2321.3500219938446;2327.208749025331;2335.7313908900524;2032.1363420546356;2186.038143216995;2293.621552443845;2309.9422514437274;2323.512062466884;1942.922283108674;2315.143396943142;2325.4070212095808;2326.1858349577683;2326.430024643956;8241.464304820167;8328.33373330132;8360.414770965974;8439.198276163756;8460.915390846074;5085.23721441783;5229.084910140748;5242.18746094356;5252.159654455307;5255.185446255837;3348.7153591944716;3399.286149908522;3585.121645196917;3660.8352624131976;3677.8528858845616;1054.2988912373262;1057.5293994172596;1065.0680654427376;1081.5781845080426;1102.1458369763418;10914.126869850394;10986.121110311156;11050.447477626167;11067.335959842436;11111.55553777856;4139.275626765304;4424.380586717862;4476.037651904856;4501.964548153943;4528.637708983273;8673.826523469495;8680.6527738891;8682.826343473094;8691.174338437839;8693.043765185825;5081.060003899291;5084.847454576368;5086.0845047891;5088.949110508847;5100.311457953128;4052.696047796427;4302.870913872589;4423.977880110643;4443.511213766483;4771.570558649738;3103.7206651401484;3640.8725694601094;3675.320065787838;3738.9435810304226;3742.4012158055125;969.1955676788249;970.6457143142843;1135.6763322453062;1137.635956493914;1145;814.2468216899463;885.4996926736502;892.8794612727198;902.7833320003283;928.3779867488798;5738.282714660668;5832.825015249549;6037.758489660365;6038.788642397495;6180.011198208265;915.9450432974043;925.5788616179622;934.3786382055856;945.5650400815564;979.2851218610455;3619.9095022624647;3739.046543131484;3864.439656249828;3992.321305378093;4012.4583181270154;8935;9762.853557196648;9927.553260103312;10126.291159618757;10164.796704065877;10144.594216231268;10236.948815255875;10253.897461025443;10254.384736915808;10276.743081422876;7847.764567062997;8012.759617211493;8116.026076870813;8127.674893004214;8139.511629302261;5644.88710225785;5647.971760141338;5708.315002199763;5872.941270587239;5953.404659534012;9397.812043759222;9580.13778759915;9616.90383096188;9632.325737198329;9638.855417168608;8867.64529418816;9179.954100229455;9288.442693438416;9474.147326740627;9561.948185699428;6738.393544581011;6988.510804243858;7195.280471952763;7208.4593655475;7249.927500725136;7934;8055.355571554379;8102.797430064295;8150.633221504928;8160.918390816016;7253.673584688596;7373.705051797868;7480.364169045474;7525.887111693439;7553.395728341829;7249.238829922782;7274.563526188445;7290.599017054113;7318.670659820427;7401.629918503945;5675.318961724454;5796.246487956539;6621.834454138685;6751.898721519279;6847.109875716125;5086.669366491315;5114.514121158484;5132.6150538709;5183.144781111075;5190.8961820764125;4235.512916014574;5052.292679025013;5087.87280317995;5195.168772996302;5224.111900976767 09:19:44 INFO - PROCESS | 1636 | |4;prototype.html;301.3746476062171;305.2262514525678;311.5482550302061;315.8120918053757;316.82574583978806;4957.553820156185;5048.671836330636;5161.587073034158;5228.660137091087;5265.420803711589;3884.4173373993917;4158.500979882411;4859.465458799529;5014.648974571782;5021.966103727987;3328.8169150696704;3432.605250396199;3759.492468516756;3980.6266837941007;4027.0267568891177;3053.4198502284494;3970.2655008823317;3971.4439978403047;4010.8596199133035;4042.9191416171657;3951.9012024699323;3978.4629075074786;3978.7612743235354;4006.8196931138023;4015.2571774221715;3979.7612143271494;3984.5019372578427;3985.342418500944;3985.8007099645047;3995.961050126988;3351.782134161296;3453.4301840196335;3503.1942653189653;3514.7012503937194;3515.6660117288816;4979.551840334388;5010.498950104997;5041.344625198737;5101.913342427852;5106.821261255838;4432.556744325574;4561.703489273186;4594.012287358234;4668.416447944007;4678.251479763256;5093.261477085802;5348.411674715778;5445.618806683534;5489.64317319373;5501.642393244426;3539.3188235588145;3756.1548651553453;3891.319019171662;3897.8440862365474;3909.276783795007;4556.54434556545;5723.685197314146;5755.963926493229;5787.247657804459;5823.5341172706085;3483.512308276843;3841.615838416136;3989.601039895987;3990.8204130814183;3993.0815912339745;3693.4829123922673;3866.3620502617314;4017.4576432181866;4022.356422972369;4038.5961403859965;4569.565891240327;4569.954300456953;4578.901063744677;4590.70160439577;4616.561426664529;4887.975560122177;5158.968206358743;5409.080456322435;5414.242006119146;5486.396496385394;1089.3028461784463;1273.5733529267613;1285.8714128587067;1310.3382791690326;1317.8352705911761;845.4884794699242;881.6510738569998;899.6086702284528;903.3541018172039;911.8905731312152;4487.865364039084;4638.814447422133;4823.927641085386;4840.903181936412;4885.7312847793;5169.534741873174;5263.921041184385;5285.286486324297;5287.920681189786;5334.253204551366;4456.844010459683;4491.371208030879;4802.51974802517;4848.927266091011;4887.633427492952;2398.7961023312864;2561.5645340292194;2668.319578507468;2788.9959614538748;2790.190844655068;4716.665250700925;4873.756312184377;5052.509509694649;5309.84058492155;5488.451154884479;4622.445306563167;5080.49195080489;5187.325647665854;5277.155655095166;5390.730463476811;4619.8845028873875;5293.708846013429;5429.163649077258;5433.225341074058;5464.18037294409;5152.3044389007;5206.817761378334;5228.790848366023;5234.057869583511;5260.473952604709;4778.546038126373;5110.105731497048;5171.767270472763;5175.948240517697;5225.764840582106;4816.566509014206;4826.32431459603;4946.258061290691;5337.519623233928;5413.348928576822;3370.9213051824163;3503.824808759603;3685.8341374638744;3752.967933818222;3809.3904975203836;4277.80749866263;5259.63182577216;5406.567474602101;5457.372402173801;5472.644278121909;5300.893982120413;5319.973400132974;5330.307060082165;5344.75948582307;5418.593605479526;4919.458859525378;5033;5107.846764597068;5332.386775520865;5335.413104558573;5237.214417837355;5260.921086183786;5264.184051472028;5282.8943421132135;5317.441668624739;4800.639952003684;5081.745912704424;5397.43769112384;5403.5136837684795;5422.430644782241;4942.380538314699;5228.45101264377;5270.762815673381;5328.4671532846405;5340.519353258225 09:19:44 INFO - PROCESS | 1636 | |5;yui.html;766.5209244222361;776.7747353267553;783.5103060587137;792.8652129138046;796.2992566541444;13981.930090349546;13991.930040349784;14429.278536073207;14431.278436078206;14873.925630371832;12952.287624180666;13300.334983250823;13324;13333;13343.866561334384;655.8622689235266;678.419950941945;822.8230930349973;836.9665213391457;841.0580150231749;677.4106527321237;814.543855440955;825.7233826668073;836.9121242269565;842.3135144856952;882.1266945723752;882.9293656507464;883.1786438612088;887.0464250930276;891.0020776730072;691.878921188795;708.7554793596202;878.6353663229752;879.885614870066;880.0407555764216;625.837282306599;640.971156297963;648.2609824799732;648.9740410383579;649.9610023398563;13929.373178207004;13977.58067257984;14317.57047288583;14319.85680143206;14332.42670293187;12488.188267762567;13162.473501059947;13320.866791332059;13443.126196797177;13614.727705445835;12052.216605920587;12156.209846359958;12165.391730413445;12540.435680394405;12735.172213806165;4479.507254202035;4493.5281795411665;4595.012072404449;4597.310403439477;4651.116287905321;80329.59835200846;81853;83760;91179.63230551546;92293.0770692298;10477.37135771856;10953.17851161166;10995;11029.393383363831;11344.035756960586;21447.034883430282;21468.570628587655;21567.352979410643;22138.114475420804;22191.334259972227;7195.928040719526;7352.669129889167;7520.247975202546;7587.430942679211;7791.805204869924;4176.394422808676;4210.442136409548;4214.4099826024385;4231.767252801125;4246.023414614594;3179.856906439216;3273.3780581689402;3280.3603297356785;3732.496113024707;3882.76703397797;695.1276148433683;704.8731228378839;710.6127160697432;714.3963350968423;715.8639858426986;581.8487193329764;588.9970550147222;621.4375989729302;669.4209508774875;687.2817905288985;8852.513111778786;9141.177294043568;9205;9217.907820921704;9306.162445379949;546.2652732075352;571.588456311455;614.3242433323309;655.2464665634558;667.7395815631908;3833.6549710526187;4471.105778844244;4520.552733955576;4545.454545454567;4587.885302867455;69125;101678;101815;102357;103703;63323.6833815828;78958.6052069736;96934;99071.50464247633;102882;77266;79381.20618793738;83579.5821020891;84971;88024;7239.819004524929;8557.957210213908;8728.738137855875;8923.375363724477;9068.954655226682;54220.728896355264;54994.17508737292;55455;55986.72006639941;56717;72397;74000.88998664917;76130.47739045299;78467.60766196133;79798.60100699459;10534.315269507457;11332;11451.370174640475;11545.307281563133;11580.36308003068;11640.592579259684;12600.369981500706;12707.237565746085;12853.100282980477;13291.136076155019;11720.355380454157;11744.412779361168;11817.82273265919;12301.200421972542;12450.63043065244;12239.571614993432;12888.48446062147;13232;13289.936805055378;14127.011109222256;12093.274403535816;12615.599720363674;12791.42438590247;13167;13575.932120339336;9804.460754658277;10660.46697665129;11029.952154545306;11157.442127893735;11405.201635885409;9760.535919611832;11277.887221127683;11280.49237784318;11538.192326537062;11647.708807279885 09:19:44 INFO - PROCESS | 1636 | __end_tp_report 09:19:44 INFO - PROCESS | 1636 | __start_cc_report 09:19:44 INFO - PROCESS | 1636 | _x_x_mozilla_cycle_collect,1682 09:19:44 INFO - PROCESS | 1636 | __end_cc_report 09:19:44 INFO - PROCESS | 1636 | __startTimestamp1479316784052__endTimestamp 09:19:44 INFO - PROCESS | 1636 | 09:19:44 INFO - PROCESS | 1636 | ------- Summary: start ------- 09:19:44 INFO - PROCESS | 1636 | Number of tests: 6 09:19:44 INFO - PROCESS | 1636 | 09:19:44 INFO - PROCESS | 1636 | [#0] dojo.html Cycles:165 Average:5659.21 Median:5842.12 stddev:645.35 (11.0%) stddev-sans-first:620.12 09:19:44 INFO - PROCESS | 1636 | Values: 3295.6 3307.6 3308.9 3313.8 3358.6 4726.8 5592.7 5625.0 5641.2 5650.0 5566.6 5650.5 5669.6 5678.3 5709.8 5126.2 5147.8 5163.3 5206.2 5267.4 5085.3 5113.7 5134.8 5251.3 5262.0 5150.8 5177.5 5200.1 5222.8 5225.3 5153.9 5183.2 5190.9 5216.7 5235.8 4406.8 5118.4 5173.1 5191.4 5197.6 5371.8 5408.5 5419.2 5574.6 5613.4 6093.4 6175.0 6185.2 6188.5 6194.0 6204.7 6250.3 6255.8 6256.4 6260.1 5647.8 5676.4 5708.8 5756.0 5782.9 6235.8 6315.1 6320.1 6323.5 6347.2 6038.5 6047.7 6070.1 6078.9 6100.5 6043.3 6126.2 6135.0 6225.1 6256.5 6029.6 6050.3 6090.5 6123.6 6125.9 6127.7 6142.5 6143.7 6147.5 6159.1 4863.9 4867.2 4878.1 4898.6 4904.6 4334.7 4350.7 4353.3 4372.1 4380.2 5640.2 5681.3 5703.8 5705.9 5727.2 6119.4 6188.7 6190.4 6198.3 6201.6 5532.4 5566.2 5604.6 5672.7 5679.7 5284.5 5297.1 5302.2 5306.7 5307.3 5181.8 6291.7 6295.5 6296.5 6318.2 6084.1 6113.6 6118.8 6123.0 6143.6 6185.7 6250.4 6260.5 6277.5 6301.0 6166.5 6169.3 6174.1 6176.8 6188.9 6237.5 6239.7 6255.2 6261.5 6273.6 5684.5 5807.3 5934.6 5956.9 5988.1 4968.0 5992.0 6004.6 6024.7 6027.3 5755.3 5829.4 5845.6 5854.9 5863.9 5842.1 5908.1 5941.0 5947.1 5980.1 5842.0 5858.1 5862.0 5913.4 5916.8 09:19:44 INFO - PROCESS | 1636 | 09:19:44 INFO - PROCESS | 1636 | [#1] ext.html Cycles:180 Average:62123.33 Median:37830.53 stddev:135238.49 (357.5%) stddev-sans-first:135538.63 09:19:44 INFO - PROCESS | 1636 | Values: 465.5 467.8 501.5 508.8 519.3 27573.6 39364.0 40414.4 40432.8 40443.0 40719.6 41119.8 41131.6 41137.0 41357.8 9134.5 9314.0 9317.7 9319.3 9321.6 9283.0 9384.2 9390.2 9393.2 9399.2 9356.2 9420.9 9424.9 9425.4 9474.6 9320.1 9359.8 9380.2 9380.7 9381.0 9080.0 9136.0 9156.9 9178.0 9178.4 38775.8 39051.6 39110.0 39130.8 39266.8 3215.0 3288.1 3301.6 3331.8 3341.2 29174.7 29980.3 30187.2 30271.7 30297.8 3180.8 3189.8 3197.4 3205.8 3303.0 425365.0 425984.0 426085.0 426192.0 427121.0 32384.5 33209.0 57835.0 58400.4 58424.4 33706.8 34133.5 34133.6 34140.7 34183.5 12393.8 12697.4 13719.4 14450.0 14490.0 12142.0 12253.8 12254.9 12264.5 12272.0 7689.3 7697.3 7738.5 7773.1 7788.0 3150.8 3203.7 3214.6 3228.9 3245.9 883.7 899.3 927.8 970.2 982.7 27420.0 28396.4 29171.4 29477.8 29883.0 989.9 1045.7 1055.7 1088.8 1119.9 12368.4 15077.5 15283.8 15401.3 15703.8 618775.0 619607.0 622090.0 622249.0 629612.0 56412.0 57046.0 57105.1 57192.0 57218.1 1339.0 1363.8 1384.1 1387.1 1391.6 26202.0 26701.5 29327.3 30116.1 30237.0 50184.7 50431.5 50456.5 50487.0 50559.5 440443.0 443216.0 446413.0 448365.0 448639.0 25623.6 26593.6 26613.9 26645.0 26655.0 42487.8 45187.3 45345.8 45411.3 45773.9 33377.5 36806.4 36904.8 37786.6 37874.4 37087.3 37242.0 37381.0 37589.4 37607.7 36928.5 36929.3 37056.8 37364.9 37448.8 31663.1 34019.8 34134.7 34176.0 34291.5 21128.3 21891.3 22544.0 24908.8 25786.6 09:19:44 INFO - PROCESS | 1636 | 09:19:44 INFO - PROCESS | 1636 | [#2] jquery.html Cycles:180 Average:34448.74 Median:5055.30 stddev:99060.64 (1959.5%) stddev-sans-first:99305.41 09:19:44 INFO - PROCESS | 1636 | Values: 330.4 351.9 376.8 380.2 402.4 14503.5 15086.5 15460.5 19501.6 20026.7 19483.5 19534.3 20211.9 20280.1 20460.3 5684.2 5980.5 5987.0 5987.5 5988.7 5486.3 5855.5 5911.8 5923.4 5924.9 5705.0 5821.0 5857.1 5858.0 5868.4 5586.3 5830.8 5832.2 5832.9 5836.6 4883.6 5043.8 5066.8 5103.3 5107.7 19612.8 24144.6 24226.0 24254.9 24533.4 2331.3 2343.0 2480.2 2724.5 2748.1 14986.8 15180.5 15539.0 15649.9 15733.8 1714.2 1757.6 1809.6 1858.9 1890.6 87472.6 94823.5 99590.5 125043.4 129031.4 9303.7 10095.5 11081.5 11152.6 11168.2 12098.2 12518.6 12520.0 12533.8 12558.1 6218.0 6274.2 7267.7 8044.9 8113.3 8312.1 8326.0 8366.0 8487.4 8489.1 3842.5 3859.1 3907.0 3930.4 3986.0 1831.5 1880.5 1929.8 1932.4 1943.8 734.7 924.5 929.9 942.9 961.6 20086.4 21302.5 21322.4 21330.3 21438.8 924.5 947.9 963.0 965.4 983.5 10407.5 11124.7 11221.3 11222.0 11271.3 513122.0 597548.0 614236.0 615414.0 616208.0 129669.0 130257.0 134557.0 135228.3 135594.3 954.4 955.8 985.5 985.7 988.4 18503.3 18647.5 18954.7 18982.5 19013.7 49283.8 53766.7 55344.4 56266.7 57314.7 79925.0 81408.6 81614.6 83615.0 83836.0 5310.2 5443.1 7963.4 8077.4 8234.8 8400.6 8826.8 9474.4 11643.0 13105.1 6906.4 7558.8 7902.1 7926.5 7999.5 7776.8 7808.1 7947.4 8058.1 8202.5 7783.7 7922.3 8045.4 8609.5 8618.2 11853.3 12422.7 12710.8 12749.7 12817.9 10684.5 11359.4 11735.9 11819.3 12082.7 09:19:44 INFO - PROCESS | 1636 | 09:19:44 INFO - PROCESS | 1636 | [#3] mootools.html Cycles:180 Average:5459.12 Median:5226.60 stddev:2998.22 (57.4%) stddev-sans-first:2990.51 09:19:44 INFO - PROCESS | 1636 | Values: 1322.1 1470.5 1485.4 1643.8 1814.0 7558.9 7996.8 8082.6 8429.7 8443.6 8250.1 8266.5 8369.8 8370.3 8387.1 2292.7 2317.7 2320.4 2323.4 2327.0 2298.8 2333.6 2339.7 2361.0 2364.3 2289.5 2298.4 2321.4 2327.2 2335.7 2032.1 2186.0 2293.6 2309.9 2323.5 1942.9 2315.1 2325.4 2326.2 2326.4 8241.5 8328.3 8360.4 8439.2 8460.9 5085.2 5229.1 5242.2 5252.2 5255.2 3348.7 3399.3 3585.1 3660.8 3677.9 1054.3 1057.5 1065.1 1081.6 1102.1 10914.1 10986.1 11050.4 11067.3 11111.6 4139.3 4424.4 4476.0 4502.0 4528.6 8673.8 8680.7 8682.8 8691.2 8693.0 5081.1 5084.8 5086.1 5088.9 5100.3 4052.7 4302.9 4424.0 4443.5 4771.6 3103.7 3640.9 3675.3 3738.9 3742.4 969.2 970.6 1135.7 1137.6 1145.0 814.2 885.5 892.9 902.8 928.4 5738.3 5832.8 6037.8 6038.8 6180.0 915.9 925.6 934.4 945.6 979.3 3619.9 3739.0 3864.4 3992.3 4012.5 8935.0 9762.9 9927.6 10126.3 10164.8 10144.6 10236.9 10253.9 10254.4 10276.7 7847.8 8012.8 8116.0 8127.7 8139.5 5644.9 5648.0 5708.3 5872.9 5953.4 9397.8 9580.1 9616.9 9632.3 9638.9 8867.6 9180.0 9288.4 9474.1 9561.9 6738.4 6988.5 7195.3 7208.5 7249.9 7934.0 8055.4 8102.8 8150.6 8160.9 7253.7 7373.7 7480.4 7525.9 7553.4 7249.2 7274.6 7290.6 7318.7 7401.6 5675.3 5796.2 6621.8 6751.9 6847.1 5086.7 5114.5 5132.6 5183.1 5190.9 4235.5 5052.3 5087.9 5195.2 5224.1 09:19:44 INFO - PROCESS | 1636 | 09:19:44 INFO - PROCESS | 1636 | [#4] prototype.html Cycles:180 Average:4341.22 Median:4879.74 stddev:1289.46 (26.4%) stddev-sans-first:1256.92 09:19:44 INFO - PROCESS | 1636 | Values: 301.4 305.2 311.5 315.8 316.8 4957.6 5048.7 5161.6 5228.7 5265.4 3884.4 4158.5 4859.5 5014.6 5022.0 3328.8 3432.6 3759.5 3980.6 4027.0 3053.4 3970.3 3971.4 4010.9 4042.9 3951.9 3978.5 3978.8 4006.8 4015.3 3979.8 3984.5 3985.3 3985.8 3996.0 3351.8 3453.4 3503.2 3514.7 3515.7 4979.6 5010.5 5041.3 5101.9 5106.8 4432.6 4561.7 4594.0 4668.4 4678.3 5093.3 5348.4 5445.6 5489.6 5501.6 3539.3 3756.2 3891.3 3897.8 3909.3 4556.5 5723.7 5756.0 5787.2 5823.5 3483.5 3841.6 3989.6 3990.8 3993.1 3693.5 3866.4 4017.5 4022.4 4038.6 4569.6 4570.0 4578.9 4590.7 4616.6 4888.0 5159.0 5409.1 5414.2 5486.4 1089.3 1273.6 1285.9 1310.3 1317.8 845.5 881.7 899.6 903.4 911.9 4487.9 4638.8 4823.9 4840.9 4885.7 5169.5 5263.9 5285.3 5287.9 5334.3 4456.8 4491.4 4802.5 4848.9 4887.6 2398.8 2561.6 2668.3 2789.0 2790.2 4716.7 4873.8 5052.5 5309.8 5488.5 4622.4 5080.5 5187.3 5277.2 5390.7 4619.9 5293.7 5429.2 5433.2 5464.2 5152.3 5206.8 5228.8 5234.1 5260.5 4778.5 5110.1 5171.8 5175.9 5225.8 4816.6 4826.3 4946.3 5337.5 5413.3 3370.9 3503.8 3685.8 3753.0 3809.4 4277.8 5259.6 5406.6 5457.4 5472.6 5300.9 5320.0 5330.3 5344.8 5418.6 4919.5 5033.0 5107.8 5332.4 5335.4 5237.2 5260.9 5264.2 5282.9 5317.4 4800.6 5081.7 5397.4 5403.5 5422.4 4942.4 5228.5 5270.8 5328.5 5340.5 09:19:44 INFO - PROCESS | 1636 | 09:19:44 INFO - PROCESS | 1636 | [#5] yui.html Cycles:180 Average:19900.00 Median:4596.16 stddev:28474.76 (619.5%) stddev-sans-first:28518.39 09:19:44 INFO - PROCESS | 1636 | Values: 766.5 776.8 783.5 792.9 796.3 13981.9 13991.9 14429.3 14431.3 14873.9 12952.3 13300.3 13324.0 13333.0 13343.9 655.9 678.4 822.8 837.0 841.1 677.4 814.5 825.7 836.9 842.3 882.1 882.9 883.2 887.0 891.0 691.9 708.8 878.6 879.9 880.0 625.8 641.0 648.3 649.0 650.0 13929.4 13977.6 14317.6 14319.9 14332.4 12488.2 13162.5 13320.9 13443.1 13614.7 12052.2 12156.2 12165.4 12540.4 12735.2 4479.5 4493.5 4595.0 4597.3 4651.1 80329.6 81853.0 83760.0 91179.6 92293.1 10477.4 10953.2 10995.0 11029.4 11344.0 21447.0 21468.6 21567.4 22138.1 22191.3 7195.9 7352.7 7520.2 7587.4 7791.8 4176.4 4210.4 4214.4 4231.8 4246.0 3179.9 3273.4 3280.4 3732.5 3882.8 695.1 704.9 710.6 714.4 715.9 581.8 589.0 621.4 669.4 687.3 8852.5 9141.2 9205.0 9217.9 9306.2 546.3 571.6 614.3 655.2 667.7 3833.7 4471.1 4520.6 4545.5 4587.9 69125.0 101678.0 101815.0 102357.0 103703.0 63323.7 78958.6 96934.0 99071.5 102882.0 77266.0 79381.2 83579.6 84971.0 88024.0 7239.8 8558.0 8728.7 8923.4 9069.0 54220.7 54994.2 55455.0 55986.7 56717.0 72397.0 74000.9 76130.5 78467.6 79798.6 10534.3 11332.0 11451.4 11545.3 11580.4 11640.6 12600.4 12707.2 12853.1 13291.1 11720.4 11744.4 11817.8 12301.2 12450.6 12239.6 12888.5 13232.0 13289.9 14127.0 12093.3 12615.6 12791.4 13167.0 13575.9 9804.5 10660.5 11030.0 11157.4 11405.2 9760.5 11277.9 11280.5 11538.2 11647.7 09:19:44 INFO - PROCESS | 1636 | -------- Summary: end -------- 09:19:44 INFO - PROCESS | 1636 | 09:19:44 INFO - TEST-INFO | 1636: exit 0 09:19:44 INFO - TEST-OK | dromaeo_css | took 1117875ms 09:19:44 INFO - TEST-START | kraken 09:19:44 INFO - operating with platform_type : mac_ 09:19:44 INFO - Initialising browser for kraken test... 09:19:44 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/tmp6KliNV/profile http://localhost:49242/getInfo.html) 09:19:46 ERROR - PROCESS | 1646 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 09:19:46 INFO - PROCESS | 1646 | __metrics Screen width/height:1600/1200 09:19:46 INFO - PROCESS | 1646 | colorDepth:24 09:19:46 INFO - PROCESS | 1646 | Browser inner width/height: 1024/689 09:19:46 INFO - PROCESS | 1646 | __metrics 09:19:46 INFO - PROCESS | 1646 | 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" 09:19:46 INFO - PROCESS | 1646 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:19:47 INFO - TEST-INFO | 1646: exit 0 09:19:47 INFO - Browser initialized. 09:19:47 INFO - Running cycle 1/1 for kraken test... 09:19:47 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/T/tmp6KliNV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:19:59 INFO - PROCESS | 1647 | RSS: Main: 238460928 09:19:59 INFO - PROCESS | 1647 | 09:20:02 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 09:20:03 INFO - PROCESS | 1647 | RSS: Main: 285229056 09:20:03 INFO - PROCESS | 1647 | 09:20:06 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 09:20:06 INFO - PROCESS | 1647 | RSS: Main: 295993344 09:20:06 INFO - PROCESS | 1647 | 09:20:09 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 09:20:10 INFO - PROCESS | 1647 | RSS: Main: 389681152 09:20:10 INFO - PROCESS | 1647 | 09:20:12 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 09:20:13 INFO - PROCESS | 1647 | RSS: Main: 294322176 09:20:13 INFO - PROCESS | 1647 | 09:20:15 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:20:15 INFO - PROCESS | 1647 | RSS: Main: 295698432 09:20:15 INFO - PROCESS | 1647 | 09:20:18 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 09:20:19 INFO - PROCESS | 1647 | RSS: Main: 336789504 09:20:19 INFO - PROCESS | 1647 | 09:20:22 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 09:20:22 INFO - PROCESS | 1647 | RSS: Main: 336896000 09:20:22 INFO - PROCESS | 1647 | 09:20:25 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 09:20:26 INFO - PROCESS | 1647 | RSS: Main: 334794752 09:20:26 INFO - PROCESS | 1647 | 09:20:27 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:20:28 INFO - PROCESS | 1647 | RSS: Main: 310390784 09:20:28 INFO - PROCESS | 1647 | 09:20:30 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:20:31 INFO - PROCESS | 1647 | RSS: Main: 311910400 09:20:31 INFO - PROCESS | 1647 | 09:20:33 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:20:33 INFO - PROCESS | 1647 | RSS: Main: 314040320 09:20:33 INFO - PROCESS | 1647 | 09:20:36 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:20:37 INFO - PROCESS | 1647 | RSS: Main: 331157504 09:20:37 INFO - PROCESS | 1647 | 09:20:40 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:20:41 INFO - PROCESS | 1647 | RSS: Main: 325111808 09:20:41 INFO - PROCESS | 1647 | 09:20:42 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 09:20:43 INFO - PROCESS | 1647 | RSS: Main: 369336320 09:20:43 INFO - PROCESS | 1647 | 09:20:43 INFO - PROCESS | 1647 | __start_tp_report 09:20:43 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 09:20:43 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 09:20:43 INFO - PROCESS | 1647 | |i|pagename|runs| 09:20:43 INFO - PROCESS | 1647 | |0;ai-astar;115;115;115;116;115;114;116;115;114;115 09:20:43 INFO - PROCESS | 1647 | |1;audio-beat-detection;147;111;113;147;116;114;111;110;143;112 09:20:43 INFO - PROCESS | 1647 | |2;audio-dft;185;184;182;184;182;185;187;222;183;190 09:20:43 INFO - PROCESS | 1647 | |3;audio-fft;80;59;59;89;59;61;82;81;81;57 09:20:43 INFO - PROCESS | 1647 | |4;audio-oscillator;87;71;95;70;68;68;91;67;69;90 09:20:43 INFO - PROCESS | 1647 | |5;imaging-gaussian-blur;87;86;89;86;87;84;89;87;88;85 09:20:43 INFO - PROCESS | 1647 | |6;imaging-darkroom;93;109;149;87;146;146;94;94;93;93 09:20:43 INFO - PROCESS | 1647 | |7;imaging-desaturate;81;83;83;79;81;80;84;83;82;88 09:20:43 INFO - PROCESS | 1647 | |8;json-parse-financial;53;55;53;52;56;57;57;55;56;57 09:20:43 INFO - PROCESS | 1647 | |9;json-stringify-tinderbox;66;76;68;76;72;70;70;72;71;72 09:20:43 INFO - PROCESS | 1647 | |10;stanford-crypto-aes;94;85;83;84;84;87;85;82;83;88 09:20:43 INFO - PROCESS | 1647 | |11;stanford-crypto-ccm;137;155;151;156;140;150;166;151;194;150 09:20:43 INFO - PROCESS | 1647 | |12;stanford-crypto-pbkdf2;167;168;165;166;167;167;164;226;220;197 09:20:43 INFO - PROCESS | 1647 | |13;stanford-crypto-sha256-iterative;67;67;67;66;66;66;66;65;66;65 09:20:43 INFO - PROCESS | 1647 | __end_tp_report 09:20:43 INFO - PROCESS | 1647 | __start_cc_report 09:20:43 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,2065 09:20:43 INFO - PROCESS | 1647 | __end_cc_report 09:20:43 INFO - PROCESS | 1647 | __startTimestamp1479316843188__endTimestamp 09:20:43 INFO - PROCESS | 1647 | 09:20:43 INFO - PROCESS | 1647 | ------- Summary: start ------- 09:20:43 INFO - PROCESS | 1647 | Number of tests: 14 09:20:43 INFO - PROCESS | 1647 | 09:20:43 INFO - PROCESS | 1647 | [#0] ai-astar Cycles:10 Average:115.00 Median:115.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 09:20:43 INFO - PROCESS | 1647 | Values: 115.0 115.0 115.0 116.0 115.0 114.0 116.0 115.0 114.0 115.0 09:20:43 INFO - PROCESS | 1647 | 09:20:43 INFO - PROCESS | 1647 | [#1] audio-beat-detection Cycles:10 Average:122.40 Median:115.00 stddev:16.18 (14.1%) stddev-sans-first:14.51 09:20:43 INFO - PROCESS | 1647 | Values: 147.0 111.0 113.0 147.0 116.0 114.0 111.0 110.0 143.0 112.0 09:20:43 INFO - PROCESS | 1647 | 09:20:43 INFO - PROCESS | 1647 | [#2] audio-dft Cycles:10 Average:188.40 Median:185.00 stddev:12.05 (6.5%) stddev-sans-first:12.72 09:20:43 INFO - PROCESS | 1647 | Values: 185.0 184.0 182.0 184.0 182.0 185.0 187.0 222.0 183.0 190.0 09:20:43 INFO - PROCESS | 1647 | 09:20:43 INFO - PROCESS | 1647 | [#3] audio-fft Cycles:10 Average:70.80 Median:80.50 stddev:12.71 (15.8%) stddev-sans-first:13.04 09:20:43 INFO - PROCESS | 1647 | Values: 80.0 59.0 59.0 89.0 59.0 61.0 82.0 81.0 81.0 57.0 09:20:43 INFO - PROCESS | 1647 | 09:20:43 INFO - PROCESS | 1647 | [#4] audio-oscillator Cycles:10 Average:77.60 Median:79.00 stddev:11.53 (14.6%) stddev-sans-first:11.72 09:20:43 INFO - PROCESS | 1647 | Values: 87.0 71.0 95.0 70.0 68.0 68.0 91.0 67.0 69.0 90.0 09:20:43 INFO - PROCESS | 1647 | 09:20:43 INFO - PROCESS | 1647 | [#5] imaging-gaussian-blur Cycles:10 Average:86.80 Median:87.00 stddev:1.62 (1.9%) stddev-sans-first:1.72 09:20:43 INFO - PROCESS | 1647 | Values: 87.0 86.0 89.0 86.0 87.0 84.0 89.0 87.0 88.0 85.0 09:20:43 INFO - PROCESS | 1647 | 09:20:43 INFO - PROCESS | 1647 | [#6] imaging-darkroom Cycles:10 Average:110.40 Median:93.00 stddev:25.86 (27.8%) stddev-sans-first:26.66 09:20:43 INFO - PROCESS | 1647 | Values: 93.0 109.0 149.0 87.0 146.0 146.0 94.0 94.0 93.0 93.0 09:20:43 INFO - PROCESS | 1647 | 09:20:43 INFO - PROCESS | 1647 | [#7] imaging-desaturate Cycles:10 Average:82.40 Median:83.00 stddev:2.50 (3.0%) stddev-sans-first:2.60 09:20:43 INFO - PROCESS | 1647 | Values: 81.0 83.0 83.0 79.0 81.0 80.0 84.0 83.0 82.0 88.0 09:20:43 INFO - PROCESS | 1647 | 09:20:43 INFO - PROCESS | 1647 | [#8] json-parse-financial Cycles:10 Average:55.10 Median:56.00 stddev:1.85 (3.3%) stddev-sans-first:1.80 09:20:43 INFO - PROCESS | 1647 | Values: 53.0 55.0 53.0 52.0 56.0 57.0 57.0 55.0 56.0 57.0 09:20:43 INFO - PROCESS | 1647 | 09:20:43 INFO - PROCESS | 1647 | [#9] json-stringify-tinderbox Cycles:10 Average:71.30 Median:72.00 stddev:3.13 (4.3%) stddev-sans-first:2.67 09:20:43 INFO - PROCESS | 1647 | Values: 66.0 76.0 68.0 76.0 72.0 70.0 70.0 72.0 71.0 72.0 09:20:43 INFO - PROCESS | 1647 | 09:20:43 INFO - PROCESS | 1647 | [#10] stanford-crypto-aes Cycles:10 Average:85.50 Median:85.00 stddev:3.50 (4.1%) stddev-sans-first:1.94 09:20:43 INFO - PROCESS | 1647 | Values: 94.0 85.0 83.0 84.0 84.0 87.0 85.0 82.0 83.0 88.0 09:20:43 INFO - PROCESS | 1647 | 09:20:43 INFO - PROCESS | 1647 | [#11] stanford-crypto-ccm Cycles:10 Average:155.00 Median:153.00 stddev:15.90 (10.4%) stddev-sans-first:15.47 09:20:43 INFO - PROCESS | 1647 | Values: 137.0 155.0 151.0 156.0 140.0 150.0 166.0 151.0 194.0 150.0 09:20:43 INFO - PROCESS | 1647 | 09:20:43 INFO - PROCESS | 1647 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.70 Median:167.50 stddev:24.33 (14.5%) stddev-sans-first:25.30 09:20:43 INFO - PROCESS | 1647 | Values: 167.0 168.0 165.0 166.0 167.0 167.0 164.0 226.0 220.0 197.0 09:20:43 INFO - PROCESS | 1647 | 09:20:43 INFO - PROCESS | 1647 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:66.00 stddev:0.74 (1.1%) stddev-sans-first:0.71 09:20:43 INFO - PROCESS | 1647 | Values: 67.0 67.0 67.0 66.0 66.0 66.0 66.0 65.0 66.0 65.0 09:20:43 INFO - PROCESS | 1647 | -------- Summary: end -------- 09:20:43 INFO - PROCESS | 1647 | 09:20:44 INFO - TEST-INFO | 1647: exit 0 09:20:44 INFO - TEST-OK | kraken | took 59412ms 09:20:44 INFO - SUITE-END | took 1177s 09:20:44 INFO - Completed test suite (00:19:37) 09:20:44 INFO - javascript benchmark 09:20:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3295.5715756951595, 3307.6196237432696, 3308.917277068073, 3313.8177400242976, 3358.596968363797, 4726.787294571744, 5592.692401917893, 5625.0, 5641.2384328115695, 5650.039493286141, 5566.610337276382, 5650.49145576898, 5669.574781891364, 5678.261825962619, 5709.800156994506, 5126.154184559543, 5147.794088236466, 5163.277141200235, 5206.245094461301, 5267.394249661281, 5085.288059671649, 5113.718745468997, 5134.768935397898, 5251.317328747257, 5262.0, 5150.845474635767, 5177.508136727005, 5200.115980283342, 5222.764975576109, 5225.346831646044, 5153.871153221162, 5183.170692689152, 5190.92213616796, 5216.739163041832, 5235.816746413857, 4406.810161256442, 5118.385793704742, 5173.146430838907, 5191.402988656278, 5197.636165468419, 5371.838844834661, 5408.540274076709, 5419.241306217133, 5574.637648552831, 5613.4386561343945, 6093.390660933916, 6175.0, 6185.16500272462, 6188.504919606421, 6194.039923811816, 6204.6587437690905, 6250.312465628777, 6255.812325630239, 6256.374362563753, 6260.060990851363, 5647.830565083054, 5676.432356764373, 5708.800191993343, 5756.0, 5782.88434231313, 6235.781747638902, 6315.115883776275, 6320.083587879732, 6323.52573556985, 6347.206599175103, 6038.5169186465855, 6047.697615119227, 6070.089486577049, 6078.908816367758, 6100.511959043266, 6043.3050199227655, 6126.203166017066, 6135.018397056539, 6225.066240064027, 6256.499480041586, 6029.60807547508, 6050.273967123885, 6090.543209259324, 6123.601965872204, 6125.877482450415, 6127.691046834749, 6142.508599312045, 6143.709820937564, 6147.538934579924, 6159.137720719103, 4863.927041094386, 4867.196912509467, 4878.121938051114, 4898.632602554822, 4904.55858972687, 4334.718243314174, 4350.73895566267, 4353.303471444554, 4372.125574885035, 4380.233459144637, 5640.210370548127, 5681.261436013292, 5703.771849125988, 5705.857353566194, 5727.226824378656, 6119.357467465941, 6188.721507532172, 6190.380961903774, 6198.318184999646, 6201.596896201822, 5532.363778165482, 5566.2207290979295, 5604.579656525777, 5672.659640421505, 5679.687617181012, 5284.497972692588, 5297.073012222845, 5302.2046692996355, 5306.655067420683, 5307.256984022163, 5181.766820493087, 6291.653959032297, 6295.464885484785, 6296.464800492009, 6318.241810982528, 6084.14821924922, 6113.637041974642, 6118.7714669719035, 6122.97760420812, 6143.569950103629, 6185.659788711484, 6250.3749625037135, 6260.530460215411, 6277.497800175884, 6300.9684951574945, 6166.506679465721, 6169.321374648695, 6174.104754810617, 6176.7838125665385, 6188.907166392418, 6237.469815065589, 6239.719212635351, 6255.218097737783, 6261.530385221035, 6273.560850740587, 5684.459976302244, 5807.303123625192, 5934.643921364732, 5956.910646340221, 5988.131720900358, 4968.0, 5991.970040149771, 6004.639721616717, 6024.728887199999, 6027.30685971119, 5755.251817263729, 5829.387914268941, 5845.590808643354, 5854.912176317273, 5863.885861686266, 5842.123681447817, 5908.05471124618, 5940.970295148497, 5947.1079338099635, 5980.1328807321825, 5841.970790146022, 5858.091995740667, 5862.0, 5913.379095194942, 5916.763329466773], "value": 5617.915506583987, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [465.4809886976023, 467.8245657878298, 501.53106845099813, 508.8397154896208, 519.3222844188335, 27573.58639620407, 39364.01589960249, 40414.39378409323, 40432.78701638956, 40443.0, 40719.59280407195, 41119.79440102795, 41131.58868411315, 41137.0, 41357.7932110339, 9134.497602631836, 9313.975462699098, 9317.673881414152, 9319.254459643213, 9321.580528876217, 9283.025282345357, 9384.202342800836, 9390.154886060252, 9393.248540116809, 9399.248060155205, 9356.204722598553, 9420.905790942106, 9424.858627120599, 9425.387349822275, 9474.573644185994, 9320.067993200659, 9359.812803743922, 9380.24958003358, 9380.71857844259, 9381.0, 9079.954600227024, 9135.954320228357, 9156.908430915671, 9177.954110229473, 9178.357514973955, 38775.80612096922, 39051.60948390508, 39110.022249443704, 39130.80434597837, 39266.80366598149, 3215.0, 3288.128645908836, 3301.636819949804, 3331.833408329574, 3341.1814105544054, 29174.70825291741, 29980.250493737614, 30187.245318866986, 30271.69728302733, 30297.848510757525, 3180.80915145092, 3189.8086114833186, 3197.4084794313126, 3205.8397080146133, 3302.9925872608846, 425365.0, 425984.0, 426085.0, 426191.9999999969, 427121.0, 32384.514232286536, 33209.00372988814, 57835.0, 58400.415995839496, 58424.41575584275, 33706.831465843, 34133.48799768005, 34133.634654614034, 34140.65859341424, 34183.48724769131, 12393.814092788616, 12697.412823397075, 13719.382627781773, 14450.0, 14490.0, 12142.0, 12253.75492490163, 12254.877451225373, 12264.509419623293, 12271.956883664989, 7689.3464055555905, 7697.307242348215, 7738.4969976951315, 7773.067231932205, 7788.026496687914, 3150.7951983121025, 3203.6796320368244, 3214.6463888972194, 3228.886988955422, 3245.918852028718, 883.6774577279247, 899.2895612466209, 927.823713494434, 970.2432102959702, 982.7444864335181, 27420.0, 28396.432071358457, 29171.416571668447, 29477.768247192493, 29882.954096606514, 989.8812142542942, 1045.6706137566641, 1055.714956961616, 1088.7822435512928, 1119.9496022679, 12368.392109025948, 15077.472288469851, 15283.770743438858, 15401.306941187673, 15703.764443533357, 618775.0, 619607.0, 622090.0, 622249.0, 629612.0, 56412.0, 57046.0, 57105.14342284869, 57192.0, 57218.141727874114, 1339.04258455205, 1363.795430685405, 1384.0795870746042, 1387.1191793211383, 1391.589481103077, 26201.951921922908, 26701.46597068009, 29327.266818329714, 30116.09651710452, 30237.0, 50184.74907625438, 50431.49568504268, 50456.49543504518, 50487.0, 50559.49440505548, 440443.0, 443216.0, 446413.0, 448365.0, 448639.0, 25623.615645764956, 26593.601095983187, 26613.866930665998, 26645.0, 26655.0, 42487.787561061996, 45187.32219016783, 45345.77327113343, 45411.31883021823, 45773.94017269297, 33377.49933750947, 36806.44790328201, 36904.81547592245, 37786.62213377831, 37874.43188352122, 37087.25825483421, 37242.0, 37381.0, 37589.43615845819, 37607.68373106928, 36928.52285908534, 36929.26141477209, 37056.814715926244, 37364.879053628436, 37448.812755936044, 31663.050108496784, 34019.82990085034, 34134.65865341414, 34176.0, 34291.4856277161, 21128.260510882046, 21891.343259702233, 22543.9565658634, 24908.750912490643, 25786.613200802378], "value": 17866.997768721558, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [330.39867441256916, 351.91378112362474, 376.82430815854536, 380.20537077508004, 402.43056075652953, 14503.492377766781, 15086.47197348093, 15460.5361839145, 19501.609967800672, 20026.699599505984, 19483.512912177168, 19534.31629892954, 20211.898940505314, 20280.087396067174, 20460.28389006385, 5684.175794509793, 5980.461758441739, 5987.042073268278, 5987.520998320124, 5988.730507127168, 5486.341639003325, 5855.531557475413, 5911.793087241947, 5923.378045305246, 5924.940750592482, 5705.0, 5820.970895145519, 5857.092150716646, 5857.970710146444, 5868.354481007107, 5586.273784408021, 5830.825075247707, 5832.212651292064, 5832.941670583282, 5836.5914385993, 4883.633727470454, 5043.84868453947, 5066.758644132175, 5103.2855400244, 5107.668001579886, 19612.803871961383, 24144.63783043256, 24226.03095876163, 24254.87872560626, 24533.423990480922, 2331.2773040357542, 2343.0, 2480.193936970492, 2724.495968245861, 2748.134337683643, 14986.775198372034, 15180.544583662508, 15539.0, 15649.904506684537, 15733.763993540106, 1714.1943286655253, 1757.6484703059439, 1809.5928416106271, 1858.9163487642954, 1890.574620710329, 87472.56263718705, 94823.52588237084, 99590.5020474893, 125043.37478312642, 129031.35484322612, 9303.674371396968, 10095.495225238708, 11081.501332440059, 11152.60965866207, 11168.218224724355, 12098.213616114925, 12518.624441266777, 12520.0, 12533.811992820116, 12558.058145639114, 6218.00511918091, 6274.1843560336865, 7267.745628903069, 8044.919550804417, 8113.269805717514, 8312.127226641236, 8325.95837020811, 8366.037905640807, 8487.448315859448, 8489.066202717697, 3842.4620553122586, 3859.0931131184147, 3907.0427745202605, 3930.4104384342004, 3985.980070099631, 1831.4505648305455, 1880.4734674291221, 1929.8456123510086, 1932.3816378758663, 1943.7861835198116, 734.6510407556368, 924.542351535994, 929.8558723397884, 942.8821397325335, 961.5721004153085, 20086.39740807778, 21302.467438313855, 21322.36032919015, 21330.253441129487, 21438.785612143678, 924.4822899176482, 947.9336446448821, 963.0465838819518, 965.4303960663242, 983.4885859353096, 10407.479626018669, 11124.666260012213, 11221.326720396639, 11222.046126079375, 11271.323720576791, 513122.0, 597548.0, 614236.0, 615414.0, 616208.0, 129669.0, 130257.0, 134557.0, 135228.32385838203, 135594.28811423716, 954.4225743425267, 955.8183945050557, 985.4678473624306, 985.7289245457556, 988.367444835306, 18503.259869605605, 18647.533811654273, 18954.715679265097, 18982.525436864187, 19013.71479427782, 49283.76790580264, 53766.73116634548, 55344.44655553393, 56266.71866640641, 57314.7134264326, 79925.0, 81408.59295703721, 81614.59192703999, 83615.0, 83836.0, 5310.212138627252, 5443.074677304947, 7963.362930965393, 8077.434579579608, 8234.752957411287, 8400.579971001547, 8826.823463530822, 9474.431534107976, 11643.0, 13105.082644214814, 6906.447484201151, 7558.773236802905, 7902.130765615892, 7926.533591285631, 7999.520028798291, 7776.766696999099, 7808.063032436144, 7947.403944704055, 8058.073321568095, 8202.466839655403, 7783.688652454065, 7922.286994170322, 8045.436819422584, 8609.526476043879, 8618.224359807647, 11853.34806585646, 12422.656350869584, 12710.809337860124, 12749.745005100032, 12817.871821281668, 10684.519196636014, 11359.375234361858, 11735.882641173479, 11819.290842549475, 12082.697932551757], "value": 9207.013850848563, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1322.094365359728, 1470.4632809024708, 1485.4281101775816, 1643.819179890212, 1814.0294942205915, 7558.924410755891, 7996.84006319874, 8082.555459449735, 8429.662813487454, 8443.577821108951, 8250.092489826115, 8266.504009759403, 8369.790755231123, 8370.288525475342, 8387.077421483647, 2292.713410823643, 2317.652352147183, 2320.408295884547, 2323.4191452136965, 2326.9767302327014, 2298.8275879309035, 2333.57995560799, 2339.6841426407386, 2361.0, 2364.3025307534317, 2289.5306462175213, 2298.4483723906224, 2321.3500219938446, 2327.208749025331, 2335.7313908900524, 2032.1363420546356, 2186.038143216995, 2293.621552443845, 2309.9422514437274, 2323.512062466884, 1942.922283108674, 2315.143396943142, 2325.4070212095808, 2326.1858349577683, 2326.430024643956, 8241.464304820167, 8328.33373330132, 8360.414770965974, 8439.198276163756, 8460.915390846074, 5085.23721441783, 5229.084910140748, 5242.18746094356, 5252.159654455307, 5255.185446255837, 3348.7153591944716, 3399.286149908522, 3585.121645196917, 3660.8352624131976, 3677.8528858845616, 1054.2988912373262, 1057.5293994172596, 1065.0680654427376, 1081.5781845080426, 1102.1458369763418, 10914.126869850394, 10986.121110311156, 11050.447477626167, 11067.335959842436, 11111.55553777856, 4139.275626765304, 4424.380586717862, 4476.037651904856, 4501.964548153943, 4528.637708983273, 8673.826523469495, 8680.6527738891, 8682.826343473094, 8691.174338437839, 8693.043765185825, 5081.060003899291, 5084.847454576368, 5086.0845047891, 5088.949110508847, 5100.311457953128, 4052.696047796427, 4302.870913872589, 4423.977880110643, 4443.511213766483, 4771.570558649738, 3103.7206651401484, 3640.8725694601094, 3675.320065787838, 3738.9435810304226, 3742.4012158055125, 969.1955676788249, 970.6457143142843, 1135.6763322453062, 1137.635956493914, 1145.0, 814.2468216899463, 885.4996926736502, 892.8794612727198, 902.7833320003283, 928.3779867488798, 5738.282714660668, 5832.825015249549, 6037.758489660365, 6038.788642397495, 6180.011198208265, 915.9450432974043, 925.5788616179622, 934.3786382055856, 945.5650400815564, 979.2851218610455, 3619.9095022624647, 3739.046543131484, 3864.439656249828, 3992.321305378093, 4012.4583181270154, 8935.0, 9762.853557196648, 9927.553260103312, 10126.291159618757, 10164.796704065877, 10144.594216231268, 10236.948815255875, 10253.897461025443, 10254.384736915808, 10276.743081422876, 7847.764567062997, 8012.759617211493, 8116.026076870813, 8127.674893004214, 8139.511629302261, 5644.88710225785, 5647.971760141338, 5708.315002199763, 5872.941270587239, 5953.404659534012, 9397.812043759222, 9580.13778759915, 9616.90383096188, 9632.325737198329, 9638.855417168608, 8867.64529418816, 9179.954100229455, 9288.442693438416, 9474.147326740627, 9561.948185699428, 6738.393544581011, 6988.510804243858, 7195.280471952763, 7208.4593655475, 7249.927500725136, 7934.0, 8055.355571554379, 8102.797430064295, 8150.633221504928, 8160.918390816016, 7253.673584688596, 7373.705051797868, 7480.364169045474, 7525.887111693439, 7553.395728341829, 7249.238829922782, 7274.563526188445, 7290.599017054113, 7318.670659820427, 7401.629918503945, 5675.318961724454, 5796.246487956539, 6621.834454138685, 6751.898721519279, 6847.109875716125, 5086.669366491315, 5114.514121158484, 5132.6150538709, 5183.144781111075, 5190.8961820764125, 4235.512916014574, 5052.292679025013, 5087.87280317995, 5195.168772996302, 5224.111900976767], "value": 4408.0984419306915, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.3746476062171, 305.2262514525678, 311.5482550302061, 315.8120918053757, 316.82574583978806, 4957.553820156185, 5048.671836330636, 5161.587073034158, 5228.660137091087, 5265.420803711589, 3884.4173373993917, 4158.500979882411, 4859.465458799529, 5014.648974571782, 5021.966103727987, 3328.8169150696704, 3432.605250396199, 3759.492468516756, 3980.6266837941007, 4027.0267568891177, 3053.4198502284494, 3970.2655008823317, 3971.4439978403047, 4010.8596199133035, 4042.9191416171657, 3951.9012024699323, 3978.4629075074786, 3978.7612743235354, 4006.8196931138023, 4015.2571774221715, 3979.7612143271494, 3984.5019372578427, 3985.342418500944, 3985.8007099645047, 3995.961050126988, 3351.782134161296, 3453.4301840196335, 3503.1942653189653, 3514.7012503937194, 3515.6660117288816, 4979.551840334388, 5010.498950104997, 5041.344625198737, 5101.913342427852, 5106.821261255838, 4432.556744325574, 4561.703489273186, 4594.012287358234, 4668.416447944007, 4678.251479763256, 5093.261477085802, 5348.411674715778, 5445.618806683534, 5489.64317319373, 5501.642393244426, 3539.3188235588145, 3756.1548651553453, 3891.319019171662, 3897.8440862365474, 3909.276783795007, 4556.54434556545, 5723.685197314146, 5755.963926493229, 5787.247657804459, 5823.5341172706085, 3483.512308276843, 3841.615838416136, 3989.601039895987, 3990.8204130814183, 3993.0815912339745, 3693.4829123922673, 3866.3620502617314, 4017.4576432181866, 4022.356422972369, 4038.5961403859965, 4569.565891240327, 4569.954300456953, 4578.901063744677, 4590.70160439577, 4616.561426664529, 4887.975560122177, 5158.968206358743, 5409.080456322435, 5414.242006119146, 5486.396496385394, 1089.3028461784463, 1273.5733529267613, 1285.8714128587067, 1310.3382791690326, 1317.8352705911761, 845.4884794699242, 881.6510738569998, 899.6086702284528, 903.3541018172039, 911.8905731312152, 4487.865364039084, 4638.814447422133, 4823.927641085386, 4840.903181936412, 4885.7312847793, 5169.534741873174, 5263.921041184385, 5285.286486324297, 5287.920681189786, 5334.253204551366, 4456.844010459683, 4491.371208030879, 4802.51974802517, 4848.927266091011, 4887.633427492952, 2398.7961023312864, 2561.5645340292194, 2668.319578507468, 2788.9959614538748, 2790.190844655068, 4716.665250700925, 4873.756312184377, 5052.509509694649, 5309.84058492155, 5488.451154884479, 4622.445306563167, 5080.49195080489, 5187.325647665854, 5277.155655095166, 5390.730463476811, 4619.8845028873875, 5293.708846013429, 5429.163649077258, 5433.225341074058, 5464.18037294409, 5152.3044389007, 5206.817761378334, 5228.790848366023, 5234.057869583511, 5260.473952604709, 4778.546038126373, 5110.105731497048, 5171.767270472763, 5175.948240517697, 5225.764840582106, 4816.566509014206, 4826.32431459603, 4946.258061290691, 5337.519623233928, 5413.348928576822, 3370.9213051824163, 3503.824808759603, 3685.8341374638744, 3752.967933818222, 3809.3904975203836, 4277.80749866263, 5259.63182577216, 5406.567474602101, 5457.372402173801, 5472.644278121909, 5300.893982120413, 5319.973400132974, 5330.307060082165, 5344.75948582307, 5418.593605479526, 4919.458859525378, 5033.0, 5107.846764597068, 5332.386775520865, 5335.413104558573, 5237.214417837355, 5260.921086183786, 5264.184051472028, 5282.8943421132135, 5317.441668624739, 4800.639952003684, 5081.745912704424, 5397.43769112384, 5403.5136837684795, 5422.430644782241, 4942.380538314699, 5228.45101264377, 5270.762815673381, 5328.4671532846405, 5340.519353258225], "value": 3932.8947624033967, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [766.5209244222361, 776.7747353267553, 783.5103060587137, 792.8652129138046, 796.2992566541444, 13981.930090349546, 13991.930040349784, 14429.278536073207, 14431.278436078206, 14873.925630371832, 12952.287624180666, 13300.334983250823, 13324.0, 13333.0, 13343.866561334384, 655.8622689235266, 678.419950941945, 822.8230930349973, 836.9665213391457, 841.0580150231749, 677.4106527321237, 814.543855440955, 825.7233826668073, 836.9121242269565, 842.3135144856952, 882.1266945723752, 882.9293656507464, 883.1786438612088, 887.0464250930276, 891.0020776730072, 691.878921188795, 708.7554793596202, 878.6353663229752, 879.885614870066, 880.0407555764216, 625.837282306599, 640.971156297963, 648.2609824799732, 648.9740410383579, 649.9610023398563, 13929.373178207004, 13977.58067257984, 14317.57047288583, 14319.85680143206, 14332.42670293187, 12488.188267762567, 13162.473501059947, 13320.866791332059, 13443.126196797177, 13614.727705445835, 12052.216605920587, 12156.209846359958, 12165.391730413445, 12540.435680394405, 12735.172213806165, 4479.507254202035, 4493.5281795411665, 4595.012072404449, 4597.310403439477, 4651.116287905321, 80329.59835200846, 81853.0, 83760.0, 91179.63230551546, 92293.0770692298, 10477.37135771856, 10953.17851161166, 10995.0, 11029.393383363831, 11344.035756960586, 21447.034883430282, 21468.570628587655, 21567.352979410643, 22138.114475420804, 22191.334259972227, 7195.928040719526, 7352.669129889167, 7520.247975202546, 7587.430942679211, 7791.805204869924, 4176.394422808676, 4210.442136409548, 4214.4099826024385, 4231.767252801125, 4246.023414614594, 3179.856906439216, 3273.3780581689402, 3280.3603297356785, 3732.496113024707, 3882.76703397797, 695.1276148433683, 704.8731228378839, 710.6127160697432, 714.3963350968423, 715.8639858426986, 581.8487193329764, 588.9970550147222, 621.4375989729302, 669.4209508774875, 687.2817905288985, 8852.513111778786, 9141.177294043568, 9205.0, 9217.907820921704, 9306.162445379949, 546.2652732075352, 571.588456311455, 614.3242433323309, 655.2464665634558, 667.7395815631908, 3833.6549710526187, 4471.105778844244, 4520.552733955576, 4545.454545454567, 4587.885302867455, 69125.0, 101678.0, 101815.0, 102357.0, 103703.0, 63323.6833815828, 78958.6052069736, 96934.0, 99071.50464247633, 102882.0, 77266.0, 79381.20618793738, 83579.5821020891, 84971.0, 88024.0, 7239.819004524929, 8557.957210213908, 8728.738137855875, 8923.375363724477, 9068.954655226682, 54220.728896355264, 54994.17508737292, 55455.0, 55986.72006639941, 56717.0, 72397.0, 74000.88998664917, 76130.47739045299, 78467.60766196133, 79798.60100699459, 10534.315269507457, 11332.0, 11451.370174640475, 11545.307281563133, 11580.36308003068, 11640.592579259684, 12600.369981500706, 12707.237565746085, 12853.100282980477, 13291.136076155019, 11720.355380454157, 11744.412779361168, 11817.82273265919, 12301.200421972542, 12450.63043065244, 12239.571614993432, 12888.48446062147, 13232.0, 13289.936805055378, 14127.011109222256, 12093.274403535816, 12615.599720363674, 12791.42438590247, 13167.0, 13575.932120339336, 9804.460754658277, 10660.46697665129, 11029.952154545306, 11157.442127893735, 11405.201635885409, 9760.535919611832, 11277.887221127683, 11280.49237784318, 11538.192326537062, 11647.708807279885], "value": 7253.397844594484, "unit": "score"}], "name": "dromaeo_css", "value": 6985.795677970848, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 115.0, 116.0, 115.0, 114.0, 116.0, 115.0, 114.0, 115.0], "value": 115.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 111.0, 113.0, 147.0, 116.0, 114.0, 111.0, 110.0, 143.0, 112.0], "value": 122.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 182.0, 184.0, 182.0, 185.0, 187.0, 222.0, 183.0, 190.0], "value": 188.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 59.0, 89.0, 59.0, 61.0, 82.0, 81.0, 81.0, 57.0], "value": 70.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 95.0, 70.0, 68.0, 68.0, 91.0, 67.0, 69.0, 90.0], "value": 77.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 89.0, 86.0, 87.0, 84.0, 89.0, 87.0, 88.0, 85.0], "value": 86.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 109.0, 149.0, 87.0, 146.0, 146.0, 94.0, 94.0, 93.0, 93.0], "value": 110.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 83.0, 79.0, 81.0, 80.0, 84.0, 83.0, 82.0, 88.0], "value": 82.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 55.0, 53.0, 52.0, 56.0, 57.0, 57.0, 55.0, 56.0, 57.0], "value": 55.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 76.0, 68.0, 76.0, 72.0, 70.0, 70.0, 72.0, 71.0, 72.0], "value": 71.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 85.0, 83.0, 84.0, 84.0, 87.0, 85.0, 82.0, 83.0, 88.0], "value": 85.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 155.0, 151.0, 156.0, 140.0, 150.0, 166.0, 151.0, 194.0, 150.0], "value": 155.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 168.0, 165.0, 166.0, 167.0, 167.0, 164.0, 226.0, 220.0, 197.0], "value": 180.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 66.0, 66.0, 66.0, 66.0, 65.0, 66.0, 65.0], "value": 66.1, "unit": "score"}], "name": "kraken", "value": 1467.4999999999998, "alertThreshold": 2.0}]} 09:20:44 INFO - Return code: 0 09:20:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:20:44 ERROR - # TBPL SUCCESS # 09:20:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:20:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:20:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:20:44 INFO - 2016-11-16 09:20:44.520 screenresolution[1651:10204] starting screenresolution argv=screenresolution get 09:20:44 INFO - 2016-11-16 09:20:44.523 screenresolution[1651:10204] Display 0: 1600x1200x32@60 09:20:44 INFO - 2016-11-16 09:20:44.529 screenresolution[1652:10207] starting screenresolution argv=screenresolution list 09:20:44 INFO - Available Modes on Display 0 09:20:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:20:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:20:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:20:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:20:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:20:44 INFO - Intel Iris: 09:20:44 INFO - Chipset Model: Intel Iris 09:20:44 INFO - Type: GPU 09:20:44 INFO - Bus: Built-In 09:20:44 INFO - VRAM (Dynamic, Max): 1536 MB 09:20:44 INFO - Vendor: Intel (0x8086) 09:20:44 INFO - Device ID: 0x0a2e 09:20:44 INFO - Revision ID: 0x0009 09:20:44 INFO - Displays: 09:20:44 INFO - Display: 09:20:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:20:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:20:44 INFO - Display Serial Number: 1600x1200 60 09:20:44 INFO - Main Display: Yes 09:20:44 INFO - Mirror: Off 09:20:44 INFO - Online: Yes 09:20:44 INFO - Rotation: Supported 09:20:44 INFO - Return code: 0 09:20:44 INFO - Running post-action listener: _resource_record_post_action 09:20:44 INFO - [mozharness: 2016-11-16 17:20:44.764389Z] Finished run-tests step (success) 09:20:44 INFO - Running post-run listener: _resource_record_post_run 09:20:44 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 37479424; Write bytes: 654024192; Read time: 239; Write time: 1913 09:20:44 INFO - TinderboxPrint: CPU usage
53.6% 09:20:44 INFO - TinderboxPrint: I/O read bytes / time
37,479,424 / 239 09:20:44 INFO - TinderboxPrint: I/O write bytes / time
654,024,192 / 1,913 09:20:44 INFO - TinderboxPrint: CPU idle
2,246.1 (46.4%) 09:20:44 INFO - TinderboxPrint: CPU system
975.7 (20.2%) 09:20:44 INFO - TinderboxPrint: CPU user
1,619.9 (33.5%) 09:20:44 INFO - TinderboxPrint: Swap in / out
390,610,944 / 0 09:20:44 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 354103808; Write bytes: 355018240; Read time: 27621; Write time: 784 09:20:44 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 34448384; Write bytes: 297498624; Read time: 217; Write time: 1121 09:20:45 INFO - Running post-run listener: _upload_blobber_files 09:20:45 INFO - Blob upload gear active. 09:20:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:20:45 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:20:45 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'] 09:20:45 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 09:20:45 INFO - (blobuploader) - INFO - Open directory for files ... 09:20:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:20:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:20:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:20:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:20:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:20:46 INFO - (blobuploader) - INFO - Done attempting. 09:20:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:20:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:20:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:20:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:20:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:20:47 INFO - (blobuploader) - INFO - Done attempting. 09:20:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:20:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:20:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:20:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:20:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:20:50 INFO - (blobuploader) - INFO - Done attempting. 09:20:50 INFO - (blobuploader) - INFO - Iteration through files over. 09:20:50 INFO - Return code: 0 09:20:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:20:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:20:50 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5400c609f3af4b2fe101e82e39890678b11f2fcdefce57755672f8fd5ac2bde8d8216d5b540936c38b66a415b4e39844a2d435664558b03f87dbfc8a53125090", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2848f488e8a877288f42fc49780203f5967202045664f8ad5640564ce173ea19d9eb8e7e1566bac9dc2a4ac179dbaff763418b09600d054727c934554f32982a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fb1e3b9aa2dd38d52c7f1b212ea3e6d3bc3c4c10fc48dd64a8a56b8d930e37d3a89eaadf596557f30d6e67bbf46254f9a435295bfd3c9b9b61ff522d678449b4"} 09:20:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:20:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:20:50 INFO - Contents: 09:20:50 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5400c609f3af4b2fe101e82e39890678b11f2fcdefce57755672f8fd5ac2bde8d8216d5b540936c38b66a415b4e39844a2d435664558b03f87dbfc8a53125090", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2848f488e8a877288f42fc49780203f5967202045664f8ad5640564ce173ea19d9eb8e7e1566bac9dc2a4ac179dbaff763418b09600d054727c934554f32982a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fb1e3b9aa2dd38d52c7f1b212ea3e6d3bc3c4c10fc48dd64a8a56b8d930e37d3a89eaadf596557f30d6e67bbf46254f9a435295bfd3c9b9b61ff522d678449b4"} 09:20:50 INFO - Running post-run listener: copy_logs_to_upload_dir 09:20:50 INFO - Copying logs to upload dir... 09:20:50 INFO - mkdir: /builds/slave/test/build/upload/logs 09:20:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.163841 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-16 09:20:50.412281) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-16 09:20:50.418076) ========= 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.x9GuvOyBOd/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.ctQCZbsW2Y/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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/5400c609f3af4b2fe101e82e39890678b11f2fcdefce57755672f8fd5ac2bde8d8216d5b540936c38b66a415b4e39844a2d435664558b03f87dbfc8a53125090", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2848f488e8a877288f42fc49780203f5967202045664f8ad5640564ce173ea19d9eb8e7e1566bac9dc2a4ac179dbaff763418b09600d054727c934554f32982a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fb1e3b9aa2dd38d52c7f1b212ea3e6d3bc3c4c10fc48dd64a8a56b8d930e37d3a89eaadf596557f30d6e67bbf46254f9a435295bfd3c9b9b61ff522d678449b4"} build_url:https://queue.taskcluster.net/v1/task/AMrsAB-XTzeRmXfTwtEONQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011635 build_url: 'https://queue.taskcluster.net/v1/task/AMrsAB-XTzeRmXfTwtEONQ/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/5400c609f3af4b2fe101e82e39890678b11f2fcdefce57755672f8fd5ac2bde8d8216d5b540936c38b66a415b4e39844a2d435664558b03f87dbfc8a53125090", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2848f488e8a877288f42fc49780203f5967202045664f8ad5640564ce173ea19d9eb8e7e1566bac9dc2a4ac179dbaff763418b09600d054727c934554f32982a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fb1e3b9aa2dd38d52c7f1b212ea3e6d3bc3c4c10fc48dd64a8a56b8d930e37d3a89eaadf596557f30d6e67bbf46254f9a435295bfd3c9b9b61ff522d678449b4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-16 09:20:50.451588) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 09:20:50.451910) ========= 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.x9GuvOyBOd/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.ctQCZbsW2Y/Listeners TMPDIR=/var/folders/9f/wvdy8f7j0_x3yrf4y9yg05cc00000w/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.005348 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-16 09:20:50.500281) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-16 09:20:50.500600) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-16 09:20:50.500908) ========= ========= Total master_lag: 0.18 =========