builder: graphics_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0327 starttime: 1480893587.01 results: success (0) buildid: 20161204125338 builduid: 9a43496d8b3c4bd8bdbd2d627715d9a5 revision: 166e3210dcc64181f5541b6b9382faafcf5859a2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-04 15:19:47.012251) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-04 15:19:47.012908) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-04 15:19:47.013190) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-04 15:19:47.219555) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-04 15:19:47.219811) ========= 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.SKxujohX7F/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.Lff5snicj5/Listeners TMPDIR=/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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-12-04 15:19:47-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 42.1M=0s 2016-12-04 15:19:47 (42.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.532561 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-04 15:19:47.818519) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-04 15:19:47.818879) ========= 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.SKxujohX7F/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.Lff5snicj5/Listeners TMPDIR=/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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.024023 ========= master_lag: 0.08 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-04 15:19:47.925883) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-12-04 15:19:47.926237) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 166e3210dcc64181f5541b6b9382faafcf5859a2 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev 166e3210dcc64181f5541b6b9382faafcf5859a2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SKxujohX7F/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.Lff5snicj5/Listeners TMPDIR=/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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-12-04 15:19:47,921 truncating revision to first 12 chars 2016-12-04 15:19:47,921 Setting DEBUG logging. 2016-12-04 15:19:47,921 attempt 1/10 2016-12-04 15:19:47,921 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/166e3210dcc6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-04 15:19:49,698 unpacking tar archive at: graphics-166e3210dcc6/testing/mozharness/ program finished with exit code 0 elapsedTime=2.038747 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-12-04 15:19:49.989441) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-04 15:19:49.989766) ========= script_repo_revision: 166e3210dcc64181f5541b6b9382faafcf5859a2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-04 15:19:49.990185) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-04 15:19:49.990507) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-04 15:19:50.010367) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-12-04 15:19:50.010698) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Graphics', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SKxujohX7F/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.Lff5snicj5/Listeners TMPDIR=/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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 15:19:50 INFO - MultiFileLogger online at 20161204 15:19:50 in /builds/slave/test 15:19:50 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 15:19:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:19:50 INFO - {'append_to_log': False, 15:19:50 INFO - 'base_work_dir': '/builds/slave/test', 15:19:50 INFO - 'blob_upload_branch': 'Graphics', 15:19:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:19:50 INFO - 'branch': 'Graphics', 15:19:50 INFO - 'buildbot_json_path': 'buildprops.json', 15:19:50 INFO - 'config_files': ('talos/mac_config.py',), 15:19:50 INFO - 'default_actions': ('clobber', 15:19:50 INFO - 'read-buildbot-config', 15:19:50 INFO - 'download-and-extract', 15:19:50 INFO - 'populate-webroot', 15:19:50 INFO - 'create-virtualenv', 15:19:50 INFO - 'install', 15:19:50 INFO - 'run-tests'), 15:19:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:19:50 INFO - 'download_minidump_stackwalk': True, 15:19:50 INFO - 'download_symbols': 'ondemand', 15:19:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:19:50 INFO - 'tooltool.py': '/tools/tooltool.py', 15:19:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:19:50 INFO - '/tools/misc-python/virtualenv.py')}, 15:19:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:19:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:19:50 INFO - 'installer_path': 'installer.exe', 15:19:50 INFO - 'log_level': 'info', 15:19:50 INFO - 'log_name': 'talos', 15:19:50 INFO - 'log_to_console': True, 15:19:50 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:19:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:19:50 INFO - 'opt_config_files': (), 15:19:50 INFO - 'pip_index': False, 15:19:50 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:19:50 INFO - 'cmd': ('bash', 15:19:50 INFO - '-c', 15:19:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:19:50 INFO - 'enabled': True, 15:19:50 INFO - 'halt_on_failure': False, 15:19:50 INFO - 'name': 'check_screen_resolution'},), 15:19:50 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:19:50 INFO - 'cmd': ('bash', 15:19:50 INFO - '-c', 15:19:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:19:50 INFO - 'enabled': True, 15:19:50 INFO - 'halt_on_failure': False, 15:19:50 INFO - 'name': 'check_screen_resolution'},), 15:19:50 INFO - 'run_cmd_checks_enabled': True, 15:19:50 INFO - 'sps_profile': False, 15:19:50 INFO - 'sps_profile_interval': 0, 15:19:50 INFO - 'suite': 'dromaeojs-e10s', 15:19:50 INFO - 'system_bits': '32', 15:19:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:19:50 INFO - 'title': 't-yosemite-r7-0327', 15:19:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:19:50 INFO - 'use_talos_json': True, 15:19:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:19:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:19:50 INFO - 'work_dir': 'build'} 15:19:50 INFO - [mozharness: 2016-12-04 23:19:50.112954Z] Running clobber step. 15:19:50 INFO - Running pre-action listener: _resource_record_pre_action 15:19:50 INFO - Running main action method: clobber 15:19:50 INFO - rmtree: /builds/slave/test/build 15:19:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:19:52 INFO - Running post-action listener: _resource_record_post_action 15:19:52 INFO - [mozharness: 2016-12-04 23:19:52.512399Z] Finished clobber step (success) 15:19:52 INFO - [mozharness: 2016-12-04 23:19:52.512555Z] Running read-buildbot-config step. 15:19:52 INFO - Running pre-action listener: _resource_record_pre_action 15:19:52 INFO - Running main action method: read_buildbot_config 15:19:52 INFO - Using buildbot properties: 15:19:52 INFO - { 15:19:52 INFO - "project": "", 15:19:52 INFO - "product": "firefox", 15:19:52 INFO - "script_repo_revision": "default", 15:19:52 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs-e10s", 15:19:52 INFO - "repository": "", 15:19:52 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs-e10s", 15:19:52 INFO - "buildid": "20161204125338", 15:19:52 INFO - "pgo_build": "False", 15:19:52 INFO - "basedir": "/builds/slave/test", 15:19:52 INFO - "buildnumber": 2, 15:19:52 INFO - "slavename": "t-yosemite-r7-0327", 15:19:52 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 15:19:52 INFO - "platform": "yosemite_r7", 15:19:52 INFO - "scheduler": "tests-graphics-macosx64-talos-8-3600", 15:19:52 INFO - "branch": "graphics", 15:19:52 INFO - "repo_path": "projects/graphics", 15:19:52 INFO - "revision": "166e3210dcc64181f5541b6b9382faafcf5859a2", 15:19:52 INFO - "stage_platform": "macosx64", 15:19:52 INFO - "builduid": "9a43496d8b3c4bd8bdbd2d627715d9a5", 15:19:52 INFO - "slavebuilddir": "test" 15:19:52 INFO - } 15:19:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/WmyoIkhBR4-IfrLvhAwWXg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 15:19:52 INFO - Running post-action listener: _resource_record_post_action 15:19:52 INFO - [mozharness: 2016-12-04 23:19:52.515707Z] Finished read-buildbot-config step (success) 15:19:52 INFO - [mozharness: 2016-12-04 23:19:52.515817Z] Running download-and-extract step. 15:19:52 INFO - Running pre-action listener: _resource_record_pre_action 15:19:52 INFO - Running main action method: download_and_extract 15:19:52 INFO - mkdir: /builds/slave/test/build/tests 15:19:52 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')]} 15:19:52 INFO - trying https://queue.taskcluster.net/v1/task/WmyoIkhBR4-IfrLvhAwWXg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 15:19:52 INFO - Downloading https://queue.taskcluster.net/v1/task/WmyoIkhBR4-IfrLvhAwWXg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 15:19:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WmyoIkhBR4-IfrLvhAwWXg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 15:19:53 INFO - Downloaded 1199 bytes. 15:19:53 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 15:19:53 INFO - Using the following test package requirements: 15:19:53 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 15:19:53 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:19:53 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 15:19:53 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:19:53 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 15:19:53 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:19:53 INFO - u'jsshell-mac.zip'], 15:19:53 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:19:53 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 15:19:53 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 15:19:53 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:19:53 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 15:19:53 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:19:53 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 15:19:53 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:19:53 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 15:19:53 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:19:53 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 15:19:53 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:19:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WmyoIkhBR4-IfrLvhAwWXg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 15:19:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WmyoIkhBR4-IfrLvhAwWXg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:19:53 INFO - Fetch https://queue.taskcluster.net/v1/task/WmyoIkhBR4-IfrLvhAwWXg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 15:19:54 INFO - Http code: 200 15:19:54 INFO - via: 1.1 ff555a0cc6346f82fe74596f8ed6cc33.cloudfront.net (CloudFront) 15:19:54 INFO - x-amz-cf-id: v7FZNdcCVXU3EUG0mZGs1lqRF6CEVynw5sgI0VyjetYLwKLLRbT1LA== 15:19:54 INFO - x-amz-version-id: ffldtD4DIMJGGbUiesAZkKaVI7tLde0E 15:19:54 INFO - Expected file size: 16282598 15:19:54 INFO - Obtained file size: 16282598 15:20:01 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 15:20:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WmyoIkhBR4-IfrLvhAwWXg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 15:20:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WmyoIkhBR4-IfrLvhAwWXg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:20:01 INFO - Fetch https://queue.taskcluster.net/v1/task/WmyoIkhBR4-IfrLvhAwWXg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 15:20:01 INFO - Http code: 200 15:20:01 INFO - via: 1.1 d8f42fc9558e3e49ebfdf8834baeb756.cloudfront.net (CloudFront) 15:20:01 INFO - x-amz-cf-id: WcloD4dJOZW5_tmRRnEqxx6n2BAKX6IAqYqKwZLu48NvElcfDSPVaQ== 15:20:01 INFO - x-amz-version-id: ffldtD4DIMJGGbUiesAZkKaVI7tLde0E 15:20:02 INFO - Expected file size: 16282598 15:20:02 INFO - Obtained file size: 16282598 15:20:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WmyoIkhBR4-IfrLvhAwWXg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 15:20:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WmyoIkhBR4-IfrLvhAwWXg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 15:20:08 INFO - Fetch https://queue.taskcluster.net/v1/task/WmyoIkhBR4-IfrLvhAwWXg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 15:20:08 INFO - Http code: 200 15:20:08 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 15:20:08 INFO - x-amz-cf-id: zZDjmRF9ANqPmcPwLQnu9ZmvhlpL98rPgQ9beP1E1gs30ppG1e9FQw== 15:20:08 INFO - x-amz-version-id: IX96gzy3YLndPTj8gll0WE2CAruqomXR 15:20:09 INFO - Expected file size: 14409702 15:20:09 INFO - Obtained file size: 14409702 15:20:09 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')]} 15:20:09 INFO - trying https://queue.taskcluster.net/v1/task/WmyoIkhBR4-IfrLvhAwWXg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 15:20:09 INFO - Downloading https://queue.taskcluster.net/v1/task/WmyoIkhBR4-IfrLvhAwWXg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 15:20:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WmyoIkhBR4-IfrLvhAwWXg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 15:20:12 INFO - Downloaded 107606756 bytes. 15:20:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WmyoIkhBR4-IfrLvhAwWXg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 15:20:12 INFO - mkdir: /builds/slave/test/properties 15:20:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:20:12 INFO - Writing to file /builds/slave/test/properties/build_url 15:20:12 INFO - Contents: 15:20:12 INFO - build_url:https://queue.taskcluster.net/v1/task/WmyoIkhBR4-IfrLvhAwWXg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 15:20:13 INFO - Running post-action listener: _resource_record_post_action 15:20:13 INFO - Running post-action listener: set_extra_try_arguments 15:20:13 INFO - [mozharness: 2016-12-04 23:20:13.023424Z] Finished download-and-extract step (success) 15:20:13 INFO - [mozharness: 2016-12-04 23:20:13.023548Z] Running populate-webroot step. 15:20:13 INFO - Running pre-action listener: _resource_record_pre_action 15:20:13 INFO - Running main action method: populate_webroot 15:20:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:20:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:20:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:20:13 INFO - '--tppagecycles', 15:20:13 INFO - '7'], 15:20:13 INFO - 'tests': ['tsvgm']}}, 15:20:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:20:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:20:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:20:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:20:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:20:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:20:13 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:20:13 INFO - 'talos_options': ['--disable-e10s'], 15:20:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:20:13 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:20:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:20:13 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:20:13 INFO - 'talos_options': ['--disable-e10s'], 15:20:13 INFO - 'tests': ['damp', 'tps']}, 15:20:13 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:20:13 INFO - 'tests': ['damp', 'tps']}, 15:20:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:20:13 INFO - 'tests': ['dromaeo_dom']}, 15:20:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:20:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:20:13 INFO - 'tests': ['basic_compositor_video']}, 15:20:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:20:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:20:13 INFO - 'tests': ['a11yr', 15:20:13 INFO - 'ts_paint', 15:20:13 INFO - 'tpaint', 15:20:13 INFO - 'sessionrestore', 15:20:13 INFO - 'sessionrestore_no_auto_restore', 15:20:13 INFO - 'tabpaint']}, 15:20:13 INFO - 'other-e10s': {'tests': ['a11yr', 15:20:13 INFO - 'ts_paint', 15:20:13 INFO - 'tpaint', 15:20:13 INFO - 'sessionrestore', 15:20:13 INFO - 'sessionrestore_no_auto_restore', 15:20:13 INFO - 'tabpaint']}, 15:20:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:20:13 INFO - 'ts_paint', 15:20:13 INFO - 'tpaint', 15:20:13 INFO - 'sessionrestore', 15:20:13 INFO - 'sessionrestore_no_auto_restore', 15:20:13 INFO - 'tabpaint']}, 15:20:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:20:13 INFO - 'ts_paint', 15:20:13 INFO - 'tpaint', 15:20:13 INFO - 'sessionrestore', 15:20:13 INFO - 'sessionrestore_no_auto_restore', 15:20:13 INFO - 'tabpaint']}, 15:20:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:20:13 INFO - 'tests': ['a11yr', 15:20:13 INFO - 'ts_paint', 15:20:13 INFO - 'tpaint', 15:20:13 INFO - 'sessionrestore', 15:20:13 INFO - 'sessionrestore_no_auto_restore', 15:20:13 INFO - 'tabpaint']}, 15:20:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:20:13 INFO - 'tests': ['a11yr', 15:20:13 INFO - 'ts_paint', 15:20:13 INFO - 'tpaint', 15:20:13 INFO - 'sessionrestore', 15:20:13 INFO - 'sessionrestore_no_auto_restore', 15:20:13 INFO - 'tabpaint']}, 15:20:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:20:13 INFO - 'tests': ['tsvgx', 15:20:13 INFO - 'tsvgr_opacity', 15:20:13 INFO - 'tart', 15:20:13 INFO - 'tscrollx', 15:20:13 INFO - 'cart', 15:20:13 INFO - 'tsvg_static']}, 15:20:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:20:13 INFO - 'tsvgr_opacity', 15:20:13 INFO - 'tart', 15:20:13 INFO - 'tscrollx', 15:20:13 INFO - 'cart', 15:20:13 INFO - 'tsvg_static']}, 15:20:13 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:20:13 INFO - 'talos_options': ['--disable-e10s'], 15:20:13 INFO - 'tests': ['tp5o']}, 15:20:13 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:20:13 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:20:13 INFO - 'talos_options': ['--disable-e10s', 15:20:13 INFO - '--xperf_path', 15:20:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:20:13 INFO - 'tests': ['tp5n']}, 15:20:13 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:20:13 INFO - 'talos_options': ['--xperf_path', 15:20:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:20:13 INFO - 'tests': ['tp5n']}}, 15:20:13 INFO - 'talos.zip': {'path': '', 15:20:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:20:13 INFO - Running post-action listener: _resource_record_post_action 15:20:13 INFO - [mozharness: 2016-12-04 23:20:13.041701Z] Finished populate-webroot step (success) 15:20:13 INFO - [mozharness: 2016-12-04 23:20:13.041809Z] Running create-virtualenv step. 15:20:13 INFO - Running pre-action listener: _resource_record_pre_action 15:20:13 INFO - Running main action method: create_virtualenv 15:20:13 INFO - Creating virtualenv /builds/slave/test/build/venv 15:20:13 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 15:20:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:20:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:20:13 INFO - Using real prefix '/tools/python27' 15:20:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:20:13 INFO - Installing distribute.............................................................................................................................................................................................done. 15:20:16 INFO - Installing pip.................done. 15:20:16 INFO - Return code: 0 15:20:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:20:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:20:17 INFO - Reading from file tmpfile_stdout 15:20:17 INFO - Output received: 15:20:17 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) 15:20:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:20: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')]} 15:20:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20: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')]} 15:20:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b5343f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4aa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe198c6d840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b34ad30>, '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 0x10b1b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b342b20>, '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.SKxujohX7F/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.Lff5snicj5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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 15:20: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', 'pip>=1.5'] in /builds/slave/test/build 15:20: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 pip>=1.5 15:20:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SKxujohX7F/Render', 15:20:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:20:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:20:17 INFO - 'HOME': '/Users/cltbld', 15:20:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:20:17 INFO - 'LOGNAME': 'cltbld', 15:20:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:17 INFO - 'MOZ_NO_REMOTE': '1', 15:20:17 INFO - 'NO_EM_RESTART': '1', 15:20:17 INFO - 'PAGER': '/bin/cat', 15:20:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:20:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:17 INFO - 'PWD': '/builds/slave/test', 15:20:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:20:17 INFO - 'SHELL': '/bin/bash', 15:20:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lff5snicj5/Listeners', 15:20:17 INFO - 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', 15:20:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:20:17 INFO - 'USER': 'cltbld', 15:20:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:20:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:20:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:17 INFO - 'XPC_FLAGS': '0x0', 15:20:17 INFO - 'XPC_SERVICE_NAME': '0', 15:20:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:20:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:17 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 15:20:17 INFO - Cleaning up... 15:20:17 INFO - Return code: 0 15:20:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:20: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')]} 15:20:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20: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')]} 15:20:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20: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', '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 0x10b5343f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4aa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe198c6d840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b34ad30>, '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 0x10b1b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b342b20>, '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.SKxujohX7F/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.Lff5snicj5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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 15:20: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', 'psutil>=3.1.1'] in /builds/slave/test/build 15:20: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 psutil>=3.1.1 15:20:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SKxujohX7F/Render', 15:20:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:20:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:20:17 INFO - 'HOME': '/Users/cltbld', 15:20:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:20:17 INFO - 'LOGNAME': 'cltbld', 15:20:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:17 INFO - 'MOZ_NO_REMOTE': '1', 15:20:17 INFO - 'NO_EM_RESTART': '1', 15:20:17 INFO - 'PAGER': '/bin/cat', 15:20:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:20:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:17 INFO - 'PWD': '/builds/slave/test', 15:20:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:20:17 INFO - 'SHELL': '/bin/bash', 15:20:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lff5snicj5/Listeners', 15:20:17 INFO - 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', 15:20:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:20:17 INFO - 'USER': 'cltbld', 15:20:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:20:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:20:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:17 INFO - 'XPC_FLAGS': '0x0', 15:20:17 INFO - 'XPC_SERVICE_NAME': '0', 15:20:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:20:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:17 INFO - Downloading/unpacking psutil>=3.1.1 15:20: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 15:20: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 15:20: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 15:20: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 15:20: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 15:20: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 15:20:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:20:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:20:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:20:21 INFO - Installing collected packages: psutil 15:20:21 INFO - Running setup.py install for psutil 15:20:21 INFO - building 'psutil._psutil_osx' extension 15:20:21 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 15:20:22 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 15:20:22 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 15:20:22 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 15:20:22 INFO - building 'psutil._psutil_posix' extension 15:20:22 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 15:20:22 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:20:22 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:20:22 INFO - ^ 15:20:22 INFO - 1 warning generated. 15:20:22 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 15:20:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:20:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:20:22 INFO - Successfully installed psutil 15:20:22 INFO - Cleaning up... 15:20:22 INFO - Return code: 0 15:20:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:20: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')]} 15:20:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20: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')]} 15:20:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b5343f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4aa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe198c6d840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b34ad30>, '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 0x10b1b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b342b20>, '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.SKxujohX7F/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.Lff5snicj5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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 15:20:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:20:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:20:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SKxujohX7F/Render', 15:20:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:20:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:20:22 INFO - 'HOME': '/Users/cltbld', 15:20:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:20:22 INFO - 'LOGNAME': 'cltbld', 15:20:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:22 INFO - 'MOZ_NO_REMOTE': '1', 15:20:22 INFO - 'NO_EM_RESTART': '1', 15:20:22 INFO - 'PAGER': '/bin/cat', 15:20:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:20:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:22 INFO - 'PWD': '/builds/slave/test', 15:20:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:20:22 INFO - 'SHELL': '/bin/bash', 15:20:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lff5snicj5/Listeners', 15:20:22 INFO - 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', 15:20:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:20:22 INFO - 'USER': 'cltbld', 15:20:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:20:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:20:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:22 INFO - 'XPC_FLAGS': '0x0', 15:20:22 INFO - 'XPC_SERVICE_NAME': '0', 15:20:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:20:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:20:22 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 15:20:22 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 15:20:22 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 15:20:22 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 15:20:22 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 15:20:22 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 15:20:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:20:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:20:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:20:25 INFO - Installing collected packages: mozsystemmonitor 15:20:25 INFO - Running setup.py install for mozsystemmonitor 15:20:25 INFO - Successfully installed mozsystemmonitor 15:20:25 INFO - Cleaning up... 15:20:25 INFO - Return code: 0 15:20:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:20:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b5343f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4aa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe198c6d840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b34ad30>, '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 0x10b1b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b342b20>, '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.SKxujohX7F/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.Lff5snicj5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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 15:20:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:20:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:20:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SKxujohX7F/Render', 15:20:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:20:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:20:25 INFO - 'HOME': '/Users/cltbld', 15:20:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:20:25 INFO - 'LOGNAME': 'cltbld', 15:20:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:25 INFO - 'MOZ_NO_REMOTE': '1', 15:20:25 INFO - 'NO_EM_RESTART': '1', 15:20:25 INFO - 'PAGER': '/bin/cat', 15:20:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:20:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:25 INFO - 'PWD': '/builds/slave/test', 15:20:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:20:25 INFO - 'SHELL': '/bin/bash', 15:20:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lff5snicj5/Listeners', 15:20:25 INFO - 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', 15:20:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:20:25 INFO - 'USER': 'cltbld', 15:20:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:20:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:20:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:25 INFO - 'XPC_FLAGS': '0x0', 15:20:25 INFO - 'XPC_SERVICE_NAME': '0', 15:20:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:20:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:25 INFO - Downloading/unpacking jsonschema==2.5.1 15:20:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:20:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:20:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:20:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:20:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:20:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:20:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:28 INFO - Downloading functools32-3.2.3-2.tar.gz 15:20:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:20:29 INFO - warning: no files found matching '*.txt' 15:20:29 INFO - no previously-included directories found matching 'build' 15:20:29 INFO - no previously-included directories found matching 'dist' 15:20:29 INFO - no previously-included directories found matching '.git*' 15:20:29 INFO - Installing collected packages: jsonschema, functools32 15:20:29 INFO - Running setup.py install for functools32 15:20:29 INFO - warning: no files found matching '*.txt' 15:20:29 INFO - no previously-included directories found matching 'build' 15:20:29 INFO - no previously-included directories found matching 'dist' 15:20:29 INFO - no previously-included directories found matching '.git*' 15:20:29 INFO - Successfully installed jsonschema functools32 15:20:29 INFO - Cleaning up... 15:20:29 INFO - Return code: 0 15:20:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:20:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:29 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 0x10b5343f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4aa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe198c6d840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b34ad30>, '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 0x10b1b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b342b20>, '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.SKxujohX7F/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.Lff5snicj5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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 15:20:29 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 15:20:29 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 15:20:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SKxujohX7F/Render', 15:20:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:20:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:20:29 INFO - 'HOME': '/Users/cltbld', 15:20:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:20:29 INFO - 'LOGNAME': 'cltbld', 15:20:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:29 INFO - 'MOZ_NO_REMOTE': '1', 15:20:29 INFO - 'NO_EM_RESTART': '1', 15:20:29 INFO - 'PAGER': '/bin/cat', 15:20:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:20:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:29 INFO - 'PWD': '/builds/slave/test', 15:20:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:20:29 INFO - 'SHELL': '/bin/bash', 15:20:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lff5snicj5/Listeners', 15:20:29 INFO - 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', 15:20:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:20:29 INFO - 'USER': 'cltbld', 15:20:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:20:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:20:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:29 INFO - 'XPC_FLAGS': '0x0', 15:20:29 INFO - 'XPC_SERVICE_NAME': '0', 15:20:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:20:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:20:29 INFO - Cleaning up... 15:20:29 INFO - Return code: 0 15:20:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:20:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:20:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:29 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 0x10b5343f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4aa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe198c6d840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b34ad30>, '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 0x10b1b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b342b20>, '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.SKxujohX7F/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.Lff5snicj5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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 15:20:29 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 15:20:29 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 15:20:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SKxujohX7F/Render', 15:20:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:20:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:20:29 INFO - 'HOME': '/Users/cltbld', 15:20:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:20:29 INFO - 'LOGNAME': 'cltbld', 15:20:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:29 INFO - 'MOZ_NO_REMOTE': '1', 15:20:29 INFO - 'NO_EM_RESTART': '1', 15:20:29 INFO - 'PAGER': '/bin/cat', 15:20:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:20:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:29 INFO - 'PWD': '/builds/slave/test', 15:20:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:20:29 INFO - 'SHELL': '/bin/bash', 15:20:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lff5snicj5/Listeners', 15:20:29 INFO - 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', 15:20:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:20:29 INFO - 'USER': 'cltbld', 15:20:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:20:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:20:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:29 INFO - 'XPC_FLAGS': '0x0', 15:20:29 INFO - 'XPC_SERVICE_NAME': '0', 15:20:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:20:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:29 INFO - Downloading/unpacking blobuploader==1.2.4 15:20: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 15:20: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 15:20: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 15:20: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 15:20: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 15:20: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 15:20:32 INFO - Downloading blobuploader-1.2.4.tar.gz 15:20:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:20:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:20:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:20:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:20:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:20:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:20:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:20:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:20:33 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 15:20:33 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 15:20:33 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 15:20:33 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 15:20:33 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 15:20:33 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 15:20:33 INFO - Downloading docopt-0.6.1.tar.gz 15:20:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:20:33 INFO - Installing collected packages: blobuploader, requests, docopt 15:20:33 INFO - Running setup.py install for blobuploader 15:20:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:20:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:20:33 INFO - Running setup.py install for requests 15:20:34 INFO - Running setup.py install for docopt 15:20:34 INFO - Successfully installed blobuploader requests docopt 15:20:34 INFO - Cleaning up... 15:20:34 INFO - Return code: 0 15:20:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:20:34 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')]} 15:20:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:34 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')]} 15:20:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:34 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 0x10b5343f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4aa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe198c6d840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b34ad30>, '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 0x10b1b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b342b20>, '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.SKxujohX7F/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.Lff5snicj5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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 15:20:34 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 15:20:34 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 15:20:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SKxujohX7F/Render', 15:20:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:20:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:20:34 INFO - 'HOME': '/Users/cltbld', 15:20:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:20:34 INFO - 'LOGNAME': 'cltbld', 15:20:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:34 INFO - 'MOZ_NO_REMOTE': '1', 15:20:34 INFO - 'NO_EM_RESTART': '1', 15:20:34 INFO - 'PAGER': '/bin/cat', 15:20:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:20:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:34 INFO - 'PWD': '/builds/slave/test', 15:20:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:20:34 INFO - 'SHELL': '/bin/bash', 15:20:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lff5snicj5/Listeners', 15:20:34 INFO - 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', 15:20:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:20:34 INFO - 'USER': 'cltbld', 15:20:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:20:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:20:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:34 INFO - 'XPC_FLAGS': '0x0', 15:20:34 INFO - 'XPC_SERVICE_NAME': '0', 15:20:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:20:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:20:34 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-iK3896-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:20:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:20:35 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-oT5AKl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:20:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:20:35 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-TIISoZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:20:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:20:35 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-agkeAW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:20:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:20:35 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-JEyrbp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:20:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:20:35 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-wvN7cc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:20:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:20:35 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-7JedrT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:20:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:20:35 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-sro85Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:20:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:20:35 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-JY2fcW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:20:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:20:35 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-Vy1Rqi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:20:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:20:35 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-35D8C7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:20:36 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-N0MWvU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:20:36 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-S0iopt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:20:36 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-hUjela-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:20:36 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-v25mNw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:20:36 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-rhJinu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:20:36 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-GlH04y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:20:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:20:36 INFO - Running setup.py install for manifestparser 15:20:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:20:36 INFO - Running setup.py install for mozcrash 15:20:37 INFO - Running setup.py install for mozdebug 15:20:37 INFO - Running setup.py install for mozdevice 15:20:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:20:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:20:37 INFO - Running setup.py install for mozfile 15:20:37 INFO - Running setup.py install for mozhttpd 15:20:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:20:37 INFO - Running setup.py install for mozinfo 15:20:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:20:37 INFO - Running setup.py install for mozInstall 15:20:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:20:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:20:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:20:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:20:37 INFO - Running setup.py install for mozleak 15:20:38 INFO - Running setup.py install for mozlog 15:20:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:20:38 INFO - Running setup.py install for moznetwork 15:20:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:20:38 INFO - Running setup.py install for mozprocess 15:20:38 INFO - Running setup.py install for mozprofile 15:20:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:20:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:20:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:20:38 INFO - Running setup.py install for mozrunner 15:20:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:20:38 INFO - Running setup.py install for mozscreenshot 15:20:39 INFO - Running setup.py install for moztest 15:20:39 INFO - Running setup.py install for mozversion 15:20:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:20:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:20:39 INFO - Cleaning up... 15:20:39 INFO - Return code: 0 15:20:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:20: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')]} 15:20:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20: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')]} 15:20:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:39 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 0x10b5343f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4aa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe198c6d840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b34ad30>, '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 0x10b1b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b342b20>, '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.SKxujohX7F/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.Lff5snicj5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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 15:20:39 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 15:20:39 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 15:20:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SKxujohX7F/Render', 15:20:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:20:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:20:39 INFO - 'HOME': '/Users/cltbld', 15:20:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:20:39 INFO - 'LOGNAME': 'cltbld', 15:20:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:39 INFO - 'MOZ_NO_REMOTE': '1', 15:20:39 INFO - 'NO_EM_RESTART': '1', 15:20:39 INFO - 'PAGER': '/bin/cat', 15:20:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:20:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:39 INFO - 'PWD': '/builds/slave/test', 15:20:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:20:39 INFO - 'SHELL': '/bin/bash', 15:20:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lff5snicj5/Listeners', 15:20:39 INFO - 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', 15:20:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:20:39 INFO - 'USER': 'cltbld', 15:20:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:20:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:20:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:39 INFO - 'XPC_FLAGS': '0x0', 15:20:39 INFO - 'XPC_SERVICE_NAME': '0', 15:20:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:20:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:20:39 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-UIBsGw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:20:39 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)) 15:20:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:20:39 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-nXQpbs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:20:39 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)) 15:20:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:20:39 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-bw7FJM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:20:39 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)) 15:20:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:20:39 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-NznKXh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:20:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:20:40 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-fjr0p_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:20:40 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)) 15:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:20:40 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-8Dt9fN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:20:40 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)) 15:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:20:40 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-PEtg7W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:20:40 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)) 15:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:20:40 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-Tzrq61-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:20:40 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)) 15:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:20:40 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-9WwSfk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:20:40 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)) 15:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:20:40 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-R6LAde-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:20:40 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)) 15:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:20:40 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-1Ai6Tn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:20:40 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)) 15:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:20:40 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-_DEGvK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:20:40 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)) 15:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:20:40 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-Sdra6B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:20:40 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)) 15:20:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:20:41 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-Q8qMjF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:20:41 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)) 15:20:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:20:41 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-p72a5w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:20:41 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)) 15:20:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:20:41 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-xqloci-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:20:41 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)) 15:20:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:20:41 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-RPqi00-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:20:41 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)) 15:20:41 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)) 15:20:41 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)) 15:20:41 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)) 15:20:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:20:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:20:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:20:41 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 15:20:41 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 15:20:41 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 15:20:41 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 15:20:41 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 15:20:41 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 15:20:43 INFO - Downloading blessings-1.6.tar.gz 15:20:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:20:44 INFO - Installing collected packages: blessings 15:20:44 INFO - Running setup.py install for blessings 15:20:44 INFO - Successfully installed blessings 15:20:44 INFO - Cleaning up... 15:20:44 INFO - Return code: 0 15:20:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:20:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:20:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:20:44 INFO - Reading from file tmpfile_stdout 15:20:44 INFO - Current package versions: 15:20:44 INFO - blessings == 1.6 15:20:44 INFO - blobuploader == 1.2.4 15:20:44 INFO - docopt == 0.6.1 15:20:44 INFO - functools32 == 3.2.3-2 15:20:44 INFO - jsonschema == 2.5.1 15:20:44 INFO - manifestparser == 1.1 15:20:44 INFO - mozInstall == 1.12 15:20:44 INFO - mozcrash == 1.0 15:20:44 INFO - mozdebug == 0.1 15:20:44 INFO - mozdevice == 0.49 15:20:44 INFO - mozfile == 1.2 15:20:44 INFO - mozhttpd == 0.7 15:20:44 INFO - mozinfo == 0.9 15:20:44 INFO - mozleak == 0.1 15:20:44 INFO - mozlog == 3.4 15:20:44 INFO - moznetwork == 0.27 15:20:44 INFO - mozprocess == 0.23 15:20:44 INFO - mozprofile == 0.28 15:20:44 INFO - mozrunner == 6.13 15:20:44 INFO - mozscreenshot == 0.1 15:20:44 INFO - mozsystemmonitor == 0.3 15:20:44 INFO - moztest == 0.7 15:20:44 INFO - mozversion == 1.4 15:20:44 INFO - psutil == 3.1.1 15:20:44 INFO - requests == 1.2.3 15:20:44 INFO - wsgiref == 0.1.2 15:20:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:20:44 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')]} 15:20:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:44 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')]} 15:20:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:44 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 0x10b5343f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4aa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe198c6d840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b34ad30>, '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 0x10b1b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b342b20>, '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.SKxujohX7F/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.Lff5snicj5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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 15:20:44 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 15:20:44 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 15:20:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SKxujohX7F/Render', 15:20:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:20:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:20:44 INFO - 'HOME': '/Users/cltbld', 15:20:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:20:44 INFO - 'LOGNAME': 'cltbld', 15:20:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:44 INFO - 'MOZ_NO_REMOTE': '1', 15:20:44 INFO - 'NO_EM_RESTART': '1', 15:20:44 INFO - 'PAGER': '/bin/cat', 15:20:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:20:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:44 INFO - 'PWD': '/builds/slave/test', 15:20:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:20:44 INFO - 'SHELL': '/bin/bash', 15:20:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lff5snicj5/Listeners', 15:20:44 INFO - 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', 15:20:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:20:44 INFO - 'USER': 'cltbld', 15:20:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:20:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:20:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:44 INFO - 'XPC_FLAGS': '0x0', 15:20:44 INFO - 'XPC_SERVICE_NAME': '0', 15:20:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:20:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:44 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)) 15:20:44 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)) 15:20:44 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)) 15:20:44 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)) 15:20:44 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)) 15:20:44 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)) 15:20:44 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)) 15:20:44 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)) 15:20:44 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)) 15:20:44 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)) 15:20:44 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)) 15:20:44 INFO - Cleaning up... 15:20:44 INFO - Return code: 0 15:20:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:20:44 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')]} 15:20:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:20:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:44 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')]} 15:20:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:20:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:20:44 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 0x10b5343f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b4aa570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe198c6d840>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b34ad30>, '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 0x10b1b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b342b20>, '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.SKxujohX7F/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.Lff5snicj5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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 15:20:44 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 15:20:44 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 15:20:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SKxujohX7F/Render', 15:20:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:20:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:20:44 INFO - 'HOME': '/Users/cltbld', 15:20:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:20:44 INFO - 'LOGNAME': 'cltbld', 15:20:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:20:44 INFO - 'MOZ_NO_REMOTE': '1', 15:20:44 INFO - 'NO_EM_RESTART': '1', 15:20:44 INFO - 'PAGER': '/bin/cat', 15:20:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:20:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:20:44 INFO - 'PWD': '/builds/slave/test', 15:20:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:20:44 INFO - 'SHELL': '/bin/bash', 15:20:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lff5snicj5/Listeners', 15:20:44 INFO - 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', 15:20:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:20:44 INFO - 'USER': 'cltbld', 15:20:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:20:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:20:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:20:44 INFO - 'XPC_FLAGS': '0x0', 15:20:44 INFO - 'XPC_SERVICE_NAME': '0', 15:20:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:20:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:20:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:20:45 INFO - Cleaning up... 15:20:45 INFO - Return code: 0 15:20:45 INFO - Running post-action listener: _resource_record_post_action 15:20:45 INFO - Running post-action listener: _start_resource_monitoring 15:20:45 INFO - Starting resource monitoring. 15:20:45 INFO - [mozharness: 2016-12-04 23:20:45.210442Z] Finished create-virtualenv step (success) 15:20:45 INFO - [mozharness: 2016-12-04 23:20:45.211426Z] Running install step. 15:20:45 INFO - Running pre-action listener: _resource_record_pre_action 15:20:45 INFO - Running main action method: install 15:20:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:20:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:20:45 INFO - Reading from file tmpfile_stdout 15:20:45 INFO - Detecting whether we're running mozinstall >=1.0... 15:20:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:20:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:20:45 INFO - Reading from file tmpfile_stdout 15:20:45 INFO - Output received: 15:20:45 INFO - Usage: mozinstall [options] installer 15:20:45 INFO - Options: 15:20:45 INFO - -h, --help show this help message and exit 15:20:45 INFO - -d DEST, --destination=DEST 15:20:45 INFO - Directory to install application into. [default: 15:20:45 INFO - "/builds/slave/test"] 15:20:45 INFO - --app=APP Application being installed. [default: firefox] 15:20:45 INFO - mkdir: /builds/slave/test/build/application 15:20:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:20:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 15:21:18 INFO - Reading from file tmpfile_stdout 15:21:18 INFO - Output received: 15:21:18 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:21:18 INFO - Running post-action listener: _resource_record_post_action 15:21:18 INFO - [mozharness: 2016-12-04 23:21:18.046390Z] Finished install step (success) 15:21:18 INFO - [mozharness: 2016-12-04 23:21:18.046514Z] Running run-tests step. 15:21:18 INFO - Running pre-action listener: _resource_record_pre_action 15:21:18 INFO - Running main action method: run_tests 15:21:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:21:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:21:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:21:18 INFO - 2016-12-04 15:21:18.060 screenresolution[1644:14387] starting screenresolution argv=screenresolution get 15:21:18 INFO - 2016-12-04 15:21:18.063 screenresolution[1644:14387] Display 0: 1600x1200x32@60 15:21:18 INFO - 2016-12-04 15:21:18.069 screenresolution[1645:14389] starting screenresolution argv=screenresolution list 15:21:18 INFO - Available Modes on Display 0 15:21:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:21:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:21:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:21:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:21:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:21:18 INFO - Intel Iris: 15:21:18 INFO - Chipset Model: Intel Iris 15:21:18 INFO - Type: GPU 15:21:18 INFO - Bus: Built-In 15:21:18 INFO - VRAM (Dynamic, Max): 1536 MB 15:21:18 INFO - Vendor: Intel (0x8086) 15:21:18 INFO - Device ID: 0x0a2e 15:21:18 INFO - Revision ID: 0x0009 15:21:18 INFO - Displays: 15:21:18 INFO - Display: 15:21:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:21:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:21:18 INFO - Display Serial Number: 1600x1200 60 15:21:18 INFO - Main Display: Yes 15:21:18 INFO - Mirror: Off 15:21:18 INFO - Online: Yes 15:21:18 INFO - Rotation: Supported 15:21:18 INFO - Return code: 0 15:21:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:21:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:21:18 INFO - Python 2.7.3 15:21:18 INFO - Return code: 0 15:21:18 INFO - grabbing minidump binary from tooltool 15:21:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:21:18 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b34ad30>, '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 0x10b1b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b342b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:21:18 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:21:18 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 15:21:18 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 15:21:18 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:21:18 INFO - Return code: 0 15:21:18 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:21:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:21:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:21:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:21:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:21:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WmyoIkhBR4-IfrLvhAwWXg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0327', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 15:21:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WmyoIkhBR4-IfrLvhAwWXg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0327 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 15:21:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SKxujohX7F/Render', 15:21:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:21:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:21:18 INFO - 'HOME': '/Users/cltbld', 15:21:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:21:18 INFO - 'LOGNAME': 'cltbld', 15:21:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:21:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:21:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:21:18 INFO - 'MOZ_NO_REMOTE': '1', 15:21:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:21:18 INFO - 'NO_EM_RESTART': '1', 15:21:18 INFO - 'PAGER': '/bin/cat', 15:21:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:21:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:21:18 INFO - 'PWD': '/builds/slave/test', 15:21:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:21:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:21:18 INFO - 'SHELL': '/bin/bash', 15:21:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lff5snicj5/Listeners', 15:21:18 INFO - 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', 15:21:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:21:18 INFO - 'USER': 'cltbld', 15:21:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:21:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:21:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:21:18 INFO - 'XPC_FLAGS': '0x0', 15:21:18 INFO - 'XPC_SERVICE_NAME': '0', 15:21:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:21:18 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WmyoIkhBR4-IfrLvhAwWXg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0327', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 15:21:18 INFO - mozversion application_buildid: 20161204125338 15:21:18 INFO - mozversion application_changeset: 166e3210dcc64181f5541b6b9382faafcf5859a2 15:21:18 INFO - mozversion application_display_name: Nightly 15:21:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:21:18 INFO - mozversion application_name: Firefox 15:21:18 INFO - mozversion application_remotingname: firefox 15:21:18 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 15:21:18 INFO - mozversion application_vendor: Mozilla 15:21:18 INFO - mozversion application_version: 53.0a1 15:21:18 INFO - mozversion platform_buildid: 20161204125338 15:21:18 INFO - mozversion platform_changeset: 166e3210dcc64181f5541b6b9382faafcf5859a2 15:21:18 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 15:21:18 INFO - mozversion platform_version: 53.0a1 15:21:18 INFO - using testdate: 1480893678 15:21:18 INFO - actual date: 1480893678 15:21:18 INFO - starting webserver on 'localhost:49240' 15:21:18 INFO - SUITE-START | Running 2 tests 15:21:18 INFO - TEST-START | dromaeo_css 15:21:18 INFO - operating with platform_type : mac_ 15:21:18 INFO - Initialising browser for dromaeo_css test... 15:21:18 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/tmp_T_dLi/profile http://localhost:49240/getInfo.html) 15:21:21 INFO - PROCESS | 1655 | 2016-12-04 15:21:21.673 plugin-container[1657:14563] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e43, name = 'com.apple.tsm.portname' 15:21:21 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:21:21 INFO - PROCESS | 1655 | 2016-12-04 15:21:21.676 plugin-container[1657:14563] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b07, name = 'com.apple.CFPasteboardClient' 15:21:21 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:21:21 INFO - PROCESS | 1655 | 2016-12-04 15:21:21.676 plugin-container[1657:14563] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:21:21 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 15:21:21 INFO - PROCESS | 1655 | colorDepth:24 15:21:21 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 15:21:21 INFO - PROCESS | 1655 | __metrics 15:21:21 ERROR - PROCESS | 1655 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3220: TypeError: this.tabs is undefined 15:21:22 INFO - TEST-INFO | 1655: exit 0 15:21:22 INFO - Browser initialized. 15:21:22 INFO - Running cycle 1/1 for dromaeo_css test... 15:21:22 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/tmp_T_dLi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:21:25 INFO - PROCESS | 1659 | 2016-12-04 15:21:25.443 plugin-container[1660:14702] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 15:21:25 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:21:25 INFO - PROCESS | 1659 | 2016-12-04 15:21:25.444 plugin-container[1660:14702] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c23, name = 'com.apple.CFPasteboardClient' 15:21:25 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:21:25 INFO - PROCESS | 1659 | 2016-12-04 15:21:25.444 plugin-container[1660:14702] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:21:35 INFO - PROCESS | 1659 | RSS: Main: 243023872 15:21:35 INFO - PROCESS | 1659 | 15:22:24 INFO - PROCESS | 1659 | 1480893744693 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] 15:24:38 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 15:24:40 INFO - PROCESS | 1659 | RSS: Main: 250060800 15:24:40 INFO - PROCESS | 1659 | 15:27:42 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 15:27:43 INFO - PROCESS | 1659 | RSS: Main: 261509120 15:27:43 INFO - PROCESS | 1659 | 15:30:45 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 15:30:46 INFO - PROCESS | 1659 | RSS: Main: 267726848 15:30:46 INFO - PROCESS | 1659 | 15:33:48 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 15:33:48 INFO - PROCESS | 1659 | RSS: Main: 266252288 15:33:48 INFO - PROCESS | 1659 | 15:36:51 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 15:36:52 INFO - PROCESS | 1659 | RSS: Main: 270770176 15:36:52 INFO - PROCESS | 1659 | 15:39:55 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 15:39:55 INFO - PROCESS | 1659 | RSS: Main: 273420288 15:39:55 INFO - PROCESS | 1659 | 15:39:55 INFO - PROCESS | 1659 | __start_tp_report 15:39:55 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 15:39:55 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 15:39:55 INFO - PROCESS | 1659 | |i|pagename|runs| 15:39:55 INFO - PROCESS | 1659 | |0;dojo.html;3340.515625234341;3381.019504343742;3433.5193072969773;3437.6906078452935;3452.792832430054;5650.886982260363;5709.800156994506;5732.5127364174095;5736.684482353469;5813.970930145354;5642.04085305498;5762.250907382045;5789.623674461157;5801.042827933396;5831.620944638596;5175.197844334115;5183.429822719508;5208.140656791625;5303.840884773463;5311.176767601009;5279.656822306538;5307.336582927134;5341.225522299264;5370.409254981949;5399.757010934498;4405.88985275369;5294.92057619136;5317.521423071923;5333.040052790496;5374.435684253156;4317.69776115672;5134.255532947702;5237.397699264595;5252.501012403815;5322.467753224685;4044.3933409988445;4536.410266665345;5023.296738456619;5239.895202095975;5253.7635806388425;5667.489925906647;5668.518175955049;5695.834100373654;5749.741261643236;5759.107338362561;6101.398774955092;6137.806028951355;6149.938500614981;6234.906476402857;6236.18929539157;6151;6268.467180289681;6288.402601752872;6288.8113356598915;6320.525960552977;5933.584649074566;5962.791302304398;5965.3438121807;5985.072313791369;6018.097285407136;6085.5435842312;6155.84610384744;6220.129181914535;6346.746130154742;6385.616862988236;5077.543021128116;5112.872178195575;5127.564157046693;6106.236720409949;6160.383961603804;6212.9068063979075;6241.438270555581;6268.09112678659;6301.558890877686;6307.716152773136;4967.279744437036;6252.093446450239;6286.371362863677;6295.685215739195;6342.746290148435;6146.508279337642;6204.689765511706;6207.503399728102;6220.7511699531515;6221.129041934133;4939.53074457926;4981.9252711209365;4987.952529968675;4997.825076122318;5011.123053465629;4422.668299877522;4423.535528769433;4429.402030725875;4463.866084017484;4467.530909254547;5869.266341707286;5932.881342373128;6021.729022194012;6034.24571928509;6045.969770151121;6161.092699601999;6236.875262494725;6238.812835614939;6242.937570624326;6251.218597675291;5793.942060579425;5832.679202643895;6032.819015429544;6038.218725475995;6041.758329666852;5391.16436952273;5433.673979561239;5433.891322173535;5454.699991500505;5462.317210348707;6272.780452684134;6365.3316401778075;6380.553361264852;6382.329855365306;6403.551751377545;5473.726313684379;6087.086936959491;6202.751889924352;6223.875522489616;6304.810855674337;6177.351378105234;6312;6371.904421433775;6382.840428989312;6390;6112.655464441373;6156.26124865019;6167.321594624677;6194.38056194395;6306.400891915261;6250.156228909039;6253.6560489173535;6268.968655156695;6269.341719119427;6273.153124328157;6154.569180157346;6163.753449862134;6211.316755156839;6221.09794079865;6223.439890409885;5972;5981.923253814181;6017.849553761191;6019.337872834072;6104.095385180791;4154.3976123462535;5508.44915508446;5843.503302219359;6109;6131.417515336036;6198.566100372931;6206.348333425106;6208.348123447154;6218.595791273553;6252.656103914146;4529.275315949432;5880.059190529495;6060.848478788065;6066.15073889647;6148.231471066117 15:39:55 INFO - PROCESS | 1659 | |1;ext.html;459.6506654942245;504.5761560289357;522.4723029739963;525.3223341888963;526.1896679114164;37944.24111517771;39292.607073929255;39327.60672393275;39375.21249575007;39413.60586394135;40479.785606431855;40604.59395406045;40770.73834093273;40830.7958460208;40854.79572602133;7855.332296754783;8382;8624.094470080645;9406;9441.29190310726;11006;11294;11341.036011938995;11496.827547586792;11524.366159861203;11357.148213883951;11444.656660300205;11451.656450306462;11460;11461.255018423817;11200.551977920873;11262.380569068699;11293.435328233638;11316.151288653302;11325.660230193025;10745.677629671123;11047.613333533287;11110.222284440093;11123.833142502868;11124.833127503094;31218.84390578055;31972.840135799404;38681.22637547233;38709.61290387088;38746.225075498616;3241.675832416763;3377.780444271139;3385.86456541738;3454.274602333488;3880.3209438348176;29239.2690182745;29353.853230733923;29713;30259.092227233217;30369.392612147632;3183.267848394882;3188.1551388881967;3200.2799370141765;3257.794615992098;3341.198112453018;376716;398234;450194;479055;479650.9999999965;59286.70356648189;59535.70232148811;59568.404315956286;59581.40418595759;59754.70122649359;61014.69492652508;61328;61505;61755.38244617586;61789.691051544454;15616.375344986074;15880.920595396949;15902.330255323255;15905;15927.761083583755;11036.889631103746;11223.989840914357;11908.88091119095;12143.878561214275;12156.696082598006;15198.240087995557;17836.28654853791;17973.37093201732;18146.546336341697;18200;7614.6954121835615;7677.616119194018;7722.459427839997;7757.4181936355;7792.649330780128;691.7129391302653;847.9915200848036;870.7170169694874;959.6593209410559;976.6484065736329;28251.293717657223;28709;28990.565141522893;29428.705712943025;29614;979.2313034268066;1015.8120747661559;1034.6171916390983;1035.4098164046572;1043.9634612788516;15014.549563513112;15028.098314100971;15199.924000379928;15366.078035317916;15369.9231503844;647118;650194;658905.7054714792;659879;660717;55466;55983.72008139933;56026.43973560294;56125.71937140288;56161.15758263629;1295.8768916952874;1322.1141834970592;1339.82582264305;1347.2455424962052;1348.6021623621054;28310.71689283163;28371.858140709166;28728.13815585536;29116.854415728634;29125.271868203465;47158;47464.52535474601;47926.04147917092;47961.040779183524;47969.28046079242;570243;571682;572693;573487;575971.2402875917;25260.62109068402;25381.111661092513;25733;26209.73790262073;26269.737302627495;44096;44215.33676994912;45115;45287.77356113198;45382.77308613436;36727.26545469022;36980.44529332008;37552.62447375491;38236.235275294894;38340;36866.26267474689;36883.63116368802;37765.24469510649;37805;38106.428403573416;36226.63773362338;36426.90719278426;36514.90455286346;36631.81684091563;37069.81465092657;26604;32289.192770180933;33029.834850825595;33206.335873282886;33300.500492492145;18564.071796409855;21358.359249221925;23558.764412355657;25232.74767252304;26366.604222671245 15:39:55 INFO - PROCESS | 1659 | |2;jquery.html;360.6591770776614;377.5280898876405;378.0605196087723;381.75376881911177;396.70643723644514;18697.626047479043;19386.709199362;19603.4713751525;19799.802001979977;19986.800131998712;16627.334906603723;20205.19179232829;20244.797552024476;20649.380518584465;21048.473788155305;6611.900821487669;6679.632620205887;6811.1826580810375;6851.451883849305;6958.338957799001;4563.908721825562;6658.733650653968;6785.491088168383;6795.7961261162245;6796.2863899290605;4804.711717296974;4827.179379505493;4853.757312134397;7785.026871641045;7919.56442395668;6583.967080164616;7039.718411263543;7859.842803143933;7867;7870.449068565203;6348.619082855043;7656.425768067362;7659.425543084291;7661;7668.309852113281;25931.962721491116;26819.86590067037;26975.865120674272;27181.728182718118;27424.17727468179;2091.4666759976108;2283.6346184610384;2309.041747674713;2375.7743014413604;2444.4988777300787;15129.773053404208;15738.449154279546;16173.91913040439;16422;16585.66828663432;1807.7288406738962;1825.6896327624336;1832.6792811257976;1882.554924488197;1941.4466876940146;132677;138707;145050;145169.27415362856;150808;20310.695339569917;20744;20769.169233230503;20871.686924696143;20889.582208355747;34886.30227395488;34899.65100349015;34904.47643285353;34987.60049598038;35062.64937350594;10188.303697929648;10408;10579.153667706565;10734.248602597743;10756.408397538211;7373.060049766669;8620.749991251234;8670.566471676391;8712.825743485095;8890.511021893728;7951.840963180704;9219.90780092204;9522.672556159025;9619.086186812241;9628.566714497865;3722.441633754904;3776.6412190841825;3777.6411240932066;3858.3633700439113;3866.4481442248543;794.5590197440476;849.7790574450687;861.4917198852708;894.695803426838;932.4125800745487;20527.4868128295;21029.579408411748;21143.78856211449;21286.042128104273;21326.893365533073;872.3152325424511;875.6103533927469;889.1419779912416;891.7324802559207;898.3756289378949;10535;10820.837687434694;10935.507902144423;10949.945250273857;10967.287126336763;482731;487271;489649;490800;560514;126165.36917315355;137956;139106;140731;142905.94875380807;958.5734348214977;976.6679329028022;1009.1724785675822;1017.8066167428165;1022.8619136416488;18864.33974810875;19039;19178.616427671648;19202.519937001685;19333.806661933762;41844.81551844791;45666;52648.68378290573;53414.198787019006;53443.39669809912;62220;73237.6338118306;74441.16132451373;77197;78197;6980.371766541036;7786.571738554434;7861.174576669596;7931;8182.386321025828;8957.59690813902;12213;12425.689357766129;12523.248605083723;12605.369731513205;10291.742706432398;10324.587016519255;10861.294015888941;10951.45242737844;11034.337939723642;10444.738881527719;10596.8940310599;10814.837777433188;10853.34879907208;10966.287191332538;9769.75575610587;10587.788244235226;10892.346459212473;11171.497282622138;11230.494627741935;6047;7993.840123197388;8053.476524025919;8056.436049476481;8065.395095367753;8991.190792828676;10659.467026648792;10930.344179349264;10957.726056848642;11032.007119359296 15:39:55 INFO - PROCESS | 1659 | |3;mootools.html;1362.9931850340747;1467.6330917270686;1624.9593760155994;1787.249355270786;1796.4909476040132;6556.606603603781;7420.851582968337;7664.463487555873;8398.076211616717;8426.915730842697;7826.217378262171;8082.191780821915;8113.188681131898;8172.550509721963;8183.877241841362;2177.5862586108587;2192.331338941622;2212.4690074382193;2213.701150344699;2217.545403192342;1938.5638231397963;2195.495036141688;2243.5064285857084;2249.1453247765826;2255.796978271955;2256.717910261217;2259.062489067035;2273.8976746046383;2282.1441959265276;2291.5073259249257;2227.498812767131;2234.095191447458;2252.8085112765434;2253.3690566641367;2265.88670566471;1902.7906930237664;1922.9230830766892;1999.9200031998703;2064.1846470644164;2251.4258863989576;8184.468009579358;8265;8271.917280827234;8312.21034001769;8716.825663486694;4787;5048.7980480780725;5068.062408454411;5074.340335756365;5096.184610462308;2697.961284905306;3314.6661135638547;3457.6542345765474;3478.7738796978115;3485.7734247274097;993.8161440133598;1031.7936412717486;1084.414416215243;1090.5201711247025;1090.6400887707039;10257.794844103077;10701.25091243621;10783.514741836558;10884.183686223485;10888.29226100301;3568.2685049564775;4311.590398912098;4381.101874115799;4386.385905973167;4406.361077643724;8499.575021248913;8523.70167044163;8560.871586926201;8565;8575.656973720981;4990.5508504234795;4996.175631020914;4997.90004199914;4999.675021123615;5021.49785021495;4513.638908887347;4648.581627653527;4701.64737644678;4715.528447155257;4727.125481785881;3378.6283508814013;3445.155936795501;3484.9651503484642;3507.0706262840367;3523.2777280657406;1031.0823367203195;1048.9423081730426;1057.4818339013827;1078.6548304542628;1101.6479815593605;874.8512752832033;881.3698205782767;890.7194233816327;898.2409863665192;915.4049867586123;4467.30756732707;5357.133933345642;5514.641548299348;5626.98714231434;5644.8306550803545;410.8212927376601;421.8820126664364;430.2513626290232;437.02325302947884;453.65522203125863;3117.5479555464785;3753.174301653632;3829.0810205551024;3848.4804551385764;3892.630200130983;9590.568424420917;10074.647387341407;10090.848637270446;10097.899021009844;10109.140723038625;10044.547995340226;10049.24630652704;10087.646932357478;10094.949525252327;10120.696379108638;6709.798706038827;7565.659545320474;7745.728899488491;7876.133625301212;7884.408669349822;4693.577578017995;5498.247619994563;5610.971945140248;5618.803341883015;5633.549316054624;9227.953860230657;9375.859362109437;9403.529823508661;9409.529523523659;9441.961384247865;8683.478991260545;8986;9106.590203440728;9442.433453992782;9477.810443791224;6896.241413444617;7082.893756593759;7152.356287934111;7228.96385518069;7252.854942901007;6536.640484773383;7630.084389873251;7854.567998760123;7928.643211055401;7961.6019199040975;7004.544704594185;7009.9649501752165;7024.5082844200415;7171.067761191011;7174.1032370953635;5928.940710593011;6851.588904665736;6953.617551034742;6976.5814051157095;7064.187618423947;6571.277159512545;6600.636964966973;6646.966765166143;6652.700628471827;6689.933100668931;4696.225122854691;4748.667593268438;4800.087983283165;4843.0313937211995;4951.5048495150195;4781.348067617278;4912.803487860446;4915.778789954389;4926.729029903389;4968.22992436173 15:39:55 INFO - PROCESS | 1659 | |4;prototype.html;286.20435190171327;305.12581454133897;311.8487533546231;315.1490974369204;321.2659074015874;4464.638285322975;5200.557952574036;5371.677699338043;5496.587755918307;5502.367227768807;5152.779512804497;5312.92030619541;5380.408155102936;5390.5418039466595;5417.911790279542;3353.6814002669707;3772.188979369435;4114.897127571812;4133.049398638315;4144.523379811315;3805.6574908258253;4015.6586690131376;4175.436316097318;4208.473940757405;4217.093324935138;3716.7548871128206;3861.5366156061314;3987.66104881084;4022.5575186729584;4073.429719839225;3785.261873934584;4015.477987861574;4046.413270075852;4047.635712785834;4051.6556092732153;2925.049358958347;2986.5072263076568;2993.069470191714;3480.6345333739846;3534.487499312611;4564.2240819060835;4605.65457590682;4915.557599815998;4984.504648605403;5283.28675628789;3943.3296339622048;4576.679632425731;4577.885552861205;4607.239805432133;4638.002829391697;4995.600351971833;5671.744771485293;5672.659640421588;5679.5801049737565;5723.627964182315;3581.9612312429363;3826.6364695353896;3857.5119048214024;3876.9806150969066;3899.7270191086636;5475.534579560703;5916.645001299936;5924.437178468039;5936.762529498858;5946.613470124428;3612.2053148307855;3892.649661530476;3936.6653834423823;3939.940900886489;3979.940300895431;3560.5549306336707;3818.6372294631965;3849.825803130016;3978.562358140602;3982.3628219484744;3827.923441531154;3861.304965106308;4037.8384864605673;4628.791704373311;4658.487566367697;4650.767546600154;5615.410381909923;5746.367899531048;5758.337791153987;5779.682117483579;1222.9877701222874;1232.3099064523894;1245.4680742686546;1279.814426908093;1282.9615111546668;732.0739264829995;854.5214679779343;871.4422769427572;873.8208667223204;876.4049430037271;4567.8839699536065;4878.195097808822;5092.9236061459105;5135.6918584885025;5156.484351564814;5028.5977121830165;5125.56432703217;5129.717865517432;5577.274954256002;5666.433356664301;4301.827926882952;4514.548545145459;5036.194208926554;5070.01134778722;5072.467390923974;2348;2356.7996720278625;2535.69571651403;2711.295063283521;2940.823550586929;4673.63304981774;5533.529649979797;5594.468525490072;5701.71491425427;5762.213713748752;5193.792248310026;5507.981023510583;5617.466340697627;5685.914711279334;5713.628614140068;5191.857791285911;5372.838814835562;5457.972710136424;5693.800716974969;5695.886082278248;5108.719020453911;5433.831726178891;5506.559475241891;5508.55931525485;5509.641873278224;4801.567858892577;5123.897522049613;5380.058489764354;5456.454354564512;5469.234307197075;4784.497627749036;5461.777551244784;5582.483035687491;5756.971215143897;5761.818827140342;3016.441958237733;3658.213484100931;3672.485851980778;3679.8712045078296;3696.5564132304294;4572.81708731647;5476.919694827379;5505.128256392845;5536.390996990408;5559.416261292506;4392.3631073493525;4610.031893302444;4674.859754207379;5168;5400.378956419905;4991.376077990251;5293.523582877559;5312.574994000393;5320.787168513216;5353.223782551586;5031.823886163967;5280.940433230966;5298.70857102863;5310.761015754378;5476.507114359727;5282.709450980233;5613.761609984435;5719.828405147852;5725.627834190765;5728.625129968694;5280.76236569363;5594.272744543184;5605.299337582802;5622.060389165731;5664.546836253007 15:39:55 INFO - PROCESS | 1659 | |5;yui.html;622.1942584353262;667.2860039757461;701.0325750464358;703.7466512055659;706.5760543673795;13948.0933739307;14470.421183152674;14538.200398978051;15141.470048548303;15764.527064188063;14637.6340591485;14936.253187340617;15062.849371506281;15131.24343782812;15138.621534461645;813.0934008580449;842.9926238145418;845.6363763581658;846.7078857794052;846.851800934837;643.1992169748646;675.2538445018258;816.2694388522269;828.2173346187855;828.225609055535;882.4440602420466;886.6187539358074;891.416122439803;895.3240303570794;897.0356866368647;881.7002219245487;883.2095274729113;884.4825776920509;885.7254251182122;889.8843150390472;648.254507316585;659.3999460490978;660.1055569703079;660.957037792542;664.3555750921598;13870.514531991332;13899.235542045184;13911.860881391158;14010.369533371022;14060.578182654537;12540.372981350896;12564.183328083645;12654.81017784734;12673;12709.110362274645;10964.28732132409;10975.451227438676;11054.557817687282;11102.833457498064;11383.373914434702;3398.5242066110764;3402.42158832999;3428.7599868009247;3456.6716161964573;3524.6827785499427;114821;117358.41320793427;118162;119190.4040479792;121023.3948830259;9595.760105997266;9872.01279872007;9944.403335799876;9977.351472154287;10419.583216671248;18436.81563184351;19290.517737056405;19310.03449827503;19404.12681429339;19599.902000489907;7085.92914070863;7151.642417879085;7220.711171553184;7295.051643286339;7329.230430804795;3667.2848794485353;3760.266747984117;3823.3500304948225;3845.9038524037123;3860.208657225262;3293.6870997255223;3365.225998020469;3393.660633936636;3397.745169112277;3401.217719924382;543.8613153645795;631.2141383976978;633.0820310549722;636.5671343486381;680.8978653202059;546.778554685353;616.740968793108;628.3057221769878;656.3305428462941;677.1400321591551;9066.682666106655;9070.727878163665;9140.954295228481;9163.555152318848;9251.121143491358;514.8352527191263;548.7777450132703;586.9853253668692;606.9136246119397;612.5650787940613;3657.829494561768;4428.778561071934;4434.290513517822;4438;4487.506374298824;78496;81983;91229;109205.81588368188;121139.39430302792;75075.1231219213;85721.57139214389;94227;97384;97415;64699.35300646933;76644;85381;85988;87869.56065219633;8625.87061194095;8643.697470588499;8704.216620503932;8730.738077857675;8920.107989201028;39410.80294598509;49362.75318623528;54266.728666356415;57750.13374799297;60394.79210415679;79462;84896;87259.75886355578;87734;88005;12780.917760127333;13064.085514013928;13078.149920255533;13085.542006029744;13326.40031198579;12300.876991229974;14447;14574.562763117123;14603.496527829406;14721.190334531704;12862.67843303925;13057.02072344559;13076;13227.933860330637;13276.800847987095;13517.121387109806;13766;13834.239116848268;14058.297085145907;14273.857261427253;13093.672658183621;13178.670533236746;13342.399592018577;13376;13938;10729.428138280811;11078.667639970814;11489.368084755419;11603.5358585659;11833.763324733629;9665.661701840401;11140.665780026613;11403.372814495282;11418.828717569077;11587.420628968686 15:39:55 INFO - PROCESS | 1659 | __end_tp_report 15:39:55 INFO - PROCESS | 1659 | __start_cc_report 15:39:55 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,371 15:39:55 INFO - PROCESS | 1659 | __end_cc_report 15:39:55 INFO - PROCESS | 1659 | __startTimestamp1480894795267__endTimestamp 15:39:55 INFO - PROCESS | 1659 | 15:39:55 INFO - PROCESS | 1659 | ------- Summary: start ------- 15:39:55 INFO - PROCESS | 1659 | Number of tests: 6 15:39:55 INFO - PROCESS | 1659 | 15:39:55 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5721.01 Median:6019.34 stddev:688.35 (11.4%) stddev-sans-first:664.65 15:39:55 INFO - PROCESS | 1659 | Values: 3340.5 3381.0 3433.5 3437.7 3452.8 5650.9 5709.8 5732.5 5736.7 5814.0 5642.0 5762.3 5789.6 5801.0 5831.6 5175.2 5183.4 5208.1 5303.8 5311.2 5279.7 5307.3 5341.2 5370.4 5399.8 4405.9 5294.9 5317.5 5333.0 5374.4 4317.7 5134.3 5237.4 5252.5 5322.5 4044.4 4536.4 5023.3 5239.9 5253.8 5667.5 5668.5 5695.8 5749.7 5759.1 6101.4 6137.8 6149.9 6234.9 6236.2 6151.0 6268.5 6288.4 6288.8 6320.5 5933.6 5962.8 5965.3 5985.1 6018.1 6085.5 6155.8 6220.1 6346.7 6385.6 5077.5 5112.9 5127.6 6106.2 6160.4 6212.9 6241.4 6268.1 6301.6 6307.7 4967.3 6252.1 6286.4 6295.7 6342.7 6146.5 6204.7 6207.5 6220.8 6221.1 4939.5 4981.9 4988.0 4997.8 5011.1 4422.7 4423.5 4429.4 4463.9 4467.5 5869.3 5932.9 6021.7 6034.2 6046.0 6161.1 6236.9 6238.8 6242.9 6251.2 5793.9 5832.7 6032.8 6038.2 6041.8 5391.2 5433.7 5433.9 5454.7 5462.3 6272.8 6365.3 6380.6 6382.3 6403.6 5473.7 6087.1 6202.8 6223.9 6304.8 6177.4 6312.0 6371.9 6382.8 6390.0 6112.7 6156.3 6167.3 6194.4 6306.4 6250.2 6253.7 6269.0 6269.3 6273.2 6154.6 6163.8 6211.3 6221.1 6223.4 5972.0 5981.9 6017.8 6019.3 6104.1 4154.4 5508.4 5843.5 6109.0 6131.4 6198.6 6206.3 6208.3 6218.6 6252.7 4529.3 5880.1 6060.8 6066.2 6148.2 15:39:55 INFO - PROCESS | 1659 | 15:39:55 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:68385.30 Median:36326.77 stddev:150565.52 (414.5%) stddev-sans-first:150901.53 15:39:55 INFO - PROCESS | 1659 | Values: 459.7 504.6 522.5 525.3 526.2 37944.2 39292.6 39327.6 39375.2 39413.6 40479.8 40604.6 40770.7 40830.8 40854.8 7855.3 8382.0 8624.1 9406.0 9441.3 11006.0 11294.0 11341.0 11496.8 11524.4 11357.1 11444.7 11451.7 11460.0 11461.3 11200.6 11262.4 11293.4 11316.2 11325.7 10745.7 11047.6 11110.2 11123.8 11124.8 31218.8 31972.8 38681.2 38709.6 38746.2 3241.7 3377.8 3385.9 3454.3 3880.3 29239.3 29353.9 29713.0 30259.1 30369.4 3183.3 3188.2 3200.3 3257.8 3341.2 376716.0 398234.0 450194.0 479055.0 479651.0 59286.7 59535.7 59568.4 59581.4 59754.7 61014.7 61328.0 61505.0 61755.4 61789.7 15616.4 15880.9 15902.3 15905.0 15927.8 11036.9 11224.0 11908.9 12143.9 12156.7 15198.2 17836.3 17973.4 18146.5 18200.0 7614.7 7677.6 7722.5 7757.4 7792.6 691.7 848.0 870.7 959.7 976.6 28251.3 28709.0 28990.6 29428.7 29614.0 979.2 1015.8 1034.6 1035.4 1044.0 15014.5 15028.1 15199.9 15366.1 15369.9 647118.0 650194.0 658905.7 659879.0 660717.0 55466.0 55983.7 56026.4 56125.7 56161.2 1295.9 1322.1 1339.8 1347.2 1348.6 28310.7 28371.9 28728.1 29116.9 29125.3 47158.0 47464.5 47926.0 47961.0 47969.3 570243.0 571682.0 572693.0 573487.0 575971.2 25260.6 25381.1 25733.0 26209.7 26269.7 44096.0 44215.3 45115.0 45287.8 45382.8 36727.3 36980.4 37552.6 38236.2 38340.0 36866.3 36883.6 37765.2 37805.0 38106.4 36226.6 36426.9 36514.9 36631.8 37069.8 26604.0 32289.2 33029.8 33206.3 33300.5 18564.1 21358.4 23558.8 25232.7 26366.6 15:39:55 INFO - PROCESS | 1659 | 15:39:55 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:34344.02 Median:34892.98 stddev:85751.16 (245.8%) stddev-sans-first:85953.76 15:39:55 INFO - PROCESS | 1659 | Values: 360.7 377.5 378.1 381.8 396.7 18697.6 19386.7 19603.5 19799.8 19986.8 16627.3 20205.2 20244.8 20649.4 21048.5 6611.9 6679.6 6811.2 6851.5 6958.3 4563.9 6658.7 6785.5 6795.8 6796.3 4804.7 4827.2 4853.8 7785.0 7919.6 6584.0 7039.7 7859.8 7867.0 7870.4 6348.6 7656.4 7659.4 7661.0 7668.3 25932.0 26819.9 26975.9 27181.7 27424.2 2091.5 2283.6 2309.0 2375.8 2444.5 15129.8 15738.4 16173.9 16422.0 16585.7 1807.7 1825.7 1832.7 1882.6 1941.4 132677.0 138707.0 145050.0 145169.3 150808.0 20310.7 20744.0 20769.2 20871.7 20889.6 34886.3 34899.7 34904.5 34987.6 35062.6 10188.3 10408.0 10579.2 10734.2 10756.4 7373.1 8620.7 8670.6 8712.8 8890.5 7951.8 9219.9 9522.7 9619.1 9628.6 3722.4 3776.6 3777.6 3858.4 3866.4 794.6 849.8 861.5 894.7 932.4 20527.5 21029.6 21143.8 21286.0 21326.9 872.3 875.6 889.1 891.7 898.4 10535.0 10820.8 10935.5 10949.9 10967.3 482731.0 487271.0 489649.0 490800.0 560514.0 126165.4 137956.0 139106.0 140731.0 142905.9 958.6 976.7 1009.2 1017.8 1022.9 18864.3 19039.0 19178.6 19202.5 19333.8 41844.8 45666.0 52648.7 53414.2 53443.4 62220.0 73237.6 74441.2 77197.0 78197.0 6980.4 7786.6 7861.2 7931.0 8182.4 8957.6 12213.0 12425.7 12523.2 12605.4 10291.7 10324.6 10861.3 10951.5 11034.3 10444.7 10596.9 10814.8 10853.3 10966.3 9769.8 10587.8 10892.3 11171.5 11230.5 6047.0 7993.8 8053.5 8056.4 8065.4 8991.2 10659.5 10930.3 10957.7 11032.0 15:39:55 INFO - PROCESS | 1659 | 15:39:55 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:5289.67 Median:4877.92 stddev:2965.57 (60.8%) stddev-sans-first:2959.21 15:39:55 INFO - PROCESS | 1659 | Values: 1363.0 1467.6 1625.0 1787.2 1796.5 6556.6 7420.9 7664.5 8398.1 8426.9 7826.2 8082.2 8113.2 8172.6 8183.9 2177.6 2192.3 2212.5 2213.7 2217.5 1938.6 2195.5 2243.5 2249.1 2255.8 2256.7 2259.1 2273.9 2282.1 2291.5 2227.5 2234.1 2252.8 2253.4 2265.9 1902.8 1922.9 1999.9 2064.2 2251.4 8184.5 8265.0 8271.9 8312.2 8716.8 4787.0 5048.8 5068.1 5074.3 5096.2 2698.0 3314.7 3457.7 3478.8 3485.8 993.8 1031.8 1084.4 1090.5 1090.6 10257.8 10701.3 10783.5 10884.2 10888.3 3568.3 4311.6 4381.1 4386.4 4406.4 8499.6 8523.7 8560.9 8565.0 8575.7 4990.6 4996.2 4997.9 4999.7 5021.5 4513.6 4648.6 4701.6 4715.5 4727.1 3378.6 3445.2 3485.0 3507.1 3523.3 1031.1 1048.9 1057.5 1078.7 1101.6 874.9 881.4 890.7 898.2 915.4 4467.3 5357.1 5514.6 5627.0 5644.8 410.8 421.9 430.3 437.0 453.7 3117.5 3753.2 3829.1 3848.5 3892.6 9590.6 10074.6 10090.8 10097.9 10109.1 10044.5 10049.2 10087.6 10094.9 10120.7 6709.8 7565.7 7745.7 7876.1 7884.4 4693.6 5498.2 5611.0 5618.8 5633.5 9228.0 9375.9 9403.5 9409.5 9442.0 8683.5 8986.0 9106.6 9442.4 9477.8 6896.2 7082.9 7152.4 7229.0 7252.9 6536.6 7630.1 7854.6 7928.6 7961.6 7004.5 7010.0 7024.5 7171.1 7174.1 5928.9 6851.6 6953.6 6976.6 7064.2 6571.3 6600.6 6647.0 6652.7 6689.9 4696.2 4748.7 4800.1 4843.0 4951.5 4781.3 4912.8 4915.8 4926.7 4968.2 15:39:55 INFO - PROCESS | 1659 | 15:39:55 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:4435.48 Median:5034.01 stddev:1380.29 (27.4%) stddev-sans-first:1348.57 15:39:55 INFO - PROCESS | 1659 | Values: 286.2 305.1 311.8 315.1 321.3 4464.6 5200.6 5371.7 5496.6 5502.4 5152.8 5312.9 5380.4 5390.5 5417.9 3353.7 3772.2 4114.9 4133.0 4144.5 3805.7 4015.7 4175.4 4208.5 4217.1 3716.8 3861.5 3987.7 4022.6 4073.4 3785.3 4015.5 4046.4 4047.6 4051.7 2925.0 2986.5 2993.1 3480.6 3534.5 4564.2 4605.7 4915.6 4984.5 5283.3 3943.3 4576.7 4577.9 4607.2 4638.0 4995.6 5671.7 5672.7 5679.6 5723.6 3582.0 3826.6 3857.5 3877.0 3899.7 5475.5 5916.6 5924.4 5936.8 5946.6 3612.2 3892.6 3936.7 3939.9 3979.9 3560.6 3818.6 3849.8 3978.6 3982.4 3827.9 3861.3 4037.8 4628.8 4658.5 4650.8 5615.4 5746.4 5758.3 5779.7 1223.0 1232.3 1245.5 1279.8 1283.0 732.1 854.5 871.4 873.8 876.4 4567.9 4878.2 5092.9 5135.7 5156.5 5028.6 5125.6 5129.7 5577.3 5666.4 4301.8 4514.5 5036.2 5070.0 5072.5 2348.0 2356.8 2535.7 2711.3 2940.8 4673.6 5533.5 5594.5 5701.7 5762.2 5193.8 5508.0 5617.5 5685.9 5713.6 5191.9 5372.8 5458.0 5693.8 5695.9 5108.7 5433.8 5506.6 5508.6 5509.6 4801.6 5123.9 5380.1 5456.5 5469.2 4784.5 5461.8 5582.5 5757.0 5761.8 3016.4 3658.2 3672.5 3679.9 3696.6 4572.8 5476.9 5505.1 5536.4 5559.4 4392.4 4610.0 4674.9 5168.0 5400.4 4991.4 5293.5 5312.6 5320.8 5353.2 5031.8 5280.9 5298.7 5310.8 5476.5 5282.7 5613.8 5719.8 5725.6 5728.6 5280.8 5594.3 5605.3 5622.1 5664.5 15:39:55 INFO - PROCESS | 1659 | 15:39:55 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:20982.81 Median:4436.15 stddev:31380.28 (707.4%) stddev-sans-first:31431.07 15:39:55 INFO - PROCESS | 1659 | Values: 622.2 667.3 701.0 703.7 706.6 13948.1 14470.4 14538.2 15141.5 15764.5 14637.6 14936.3 15062.8 15131.2 15138.6 813.1 843.0 845.6 846.7 846.9 643.2 675.3 816.3 828.2 828.2 882.4 886.6 891.4 895.3 897.0 881.7 883.2 884.5 885.7 889.9 648.3 659.4 660.1 661.0 664.4 13870.5 13899.2 13911.9 14010.4 14060.6 12540.4 12564.2 12654.8 12673.0 12709.1 10964.3 10975.5 11054.6 11102.8 11383.4 3398.5 3402.4 3428.8 3456.7 3524.7 114821.0 117358.4 118162.0 119190.4 121023.4 9595.8 9872.0 9944.4 9977.4 10419.6 18436.8 19290.5 19310.0 19404.1 19599.9 7085.9 7151.6 7220.7 7295.1 7329.2 3667.3 3760.3 3823.4 3845.9 3860.2 3293.7 3365.2 3393.7 3397.7 3401.2 543.9 631.2 633.1 636.6 680.9 546.8 616.7 628.3 656.3 677.1 9066.7 9070.7 9141.0 9163.6 9251.1 514.8 548.8 587.0 606.9 612.6 3657.8 4428.8 4434.3 4438.0 4487.5 78496.0 81983.0 91229.0 109205.8 121139.4 75075.1 85721.6 94227.0 97384.0 97415.0 64699.4 76644.0 85381.0 85988.0 87869.6 8625.9 8643.7 8704.2 8730.7 8920.1 39410.8 49362.8 54266.7 57750.1 60394.8 79462.0 84896.0 87259.8 87734.0 88005.0 12780.9 13064.1 13078.1 13085.5 13326.4 12300.9 14447.0 14574.6 14603.5 14721.2 12862.7 13057.0 13076.0 13227.9 13276.8 13517.1 13766.0 13834.2 14058.3 14273.9 13093.7 13178.7 13342.4 13376.0 13938.0 10729.4 11078.7 11489.4 11603.5 11833.8 9665.7 11140.7 11403.4 11418.8 11587.4 15:39:55 INFO - PROCESS | 1659 | -------- Summary: end -------- 15:39:55 INFO - PROCESS | 1659 | 15:39:56 INFO - TEST-INFO | 1659: exit 0 15:39:56 INFO - TEST-OK | dromaeo_css | took 1117370ms 15:39:56 INFO - TEST-START | kraken 15:39:56 INFO - operating with platform_type : mac_ 15:39:56 INFO - Initialising browser for kraken test... 15:39:56 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/tmp4oLUSU/profile http://localhost:49240/getInfo.html) 15:39:58 INFO - PROCESS | 1667 | 2016-12-04 15:39:58.983 plugin-container[1668:16959] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b3f, name = 'com.apple.tsm.portname' 15:39:58 INFO - PROCESS | 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:39:58 INFO - PROCESS | 1667 | 2016-12-04 15:39:58.986 plugin-container[1668:16959] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900f, name = 'com.apple.CFPasteboardClient' 15:39:58 INFO - PROCESS | 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:39:58 INFO - PROCESS | 1667 | 2016-12-04 15:39:58.986 plugin-container[1668:16959] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:39:59 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 15:39:59 INFO - PROCESS | 1667 | colorDepth:24 15:39:59 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 15:39:59 INFO - PROCESS | 1667 | __metrics 15:39:59 ERROR - PROCESS | 1667 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3220: TypeError: this.tabs is undefined 15:39:59 INFO - TEST-INFO | 1667: exit 0 15:39:59 INFO - Browser initialized. 15:39:59 INFO - Running cycle 1/1 for kraken test... 15:39:59 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/tmp4oLUSU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:40:02 INFO - PROCESS | 1669 | 2016-12-04 15:40:02.437 plugin-container[1670:17082] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 15:40:02 INFO - PROCESS | 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:40:02 INFO - PROCESS | 1669 | 2016-12-04 15:40:02.437 plugin-container[1670:17082] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8923, name = 'com.apple.CFPasteboardClient' 15:40:02 INFO - PROCESS | 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:40:02 INFO - PROCESS | 1669 | 2016-12-04 15:40:02.437 plugin-container[1670:17082] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:40:12 INFO - PROCESS | 1669 | RSS: Main: 242630656 15:40:12 INFO - PROCESS | 1669 | 15:40:15 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 15:40:16 INFO - PROCESS | 1669 | RSS: Main: 255000576 15:40:16 INFO - PROCESS | 1669 | 15:40:18 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 15:40:19 INFO - PROCESS | 1669 | RSS: Main: 262774784 15:40:19 INFO - PROCESS | 1669 | 15:40:22 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 15:40:23 INFO - PROCESS | 1669 | RSS: Main: 265379840 15:40:23 INFO - PROCESS | 1669 | 15:40:25 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 15:40:25 INFO - PROCESS | 1669 | RSS: Main: 266526720 15:40:25 INFO - PROCESS | 1669 | 15:40:27 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:40:28 INFO - PROCESS | 1669 | RSS: Main: 268173312 15:40:28 INFO - PROCESS | 1669 | 15:40:31 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 15:40:32 INFO - PROCESS | 1669 | RSS: Main: 274374656 15:40:32 INFO - PROCESS | 1669 | 15:40:35 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 15:40:35 INFO - PROCESS | 1669 | RSS: Main: 273358848 15:40:35 INFO - PROCESS | 1669 | 15:40:38 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 15:40:39 INFO - PROCESS | 1669 | RSS: Main: 273432576 15:40:39 INFO - PROCESS | 1669 | 15:40:40 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:40:41 INFO - PROCESS | 1669 | RSS: Main: 272449536 15:40:41 INFO - PROCESS | 1669 | 15:40:43 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:40:44 INFO - PROCESS | 1669 | RSS: Main: 272506880 15:40:44 INFO - PROCESS | 1669 | 15:40:46 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:40:47 INFO - PROCESS | 1669 | RSS: Main: 272543744 15:40:47 INFO - PROCESS | 1669 | 15:40:50 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:40:50 INFO - PROCESS | 1669 | RSS: Main: 272814080 15:40:50 INFO - PROCESS | 1669 | 15:40:53 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:40:54 INFO - PROCESS | 1669 | RSS: Main: 272916480 15:40:54 INFO - PROCESS | 1669 | 15:40:56 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 15:40:56 INFO - PROCESS | 1669 | RSS: Main: 274739200 15:40:56 INFO - PROCESS | 1669 | 15:40:56 INFO - PROCESS | 1669 | __start_tp_report 15:40:56 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 15:40:56 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 15:40:56 INFO - PROCESS | 1669 | |i|pagename|runs| 15:40:56 INFO - PROCESS | 1669 | |0;ai-astar;109;118;117;118;116;116;116;117;116;120 15:40:56 INFO - PROCESS | 1669 | |1;audio-beat-detection;149;121;119;155;116;119;117;123;142;153 15:40:56 INFO - PROCESS | 1669 | |2;audio-dft;228;257;239;196;195;197;199;198;197;196 15:40:56 INFO - PROCESS | 1669 | |3;audio-fft;79;59;59;84;61;62;85;53;93;82 15:40:56 INFO - PROCESS | 1669 | |4;audio-oscillator;86;72;107;103;99;80;83;73;70;84 15:40:56 INFO - PROCESS | 1669 | |5;imaging-gaussian-blur;89;87;125;93;93;94;90;85;84;86 15:40:56 INFO - PROCESS | 1669 | |6;imaging-darkroom;117;116;96;97;96;126;96;97;96;96 15:40:56 INFO - PROCESS | 1669 | |7;imaging-desaturate;76;146;143;91;92;94;95;93;92;100 15:40:56 INFO - PROCESS | 1669 | |8;json-parse-financial;58;58;60;57;89;88;60;57;54;58 15:40:56 INFO - PROCESS | 1669 | |9;json-stringify-tinderbox;66;70;62;92;101;107;104;61;95;62 15:40:56 INFO - PROCESS | 1669 | |10;stanford-crypto-aes;89;92;90;87;91;86;113;110;86;111 15:40:56 INFO - PROCESS | 1669 | |11;stanford-crypto-ccm;140;157;176;142;134;163;159;169;223;166 15:40:56 INFO - PROCESS | 1669 | |12;stanford-crypto-pbkdf2;242;181;178;175;176;177;184;175;179;179 15:40:56 INFO - PROCESS | 1669 | |13;stanford-crypto-sha256-iterative;66;86;90;71;65;89;91;93;91;65 15:40:56 INFO - PROCESS | 1669 | __end_tp_report 15:40:56 INFO - PROCESS | 1669 | __start_cc_report 15:40:56 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,931 15:40:56 INFO - PROCESS | 1669 | __end_cc_report 15:40:56 INFO - PROCESS | 1669 | __startTimestamp1480894856676__endTimestamp 15:40:56 INFO - PROCESS | 1669 | 15:40:56 INFO - PROCESS | 1669 | ------- Summary: start ------- 15:40:56 INFO - PROCESS | 1669 | Number of tests: 14 15:40:56 INFO - PROCESS | 1669 | 15:40:56 INFO - PROCESS | 1669 | [#0] ai-astar Cycles:10 Average:116.30 Median:117.00 stddev:2.87 (2.5%) stddev-sans-first:1.36 15:40:56 INFO - PROCESS | 1669 | Values: 109.0 118.0 117.0 118.0 116.0 116.0 116.0 117.0 116.0 120.0 15:40:56 INFO - PROCESS | 1669 | 15:40:56 INFO - PROCESS | 1669 | [#1] audio-beat-detection Cycles:10 Average:131.40 Median:132.50 stddev:16.25 (12.3%) stddev-sans-first:15.94 15:40:56 INFO - PROCESS | 1669 | Values: 149.0 121.0 119.0 155.0 116.0 119.0 117.0 123.0 142.0 153.0 15:40:56 INFO - PROCESS | 1669 | 15:40:56 INFO - PROCESS | 1669 | [#2] audio-dft Cycles:10 Average:210.20 Median:198.50 stddev:22.59 (11.4%) stddev-sans-first:23.03 15:40:56 INFO - PROCESS | 1669 | Values: 228.0 257.0 239.0 196.0 195.0 197.0 199.0 198.0 197.0 196.0 15:40:56 INFO - PROCESS | 1669 | 15:40:56 INFO - PROCESS | 1669 | [#3] audio-fft Cycles:10 Average:71.70 Median:80.50 stddev:14.23 (17.7%) stddev-sans-first:14.84 15:40:56 INFO - PROCESS | 1669 | Values: 79.0 59.0 59.0 84.0 61.0 62.0 85.0 53.0 93.0 82.0 15:40:56 INFO - PROCESS | 1669 | 15:40:56 INFO - PROCESS | 1669 | [#4] audio-oscillator Cycles:10 Average:85.70 Median:81.50 stddev:13.20 (16.2%) stddev-sans-first:14.00 15:40:56 INFO - PROCESS | 1669 | Values: 86.0 72.0 107.0 103.0 99.0 80.0 83.0 73.0 70.0 84.0 15:40:56 INFO - PROCESS | 1669 | 15:40:56 INFO - PROCESS | 1669 | [#5] imaging-gaussian-blur Cycles:10 Average:92.60 Median:89.50 stddev:11.92 (13.3%) stddev-sans-first:12.57 15:40:56 INFO - PROCESS | 1669 | Values: 89.0 87.0 125.0 93.0 93.0 94.0 90.0 85.0 84.0 86.0 15:40:56 INFO - PROCESS | 1669 | 15:40:56 INFO - PROCESS | 1669 | [#6] imaging-darkroom Cycles:10 Average:103.30 Median:96.00 stddev:11.60 (12.1%) stddev-sans-first:11.19 15:40:56 INFO - PROCESS | 1669 | Values: 117.0 116.0 96.0 97.0 96.0 126.0 96.0 97.0 96.0 96.0 15:40:56 INFO - PROCESS | 1669 | 15:40:56 INFO - PROCESS | 1669 | [#7] imaging-desaturate Cycles:10 Average:102.20 Median:92.00 stddev:23.12 (25.1%) stddev-sans-first:22.50 15:40:56 INFO - PROCESS | 1669 | Values: 76.0 146.0 143.0 91.0 92.0 94.0 95.0 93.0 92.0 100.0 15:40:56 INFO - PROCESS | 1669 | 15:40:56 INFO - PROCESS | 1669 | [#8] json-parse-financial Cycles:10 Average:63.90 Median:59.00 stddev:13.08 (22.2%) stddev-sans-first:13.69 15:40:56 INFO - PROCESS | 1669 | Values: 58.0 58.0 60.0 57.0 89.0 88.0 60.0 57.0 54.0 58.0 15:40:56 INFO - PROCESS | 1669 | 15:40:56 INFO - PROCESS | 1669 | [#9] json-stringify-tinderbox Cycles:10 Average:82.00 Median:64.00 stddev:19.38 (30.3%) stddev-sans-first:19.67 15:40:56 INFO - PROCESS | 1669 | Values: 66.0 70.0 62.0 92.0 101.0 107.0 104.0 61.0 95.0 62.0 15:40:56 INFO - PROCESS | 1669 | 15:40:56 INFO - PROCESS | 1669 | [#10] stanford-crypto-aes Cycles:10 Average:95.50 Median:88.00 stddev:11.13 (12.6%) stddev-sans-first:11.55 15:40:56 INFO - PROCESS | 1669 | Values: 89.0 92.0 90.0 87.0 91.0 86.0 113.0 110.0 86.0 111.0 15:40:56 INFO - PROCESS | 1669 | 15:40:56 INFO - PROCESS | 1669 | [#11] stanford-crypto-ccm Cycles:10 Average:162.90 Median:164.50 stddev:25.12 (15.3%) stddev-sans-first:25.23 15:40:56 INFO - PROCESS | 1669 | Values: 140.0 157.0 176.0 142.0 134.0 163.0 159.0 169.0 223.0 166.0 15:40:56 INFO - PROCESS | 1669 | 15:40:56 INFO - PROCESS | 1669 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.60 Median:179.00 stddev:20.36 (11.4%) stddev-sans-first:2.95 15:40:56 INFO - PROCESS | 1669 | Values: 242.0 181.0 178.0 175.0 176.0 177.0 184.0 175.0 179.0 179.0 15:40:56 INFO - PROCESS | 1669 | 15:40:56 INFO - PROCESS | 1669 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:80.70 Median:89.50 stddev:12.25 (13.7%) stddev-sans-first:11.78 15:40:56 INFO - PROCESS | 1669 | Values: 66.0 86.0 90.0 71.0 65.0 89.0 91.0 93.0 91.0 65.0 15:40:56 INFO - PROCESS | 1669 | -------- Summary: end -------- 15:40:56 INFO - PROCESS | 1669 | 15:40:57 INFO - TEST-INFO | 1669: exit 0 15:40:57 INFO - TEST-OK | kraken | took 61660ms 15:40:57 INFO - SUITE-END | took 1179s 15:40:57 INFO - Completed test suite (00:19:39) 15:40:57 INFO - javascript benchmark 15:40:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7240.224644014455, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3340.515625234341, 3381.019504343742, 3433.5193072969773, 3437.6906078452935, 3452.792832430054, 5650.886982260363, 5709.800156994506, 5732.5127364174095, 5736.684482353469, 5813.970930145354, 5642.04085305498, 5762.250907382045, 5789.623674461157, 5801.042827933396, 5831.620944638596, 5175.197844334115, 5183.429822719508, 5208.140656791625, 5303.840884773463, 5311.176767601009, 5279.656822306538, 5307.336582927134, 5341.225522299264, 5370.409254981949, 5399.757010934498, 4405.88985275369, 5294.92057619136, 5317.521423071923, 5333.040052790496, 5374.435684253156, 4317.69776115672, 5134.255532947702, 5237.397699264595, 5252.501012403815, 5322.467753224685, 4044.3933409988445, 4536.410266665345, 5023.296738456619, 5239.895202095975, 5253.7635806388425, 5667.489925906647, 5668.518175955049, 5695.834100373654, 5749.741261643236, 5759.107338362561, 6101.398774955092, 6137.806028951355, 6149.938500614981, 6234.906476402857, 6236.18929539157, 6151.0, 6268.467180289681, 6288.402601752872, 6288.8113356598915, 6320.525960552977, 5933.584649074566, 5962.791302304398, 5965.3438121807, 5985.072313791369, 6018.097285407136, 6085.5435842312, 6155.84610384744, 6220.129181914535, 6346.746130154742, 6385.616862988236, 5077.543021128116, 5112.872178195575, 5127.564157046693, 6106.236720409949, 6160.383961603804, 6212.9068063979075, 6241.438270555581, 6268.09112678659, 6301.558890877686, 6307.716152773136, 4967.279744437036, 6252.093446450239, 6286.371362863677, 6295.685215739195, 6342.746290148435, 6146.508279337642, 6204.689765511706, 6207.503399728102, 6220.7511699531515, 6221.129041934133, 4939.53074457926, 4981.9252711209365, 4987.952529968675, 4997.825076122318, 5011.123053465629, 4422.668299877522, 4423.535528769433, 4429.402030725875, 4463.866084017484, 4467.530909254547, 5869.266341707286, 5932.881342373128, 6021.729022194012, 6034.24571928509, 6045.969770151121, 6161.092699601999, 6236.875262494725, 6238.812835614939, 6242.937570624326, 6251.218597675291, 5793.942060579425, 5832.679202643895, 6032.819015429544, 6038.218725475995, 6041.758329666852, 5391.16436952273, 5433.673979561239, 5433.891322173535, 5454.699991500505, 5462.317210348707, 6272.780452684134, 6365.3316401778075, 6380.553361264852, 6382.329855365306, 6403.551751377545, 5473.726313684379, 6087.086936959491, 6202.751889924352, 6223.875522489616, 6304.810855674337, 6177.351378105234, 6312.0, 6371.904421433775, 6382.840428989312, 6390.0, 6112.655464441373, 6156.26124865019, 6167.321594624677, 6194.38056194395, 6306.400891915261, 6250.156228909039, 6253.6560489173535, 6268.968655156695, 6269.341719119427, 6273.153124328157, 6154.569180157346, 6163.753449862134, 6211.316755156839, 6221.09794079865, 6223.439890409885, 5972.0, 5981.923253814181, 6017.849553761191, 6019.337872834072, 6104.095385180791, 4154.3976123462535, 5508.44915508446, 5843.503302219359, 6109.0, 6131.417515336036, 6198.566100372931, 6206.348333425106, 6208.348123447154, 6218.595791273553, 6252.656103914146, 4529.275315949432, 5880.059190529495, 6060.848478788065, 6066.15073889647, 6148.231471066117], "value": 5679.1876115905925, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [459.6506654942245, 504.5761560289357, 522.4723029739963, 525.3223341888963, 526.1896679114164, 37944.24111517771, 39292.607073929255, 39327.60672393275, 39375.21249575007, 39413.60586394135, 40479.785606431855, 40604.59395406045, 40770.73834093273, 40830.7958460208, 40854.79572602133, 7855.332296754783, 8382.0, 8624.094470080645, 9406.0, 9441.29190310726, 11006.0, 11294.0, 11341.036011938995, 11496.827547586792, 11524.366159861203, 11357.148213883951, 11444.656660300205, 11451.656450306462, 11460.0, 11461.255018423817, 11200.551977920873, 11262.380569068699, 11293.435328233638, 11316.151288653302, 11325.660230193025, 10745.677629671123, 11047.613333533287, 11110.222284440093, 11123.833142502868, 11124.833127503094, 31218.84390578055, 31972.840135799404, 38681.22637547233, 38709.61290387088, 38746.225075498616, 3241.675832416763, 3377.780444271139, 3385.86456541738, 3454.274602333488, 3880.3209438348176, 29239.2690182745, 29353.853230733923, 29713.0, 30259.092227233217, 30369.392612147632, 3183.267848394882, 3188.1551388881967, 3200.2799370141765, 3257.794615992098, 3341.198112453018, 376716.0, 398234.0, 450194.0, 479055.0, 479650.9999999965, 59286.70356648189, 59535.70232148811, 59568.404315956286, 59581.40418595759, 59754.70122649359, 61014.69492652508, 61328.0, 61505.0, 61755.38244617586, 61789.691051544454, 15616.375344986074, 15880.920595396949, 15902.330255323255, 15905.0, 15927.761083583755, 11036.889631103746, 11223.989840914357, 11908.88091119095, 12143.878561214275, 12156.696082598006, 15198.240087995557, 17836.28654853791, 17973.37093201732, 18146.546336341697, 18200.0, 7614.6954121835615, 7677.616119194018, 7722.459427839997, 7757.4181936355, 7792.649330780128, 691.7129391302653, 847.9915200848036, 870.7170169694874, 959.6593209410559, 976.6484065736329, 28251.293717657223, 28709.0, 28990.565141522893, 29428.705712943025, 29614.0, 979.2313034268066, 1015.8120747661559, 1034.6171916390983, 1035.4098164046572, 1043.9634612788516, 15014.549563513112, 15028.098314100971, 15199.924000379928, 15366.078035317916, 15369.9231503844, 647118.0, 650194.0, 658905.7054714792, 659879.0, 660717.0, 55466.0, 55983.72008139933, 56026.43973560294, 56125.71937140288, 56161.15758263629, 1295.8768916952874, 1322.1141834970592, 1339.82582264305, 1347.2455424962052, 1348.6021623621054, 28310.71689283163, 28371.858140709166, 28728.13815585536, 29116.854415728634, 29125.271868203465, 47158.0, 47464.52535474601, 47926.04147917092, 47961.040779183524, 47969.28046079242, 570243.0, 571682.0, 572693.0, 573487.0, 575971.2402875917, 25260.62109068402, 25381.111661092513, 25733.0, 26209.73790262073, 26269.737302627495, 44096.0, 44215.33676994912, 45115.0, 45287.77356113198, 45382.77308613436, 36727.26545469022, 36980.44529332008, 37552.62447375491, 38236.235275294894, 38340.0, 36866.26267474689, 36883.63116368802, 37765.24469510649, 37805.0, 38106.428403573416, 36226.63773362338, 36426.90719278426, 36514.90455286346, 36631.81684091563, 37069.81465092657, 26604.0, 32289.192770180933, 33029.834850825595, 33206.335873282886, 33300.500492492145, 18564.071796409855, 21358.359249221925, 23558.764412355657, 25232.74767252304, 26366.604222671245], "value": 19637.768414866976, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [360.6591770776614, 377.5280898876405, 378.0605196087723, 381.75376881911177, 396.70643723644514, 18697.626047479043, 19386.709199362, 19603.4713751525, 19799.802001979977, 19986.800131998712, 16627.334906603723, 20205.19179232829, 20244.797552024476, 20649.380518584465, 21048.473788155305, 6611.900821487669, 6679.632620205887, 6811.1826580810375, 6851.451883849305, 6958.338957799001, 4563.908721825562, 6658.733650653968, 6785.491088168383, 6795.7961261162245, 6796.2863899290605, 4804.711717296974, 4827.179379505493, 4853.757312134397, 7785.026871641045, 7919.56442395668, 6583.967080164616, 7039.718411263543, 7859.842803143933, 7867.0, 7870.449068565203, 6348.619082855043, 7656.425768067362, 7659.425543084291, 7661.0, 7668.309852113281, 25931.962721491116, 26819.86590067037, 26975.865120674272, 27181.728182718118, 27424.17727468179, 2091.4666759976108, 2283.6346184610384, 2309.041747674713, 2375.7743014413604, 2444.4988777300787, 15129.773053404208, 15738.449154279546, 16173.91913040439, 16422.0, 16585.66828663432, 1807.7288406738962, 1825.6896327624336, 1832.6792811257976, 1882.554924488197, 1941.4466876940146, 132677.0, 138707.0, 145050.0, 145169.27415362856, 150808.0, 20310.695339569917, 20744.0, 20769.169233230503, 20871.686924696143, 20889.582208355747, 34886.30227395488, 34899.65100349015, 34904.47643285353, 34987.60049598038, 35062.64937350594, 10188.303697929648, 10408.0, 10579.153667706565, 10734.248602597743, 10756.408397538211, 7373.060049766669, 8620.749991251234, 8670.566471676391, 8712.825743485095, 8890.511021893728, 7951.840963180704, 9219.90780092204, 9522.672556159025, 9619.086186812241, 9628.566714497865, 3722.441633754904, 3776.6412190841825, 3777.6411240932066, 3858.3633700439113, 3866.4481442248543, 794.5590197440476, 849.7790574450687, 861.4917198852708, 894.695803426838, 932.4125800745487, 20527.4868128295, 21029.579408411748, 21143.78856211449, 21286.042128104273, 21326.893365533073, 872.3152325424511, 875.6103533927469, 889.1419779912416, 891.7324802559207, 898.3756289378949, 10535.0, 10820.837687434694, 10935.507902144423, 10949.945250273857, 10967.287126336763, 482731.0, 487271.0, 489649.0, 490800.0, 560514.0, 126165.36917315355, 137956.0, 139106.0, 140731.0, 142905.94875380807, 958.5734348214977, 976.6679329028022, 1009.1724785675822, 1017.8066167428165, 1022.8619136416488, 18864.33974810875, 19039.0, 19178.616427671648, 19202.519937001685, 19333.806661933762, 41844.81551844791, 45666.0, 52648.68378290573, 53414.198787019006, 53443.39669809912, 62220.0, 73237.6338118306, 74441.16132451373, 77197.0, 78197.0, 6980.371766541036, 7786.571738554434, 7861.174576669596, 7931.0, 8182.386321025828, 8957.59690813902, 12213.0, 12425.689357766129, 12523.248605083723, 12605.369731513205, 10291.742706432398, 10324.587016519255, 10861.294015888941, 10951.45242737844, 11034.337939723642, 10444.738881527719, 10596.8940310599, 10814.837777433188, 10853.34879907208, 10966.287191332538, 9769.75575610587, 10587.788244235226, 10892.346459212473, 11171.497282622138, 11230.494627741935, 6047.0, 7993.840123197388, 8053.476524025919, 8056.436049476481, 8065.395095367753, 8991.190792828676, 10659.467026648792, 10930.344179349264, 10957.726056848642, 11032.007119359296], "value": 10637.477315518776, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1362.9931850340747, 1467.6330917270686, 1624.9593760155994, 1787.249355270786, 1796.4909476040132, 6556.606603603781, 7420.851582968337, 7664.463487555873, 8398.076211616717, 8426.915730842697, 7826.217378262171, 8082.191780821915, 8113.188681131898, 8172.550509721963, 8183.877241841362, 2177.5862586108587, 2192.331338941622, 2212.4690074382193, 2213.701150344699, 2217.545403192342, 1938.5638231397963, 2195.495036141688, 2243.5064285857084, 2249.1453247765826, 2255.796978271955, 2256.717910261217, 2259.062489067035, 2273.8976746046383, 2282.1441959265276, 2291.5073259249257, 2227.498812767131, 2234.095191447458, 2252.8085112765434, 2253.3690566641367, 2265.88670566471, 1902.7906930237664, 1922.9230830766892, 1999.9200031998703, 2064.1846470644164, 2251.4258863989576, 8184.468009579358, 8265.0, 8271.917280827234, 8312.21034001769, 8716.825663486694, 4787.0, 5048.7980480780725, 5068.062408454411, 5074.340335756365, 5096.184610462308, 2697.961284905306, 3314.6661135638547, 3457.6542345765474, 3478.7738796978115, 3485.7734247274097, 993.8161440133598, 1031.7936412717486, 1084.414416215243, 1090.5201711247025, 1090.6400887707039, 10257.794844103077, 10701.25091243621, 10783.514741836558, 10884.183686223485, 10888.29226100301, 3568.2685049564775, 4311.590398912098, 4381.101874115799, 4386.385905973167, 4406.361077643724, 8499.575021248913, 8523.70167044163, 8560.871586926201, 8565.0, 8575.656973720981, 4990.5508504234795, 4996.175631020914, 4997.90004199914, 4999.675021123615, 5021.49785021495, 4513.638908887347, 4648.581627653527, 4701.64737644678, 4715.528447155257, 4727.125481785881, 3378.6283508814013, 3445.155936795501, 3484.9651503484642, 3507.0706262840367, 3523.2777280657406, 1031.0823367203195, 1048.9423081730426, 1057.4818339013827, 1078.6548304542628, 1101.6479815593605, 874.8512752832033, 881.3698205782767, 890.7194233816327, 898.2409863665192, 915.4049867586123, 4467.30756732707, 5357.133933345642, 5514.641548299348, 5626.98714231434, 5644.8306550803545, 410.8212927376601, 421.8820126664364, 430.2513626290232, 437.02325302947884, 453.65522203125863, 3117.5479555464785, 3753.174301653632, 3829.0810205551024, 3848.4804551385764, 3892.630200130983, 9590.568424420917, 10074.647387341407, 10090.848637270446, 10097.899021009844, 10109.140723038625, 10044.547995340226, 10049.24630652704, 10087.646932357478, 10094.949525252327, 10120.696379108638, 6709.798706038827, 7565.659545320474, 7745.728899488491, 7876.133625301212, 7884.408669349822, 4693.577578017995, 5498.247619994563, 5610.971945140248, 5618.803341883015, 5633.549316054624, 9227.953860230657, 9375.859362109437, 9403.529823508661, 9409.529523523659, 9441.961384247865, 8683.478991260545, 8986.0, 9106.590203440728, 9442.433453992782, 9477.810443791224, 6896.241413444617, 7082.893756593759, 7152.356287934111, 7228.96385518069, 7252.854942901007, 6536.640484773383, 7630.084389873251, 7854.567998760123, 7928.643211055401, 7961.6019199040975, 7004.544704594185, 7009.9649501752165, 7024.5082844200415, 7171.067761191011, 7174.1032370953635, 5928.940710593011, 6851.588904665736, 6953.617551034742, 6976.5814051157095, 7064.187618423947, 6571.277159512545, 6600.636964966973, 6646.966765166143, 6652.700628471827, 6689.933100668931, 4696.225122854691, 4748.667593268438, 4800.087983283165, 4843.0313937211995, 4951.5048495150195, 4781.348067617278, 4912.803487860446, 4915.778789954389, 4926.729029903389, 4968.22992436173], "value": 4193.103529258839, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [286.20435190171327, 305.12581454133897, 311.8487533546231, 315.1490974369204, 321.2659074015874, 4464.638285322975, 5200.557952574036, 5371.677699338043, 5496.587755918307, 5502.367227768807, 5152.779512804497, 5312.92030619541, 5380.408155102936, 5390.5418039466595, 5417.911790279542, 3353.6814002669707, 3772.188979369435, 4114.897127571812, 4133.049398638315, 4144.523379811315, 3805.6574908258253, 4015.6586690131376, 4175.436316097318, 4208.473940757405, 4217.093324935138, 3716.7548871128206, 3861.5366156061314, 3987.66104881084, 4022.5575186729584, 4073.429719839225, 3785.261873934584, 4015.477987861574, 4046.413270075852, 4047.635712785834, 4051.6556092732153, 2925.049358958347, 2986.5072263076568, 2993.069470191714, 3480.6345333739846, 3534.487499312611, 4564.2240819060835, 4605.65457590682, 4915.557599815998, 4984.504648605403, 5283.28675628789, 3943.3296339622048, 4576.679632425731, 4577.885552861205, 4607.239805432133, 4638.002829391697, 4995.600351971833, 5671.744771485293, 5672.659640421588, 5679.5801049737565, 5723.627964182315, 3581.9612312429363, 3826.6364695353896, 3857.5119048214024, 3876.9806150969066, 3899.7270191086636, 5475.534579560703, 5916.645001299936, 5924.437178468039, 5936.762529498858, 5946.613470124428, 3612.2053148307855, 3892.649661530476, 3936.6653834423823, 3939.940900886489, 3979.940300895431, 3560.5549306336707, 3818.6372294631965, 3849.825803130016, 3978.562358140602, 3982.3628219484744, 3827.923441531154, 3861.304965106308, 4037.8384864605673, 4628.791704373311, 4658.487566367697, 4650.767546600154, 5615.410381909923, 5746.367899531048, 5758.337791153987, 5779.682117483579, 1222.9877701222874, 1232.3099064523894, 1245.4680742686546, 1279.814426908093, 1282.9615111546668, 732.0739264829995, 854.5214679779343, 871.4422769427572, 873.8208667223204, 876.4049430037271, 4567.8839699536065, 4878.195097808822, 5092.9236061459105, 5135.6918584885025, 5156.484351564814, 5028.5977121830165, 5125.56432703217, 5129.717865517432, 5577.274954256002, 5666.433356664301, 4301.827926882952, 4514.548545145459, 5036.194208926554, 5070.01134778722, 5072.467390923974, 2348.0, 2356.7996720278625, 2535.69571651403, 2711.295063283521, 2940.823550586929, 4673.63304981774, 5533.529649979797, 5594.468525490072, 5701.71491425427, 5762.213713748752, 5193.792248310026, 5507.981023510583, 5617.466340697627, 5685.914711279334, 5713.628614140068, 5191.857791285911, 5372.838814835562, 5457.972710136424, 5693.800716974969, 5695.886082278248, 5108.719020453911, 5433.831726178891, 5506.559475241891, 5508.55931525485, 5509.641873278224, 4801.567858892577, 5123.897522049613, 5380.058489764354, 5456.454354564512, 5469.234307197075, 4784.497627749036, 5461.777551244784, 5582.483035687491, 5756.971215143897, 5761.818827140342, 3016.441958237733, 3658.213484100931, 3672.485851980778, 3679.8712045078296, 3696.5564132304294, 4572.81708731647, 5476.919694827379, 5505.128256392845, 5536.390996990408, 5559.416261292506, 4392.3631073493525, 4610.031893302444, 4674.859754207379, 5168.0, 5400.378956419905, 4991.376077990251, 5293.523582877559, 5312.574994000393, 5320.787168513216, 5353.223782551586, 5031.823886163967, 5280.940433230966, 5298.70857102863, 5310.761015754378, 5476.507114359727, 5282.709450980233, 5613.761609984435, 5719.828405147852, 5725.627834190765, 5728.625129968694, 5280.76236569363, 5594.272744543184, 5605.299337582802, 5622.060389165731, 5664.546836253007], "value": 3992.8648139262123, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [622.1942584353262, 667.2860039757461, 701.0325750464358, 703.7466512055659, 706.5760543673795, 13948.0933739307, 14470.421183152674, 14538.200398978051, 15141.470048548303, 15764.527064188063, 14637.6340591485, 14936.253187340617, 15062.849371506281, 15131.24343782812, 15138.621534461645, 813.0934008580449, 842.9926238145418, 845.6363763581658, 846.7078857794052, 846.851800934837, 643.1992169748646, 675.2538445018258, 816.2694388522269, 828.2173346187855, 828.225609055535, 882.4440602420466, 886.6187539358074, 891.416122439803, 895.3240303570794, 897.0356866368647, 881.7002219245487, 883.2095274729113, 884.4825776920509, 885.7254251182122, 889.8843150390472, 648.254507316585, 659.3999460490978, 660.1055569703079, 660.957037792542, 664.3555750921598, 13870.514531991332, 13899.235542045184, 13911.860881391158, 14010.369533371022, 14060.578182654537, 12540.372981350896, 12564.183328083645, 12654.81017784734, 12673.0, 12709.110362274645, 10964.28732132409, 10975.451227438676, 11054.557817687282, 11102.833457498064, 11383.373914434702, 3398.5242066110764, 3402.42158832999, 3428.7599868009247, 3456.6716161964573, 3524.6827785499427, 114821.0, 117358.41320793427, 118162.0, 119190.4040479792, 121023.3948830259, 9595.760105997266, 9872.01279872007, 9944.403335799876, 9977.351472154287, 10419.583216671248, 18436.81563184351, 19290.517737056405, 19310.03449827503, 19404.12681429339, 19599.902000489907, 7085.92914070863, 7151.642417879085, 7220.711171553184, 7295.051643286339, 7329.230430804795, 3667.2848794485353, 3760.266747984117, 3823.3500304948225, 3845.9038524037123, 3860.208657225262, 3293.6870997255223, 3365.225998020469, 3393.660633936636, 3397.745169112277, 3401.217719924382, 543.8613153645795, 631.2141383976978, 633.0820310549722, 636.5671343486381, 680.8978653202059, 546.778554685353, 616.740968793108, 628.3057221769878, 656.3305428462941, 677.1400321591551, 9066.682666106655, 9070.727878163665, 9140.954295228481, 9163.555152318848, 9251.121143491358, 514.8352527191263, 548.7777450132703, 586.9853253668692, 606.9136246119397, 612.5650787940613, 3657.829494561768, 4428.778561071934, 4434.290513517822, 4438.0, 4487.506374298824, 78496.0, 81983.0, 91229.0, 109205.81588368188, 121139.39430302792, 75075.1231219213, 85721.57139214389, 94227.0, 97384.0, 97415.0, 64699.35300646933, 76644.0, 85381.0, 85988.0, 87869.56065219633, 8625.87061194095, 8643.697470588499, 8704.216620503932, 8730.738077857675, 8920.107989201028, 39410.80294598509, 49362.75318623528, 54266.728666356415, 57750.13374799297, 60394.79210415679, 79462.0, 84896.0, 87259.75886355578, 87734.0, 88005.0, 12780.917760127333, 13064.085514013928, 13078.149920255533, 13085.542006029744, 13326.40031198579, 12300.876991229974, 14447.0, 14574.562763117123, 14603.496527829406, 14721.190334531704, 12862.67843303925, 13057.02072344559, 13076.0, 13227.933860330637, 13276.800847987095, 13517.121387109806, 13766.0, 13834.239116848268, 14058.297085145907, 14273.857261427253, 13093.672658183621, 13178.670533236746, 13342.399592018577, 13376.0, 13938.0, 10729.428138280811, 11078.667639970814, 11489.368084755419, 11603.5358585659, 11833.763324733629, 9665.661701840401, 11140.665780026613, 11403.372814495282, 11418.828717569077, 11587.420628968686], "value": 7251.424501302116, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1583.0000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 118.0, 117.0, 118.0, 116.0, 116.0, 116.0, 117.0, 116.0, 120.0], "value": 116.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 121.0, 119.0, 155.0, 116.0, 119.0, 117.0, 123.0, 142.0, 153.0], "value": 131.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [228.0, 257.0, 239.0, 196.0, 195.0, 197.0, 199.0, 198.0, 197.0, 196.0], "value": 210.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 59.0, 59.0, 84.0, 61.0, 62.0, 85.0, 53.0, 93.0, 82.0], "value": 71.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 72.0, 107.0, 103.0, 99.0, 80.0, 83.0, 73.0, 70.0, 84.0], "value": 85.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 125.0, 93.0, 93.0, 94.0, 90.0, 85.0, 84.0, 86.0], "value": 92.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 96.0, 97.0, 96.0, 126.0, 96.0, 97.0, 96.0, 96.0], "value": 103.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 146.0, 143.0, 91.0, 92.0, 94.0, 95.0, 93.0, 92.0, 100.0], "value": 102.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 60.0, 57.0, 89.0, 88.0, 60.0, 57.0, 54.0, 58.0], "value": 63.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 70.0, 62.0, 92.0, 101.0, 107.0, 104.0, 61.0, 95.0, 62.0], "value": 82.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 92.0, 90.0, 87.0, 91.0, 86.0, 113.0, 110.0, 86.0, 111.0], "value": 95.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 157.0, 176.0, 142.0, 134.0, 163.0, 159.0, 169.0, 223.0, 166.0], "value": 162.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [242.0, 181.0, 178.0, 175.0, 176.0, 177.0, 184.0, 175.0, 179.0, 179.0], "value": 184.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 86.0, 90.0, 71.0, 65.0, 89.0, 91.0, 93.0, 91.0, 65.0], "value": 80.7, "unit": "score"}]}]} 15:40:57 INFO - Return code: 0 15:40:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:40:58 ERROR - # TBPL SUCCESS # 15:40:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:40:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:40:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:40:58 INFO - 2016-12-04 15:40:58.026 screenresolution[1674:17474] starting screenresolution argv=screenresolution get 15:40:58 INFO - 2016-12-04 15:40:58.030 screenresolution[1674:17474] Display 0: 1600x1200x32@60 15:40:58 INFO - 2016-12-04 15:40:58.036 screenresolution[1675:17477] starting screenresolution argv=screenresolution list 15:40:58 INFO - Available Modes on Display 0 15:40:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:40:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:40:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:40:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:40:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:40:58 INFO - Intel Iris: 15:40:58 INFO - Chipset Model: Intel Iris 15:40:58 INFO - Type: GPU 15:40:58 INFO - Bus: Built-In 15:40:58 INFO - VRAM (Dynamic, Max): 1536 MB 15:40:58 INFO - Vendor: Intel (0x8086) 15:40:58 INFO - Device ID: 0x0a2e 15:40:58 INFO - Revision ID: 0x0009 15:40:58 INFO - Displays: 15:40:58 INFO - Display: 15:40:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:40:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:40:58 INFO - Display Serial Number: 1600x1200 60 15:40:58 INFO - Main Display: Yes 15:40:58 INFO - Mirror: Off 15:40:58 INFO - Online: Yes 15:40:58 INFO - Rotation: Supported 15:40:58 INFO - Return code: 0 15:40:58 INFO - Running post-action listener: _resource_record_post_action 15:40:58 INFO - [mozharness: 2016-12-04 23:40:58.275695Z] Finished run-tests step (success) 15:40:58 INFO - Running post-run listener: _resource_record_post_run 15:40:58 INFO - Total resource usage - Wall time: 1212s; CPU: 55.0%; Read bytes: 32834048; Write bytes: 699392512; Read time: 197; Write time: 2024 15:40:58 INFO - TinderboxPrint: CPU usage
54.8% 15:40:58 INFO - TinderboxPrint: I/O read bytes / time
32,834,048 / 197 15:40:58 INFO - TinderboxPrint: I/O write bytes / time
699,392,512 / 2,024 15:40:58 INFO - TinderboxPrint: CPU idle
2,193.5 (45.2%) 15:40:58 INFO - TinderboxPrint: CPU system
983.0 (20.3%) 15:40:58 INFO - TinderboxPrint: CPU user
1,673.4 (34.5%) 15:40:58 INFO - TinderboxPrint: Swap in / out
413,163,520 / 0 15:40:58 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 367557120; Write bytes: 367802368; Read time: 27764; Write time: 778 15:40:58 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 31912448; Write bytes: 313043456; Read time: 185; Write time: 1199 15:40:58 INFO - Running post-run listener: _upload_blobber_files 15:40:58 INFO - Blob upload gear active. 15:40:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:40:58 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 15:40:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:40:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:40:59 INFO - (blobuploader) - INFO - Open directory for files ... 15:40:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:40:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:40:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:41:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:41:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:41:00 INFO - (blobuploader) - INFO - Done attempting. 15:41:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:41:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:41:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:41:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:41:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:41:01 INFO - (blobuploader) - INFO - Done attempting. 15:41:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:41:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:41:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:41:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:41:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:41:03 INFO - (blobuploader) - INFO - Done attempting. 15:41:03 INFO - (blobuploader) - INFO - Iteration through files over. 15:41:03 INFO - Return code: 0 15:41:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:41:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:41:03 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/f1c5ad5847ef5a3a1d59dfb887b1e1d8d6c41219d3c0e0cded76559a5e968e2327a2aad1cb055bc726d64cac03e395e9e266919f65d1080e0a2b7d8d5e04b54c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/562e229737cfe1ac8a82d01ca3d16599808f43b0785b06182ea85a76460ac5397765ae552906d2ba24d3964835df0634de986878bd9fa232a040a630aa73ccf2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d49a45baae7a3f0aef3c91651843024b372355ff51ded5711f2840592289939850ae1dab2cd88c7a16356c1de5013318d12f205120fb29ce9cd6f4f1fcb6ca52"} 15:41:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:41:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:41:03 INFO - Contents: 15:41:03 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/f1c5ad5847ef5a3a1d59dfb887b1e1d8d6c41219d3c0e0cded76559a5e968e2327a2aad1cb055bc726d64cac03e395e9e266919f65d1080e0a2b7d8d5e04b54c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/562e229737cfe1ac8a82d01ca3d16599808f43b0785b06182ea85a76460ac5397765ae552906d2ba24d3964835df0634de986878bd9fa232a040a630aa73ccf2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d49a45baae7a3f0aef3c91651843024b372355ff51ded5711f2840592289939850ae1dab2cd88c7a16356c1de5013318d12f205120fb29ce9cd6f4f1fcb6ca52"} 15:41:03 INFO - Running post-run listener: copy_logs_to_upload_dir 15:41:03 INFO - Copying logs to upload dir... 15:41:03 INFO - mkdir: /builds/slave/test/build/upload/logs 15:41:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.544638 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-12-04 15:41:03.597113) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-04 15:41:03.600765) ========= 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.SKxujohX7F/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.Lff5snicj5/Listeners TMPDIR=/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/f1c5ad5847ef5a3a1d59dfb887b1e1d8d6c41219d3c0e0cded76559a5e968e2327a2aad1cb055bc726d64cac03e395e9e266919f65d1080e0a2b7d8d5e04b54c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/562e229737cfe1ac8a82d01ca3d16599808f43b0785b06182ea85a76460ac5397765ae552906d2ba24d3964835df0634de986878bd9fa232a040a630aa73ccf2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d49a45baae7a3f0aef3c91651843024b372355ff51ded5711f2840592289939850ae1dab2cd88c7a16356c1de5013318d12f205120fb29ce9cd6f4f1fcb6ca52"} build_url:https://queue.taskcluster.net/v1/task/WmyoIkhBR4-IfrLvhAwWXg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010922 build_url: 'https://queue.taskcluster.net/v1/task/WmyoIkhBR4-IfrLvhAwWXg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/f1c5ad5847ef5a3a1d59dfb887b1e1d8d6c41219d3c0e0cded76559a5e968e2327a2aad1cb055bc726d64cac03e395e9e266919f65d1080e0a2b7d8d5e04b54c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/562e229737cfe1ac8a82d01ca3d16599808f43b0785b06182ea85a76460ac5397765ae552906d2ba24d3964835df0634de986878bd9fa232a040a630aa73ccf2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/d49a45baae7a3f0aef3c91651843024b372355ff51ded5711f2840592289939850ae1dab2cd88c7a16356c1de5013318d12f205120fb29ce9cd6f4f1fcb6ca52"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-04 15:41:03.635876) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-04 15:41:03.636355) ========= 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.SKxujohX7F/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.Lff5snicj5/Listeners TMPDIR=/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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.005232 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-04 15:41:03.685498) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-04 15:41:03.685899) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-04 15:41:03.686262) ========= ========= Total master_lag: 0.28 =========