builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0097 starttime: 1475558868.77 results: success (0) buildid: 20161003203702 builduid: 7baa3a7f71f44ce9806943decdca824f revision: a9481398f9a76f5510ebf0b974826e423fd5448e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-03 22:27:48.769896) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-03 22:27:48.770921) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-03 22:27:48.771263) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-03 22:27:48.796901) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 22:27:48.797601) ========= 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.kXyTyVPiVf/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.vQKueimyk3/Listeners TMPDIR=/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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-10-03 22:27:48-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 56.2M=0s 2016-10-03 22:27:48 (56.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.108387 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 22:27:48.930360) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 22:27:48.930704) ========= 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.kXyTyVPiVf/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.vQKueimyk3/Listeners TMPDIR=/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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.028674 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 22:27:48.982640) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 22:27:48.983558) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev a9481398f9a76f5510ebf0b974826e423fd5448e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev a9481398f9a76f5510ebf0b974826e423fd5448e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kXyTyVPiVf/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.vQKueimyk3/Listeners TMPDIR=/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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-10-03 22:27:49,058 truncating revision to first 12 chars 2016-10-03 22:27:49,058 Setting DEBUG logging. 2016-10-03 22:27:49,058 attempt 1/10 2016-10-03 22:27:49,058 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/a9481398f9a7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-03 22:27:49,422 unpacking tar archive at: autoland-a9481398f9a7/testing/mozharness/ program finished with exit code 0 elapsedTime=0.647586 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 22:27:49.659209) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-03 22:27:49.660068) ========= script_repo_revision: a9481398f9a76f5510ebf0b974826e423fd5448e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-03 22:27:49.661023) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-03 22:27:49.661397) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-03 22:27:49.680286) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-10-03 22:27:49.680778) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kXyTyVPiVf/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.vQKueimyk3/Listeners TMPDIR=/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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 22:27:49 INFO - MultiFileLogger online at 20161003 22:27:49 in /builds/slave/test 22:27:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 22:27:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:27:49 INFO - {'append_to_log': False, 22:27:49 INFO - 'base_work_dir': '/builds/slave/test', 22:27:49 INFO - 'blob_upload_branch': 'Autoland', 22:27:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:27:49 INFO - 'branch': 'Autoland', 22:27:49 INFO - 'buildbot_json_path': 'buildprops.json', 22:27:49 INFO - 'config_files': ('talos/mac_config.py',), 22:27:49 INFO - 'default_actions': ('clobber', 22:27:49 INFO - 'read-buildbot-config', 22:27:49 INFO - 'download-and-extract', 22:27:49 INFO - 'populate-webroot', 22:27:49 INFO - 'create-virtualenv', 22:27:49 INFO - 'install', 22:27:49 INFO - 'run-tests'), 22:27:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:27:49 INFO - 'download_minidump_stackwalk': True, 22:27:49 INFO - 'download_symbols': 'ondemand', 22:27:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:27:49 INFO - 'tooltool.py': '/tools/tooltool.py', 22:27:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:27:49 INFO - '/tools/misc-python/virtualenv.py')}, 22:27:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:27:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:27:49 INFO - 'installer_path': 'installer.exe', 22:27:49 INFO - 'log_level': 'info', 22:27:49 INFO - 'log_name': 'talos', 22:27:49 INFO - 'log_to_console': True, 22:27:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:27:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:27:49 INFO - 'opt_config_files': (), 22:27:49 INFO - 'pip_index': False, 22:27:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:27:49 INFO - 'cmd': ('bash', 22:27:49 INFO - '-c', 22:27:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:27:49 INFO - 'enabled': True, 22:27:49 INFO - 'halt_on_failure': False, 22:27:49 INFO - 'name': 'check_screen_resolution'},), 22:27:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:27:49 INFO - 'cmd': ('bash', 22:27:49 INFO - '-c', 22:27:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:27:49 INFO - 'enabled': True, 22:27:49 INFO - 'halt_on_failure': False, 22:27:49 INFO - 'name': 'check_screen_resolution'},), 22:27:49 INFO - 'run_cmd_checks_enabled': True, 22:27:49 INFO - 'sps_profile': False, 22:27:49 INFO - 'sps_profile_interval': 0, 22:27:49 INFO - 'suite': 'dromaeojs', 22:27:49 INFO - 'system_bits': '32', 22:27:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:27:49 INFO - 'title': 't-yosemite-r7-0097', 22:27:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:27:49 INFO - 'use_talos_json': True, 22:27:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:27:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:27:49 INFO - 'work_dir': 'build'} 22:27:49 INFO - [mozharness: 2016-10-04 05:27:49.882067Z] Running clobber step. 22:27:49 INFO - Running pre-action listener: _resource_record_pre_action 22:27:49 INFO - Running main action method: clobber 22:27:49 INFO - rmtree: /builds/slave/test/build 22:27:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:27:52 INFO - Running post-action listener: _resource_record_post_action 22:27:52 INFO - [mozharness: 2016-10-04 05:27:52.276467Z] Finished clobber step (success) 22:27:52 INFO - [mozharness: 2016-10-04 05:27:52.276624Z] Running read-buildbot-config step. 22:27:52 INFO - Running pre-action listener: _resource_record_pre_action 22:27:52 INFO - Running main action method: read_buildbot_config 22:27:52 INFO - Using buildbot properties: 22:27:52 INFO - { 22:27:52 INFO - "project": "", 22:27:52 INFO - "product": "firefox", 22:27:52 INFO - "script_repo_revision": "production", 22:27:52 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 22:27:52 INFO - "repository": "", 22:27:52 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 22:27:52 INFO - "buildid": "20161003203702", 22:27:52 INFO - "pgo_build": "False", 22:27:52 INFO - "basedir": "/builds/slave/test", 22:27:52 INFO - "buildnumber": 345, 22:27:52 INFO - "slavename": "t-yosemite-r7-0097", 22:27:52 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 22:27:52 INFO - "platform": "yosemite_r7", 22:27:52 INFO - "scheduler": "tests-autoland-macosx64-talos", 22:27:52 INFO - "branch": "autoland", 22:27:52 INFO - "repo_path": "integration/autoland", 22:27:52 INFO - "revision": "a9481398f9a76f5510ebf0b974826e423fd5448e", 22:27:52 INFO - "stage_platform": "macosx64", 22:27:52 INFO - "builduid": "7baa3a7f71f44ce9806943decdca824f", 22:27:52 INFO - "slavebuilddir": "test" 22:27:52 INFO - } 22:27:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/W3kOJZY_Sjio8bvdDg6g7A/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 22:27:52 INFO - Running post-action listener: _resource_record_post_action 22:27:52 INFO - [mozharness: 2016-10-04 05:27:52.279996Z] Finished read-buildbot-config step (success) 22:27:52 INFO - [mozharness: 2016-10-04 05:27:52.280101Z] Running download-and-extract step. 22:27:52 INFO - Running pre-action listener: _resource_record_pre_action 22:27:52 INFO - Running main action method: download_and_extract 22:27:52 INFO - mkdir: /builds/slave/test/build/tests 22:27: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')]} 22:27:52 INFO - trying https://queue.taskcluster.net/v1/task/W3kOJZY_Sjio8bvdDg6g7A/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 22:27:52 INFO - Downloading https://queue.taskcluster.net/v1/task/W3kOJZY_Sjio8bvdDg6g7A/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 22:27:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W3kOJZY_Sjio8bvdDg6g7A/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 22:27:53 INFO - Downloaded 1199 bytes. 22:27:53 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 22:27:53 INFO - Using the following test package requirements: 22:27:53 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 22:27:53 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:27:53 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 22:27:53 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:27:53 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 22:27:53 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:27:53 INFO - u'jsshell-mac.zip'], 22:27:53 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:27:53 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 22:27:53 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 22:27:53 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:27:53 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 22:27:53 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:27:53 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 22:27:53 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:27:53 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 22:27:53 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:27:53 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 22:27:53 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:27:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/W3kOJZY_Sjio8bvdDg6g7A/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 22:27:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/W3kOJZY_Sjio8bvdDg6g7A/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:27:53 INFO - Fetch https://queue.taskcluster.net/v1/task/W3kOJZY_Sjio8bvdDg6g7A/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 22:27:53 INFO - Http code: 200 22:27:53 INFO - via: 1.1 ff555a0cc6346f82fe74596f8ed6cc33.cloudfront.net (CloudFront) 22:27:53 INFO - x-amz-cf-id: w7hnpPr4Y1ag7Zovd4YVRX6JHI06pmfbcWEO010kH9_DM0U5APb7Ow== 22:27:53 INFO - x-amz-version-id: AQ8GBbECVhjnJIgHNq3CzqH2orrqH9Ft 22:27:54 INFO - Expected file size: 16476587 22:27:54 INFO - Obtained file size: 16476587 22:28:01 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 22:28:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/W3kOJZY_Sjio8bvdDg6g7A/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 22:28:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/W3kOJZY_Sjio8bvdDg6g7A/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:28:01 INFO - Fetch https://queue.taskcluster.net/v1/task/W3kOJZY_Sjio8bvdDg6g7A/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 22:28:01 INFO - Http code: 200 22:28:01 INFO - via: 1.1 9baadab4e4d95afe7af7bcba342b29c6.cloudfront.net (CloudFront) 22:28:01 INFO - x-amz-cf-id: TGBQ9kLfXBxNCXxBy25JCkPupIVeq51KsUmkeuWjVQKRo-K9c28Oqg== 22:28:01 INFO - x-amz-version-id: AQ8GBbECVhjnJIgHNq3CzqH2orrqH9Ft 22:28:01 INFO - Expected file size: 16476587 22:28:01 INFO - Obtained file size: 16476587 22:28:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/W3kOJZY_Sjio8bvdDg6g7A/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 22:28:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/W3kOJZY_Sjio8bvdDg6g7A/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:28:08 INFO - Fetch https://queue.taskcluster.net/v1/task/W3kOJZY_Sjio8bvdDg6g7A/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 22:28:09 INFO - Http code: 200 22:28:09 INFO - via: 1.1 c54d7f08e2f3dab1918454910cc8aad0.cloudfront.net (CloudFront) 22:28:09 INFO - x-amz-cf-id: 8S265Srukva5Og5ZXqyGeAeNBYCWYjjLK70FZQCYB3Ef0wMfxrGtsQ== 22:28:09 INFO - x-amz-version-id: zgxkF.U.1QVE23ZQy5VYOf5nsWAc4rMy 22:28:10 INFO - Expected file size: 14408522 22:28:10 INFO - Obtained file size: 14408522 22:28:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:28:10 INFO - trying https://queue.taskcluster.net/v1/task/W3kOJZY_Sjio8bvdDg6g7A/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 22:28:10 INFO - Downloading https://queue.taskcluster.net/v1/task/W3kOJZY_Sjio8bvdDg6g7A/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 22:28:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W3kOJZY_Sjio8bvdDg6g7A/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 22:28:13 INFO - Downloaded 111678762 bytes. 22:28:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/W3kOJZY_Sjio8bvdDg6g7A/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 22:28:13 INFO - mkdir: /builds/slave/test/properties 22:28:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:28:13 INFO - Writing to file /builds/slave/test/properties/build_url 22:28:13 INFO - Contents: 22:28:13 INFO - build_url:https://queue.taskcluster.net/v1/task/W3kOJZY_Sjio8bvdDg6g7A/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 22:28:13 INFO - Running post-action listener: _resource_record_post_action 22:28:13 INFO - Running post-action listener: set_extra_try_arguments 22:28:13 INFO - [mozharness: 2016-10-04 05:28:13.904467Z] Finished download-and-extract step (success) 22:28:13 INFO - [mozharness: 2016-10-04 05:28:13.904602Z] Running populate-webroot step. 22:28:13 INFO - Running pre-action listener: _resource_record_pre_action 22:28:13 INFO - Running main action method: populate_webroot 22:28:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:28:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:28:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:28:13 INFO - '--tppagecycles', 22:28:13 INFO - '7'], 22:28:13 INFO - 'tests': ['tsvgm']}}, 22:28:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:28:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:28:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:28:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:28:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:28:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:28:13 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:28:13 INFO - 'talos_options': ['--disable-e10s'], 22:28:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:28:13 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:28:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:28:13 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:28:13 INFO - 'talos_options': ['--disable-e10s'], 22:28:13 INFO - 'tests': ['damp', 'tps']}, 22:28:13 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:28:13 INFO - 'tests': ['damp', 'tps']}, 22:28:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:28:13 INFO - 'tests': ['dromaeo_dom']}, 22:28:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:28:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:28:13 INFO - 'tests': ['basic_compositor_video']}, 22:28:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:28:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:28:13 INFO - 'tests': ['a11yr', 22:28:13 INFO - 'ts_paint', 22:28:13 INFO - 'tpaint', 22:28:13 INFO - 'sessionrestore', 22:28:13 INFO - 'sessionrestore_no_auto_restore', 22:28:13 INFO - 'tabpaint']}, 22:28:13 INFO - 'other-e10s': {'tests': ['a11yr', 22:28:13 INFO - 'ts_paint', 22:28:13 INFO - 'tpaint', 22:28:13 INFO - 'sessionrestore', 22:28:13 INFO - 'sessionrestore_no_auto_restore', 22:28:13 INFO - 'tabpaint']}, 22:28:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:28:13 INFO - 'ts_paint', 22:28:13 INFO - 'tpaint', 22:28:13 INFO - 'sessionrestore', 22:28:13 INFO - 'sessionrestore_no_auto_restore', 22:28:13 INFO - 'tabpaint']}, 22:28:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:28:13 INFO - 'ts_paint', 22:28:13 INFO - 'tpaint', 22:28:13 INFO - 'sessionrestore', 22:28:13 INFO - 'sessionrestore_no_auto_restore', 22:28:13 INFO - 'tabpaint']}, 22:28:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:28:13 INFO - 'tests': ['a11yr', 22:28:13 INFO - 'ts_paint', 22:28:13 INFO - 'tpaint', 22:28:13 INFO - 'sessionrestore', 22:28:13 INFO - 'sessionrestore_no_auto_restore', 22:28:13 INFO - 'tabpaint']}, 22:28:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:28:13 INFO - 'tests': ['a11yr', 22:28:13 INFO - 'ts_paint', 22:28:13 INFO - 'tpaint', 22:28:13 INFO - 'sessionrestore', 22:28:13 INFO - 'sessionrestore_no_auto_restore', 22:28:13 INFO - 'tabpaint']}, 22:28:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:28:13 INFO - 'tests': ['tsvgx', 22:28:13 INFO - 'tsvgr_opacity', 22:28:13 INFO - 'tart', 22:28:13 INFO - 'tscrollx', 22:28:13 INFO - 'cart']}, 22:28:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:28:13 INFO - 'tsvgr_opacity', 22:28:13 INFO - 'tart', 22:28:13 INFO - 'tscrollx', 22:28:13 INFO - 'cart']}, 22:28:13 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:28:13 INFO - 'talos_options': ['--disable-e10s'], 22:28:13 INFO - 'tests': ['tp5o']}, 22:28:13 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:28:13 INFO - 'tests': ['tp5o']}, 22:28:13 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:28:13 INFO - 'talos_options': ['--disable-e10s', 22:28:13 INFO - '--xperf_path', 22:28:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:28:13 INFO - 'tests': ['tp5n']}, 22:28:13 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:28:13 INFO - 'talos_options': ['--xperf_path', 22:28:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:28:13 INFO - 'tests': ['tp5n']}}, 22:28:13 INFO - 'talos.zip': {'path': '', 22:28:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:28:13 INFO - Running post-action listener: _resource_record_post_action 22:28:13 INFO - [mozharness: 2016-10-04 05:28:13.923356Z] Finished populate-webroot step (success) 22:28:13 INFO - [mozharness: 2016-10-04 05:28:13.923473Z] Running create-virtualenv step. 22:28:13 INFO - Running pre-action listener: _resource_record_pre_action 22:28:13 INFO - Running main action method: create_virtualenv 22:28:13 INFO - Creating virtualenv /builds/slave/test/build/venv 22:28: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 22:28:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:28:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:28:14 INFO - Using real prefix '/tools/python27' 22:28:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:28:14 INFO - Installing distribute.............................................................................................................................................................................................done. 22:28:17 INFO - Installing pip.................done. 22:28:17 INFO - Return code: 0 22:28:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:28:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:28:17 INFO - Reading from file tmpfile_stdout 22:28:17 INFO - Output received: 22:28: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) 22:28:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:28: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')]} 22:28:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:28:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:28: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')]} 22:28:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:28:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:28: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 0x10fc12490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb88570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe994a7790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa26d30>, '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 0x10f84cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa1eb20>, '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.kXyTyVPiVf/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.vQKueimyk3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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 22:28: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 22:28: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 22:28:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kXyTyVPiVf/Render', 22:28:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:28:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:28:17 INFO - 'HOME': '/Users/cltbld', 22:28:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:28:17 INFO - 'LOGNAME': 'cltbld', 22:28:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:28:17 INFO - 'MOZ_NO_REMOTE': '1', 22:28:17 INFO - 'NO_EM_RESTART': '1', 22:28:17 INFO - 'PAGER': '/bin/cat', 22:28:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:28:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:28:17 INFO - 'PWD': '/builds/slave/test', 22:28:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:28:17 INFO - 'SHELL': '/bin/bash', 22:28:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vQKueimyk3/Listeners', 22:28:17 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 22:28:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:28:17 INFO - 'USER': 'cltbld', 22:28:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:28:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:28:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:28:17 INFO - 'XPC_FLAGS': '0x0', 22:28:17 INFO - 'XPC_SERVICE_NAME': '0', 22:28:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:28:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:28:18 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 22:28:18 INFO - Cleaning up... 22:28:18 INFO - Return code: 0 22:28:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:28: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')]} 22:28:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:28:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:28: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')]} 22:28:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:28:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:28:18 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 0x10fc12490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb88570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe994a7790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa26d30>, '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 0x10f84cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa1eb20>, '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.kXyTyVPiVf/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.vQKueimyk3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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 22:28:18 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 22:28:18 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 22:28:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kXyTyVPiVf/Render', 22:28:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:28:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:28:18 INFO - 'HOME': '/Users/cltbld', 22:28:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:28:18 INFO - 'LOGNAME': 'cltbld', 22:28:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:28:18 INFO - 'MOZ_NO_REMOTE': '1', 22:28:18 INFO - 'NO_EM_RESTART': '1', 22:28:18 INFO - 'PAGER': '/bin/cat', 22:28:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:28:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:28:18 INFO - 'PWD': '/builds/slave/test', 22:28:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:28:18 INFO - 'SHELL': '/bin/bash', 22:28:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vQKueimyk3/Listeners', 22:28:18 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 22:28:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:28:18 INFO - 'USER': 'cltbld', 22:28:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:28:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:28:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:28:18 INFO - 'XPC_FLAGS': '0x0', 22:28:18 INFO - 'XPC_SERVICE_NAME': '0', 22:28:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:28:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:28:18 INFO - Downloading/unpacking psutil>=3.1.1 22:28:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:28:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:28:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:28:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:28:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:28:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:28:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:28:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:28:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:28:21 INFO - Installing collected packages: psutil 22:28:21 INFO - Running setup.py install for psutil 22:28:21 INFO - building 'psutil._psutil_osx' extension 22:28: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 22:28: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 22:28: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 22:28: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 22:28:22 INFO - building 'psutil._psutil_posix' extension 22:28: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 22:28:22 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:28:22 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:28:22 INFO - ^ 22:28:22 INFO - 1 warning generated. 22:28: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 22:28:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:28:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:28:23 INFO - Successfully installed psutil 22:28:23 INFO - Cleaning up... 22:28:23 INFO - Return code: 0 22:28:23 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:28:23 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')]} 22:28:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:28:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:28:23 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')]} 22:28:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:28:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:28:23 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 0x10fc12490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb88570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe994a7790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa26d30>, '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 0x10f84cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa1eb20>, '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.kXyTyVPiVf/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.vQKueimyk3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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 22:28:23 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 22:28:23 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 22:28:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kXyTyVPiVf/Render', 22:28:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:28:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:28:23 INFO - 'HOME': '/Users/cltbld', 22:28:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:28:23 INFO - 'LOGNAME': 'cltbld', 22:28:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:28:23 INFO - 'MOZ_NO_REMOTE': '1', 22:28:23 INFO - 'NO_EM_RESTART': '1', 22:28:23 INFO - 'PAGER': '/bin/cat', 22:28:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:28:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:28:23 INFO - 'PWD': '/builds/slave/test', 22:28:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:28:23 INFO - 'SHELL': '/bin/bash', 22:28:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vQKueimyk3/Listeners', 22:28:23 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 22:28:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:28:23 INFO - 'USER': 'cltbld', 22:28:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:28:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:28:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:28:23 INFO - 'XPC_FLAGS': '0x0', 22:28:23 INFO - 'XPC_SERVICE_NAME': '0', 22:28:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:28:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:28:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:28:23 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 22:28:23 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 22:28:23 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 22:28:23 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 22:28:23 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 22:28:23 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 22:28:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:28:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:28:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:28:25 INFO - Installing collected packages: mozsystemmonitor 22:28:25 INFO - Running setup.py install for mozsystemmonitor 22:28:26 INFO - Successfully installed mozsystemmonitor 22:28:26 INFO - Cleaning up... 22:28:26 INFO - Return code: 0 22:28:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:28:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:28:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:28:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:28:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:28:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:28:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:28:26 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 0x10fc12490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb88570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe994a7790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa26d30>, '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 0x10f84cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa1eb20>, '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.kXyTyVPiVf/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.vQKueimyk3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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 22:28:26 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 22:28:26 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 22:28:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kXyTyVPiVf/Render', 22:28:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:28:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:28:26 INFO - 'HOME': '/Users/cltbld', 22:28:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:28:26 INFO - 'LOGNAME': 'cltbld', 22:28:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:28:26 INFO - 'MOZ_NO_REMOTE': '1', 22:28:26 INFO - 'NO_EM_RESTART': '1', 22:28:26 INFO - 'PAGER': '/bin/cat', 22:28:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:28:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:28:26 INFO - 'PWD': '/builds/slave/test', 22:28:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:28:26 INFO - 'SHELL': '/bin/bash', 22:28:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vQKueimyk3/Listeners', 22:28:26 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 22:28:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:28:26 INFO - 'USER': 'cltbld', 22:28:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:28:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:28:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:28:26 INFO - 'XPC_FLAGS': '0x0', 22:28:26 INFO - 'XPC_SERVICE_NAME': '0', 22:28:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:28:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:28:26 INFO - Downloading/unpacking jsonschema==2.5.1 22:28: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 22:28: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 22:28: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 22:28: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 22:28: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 22:28: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 22:28:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:28:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:28: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 22:28: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 22:28: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 22:28: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 22:28: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 22:28: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 22:28:29 INFO - Downloading functools32-3.2.3-2.tar.gz 22:28:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:28:29 INFO - warning: no files found matching '*.txt' 22:28:29 INFO - no previously-included directories found matching 'build' 22:28:29 INFO - no previously-included directories found matching 'dist' 22:28:29 INFO - no previously-included directories found matching '.git*' 22:28:29 INFO - Installing collected packages: jsonschema, functools32 22:28:29 INFO - Running setup.py install for functools32 22:28:29 INFO - warning: no files found matching '*.txt' 22:28:29 INFO - no previously-included directories found matching 'build' 22:28:29 INFO - no previously-included directories found matching 'dist' 22:28:29 INFO - no previously-included directories found matching '.git*' 22:28:29 INFO - Successfully installed jsonschema functools32 22:28:29 INFO - Cleaning up... 22:28:29 INFO - Return code: 0 22:28:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:28: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')]} 22:28:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:28:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:28: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')]} 22:28:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:28:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:28: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 0x10fc12490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb88570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe994a7790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa26d30>, '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 0x10f84cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa1eb20>, '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.kXyTyVPiVf/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.vQKueimyk3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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 22:28: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 22:28: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 22:28:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kXyTyVPiVf/Render', 22:28:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:28:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:28:29 INFO - 'HOME': '/Users/cltbld', 22:28:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:28:29 INFO - 'LOGNAME': 'cltbld', 22:28:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:28:29 INFO - 'MOZ_NO_REMOTE': '1', 22:28:29 INFO - 'NO_EM_RESTART': '1', 22:28:29 INFO - 'PAGER': '/bin/cat', 22:28:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:28:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:28:29 INFO - 'PWD': '/builds/slave/test', 22:28:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:28:29 INFO - 'SHELL': '/bin/bash', 22:28:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vQKueimyk3/Listeners', 22:28:29 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 22:28:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:28:29 INFO - 'USER': 'cltbld', 22:28:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:28:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:28:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:28:29 INFO - 'XPC_FLAGS': '0x0', 22:28:29 INFO - 'XPC_SERVICE_NAME': '0', 22:28:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:28:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:28:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:28:29 INFO - Cleaning up... 22:28:29 INFO - Return code: 0 22:28:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:28: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')]} 22:28:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:28:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:28: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')]} 22:28:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:28:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:28: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 0x10fc12490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb88570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe994a7790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa26d30>, '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 0x10f84cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa1eb20>, '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.kXyTyVPiVf/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.vQKueimyk3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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 22:28: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 22:28: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 22:28:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kXyTyVPiVf/Render', 22:28:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:28:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:28:29 INFO - 'HOME': '/Users/cltbld', 22:28:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:28:29 INFO - 'LOGNAME': 'cltbld', 22:28:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:28:29 INFO - 'MOZ_NO_REMOTE': '1', 22:28:29 INFO - 'NO_EM_RESTART': '1', 22:28:29 INFO - 'PAGER': '/bin/cat', 22:28:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:28:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:28:29 INFO - 'PWD': '/builds/slave/test', 22:28:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:28:29 INFO - 'SHELL': '/bin/bash', 22:28:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vQKueimyk3/Listeners', 22:28:29 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 22:28:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:28:29 INFO - 'USER': 'cltbld', 22:28:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:28:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:28:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:28:29 INFO - 'XPC_FLAGS': '0x0', 22:28:29 INFO - 'XPC_SERVICE_NAME': '0', 22:28:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:28:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:28:30 INFO - Downloading/unpacking blobuploader==1.2.4 22:28:30 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 22:28:30 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 22:28:30 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 22:28:30 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 22:28:30 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 22:28:30 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 22:28:32 INFO - Downloading blobuploader-1.2.4.tar.gz 22:28:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:28:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:28: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 22:28: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 22:28: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 22:28: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 22:28: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 22:28: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 22:28:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:28:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:28: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 22:28: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 22:28: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 22:28: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 22:28: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 22:28: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 22:28:33 INFO - Downloading docopt-0.6.1.tar.gz 22:28:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:28:33 INFO - Installing collected packages: blobuploader, requests, docopt 22:28:33 INFO - Running setup.py install for blobuploader 22:28:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:28:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:28:34 INFO - Running setup.py install for requests 22:28:34 INFO - Running setup.py install for docopt 22:28:34 INFO - Successfully installed blobuploader requests docopt 22:28:34 INFO - Cleaning up... 22:28:34 INFO - Return code: 0 22:28:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:28: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')]} 22:28:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:28:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:28: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')]} 22:28:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:28:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:28: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 0x10fc12490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb88570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe994a7790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa26d30>, '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 0x10f84cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa1eb20>, '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.kXyTyVPiVf/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.vQKueimyk3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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 22:28: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 22:28: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 22:28:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kXyTyVPiVf/Render', 22:28:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:28:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:28:34 INFO - 'HOME': '/Users/cltbld', 22:28:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:28:34 INFO - 'LOGNAME': 'cltbld', 22:28:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:28:34 INFO - 'MOZ_NO_REMOTE': '1', 22:28:34 INFO - 'NO_EM_RESTART': '1', 22:28:34 INFO - 'PAGER': '/bin/cat', 22:28:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:28:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:28:34 INFO - 'PWD': '/builds/slave/test', 22:28:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:28:34 INFO - 'SHELL': '/bin/bash', 22:28:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vQKueimyk3/Listeners', 22:28:34 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 22:28:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:28:34 INFO - 'USER': 'cltbld', 22:28:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:28:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:28:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:28:34 INFO - 'XPC_FLAGS': '0x0', 22:28:34 INFO - 'XPC_SERVICE_NAME': '0', 22:28:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:28:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:28:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:28:35 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-u0b978-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:28:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:28:35 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-0REFVd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:28:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:28:35 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-TmUW6H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:28:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:28:35 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-l5jVYb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:28:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:28:35 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-oJuMu5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:28:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:28:35 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-MMOqun-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:28:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:28:35 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-38drzp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:28:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:28:35 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-hBHxTz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:28:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:28:35 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-_noeaC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:28:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:28:35 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-gcmaBq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:28:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:28:36 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-3Uvo9E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:28:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:28:36 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-AwO5mI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:28:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:28:36 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-oHTtlP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:28:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:28:36 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-7nupCI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:28:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:28:36 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-SqL_7Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:28:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:28:36 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-HpOblM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:28:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:28:36 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-aZep3c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:28:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:28:36 INFO - Running setup.py install for manifestparser 22:28:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:28:36 INFO - Running setup.py install for mozcrash 22:28:37 INFO - Running setup.py install for mozdebug 22:28:37 INFO - Running setup.py install for mozdevice 22:28:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:28:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:28:37 INFO - Running setup.py install for mozfile 22:28:37 INFO - Running setup.py install for mozhttpd 22:28:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:28:37 INFO - Running setup.py install for mozinfo 22:28:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:28:37 INFO - Running setup.py install for mozInstall 22:28:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:28:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:28:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:28:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:28:37 INFO - Running setup.py install for mozleak 22:28:38 INFO - Running setup.py install for mozlog 22:28:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:28:38 INFO - Running setup.py install for moznetwork 22:28:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:28:38 INFO - Running setup.py install for mozprocess 22:28:38 INFO - Running setup.py install for mozprofile 22:28:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:28:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:28:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:28:38 INFO - Running setup.py install for mozrunner 22:28:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:28:38 INFO - Running setup.py install for mozscreenshot 22:28:39 INFO - Running setup.py install for moztest 22:28:39 INFO - Running setup.py install for mozversion 22:28:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:28:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:28:39 INFO - Cleaning up... 22:28:39 INFO - Return code: 0 22:28:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:28: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')]} 22:28:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:28:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:28: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')]} 22:28:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:28:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:28: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 0x10fc12490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb88570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe994a7790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa26d30>, '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 0x10f84cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa1eb20>, '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.kXyTyVPiVf/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.vQKueimyk3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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 22:28: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 22:28: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 22:28:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kXyTyVPiVf/Render', 22:28:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:28:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:28:39 INFO - 'HOME': '/Users/cltbld', 22:28:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:28:39 INFO - 'LOGNAME': 'cltbld', 22:28:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:28:39 INFO - 'MOZ_NO_REMOTE': '1', 22:28:39 INFO - 'NO_EM_RESTART': '1', 22:28:39 INFO - 'PAGER': '/bin/cat', 22:28:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:28:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:28:39 INFO - 'PWD': '/builds/slave/test', 22:28:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:28:39 INFO - 'SHELL': '/bin/bash', 22:28:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vQKueimyk3/Listeners', 22:28:39 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 22:28:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:28:39 INFO - 'USER': 'cltbld', 22:28:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:28:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:28:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:28:39 INFO - 'XPC_FLAGS': '0x0', 22:28:39 INFO - 'XPC_SERVICE_NAME': '0', 22:28:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:28:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:28:39 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-QXbR8I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:28: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)) 22:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:28:39 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-0YRLht-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:28:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 22:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:28:39 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-1r1kiH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:28: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)) 22:28:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:28:39 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-hrGk9r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:28:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:28:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:28:40 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-anLDhO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:28: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)) 22:28:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:28:40 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-DbA9J7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:28: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)) 22:28:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:28:40 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-QTSwOX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:28: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)) 22:28:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:28:40 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-gSSl1L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:28: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)) 22:28:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:28:40 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-c6LcKy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:28: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)) 22:28:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:28:40 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-vTOrCe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:28: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)) 22:28:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:28:40 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-yQeB3A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:28: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)) 22:28:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:28:40 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-dU3JZq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:28: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)) 22:28:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:28:40 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-nYc__X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:28:41 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)) 22:28:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:28:41 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-VwbPd8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:28:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 22:28:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:28:41 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-ZoAACq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:28: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)) 22:28:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:28:41 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-caRfWL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:28: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)) 22:28:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:28:41 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-m7YtKg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:28: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)) 22:28: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==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:28: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==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:28: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)) 22:28: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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:28: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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:28:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:28: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 22:28: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 22:28: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 22:28: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 22:28: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 22:28: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 22:28:43 INFO - Downloading blessings-1.6.tar.gz 22:28:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:28:44 INFO - Installing collected packages: blessings 22:28:44 INFO - Running setup.py install for blessings 22:28:44 INFO - Successfully installed blessings 22:28:44 INFO - Cleaning up... 22:28:44 INFO - Return code: 0 22:28:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:28:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:28:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:28:44 INFO - Reading from file tmpfile_stdout 22:28:44 INFO - Current package versions: 22:28:44 INFO - blessings == 1.6 22:28:44 INFO - blobuploader == 1.2.4 22:28:44 INFO - docopt == 0.6.1 22:28:44 INFO - functools32 == 3.2.3-2 22:28:44 INFO - jsonschema == 2.5.1 22:28:44 INFO - manifestparser == 1.1 22:28:44 INFO - mozInstall == 1.12 22:28:44 INFO - mozcrash == 0.17 22:28:44 INFO - mozdebug == 0.1 22:28:44 INFO - mozdevice == 0.48 22:28:44 INFO - mozfile == 1.2 22:28:44 INFO - mozhttpd == 0.7 22:28:44 INFO - mozinfo == 0.9 22:28:44 INFO - mozleak == 0.1 22:28:44 INFO - mozlog == 3.4 22:28:44 INFO - moznetwork == 0.27 22:28:44 INFO - mozprocess == 0.23 22:28:44 INFO - mozprofile == 0.28 22:28:44 INFO - mozrunner == 6.12 22:28:44 INFO - mozscreenshot == 0.1 22:28:44 INFO - mozsystemmonitor == 0.3 22:28:44 INFO - moztest == 0.7 22:28:44 INFO - mozversion == 1.4 22:28:44 INFO - psutil == 3.1.1 22:28:44 INFO - requests == 1.2.3 22:28:44 INFO - wsgiref == 0.1.2 22:28:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:28: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')]} 22:28:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:28:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:28: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')]} 22:28:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:28:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:28: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 0x10fc12490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb88570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe994a7790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa26d30>, '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 0x10f84cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa1eb20>, '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.kXyTyVPiVf/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.vQKueimyk3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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 22:28: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 22:28: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 22:28:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kXyTyVPiVf/Render', 22:28:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:28:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:28:44 INFO - 'HOME': '/Users/cltbld', 22:28:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:28:44 INFO - 'LOGNAME': 'cltbld', 22:28:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:28:44 INFO - 'MOZ_NO_REMOTE': '1', 22:28:44 INFO - 'NO_EM_RESTART': '1', 22:28:44 INFO - 'PAGER': '/bin/cat', 22:28:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:28:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:28:44 INFO - 'PWD': '/builds/slave/test', 22:28:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:28:44 INFO - 'SHELL': '/bin/bash', 22:28:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vQKueimyk3/Listeners', 22:28:44 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 22:28:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:28:44 INFO - 'USER': 'cltbld', 22:28:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:28:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:28:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:28:44 INFO - 'XPC_FLAGS': '0x0', 22:28:44 INFO - 'XPC_SERVICE_NAME': '0', 22:28:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:28:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:28: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)) 22:28: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)) 22:28: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)) 22:28: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)) 22:28: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)) 22:28: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)) 22:28: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)) 22:28: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)) 22:28: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)) 22:28: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)) 22:28: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)) 22:28:44 INFO - Cleaning up... 22:28:44 INFO - Return code: 0 22:28:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:28: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')]} 22:28:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:28:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:28: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')]} 22:28:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:28:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:28: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 0x10fc12490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb88570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe994a7790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa26d30>, '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 0x10f84cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa1eb20>, '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.kXyTyVPiVf/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.vQKueimyk3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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 22:28: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 22:28: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 22:28:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kXyTyVPiVf/Render', 22:28:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:28:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:28:44 INFO - 'HOME': '/Users/cltbld', 22:28:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:28:44 INFO - 'LOGNAME': 'cltbld', 22:28:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:28:44 INFO - 'MOZ_NO_REMOTE': '1', 22:28:44 INFO - 'NO_EM_RESTART': '1', 22:28:44 INFO - 'PAGER': '/bin/cat', 22:28:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:28:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:28:44 INFO - 'PWD': '/builds/slave/test', 22:28:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:28:44 INFO - 'SHELL': '/bin/bash', 22:28:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vQKueimyk3/Listeners', 22:28:44 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 22:28:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:28:44 INFO - 'USER': 'cltbld', 22:28:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:28:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:28:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:28:44 INFO - 'XPC_FLAGS': '0x0', 22:28:44 INFO - 'XPC_SERVICE_NAME': '0', 22:28:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:28:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:28:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:28:45 INFO - Cleaning up... 22:28:45 INFO - Return code: 0 22:28:45 INFO - Running post-action listener: _resource_record_post_action 22:28:45 INFO - Running post-action listener: _start_resource_monitoring 22:28:45 INFO - Starting resource monitoring. 22:28:45 INFO - [mozharness: 2016-10-04 05:28:45.162634Z] Finished create-virtualenv step (success) 22:28:45 INFO - [mozharness: 2016-10-04 05:28:45.163628Z] Running install step. 22:28:45 INFO - Running pre-action listener: _resource_record_pre_action 22:28:45 INFO - Running main action method: install 22:28:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:28:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:28:45 INFO - Reading from file tmpfile_stdout 22:28:45 INFO - Detecting whether we're running mozinstall >=1.0... 22:28:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:28:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:28:45 INFO - Reading from file tmpfile_stdout 22:28:45 INFO - Output received: 22:28:45 INFO - Usage: mozinstall [options] installer 22:28:45 INFO - Options: 22:28:45 INFO - -h, --help show this help message and exit 22:28:45 INFO - -d DEST, --destination=DEST 22:28:45 INFO - Directory to install application into. [default: 22:28:45 INFO - "/builds/slave/test"] 22:28:45 INFO - --app=APP Application being installed. [default: firefox] 22:28:45 INFO - mkdir: /builds/slave/test/build/application 22:28:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 22:28:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 22:29:19 INFO - Reading from file tmpfile_stdout 22:29:19 INFO - Output received: 22:29:19 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:29:19 INFO - Running post-action listener: _resource_record_post_action 22:29:19 INFO - [mozharness: 2016-10-04 05:29:19.771412Z] Finished install step (success) 22:29:19 INFO - [mozharness: 2016-10-04 05:29:19.771541Z] Running run-tests step. 22:29:19 INFO - Running pre-action listener: _resource_record_pre_action 22:29:19 INFO - Running main action method: run_tests 22:29:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:29:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:29:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:29:19 INFO - 2016-10-03 22:29:19.785 screenresolution[1638:7516] starting screenresolution argv=screenresolution get 22:29:19 INFO - 2016-10-03 22:29:19.788 screenresolution[1638:7516] Display 0: 1600x1200x32@60 22:29:19 INFO - 2016-10-03 22:29:19.794 screenresolution[1639:7518] starting screenresolution argv=screenresolution list 22:29:19 INFO - Available Modes on Display 0 22:29:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:29:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:29:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:29:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:29:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:29:20 INFO - Intel Iris: 22:29:20 INFO - Chipset Model: Intel Iris 22:29:20 INFO - Type: GPU 22:29:20 INFO - Bus: Built-In 22:29:20 INFO - VRAM (Dynamic, Max): 1536 MB 22:29:20 INFO - Vendor: Intel (0x8086) 22:29:20 INFO - Device ID: 0x0a2e 22:29:20 INFO - Revision ID: 0x0009 22:29:20 INFO - Displays: 22:29:20 INFO - Display: 22:29:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:29:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:29:20 INFO - Display Serial Number: 1600x1200 60 22:29:20 INFO - Main Display: Yes 22:29:20 INFO - Mirror: Off 22:29:20 INFO - Online: Yes 22:29:20 INFO - Rotation: Supported 22:29:20 INFO - Return code: 0 22:29:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:29:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:29:20 INFO - Python 2.7.3 22:29:20 INFO - Return code: 0 22:29:20 INFO - grabbing minidump binary from tooltool 22:29:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:29:20 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 0x10fa26d30>, '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 0x10f84cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa1eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:29:20 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 22:29:20 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 22:29:20 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 22:29:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:29:20 INFO - Return code: 0 22:29:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:29:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:29:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:29:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:29:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:29:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/W3kOJZY_Sjio8bvdDg6g7A/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0097', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 22:29:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/W3kOJZY_Sjio8bvdDg6g7A/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0097 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 22:29:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kXyTyVPiVf/Render', 22:29:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:29:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:29:20 INFO - 'HOME': '/Users/cltbld', 22:29:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:29:20 INFO - 'LOGNAME': 'cltbld', 22:29:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:29:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:29:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:29:20 INFO - 'MOZ_NO_REMOTE': '1', 22:29:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:29:20 INFO - 'NO_EM_RESTART': '1', 22:29:20 INFO - 'PAGER': '/bin/cat', 22:29:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:29:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:29:20 INFO - 'PWD': '/builds/slave/test', 22:29:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:29:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:29:20 INFO - 'SHELL': '/bin/bash', 22:29:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vQKueimyk3/Listeners', 22:29:20 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 22:29:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:29:20 INFO - 'USER': 'cltbld', 22:29:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:29:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:29:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:29:20 INFO - 'XPC_FLAGS': '0x0', 22:29:20 INFO - 'XPC_SERVICE_NAME': '0', 22:29:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:29:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/W3kOJZY_Sjio8bvdDg6g7A/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0097', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 22:29:20 INFO - mozversion application_buildid: 20161003203702 22:29:20 INFO - mozversion application_changeset: a9481398f9a76f5510ebf0b974826e423fd5448e 22:29:20 INFO - mozversion application_display_name: Nightly 22:29:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:29:20 INFO - mozversion application_name: Firefox 22:29:20 INFO - mozversion application_remotingname: firefox 22:29:20 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 22:29:20 INFO - mozversion application_vendor: Mozilla 22:29:20 INFO - mozversion application_version: 52.0a1 22:29:20 INFO - mozversion platform_buildid: 20161003203702 22:29:20 INFO - mozversion platform_changeset: a9481398f9a76f5510ebf0b974826e423fd5448e 22:29:20 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 22:29:20 INFO - mozversion platform_version: 52.0a1 22:29:20 INFO - using testdate: 1475558960 22:29:20 INFO - actual date: 1475558960 22:29:20 INFO - starting webserver on 'localhost:49277' 22:29:20 INFO - SUITE-START | Running 2 tests 22:29:20 INFO - TEST-START | dromaeo_css 22:29:20 INFO - operating with platform_type : mac_ 22:29:20 INFO - Initialising browser for dromaeo_css test... 22:29:20 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/tmp1DVSki/profile http://localhost:49277/getInfo.html) 22:29:22 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 22:29:22 INFO - PROCESS | 1649 | colorDepth:24 22:29:22 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 22:29:22 INFO - PROCESS | 1649 | __metrics 22:29:22 INFO - PROCESS | 1649 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:29:22 INFO - PROCESS | 1649 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:29:23 INFO - TEST-INFO | 1649: exit 0 22:29:23 INFO - Browser initialized. 22:29:23 INFO - Running cycle 1/1 for dromaeo_css test... 22:29:23 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/tmp1DVSki/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:29:35 INFO - PROCESS | 1651 | RSS: Main: 236134400 22:29:35 INFO - PROCESS | 1651 | 22:30:26 INFO - PROCESS | 1651 | 1475559026103 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] 22:32:39 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 22:32:40 INFO - PROCESS | 1651 | RSS: Main: 382087168 22:32:40 INFO - PROCESS | 1651 | 22:35:43 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 22:35:44 INFO - PROCESS | 1651 | RSS: Main: 373817344 22:35:44 INFO - PROCESS | 1651 | 22:38:46 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 22:38:47 INFO - PROCESS | 1651 | RSS: Main: 336986112 22:38:47 INFO - PROCESS | 1651 | 22:41:49 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 22:41:50 INFO - PROCESS | 1651 | RSS: Main: 342650880 22:41:50 INFO - PROCESS | 1651 | 22:44:53 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 22:44:54 INFO - PROCESS | 1651 | RSS: Main: 332378112 22:44:54 INFO - PROCESS | 1651 | 22:47:58 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 22:47:58 INFO - PROCESS | 1651 | RSS: Main: 387637248 22:47:58 INFO - PROCESS | 1651 | 22:47:58 INFO - PROCESS | 1651 | __start_tp_report 22:47:58 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 22:47:58 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 22:47:58 INFO - PROCESS | 1651 | |i|pagename|runs| 22:47:58 INFO - PROCESS | 1651 | |0;dojo.html;3280.5407242985984;3282.6389097199312;3284.195372133827;3286.34273145371;3335.5997280326364;5528.668279903209;5581.8604534886645;5620.718964051802;5625.831225063244;5658.915116273254;5575.972120139404;5595.972020139904;5678.971605141968;5678.971605141979;5699.287589051369;4603.746793926332;4969.776360063788;5024.522670346311;5059.949400506003;5156.175011998081;4978.054169707762;5167.328247327842;5180.611454140936;5197.636165468419;5225.425203227642;5167.379914410276;5176.249443830661;5197.558207552324;5200.843974680765;5249;5051.469595692436;5103.897922041576;5128.153854613985;5133.8203162889495;5149.176131818928;4239.724417912826;5051.9494805051845;5079.339685840854;5113.53978141969;5180.197069454241;5523.364813046511;5564.86087847803;5604.215409842625;5606.803761868315;5639.887202255973;6040.244969378828;6084.209052823149;6108.7251073701345;6116.877662446771;6125.264968203799;4793.9280910786365;6119.9278719228705;6125.876495711566;6186.783462578788;6215.1609532712955;5703.17303990919;5706;5708.40061793514;5716.228309178291;5740.971295143498;6172.321044685081;6279.874402511924;6299.96850015747;6340.239171299474;6363.713632886531;5950.940490595039;5971.313298970587;5995.46040856325;6069.3020302665755;6113.480354169857;6156.530408773679;6206.224221972254;6207.006878899354;6211.3167551569295;6218.409251121137;6056.606320589148;6097.481714054266;6111.96944015277;6119.3197658210165;6120.571559990847;5996.670183139882;6052.878942421215;6101.3079194897555;6105.511559075263;6106.969465152646;4819.614430845524;4860.027994401134;4860.246661767432;4874.2444921037295;4876.366072410564;4275.315949448073;4352.151330490524;4404.031113155101;4453.688241823042;4465.0400163964905;4734.597559207506;5592.7762889484775;5653.434656534314;5657.660540367591;5659.971700141556;6081.057436097412;6132.906245166601;6211.844703882439;6245.219347581552;6251.53113516479;5604.4395560444445;5641.548676105902;5689.2319536862;5691.9715401423555;5724.055530837449;5334.7065911374475;5342.545883599938;5344.144936810092;5348.03735327637;5364.22218778275;6256.812295631138;6334.809955701337;6337.366263373626;6353.174087368612;6365.267994180728;6094.54290928191;6127.372786728296;6138.449706860676;6176.104464852661;6249.96875015622;6280.9685951569945;6336.081268216175;6342.714577843916;6343.365663433804;6344.048392741126;5107.412647545431;6055.061465472859;6062.636241825328;6123.540734445023;6124.014786914997;6122;6181.443670069715;6188.319284878749;6192.566520343533;6203.503719702323;5747.827565173052;5991.2510936132985;6015.368386319374;6016.278046634432;6035.336113027652;5816.70916454184;5952.8809423812145;5985.832762611249;5990.131430942576;6081.361457046946;4688.780916961683;4875.414950205998;5738.311402631711;5860.501857342174;5871.823845284648;6021.909671354845;6028.4272994065495;6045.335013148462;6060.2424696912885;6061.212042434456;5714.428557144252;5773.191753154646;5820.796272130455;5823.970880145572;5865.120231965068 22:47:58 INFO - PROCESS | 1651 | |1;ext.html;452.66728954218644;479.4893438488011;510.6706174517436;512.0475914798476;514.5986130817961;29968.400631987373;40319;41426.96432589183;41620.58379416205;43399.915002124966;42538.7873060635;42544.78727606365;42611.573884261146;42630.78684606573;42937.7853110734;9560.569774360152;9738.367006144577;9752.756181095494;9755;9763.316567840255;8920.553972301392;9219;9611.855822162674;9706.902930970671;9715;9656.75858103546;9734.756631084208;9739.123478886899;9739.41563506192;9766.902330976705;9606.9519652402;9677.951610241975;9701.854472182922;9750.853737193947;9755.951220243924;7840.275139469308;7895.763127106138;7900.130985591581;7911.248431399008;7919.168487308865;40559.7972010141;40844.79577602123;40933.181336373105;41002.1799564007;41065;3387.7289816814596;3456.4642480415578;3499.0552550811394;3673.779573225615;3920.8039598019986;30278.697213027808;30672;31784.523232151536;31990.360192796015;32204.83897580497;3157.921051973696;3238.4656531672244;3269.6566860479543;3323.418401779679;3354.2788300515263;405097;480353;481686;482104;482793;58323.70838145782;58766;58831;58865.70567147137;58884;56897.14654280189;56952.71523642355;57000;57056.42943570594;57066;15940.760888586681;16148.111853847917;16173.029618222945;16191.514254572381;16193.676126477405;12518.248905065542;12579.559715409916;12585.874141258653;12594.433250503565;12611.432485538173;15628.765568516481;16430.83569164317;16456;16525.6694866102;16704.916475417787;6802.292750451016;6803.523753337219;6813;6827.513354062011;6904.171499420001;881.7002219245487;927.7495076329353;938.474454305591;966.8791401074866;1037.0666400239693;27596.586051209248;28678.569821452278;29291.414171716446;29707.554386684216;30018.148275913754;990.5393991793851;1030.525958059286;1061.9150467962545;1085.4084523934475;1096.9122470202524;14625.610566996118;15376.846231537766;15386.230688465532;15633.452829151152;15741.212939352987;621108;625789;628449;629208;629571;56992;57035.71482142563;57177;57220.71389643025;57221.427785722444;1080.3733834376044;1243.0863315463127;1307.1503522710314;1350.9189448633113;1368.6236285021698;29692.703072969856;30752.077437676904;31002;31708.84145579257;31771;48442.03115937732;49089.754551227015;49113.017739644296;49181.01637967149;49322;529034;532914;533024;533774.3311283418;534762;31502.842485787423;32534;40285.992850178984;41521.584784151775;42254;45788.08423831438;46552;46777.53222467868;46793.2981005292;46981.765091174326;38173.61826381701;38251.426228607146;38280;40085;40291.798541007105;35239.180865531205;37904.24191516209;39056.804715976235;39447.60552394554;39534.604653954244;37196;37257;37274.81362593278;37410.8129459351;37522;32566.674333256364;33785.15537111494;33957.32085358328;34130.31739365248;34211.82894085613;24503.142390016263;24585;24622.876885615457;24890.128845490322;25232.665026955998 22:47:58 INFO - PROCESS | 1651 | |2;jquery.html;350.09500441359086;363.97452178347515;372.35582442374675;377.6223776223776;401.25567073079435;14776.704465910676;14977.625559361022;15577.532674019769;20687.379378618665;20691.689624655643;19033.90483047583;20158.899205503953;20817;20869.68695469569;21082.789172108274;5630.014747419205;5687.431256874311;5982.97008514959;5991.131285963533;5993.220881285427;5889.528837692996;5974.402559744034;6038.637681739087;6043.214382130339;6047.788327408542;4119.423280740699;4347.78261086946;5773.740181691813;5987.461128498434;6087.20866287382;6100.481459075984;6110.847228819271;6151;6154.907676384858;6229.563930524865;5740.282464691913;5791.160281759164;5792.768289268424;5798.768049278024;5800.709964501758;22262.88868555663;22635.886820565793;23597.174098906627;23758.524829503487;26145.607815882777;2070.730804995341;2205.878676672782;2517.446161844391;2586.935326616831;2729.4541091781725;9895.356801807931;9917.107460328605;9988.95005524975;10422.062014418738;10466.581336746522;1761.4803632928317;1821.726740988849;1833.3949796567233;1857.3963461874944;1891.9905400473046;133310;133496.66503335038;133574.6642533572;140103;140714.99999999898;18422.723659145122;19653.410397687807;20500.589988200452;20999.265025724028;21134.788652113282;33244.51199088141;33337.99986000424;33341;33414.16464588405;33422.66577334244;10334.431606261727;10495.52770125331;10529.84205236922;10724.89275107239;10865.728356790985;7117.359437650636;7428.762722280779;8697.086805885418;8744.300455963508;8912.955435222782;9280.582373793195;9442.339036267533;9516.38143520669;9555.9044409555;9569.856452153223;3003.5494675798805;3284.6861255497993;3579.8544465771224;3642.18050938542;3663.7618554794385;833.5332213960202;918.237862574062;936.9390989585792;956.364017928084;979.7109852593502;15455.995360301544;21181.94090295479;21451.14195432165;21455.927203639756;21573.136616591702;722.9457790665721;887.5695287785419;894.9350273174902;938.2447130060367;991.3209451525728;10283.43441110746;10780.946095269474;11150.330980141218;11177.44112794357;11254.549818007352;538558;560249.1987540118;584432;601644;604269.6157345772;113581.43209283901;114880;115410.42294788473;132908;137924;959.5777857742571;977.3061126600194;985.0641890204191;1000;1000.8548760429843;15884.68230635404;19708.802911970695;19828.306009290183;19830.10764515629;19891.701624475932;44007.77996109999;46203;55962;56153.03464378727;56244.718776405854;81833;83612;84871.45385638441;85123.50832637514;86892;6611.570247933869;7226.74706385295;8078.272955434039;8230.42387032902;8379.45533540318;10815.242932994615;13020.739585208054;13456.798148027983;13549.983751218895;13713.725725485634;10856.294340868135;10880.238383313408;11361.54553817838;11632.941835290769;11680.766384672428;10387.480625968521;10495.790084198426;11343.773124537629;11593.18847680688;11891.227070240753;9996.850047249152;11683.824742628698;11767.88232117668;11921.225120367148;11923;7793.805154871174;8027.2775450209765;8032.116467188488;8495.235428811437;8524.488530688179;6864.759733409307;9673.274504412339;11395.943020284845;11526.07791376671;11628.418579071182 22:47:58 INFO - PROCESS | 1651 | |3;mootools.html;1341.7722783652957;1567.9999999999995;1574.3151728997886;1770.035330744744;1790.862802120654;7898.802529936755;8393.07676155623;8728.650853965843;8997.865032024525;9010.27917766577;8845.513496757676;8943.64225430982;8953.373263871532;8953.86569201461;8960.372773905812;2327.674125622414;2368.3250273672006;2369.763023697634;2371.2530552875787;2379.1791831817995;1929.3729537900238;2320.5938960681897;2357.976420235793;2362;2363.161077817376;2109.535902101543;2200;2328.196772113618;2362.0669835415;2364.609839376501;2303.861768293891;2319.18828410056;2326.6510023496444;2326.9650955235684;2331.0326214620914;2311.526137141882;2331.9067237310487;2332.825038122148;2333.171724037976;2335.1243283768586;8857.689980850639;9041.276697864156;9090.545472726404;9104.271658267322;9115.088491150831;5249.737513124329;5453.809116680897;5462.972685136589;5468.207109969032;5472.398036216013;3132.5457808617853;3566.5198942438856;3629.727770417203;3669.94495082574;3708.369577171887;1072.8390741388855;1089.3627228071587;1093.4532733633184;1096.4901320885747;1110.1618278199987;11921.165518413714;11939.985101266402;11999.040076793835;12002.759944801055;12005.639830805003;3598.8020658864016;3792.3173828710546;4305.892352691145;4576.6109880660515;4578.427696537933;7772;9237.261019118454;9241.399309044891;9242;9250.90749092514;4462.464504259445;5278.762455689503;5279.340082489689;5279.41926388097;5285.867853303621;4049.5545489996075;4429.68992170545;4505.549445055468;4604.194266003436;4985.4266759323145;3638.2541578976247;3692.7968961707365;3740.625937406292;3791.848326066982;3825.311443940062;1163.4124766992784;1167.2237961755507;1168.8422063021553;1176.5117476247433;1178.8997935175435;821.5358322547742;835.1398059998212;864.9394542382099;910.4264313482464;958.4441024205944;4447.866564003085;5721.342045664802;5936.436038576328;6042.667653279024;6048.243969503812;845.0031351706341;921.6037104045325;928.456852741154;953.3326671330096;964.7636329099415;3317.419451595961;3953.367461206193;4033.6773058155277;4073.9388909166387;4074.8573799917453;10804.459777011118;11105.666829995112;11186.664400068012;11221.495032723547;11239.55041798319;10062.798744025078;10490.947545262225;10931.508082136323;11171.32972021665;11195.832062519068;8131.512109273463;8183.754487365389;8215.260626543639;8426.031006434216;8470.788230294169;5305.575553955751;5721;5837.182794408871;5991.910121348096;6046.758129674764;9934;10157.644482443078;10465.633702820365;10494.370337779757;10512.789744205225;9592.9040709592;10244.385336879812;10574.259801814107;10604.416757078126;10613.09788667972;7037.296270372922;7257;7421.628918554159;7569.6593653284635;7707.614619268902;8299.08710041907;8370.414071014971;8404.579771011548;8460.915390846012;8480.32157427392;7528.134264559427;7584.810379740551;7622.69509219625;7633.389328853567;7687.461877668509;5808.477237048517;7400.5189662671755;7454.850902982019;7497.437692173;7635.23647635232;6853.383195512428;6863.078338065349;6939.514234003773;6991.440684745106;6991.937378473261;4882.4629290777275;4928.334674818853;4965.776540055634;5045.217991211368;5178.585713143015;4336.913261734685;4967.627427942846;5001.924971125363;5061.443241243386;5109.974450127875 22:47:58 INFO - PROCESS | 1651 | |4;prototype.html;296.3983114278016;298.4240415997126;306.6473555411277;310.5264471680688;314.22229980797545;5256.632035757494;5286.418493965665;5290.947090529094;5316.787328506855;5330.946690533094;4008.4588580541617;4347.500037495689;4748.723380867199;5082.923756143651;5213.400458947222;3331.0506505645894;3540.7516227195724;3594.454384614615;3737.9065523361996;3810.2569998850236;3983.4821473208444;3989.9601003990024;3992.3213053780783;3998.620131087542;4041.4746083009313;3755.5681096673957;3971.86098486553;3977.264206121862;3991.42124391963;4002.779847108408;3471.89584312471;3974.3641017437362;4013.5585085640555;4022.5374081980654;4037.9596204038025;3088.3051313454293;3245.415825151472;3443.6212016678396;3469.531613232232;3472.4270495368232;5158.174692049254;5159.974200128975;5207.463798179509;5217.791288348461;5227.581793456515;4281.357796330544;4545;4560.589546940758;4577.816887324503;4602.723836569817;4572.748498832563;5056.91276375581;5147.974260128675;5508.749837595838;5520.66875987442;3397.456406974872;3762.473253744478;3766.6045065268027;3788.507494025759;3808.371618682914;5011.523905228997;5600.887982240332;5810.767569297223;5836.299644042742;5861.0915308127305;3824.7131465140224;3831.3525184170517;3849.268638958589;3857.96142038576;3875.8643447479203;3548.716102711777;3643.9453408198897;3877.3990031545154;3903.277893589638;3926.6269704378037;4339.63113135387;4548.931766023512;4549.909001819945;4559.609319157623;4582.679212455163;4845.53967373099;4873.805047798049;5371.811986580451;5583.692896890629;5608;1109.7003808971533;1301.1932601786953;1320.9762434113638;1325.8597606058781;1329.654289884637;790.8497385496737;794.9046114466302;821.4351660087543;846.3398549131687;882.7395918204145;4551.908961820811;4703.717776933395;4978.8257410990445;5119.718415487184;5171.4311425743135;5610.663360198401;5614.831555053355;5627.649364152574;5656.886862262814;5713.685747283939;4369.410129632459;4441.578050085237;4650.558196971282;4704.341392205094;4991.850244492671;2383.74970628085;2925.5611658251432;2944.3522425066453;2958.926026849346;2962.3482833776534;5076.6446348755235;5277.894442111136;5791.247137872049;5810.854728631733;5812.534997200214;4285.100128972888;5144.91956689092;5249.005377956352;5393.406725260218;5704.600677952503;4974;5692.573057020657;5768.423157684198;5797.391273916262;5842.707864606753;4402.713823601455;4724.551167639069;5577.358603760457;5594.41258667834;5640.407757185437;4647.395838540968;5489.917651235155;5533.335999680065;5550.222968784454;5556.333240011225;4854.95145048545;4871.44113883568;5317.760700768397;5779.8555036124435;5843.444872737083;3540.2211513466996;3728.0241472019875;3739.2895349883433;3768.773873567595;3770.1517158639085;4640.930386044144;4667.649926255476;5123.487651234846;5632.436756324335;5643.915341269966;4795.664303498722;4994.600431965361;5407.297051383295;5464.28964234647;5464.726763661881;5185.585153187812;5388.757505912165;5445.455454454681;5464.060258608137;5498.477644623755;4861.246506791453;4896.4124305083615;4925.916298414343;5055.469175736642;5309.309789727311;4580.083983203306;5369;5481.766602514403;5491.533219676213;5497.945020549903;4910.975445122752;5205.984832957537;5436.906790885459;5437.646552974044;5452.890942181055 22:47:58 INFO - PROCESS | 1651 | |5;yui.html;704.73220176333;720.5568575326176;728.202618133144;731.1190016030683;775.5075527040327;15278.541643750692;15859.127747973856;15892.84107158928;15898.841011589895;15899.920500397511;14149.646258843533;14287.571372858805;14304.213268270241;14306.785398219035;14341.56975290743;660.5178219899476;682.8941514065327;684.4626967830254;824.3940703582862;839.4249938791917;826.2604968553142;843.9873401898976;853.8377708235446;854.9230569248766;861.8448679237734;911.1844898815556;913.5614904845678;914.8764916736255;915.0209276074603;916.5554705967601;583.600233839821;693.1024323501078;908.6501696846728;910.2126660438739;912.3066469483174;647.4205586000503;660.7026837922906;662.3178126529682;665.8002599220263;672.9495287853431;15151.318190681446;15177.317020734094;15181.16503592313;15186.240687965557;15228.390864365412;12660.493580256778;13718.971077169252;13746.862531374758;14115.435382584685;14500.637484062878;13045.152065115737;13055.21668699881;13147.408366623526;13238.470461181541;13266.602001939957;4433.202023635744;4480.372747815309;4555.202839503107;4702.200625893606;4708.4820669726305;109386;111319.88680113177;111805;113502.43248783785;114384;9083.45499270046;11099.38953357557;11194.944025279821;11199.272047317061;11473.942630286962;21775.564488710137;22572.88713556422;22578.774212257995;22597.661035084486;22644.886775566018;7698.461107722517;7800.84398312042;7901.209879012168;8040.678372865137;8126.065502467174;4339.609435150788;4366.956330436718;4414.713043652152;4434.800433980478;4504.684672072989;3342.5988881334397;3396.847141878621;3463.0476618929492;3470.65293470656;3488.1977145256733;622.6544267931341;626.9561130720806;640.2445114764529;648.7891435283552;650.5023656902379;534.8181618249814;565.1833101168771;594.8542607061298;614.7878981751288;620.8013435700623;8311.6675332986;9082.909513937562;9105.68130115456;9184.63261469547;9261.259099272042;568.091687817251;594.2927915780208;629.8897692903723;630.5531670965908;630.9526785491106;4298;4562.266338791238;4575.81696732127;4910.8234789076505;5086.440491545927;124758;142818;143758.2181336383;157244;163586;79260.60369698114;79345.20654793378;112307;114831;132803;31737.365252694355;31750.682493174772;33283.500747488804;111536.44231778951;113889;9233.630654773733;9940.602375904882;9948.253880959102;9975.900240997496;9993.500324983575;47362;51050;56648.300550983535;58419.4158058431;65170.67414662896;93326;94910;95685;99647.50176249072;100748;13295.80056299137;13369;13369.933150334185;13442.327883605976;13504.324783760576;14473.92763036178;14618.926905365404;15021.624459388602;15062.698746025237;15385.923070385024;13543.187408755506;13958;13974.930125349309;14018.509352172625;14114.50599229022;13707.794383084462;13794.310284485937;14268.215248161036;14418.495352662607;14438;13341.066125371131;13455.932720336335;13663.931680341535;13824.447022119002;14113.435462581792;11391.430428478709;11492;11671.59149429766;11683;11707.88292117068;11223.03739181247;11398;11619.767604648028;11634.418279085843;11663.650090497298 22:47:58 INFO - PROCESS | 1651 | __end_tp_report 22:47:58 INFO - PROCESS | 1651 | __start_cc_report 22:47:58 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,1498 22:47:58 INFO - PROCESS | 1651 | __end_cc_report 22:47:58 INFO - PROCESS | 1651 | __startTimestamp1475560078417__endTimestamp 22:47:58 INFO - PROCESS | 1651 | 22:47:58 INFO - PROCESS | 1651 | ------- Summary: start ------- 22:47:58 INFO - PROCESS | 1651 | Number of tests: 6 22:47:58 INFO - PROCESS | 1651 | 22:47:58 INFO - PROCESS | 1651 | [#0] dojo.html Cycles:165 Average:5631.68 Median:5773.19 stddev:663.21 (11.5%) stddev-sans-first:639.09 22:47:58 INFO - PROCESS | 1651 | Values: 3280.5 3282.6 3284.2 3286.3 3335.6 5528.7 5581.9 5620.7 5625.8 5658.9 5576.0 5596.0 5679.0 5679.0 5699.3 4603.7 4969.8 5024.5 5059.9 5156.2 4978.1 5167.3 5180.6 5197.6 5225.4 5167.4 5176.2 5197.6 5200.8 5249.0 5051.5 5103.9 5128.2 5133.8 5149.2 4239.7 5051.9 5079.3 5113.5 5180.2 5523.4 5564.9 5604.2 5606.8 5639.9 6040.2 6084.2 6108.7 6116.9 6125.3 4793.9 6119.9 6125.9 6186.8 6215.2 5703.2 5706.0 5708.4 5716.2 5741.0 6172.3 6279.9 6300.0 6340.2 6363.7 5950.9 5971.3 5995.5 6069.3 6113.5 6156.5 6206.2 6207.0 6211.3 6218.4 6056.6 6097.5 6112.0 6119.3 6120.6 5996.7 6052.9 6101.3 6105.5 6107.0 4819.6 4860.0 4860.2 4874.2 4876.4 4275.3 4352.2 4404.0 4453.7 4465.0 4734.6 5592.8 5653.4 5657.7 5660.0 6081.1 6132.9 6211.8 6245.2 6251.5 5604.4 5641.5 5689.2 5692.0 5724.1 5334.7 5342.5 5344.1 5348.0 5364.2 6256.8 6334.8 6337.4 6353.2 6365.3 6094.5 6127.4 6138.4 6176.1 6250.0 6281.0 6336.1 6342.7 6343.4 6344.0 5107.4 6055.1 6062.6 6123.5 6124.0 6122.0 6181.4 6188.3 6192.6 6203.5 5747.8 5991.3 6015.4 6016.3 6035.3 5816.7 5952.9 5985.8 5990.1 6081.4 4688.8 4875.4 5738.3 5860.5 5871.8 6021.9 6028.4 6045.3 6060.2 6061.2 5714.4 5773.2 5820.8 5824.0 5865.1 22:47:58 INFO - PROCESS | 1651 | 22:47:58 INFO - PROCESS | 1651 | [#1] ext.html Cycles:180 Average:67881.74 Median:41245.98 stddev:145766.43 (353.4%) stddev-sans-first:146087.43 22:47:58 INFO - PROCESS | 1651 | Values: 452.7 479.5 510.7 512.0 514.6 29968.4 40319.0 41427.0 41620.6 43399.9 42538.8 42544.8 42611.6 42630.8 42937.8 9560.6 9738.4 9752.8 9755.0 9763.3 8920.6 9219.0 9611.9 9706.9 9715.0 9656.8 9734.8 9739.1 9739.4 9766.9 9607.0 9678.0 9701.9 9750.9 9756.0 7840.3 7895.8 7900.1 7911.2 7919.2 40559.8 40844.8 40933.2 41002.2 41065.0 3387.7 3456.5 3499.1 3673.8 3920.8 30278.7 30672.0 31784.5 31990.4 32204.8 3157.9 3238.5 3269.7 3323.4 3354.3 405097.0 480353.0 481686.0 482104.0 482793.0 58323.7 58766.0 58831.0 58865.7 58884.0 56897.1 56952.7 57000.0 57056.4 57066.0 15940.8 16148.1 16173.0 16191.5 16193.7 12518.2 12579.6 12585.9 12594.4 12611.4 15628.8 16430.8 16456.0 16525.7 16704.9 6802.3 6803.5 6813.0 6827.5 6904.2 881.7 927.7 938.5 966.9 1037.1 27596.6 28678.6 29291.4 29707.6 30018.1 990.5 1030.5 1061.9 1085.4 1096.9 14625.6 15376.8 15386.2 15633.5 15741.2 621108.0 625789.0 628449.0 629208.0 629571.0 56992.0 57035.7 57177.0 57220.7 57221.4 1080.4 1243.1 1307.2 1350.9 1368.6 29692.7 30752.1 31002.0 31708.8 31771.0 48442.0 49089.8 49113.0 49181.0 49322.0 529034.0 532914.0 533024.0 533774.3 534762.0 31502.8 32534.0 40286.0 41521.6 42254.0 45788.1 46552.0 46777.5 46793.3 46981.8 38173.6 38251.4 38280.0 40085.0 40291.8 35239.2 37904.2 39056.8 39447.6 39534.6 37196.0 37257.0 37274.8 37410.8 37522.0 32566.7 33785.2 33957.3 34130.3 34211.8 24503.1 24585.0 24622.9 24890.1 25232.7 22:47:58 INFO - PROCESS | 1651 | 22:47:58 INFO - PROCESS | 1651 | [#2] jquery.html Cycles:180 Average:35818.56 Median:33377.58 stddev:97026.51 (290.7%) stddev-sans-first:97262.14 22:47:58 INFO - PROCESS | 1651 | Values: 350.1 364.0 372.4 377.6 401.3 14776.7 14977.6 15577.5 20687.4 20691.7 19033.9 20158.9 20817.0 20869.7 21082.8 5630.0 5687.4 5983.0 5991.1 5993.2 5889.5 5974.4 6038.6 6043.2 6047.8 4119.4 4347.8 5773.7 5987.5 6087.2 6100.5 6110.8 6151.0 6154.9 6229.6 5740.3 5791.2 5792.8 5798.8 5800.7 22262.9 22635.9 23597.2 23758.5 26145.6 2070.7 2205.9 2517.4 2586.9 2729.5 9895.4 9917.1 9989.0 10422.1 10466.6 1761.5 1821.7 1833.4 1857.4 1892.0 133310.0 133496.7 133574.7 140103.0 140715.0 18422.7 19653.4 20500.6 20999.3 21134.8 33244.5 33338.0 33341.0 33414.2 33422.7 10334.4 10495.5 10529.8 10724.9 10865.7 7117.4 7428.8 8697.1 8744.3 8913.0 9280.6 9442.3 9516.4 9555.9 9569.9 3003.5 3284.7 3579.9 3642.2 3663.8 833.5 918.2 936.9 956.4 979.7 15456.0 21181.9 21451.1 21455.9 21573.1 722.9 887.6 894.9 938.2 991.3 10283.4 10780.9 11150.3 11177.4 11254.5 538558.0 560249.2 584432.0 601644.0 604269.6 113581.4 114880.0 115410.4 132908.0 137924.0 959.6 977.3 985.1 1000.0 1000.9 15884.7 19708.8 19828.3 19830.1 19891.7 44007.8 46203.0 55962.0 56153.0 56244.7 81833.0 83612.0 84871.5 85123.5 86892.0 6611.6 7226.7 8078.3 8230.4 8379.5 10815.2 13020.7 13456.8 13550.0 13713.7 10856.3 10880.2 11361.5 11632.9 11680.8 10387.5 10495.8 11343.8 11593.2 11891.2 9996.9 11683.8 11767.9 11921.2 11923.0 7793.8 8027.3 8032.1 8495.2 8524.5 6864.8 9673.3 11395.9 11526.1 11628.4 22:47:58 INFO - PROCESS | 1651 | 22:47:58 INFO - PROCESS | 1651 | [#3] mootools.html Cycles:180 Average:5687.31 Median:4905.40 stddev:3272.93 (66.7%) stddev-sans-first:3265.82 22:47:58 INFO - PROCESS | 1651 | Values: 1341.8 1568.0 1574.3 1770.0 1790.9 7898.8 8393.1 8728.7 8997.9 9010.3 8845.5 8943.6 8953.4 8953.9 8960.4 2327.7 2368.3 2369.8 2371.3 2379.2 1929.4 2320.6 2358.0 2362.0 2363.2 2109.5 2200.0 2328.2 2362.1 2364.6 2303.9 2319.2 2326.7 2327.0 2331.0 2311.5 2331.9 2332.8 2333.2 2335.1 8857.7 9041.3 9090.5 9104.3 9115.1 5249.7 5453.8 5463.0 5468.2 5472.4 3132.5 3566.5 3629.7 3669.9 3708.4 1072.8 1089.4 1093.5 1096.5 1110.2 11921.2 11940.0 11999.0 12002.8 12005.6 3598.8 3792.3 4305.9 4576.6 4578.4 7772.0 9237.3 9241.4 9242.0 9250.9 4462.5 5278.8 5279.3 5279.4 5285.9 4049.6 4429.7 4505.5 4604.2 4985.4 3638.3 3692.8 3740.6 3791.8 3825.3 1163.4 1167.2 1168.8 1176.5 1178.9 821.5 835.1 864.9 910.4 958.4 4447.9 5721.3 5936.4 6042.7 6048.2 845.0 921.6 928.5 953.3 964.8 3317.4 3953.4 4033.7 4073.9 4074.9 10804.5 11105.7 11186.7 11221.5 11239.6 10062.8 10490.9 10931.5 11171.3 11195.8 8131.5 8183.8 8215.3 8426.0 8470.8 5305.6 5721.0 5837.2 5991.9 6046.8 9934.0 10157.6 10465.6 10494.4 10512.8 9592.9 10244.4 10574.3 10604.4 10613.1 7037.3 7257.0 7421.6 7569.7 7707.6 8299.1 8370.4 8404.6 8460.9 8480.3 7528.1 7584.8 7622.7 7633.4 7687.5 5808.5 7400.5 7454.9 7497.4 7635.2 6853.4 6863.1 6939.5 6991.4 6991.9 4882.5 4928.3 4965.8 5045.2 5178.6 4336.9 4967.6 5001.9 5061.4 5110.0 22:47:58 INFO - PROCESS | 1651 | 22:47:58 INFO - PROCESS | 1651 | [#4] prototype.html Cycles:180 Average:4390.27 Median:4866.34 stddev:1339.99 (27.5%) stddev-sans-first:1308.04 22:47:58 INFO - PROCESS | 1651 | Values: 296.4 298.4 306.6 310.5 314.2 5256.6 5286.4 5290.9 5316.8 5330.9 4008.5 4347.5 4748.7 5082.9 5213.4 3331.1 3540.8 3594.5 3737.9 3810.3 3983.5 3990.0 3992.3 3998.6 4041.5 3755.6 3971.9 3977.3 3991.4 4002.8 3471.9 3974.4 4013.6 4022.5 4038.0 3088.3 3245.4 3443.6 3469.5 3472.4 5158.2 5160.0 5207.5 5217.8 5227.6 4281.4 4545.0 4560.6 4577.8 4602.7 4572.7 5056.9 5148.0 5508.7 5520.7 3397.5 3762.5 3766.6 3788.5 3808.4 5011.5 5600.9 5810.8 5836.3 5861.1 3824.7 3831.4 3849.3 3858.0 3875.9 3548.7 3643.9 3877.4 3903.3 3926.6 4339.6 4548.9 4549.9 4559.6 4582.7 4845.5 4873.8 5371.8 5583.7 5608.0 1109.7 1301.2 1321.0 1325.9 1329.7 790.8 794.9 821.4 846.3 882.7 4551.9 4703.7 4978.8 5119.7 5171.4 5610.7 5614.8 5627.6 5656.9 5713.7 4369.4 4441.6 4650.6 4704.3 4991.9 2383.7 2925.6 2944.4 2958.9 2962.3 5076.6 5277.9 5791.2 5810.9 5812.5 4285.1 5144.9 5249.0 5393.4 5704.6 4974.0 5692.6 5768.4 5797.4 5842.7 4402.7 4724.6 5577.4 5594.4 5640.4 4647.4 5489.9 5533.3 5550.2 5556.3 4855.0 4871.4 5317.8 5779.9 5843.4 3540.2 3728.0 3739.3 3768.8 3770.2 4640.9 4667.6 5123.5 5632.4 5643.9 4795.7 4994.6 5407.3 5464.3 5464.7 5185.6 5388.8 5445.5 5464.1 5498.5 4861.2 4896.4 4925.9 5055.5 5309.3 4580.1 5369.0 5481.8 5491.5 5497.9 4911.0 5206.0 5436.9 5437.6 5452.9 22:47:58 INFO - PROCESS | 1651 | 22:47:58 INFO - PROCESS | 1651 | [#5] yui.html Cycles:180 Average:23003.28 Median:3466.85 stddev:36546.86 (1054.2%) stddev-sans-first:36611.03 22:47:58 INFO - PROCESS | 1651 | Values: 704.7 720.6 728.2 731.1 775.5 15278.5 15859.1 15892.8 15898.8 15899.9 14149.6 14287.6 14304.2 14306.8 14341.6 660.5 682.9 684.5 824.4 839.4 826.3 844.0 853.8 854.9 861.8 911.2 913.6 914.9 915.0 916.6 583.6 693.1 908.7 910.2 912.3 647.4 660.7 662.3 665.8 672.9 15151.3 15177.3 15181.2 15186.2 15228.4 12660.5 13719.0 13746.9 14115.4 14500.6 13045.2 13055.2 13147.4 13238.5 13266.6 4433.2 4480.4 4555.2 4702.2 4708.5 109386.0 111319.9 111805.0 113502.4 114384.0 9083.5 11099.4 11194.9 11199.3 11473.9 21775.6 22572.9 22578.8 22597.7 22644.9 7698.5 7800.8 7901.2 8040.7 8126.1 4339.6 4367.0 4414.7 4434.8 4504.7 3342.6 3396.8 3463.0 3470.7 3488.2 622.7 627.0 640.2 648.8 650.5 534.8 565.2 594.9 614.8 620.8 8311.7 9082.9 9105.7 9184.6 9261.3 568.1 594.3 629.9 630.6 631.0 4298.0 4562.3 4575.8 4910.8 5086.4 124758.0 142818.0 143758.2 157244.0 163586.0 79260.6 79345.2 112307.0 114831.0 132803.0 31737.4 31750.7 33283.5 111536.4 113889.0 9233.6 9940.6 9948.3 9975.9 9993.5 47362.0 51050.0 56648.3 58419.4 65170.7 93326.0 94910.0 95685.0 99647.5 100748.0 13295.8 13369.0 13369.9 13442.3 13504.3 14473.9 14618.9 15021.6 15062.7 15385.9 13543.2 13958.0 13974.9 14018.5 14114.5 13707.8 13794.3 14268.2 14418.5 14438.0 13341.1 13455.9 13663.9 13824.4 14113.4 11391.4 11492.0 11671.6 11683.0 11707.9 11223.0 11398.0 11619.8 11634.4 11663.7 22:47:58 INFO - PROCESS | 1651 | -------- Summary: end -------- 22:47:58 INFO - PROCESS | 1651 | 22:47:59 INFO - TEST-INFO | 1651: exit 0 22:47:59 INFO - TEST-OK | dromaeo_css | took 1118669ms 22:47:59 INFO - TEST-START | kraken 22:47:59 INFO - operating with platform_type : mac_ 22:47:59 INFO - Initialising browser for kraken test... 22:47:59 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/tmpI3JNO_/profile http://localhost:49277/getInfo.html) 22:48:01 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 22:48:01 INFO - PROCESS | 1662 | colorDepth:24 22:48:01 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 22:48:01 INFO - PROCESS | 1662 | __metrics 22:48:01 INFO - PROCESS | 1662 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:48:01 INFO - PROCESS | 1662 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:48:02 INFO - TEST-INFO | 1662: exit 0 22:48:02 INFO - Browser initialized. 22:48:02 INFO - Running cycle 1/1 for kraken test... 22:48:02 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/tmpI3JNO_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:48:14 INFO - PROCESS | 1663 | RSS: Main: 236281856 22:48:14 INFO - PROCESS | 1663 | 22:48:16 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 22:48:17 INFO - PROCESS | 1663 | RSS: Main: 294580224 22:48:17 INFO - PROCESS | 1663 | 22:48:20 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 22:48:21 INFO - PROCESS | 1663 | RSS: Main: 295079936 22:48:21 INFO - PROCESS | 1663 | 22:48:24 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 22:48:25 INFO - PROCESS | 1663 | RSS: Main: 386572288 22:48:25 INFO - PROCESS | 1663 | 22:48:27 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 22:48:27 INFO - PROCESS | 1663 | RSS: Main: 285736960 22:48:27 INFO - PROCESS | 1663 | 22:48:29 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:48:30 INFO - PROCESS | 1663 | RSS: Main: 289923072 22:48:30 INFO - PROCESS | 1663 | 22:48:33 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 22:48:33 INFO - PROCESS | 1663 | RSS: Main: 328224768 22:48:33 INFO - PROCESS | 1663 | 22:48:36 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 22:48:37 INFO - PROCESS | 1663 | RSS: Main: 327761920 22:48:37 INFO - PROCESS | 1663 | 22:48:40 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 22:48:40 INFO - PROCESS | 1663 | RSS: Main: 326914048 22:48:40 INFO - PROCESS | 1663 | 22:48:42 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:48:42 INFO - PROCESS | 1663 | RSS: Main: 303820800 22:48:42 INFO - PROCESS | 1663 | 22:48:44 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:48:45 INFO - PROCESS | 1663 | RSS: Main: 307204096 22:48:45 INFO - PROCESS | 1663 | 22:48:47 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:48:48 INFO - PROCESS | 1663 | RSS: Main: 310702080 22:48:48 INFO - PROCESS | 1663 | 22:48:51 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:48:52 INFO - PROCESS | 1663 | RSS: Main: 319844352 22:48:52 INFO - PROCESS | 1663 | 22:48:55 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:48:55 INFO - PROCESS | 1663 | RSS: Main: 320307200 22:48:55 INFO - PROCESS | 1663 | 22:48:57 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 22:48:57 INFO - PROCESS | 1663 | RSS: Main: 363655168 22:48:57 INFO - PROCESS | 1663 | 22:48:57 INFO - PROCESS | 1663 | __start_tp_report 22:48:57 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 22:48:57 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 22:48:57 INFO - PROCESS | 1663 | |i|pagename|runs| 22:48:57 INFO - PROCESS | 1663 | |0;ai-astar;113;113;114;112;114;116;160;168;113;112 22:48:57 INFO - PROCESS | 1663 | |1;audio-beat-detection;151;112;114;151;116;115;118;113;145;115 22:48:57 INFO - PROCESS | 1663 | |2;audio-dft;182;184;185;184;183;185;180;185;181;181 22:48:57 INFO - PROCESS | 1663 | |3;audio-fft;81;60;60;88;61;59;61;58;82;58 22:48:57 INFO - PROCESS | 1663 | |4;audio-oscillator;86;72;92;71;68;69;91;67;69;93 22:48:57 INFO - PROCESS | 1663 | |5;imaging-gaussian-blur;90;86;90;129;89;85;91;87;89;85 22:48:57 INFO - PROCESS | 1663 | |6;imaging-darkroom;94;102;142;95;106;148;95;95;96;94 22:48:57 INFO - PROCESS | 1663 | |7;imaging-desaturate;84;86;84;84;86;84;87;86;86;85 22:48:57 INFO - PROCESS | 1663 | |8;json-parse-financial;56;53;55;53;55;55;54;54;55;57 22:48:57 INFO - PROCESS | 1663 | |9;json-stringify-tinderbox;63;74;63;75;72;68;68;70;69;70 22:48:57 INFO - PROCESS | 1663 | |10;stanford-crypto-aes;88;85;87;86;86;85;86;90;87;87 22:48:57 INFO - PROCESS | 1663 | |11;stanford-crypto-ccm;161;163;159;171;140;152;156;160;246;144 22:48:57 INFO - PROCESS | 1663 | |12;stanford-crypto-pbkdf2;177;175;174;173;175;174;177;169;173;180 22:48:57 INFO - PROCESS | 1663 | |13;stanford-crypto-sha256-iterative;63;77;89;72;68;70;70;69;68;70 22:48:57 INFO - PROCESS | 1663 | __end_tp_report 22:48:57 INFO - PROCESS | 1663 | __start_cc_report 22:48:57 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,1986 22:48:57 INFO - PROCESS | 1663 | __end_cc_report 22:48:57 INFO - PROCESS | 1663 | __startTimestamp1475560137706__endTimestamp 22:48:57 INFO - PROCESS | 1663 | 22:48:57 INFO - PROCESS | 1663 | ------- Summary: start ------- 22:48:57 INFO - PROCESS | 1663 | Number of tests: 14 22:48:57 INFO - PROCESS | 1663 | 22:48:57 INFO - PROCESS | 1663 | [#0] ai-astar Cycles:10 Average:123.50 Median:114.00 stddev:21.46 (18.8%) stddev-sans-first:22.42 22:48:57 INFO - PROCESS | 1663 | Values: 113.0 113.0 114.0 112.0 114.0 116.0 160.0 168.0 113.0 112.0 22:48:57 INFO - PROCESS | 1663 | 22:48:57 INFO - PROCESS | 1663 | [#1] audio-beat-detection Cycles:10 Average:125.00 Median:117.00 stddev:16.72 (14.3%) stddev-sans-first:14.85 22:48:57 INFO - PROCESS | 1663 | Values: 151.0 112.0 114.0 151.0 116.0 115.0 118.0 113.0 145.0 115.0 22:48:57 INFO - PROCESS | 1663 | 22:48:57 INFO - PROCESS | 1663 | [#2] audio-dft Cycles:10 Average:183.00 Median:184.00 stddev:1.89 (1.0%) stddev-sans-first:1.96 22:48:57 INFO - PROCESS | 1663 | Values: 182.0 184.0 185.0 184.0 183.0 185.0 180.0 185.0 181.0 181.0 22:48:57 INFO - PROCESS | 1663 | 22:48:57 INFO - PROCESS | 1663 | [#3] audio-fft Cycles:10 Average:66.80 Median:61.00 stddev:11.82 (19.4%) stddev-sans-first:11.37 22:48:57 INFO - PROCESS | 1663 | Values: 81.0 60.0 60.0 88.0 61.0 59.0 61.0 58.0 82.0 58.0 22:48:57 INFO - PROCESS | 1663 | 22:48:57 INFO - PROCESS | 1663 | [#4] audio-oscillator Cycles:10 Average:77.80 Median:79.00 stddev:11.16 (14.1%) stddev-sans-first:11.44 22:48:57 INFO - PROCESS | 1663 | Values: 86.0 72.0 92.0 71.0 68.0 69.0 91.0 67.0 69.0 93.0 22:48:57 INFO - PROCESS | 1663 | 22:48:57 INFO - PROCESS | 1663 | [#5] imaging-gaussian-blur Cycles:10 Average:92.10 Median:89.00 stddev:13.14 (14.8%) stddev-sans-first:13.92 22:48:57 INFO - PROCESS | 1663 | Values: 90.0 86.0 90.0 129.0 89.0 85.0 91.0 87.0 89.0 85.0 22:48:57 INFO - PROCESS | 1663 | 22:48:57 INFO - PROCESS | 1663 | [#6] imaging-darkroom Cycles:10 Average:106.70 Median:94.50 stddev:20.61 (21.8%) stddev-sans-first:21.34 22:48:57 INFO - PROCESS | 1663 | Values: 94.0 102.0 142.0 95.0 106.0 148.0 95.0 95.0 96.0 94.0 22:48:57 INFO - PROCESS | 1663 | 22:48:57 INFO - PROCESS | 1663 | [#7] imaging-desaturate Cycles:10 Average:85.20 Median:86.00 stddev:1.14 (1.3%) stddev-sans-first:1.12 22:48:57 INFO - PROCESS | 1663 | Values: 84.0 86.0 84.0 84.0 86.0 84.0 87.0 86.0 86.0 85.0 22:48:57 INFO - PROCESS | 1663 | 22:48:57 INFO - PROCESS | 1663 | [#8] json-parse-financial Cycles:10 Average:54.70 Median:55.00 stddev:1.25 (2.3%) stddev-sans-first:1.24 22:48:57 INFO - PROCESS | 1663 | Values: 56.0 53.0 55.0 53.0 55.0 55.0 54.0 54.0 55.0 57.0 22:48:57 INFO - PROCESS | 1663 | 22:48:57 INFO - PROCESS | 1663 | [#9] json-stringify-tinderbox Cycles:10 Average:69.20 Median:70.00 stddev:4.02 (5.7%) stddev-sans-first:3.59 22:48:57 INFO - PROCESS | 1663 | Values: 63.0 74.0 63.0 75.0 72.0 68.0 68.0 70.0 69.0 70.0 22:48:57 INFO - PROCESS | 1663 | 22:48:57 INFO - PROCESS | 1663 | [#10] stanford-crypto-aes Cycles:10 Average:86.70 Median:87.00 stddev:1.49 (1.7%) stddev-sans-first:1.51 22:48:57 INFO - PROCESS | 1663 | Values: 88.0 85.0 87.0 86.0 86.0 85.0 86.0 90.0 87.0 87.0 22:48:57 INFO - PROCESS | 1663 | 22:48:57 INFO - PROCESS | 1663 | [#11] stanford-crypto-ccm Cycles:10 Average:165.20 Median:160.50 stddev:29.80 (18.6%) stddev-sans-first:31.57 22:48:57 INFO - PROCESS | 1663 | Values: 161.0 163.0 159.0 171.0 140.0 152.0 156.0 160.0 246.0 144.0 22:48:57 INFO - PROCESS | 1663 | 22:48:57 INFO - PROCESS | 1663 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.70 Median:175.00 stddev:2.95 (1.7%) stddev-sans-first:3.00 22:48:57 INFO - PROCESS | 1663 | Values: 177.0 175.0 174.0 173.0 175.0 174.0 177.0 169.0 173.0 180.0 22:48:57 INFO - PROCESS | 1663 | 22:48:57 INFO - PROCESS | 1663 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.60 Median:70.00 stddev:7.04 (10.1%) stddev-sans-first:6.75 22:48:57 INFO - PROCESS | 1663 | Values: 63.0 77.0 89.0 72.0 68.0 70.0 70.0 69.0 68.0 70.0 22:48:57 INFO - PROCESS | 1663 | -------- Summary: end -------- 22:48:57 INFO - PROCESS | 1663 | 22:48:58 INFO - TEST-INFO | 1663: exit 0 22:48:58 INFO - TEST-OK | kraken | took 59591ms 22:48:58 INFO - SUITE-END | took 1178s 22:48:58 INFO - Completed test suite (00:19:38) 22:48:58 INFO - javascript benchmark 22:48:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3280.5407242985984, 3282.6389097199312, 3284.195372133827, 3286.34273145371, 3335.5997280326364, 5528.668279903209, 5581.8604534886645, 5620.718964051802, 5625.831225063244, 5658.915116273254, 5575.972120139404, 5595.972020139904, 5678.971605141968, 5678.971605141979, 5699.287589051369, 4603.746793926332, 4969.776360063788, 5024.522670346311, 5059.949400506003, 5156.175011998081, 4978.054169707762, 5167.328247327842, 5180.611454140936, 5197.636165468419, 5225.425203227642, 5167.379914410276, 5176.249443830661, 5197.558207552324, 5200.843974680765, 5249.0, 5051.469595692436, 5103.897922041576, 5128.153854613985, 5133.8203162889495, 5149.176131818928, 4239.724417912826, 5051.9494805051845, 5079.339685840854, 5113.53978141969, 5180.197069454241, 5523.364813046511, 5564.86087847803, 5604.215409842625, 5606.803761868315, 5639.887202255973, 6040.244969378828, 6084.209052823149, 6108.7251073701345, 6116.877662446771, 6125.264968203799, 4793.9280910786365, 6119.9278719228705, 6125.876495711566, 6186.783462578788, 6215.1609532712955, 5703.17303990919, 5706.0, 5708.40061793514, 5716.228309178291, 5740.971295143498, 6172.321044685081, 6279.874402511924, 6299.96850015747, 6340.239171299474, 6363.713632886531, 5950.940490595039, 5971.313298970587, 5995.46040856325, 6069.3020302665755, 6113.480354169857, 6156.530408773679, 6206.224221972254, 6207.006878899354, 6211.3167551569295, 6218.409251121137, 6056.606320589148, 6097.481714054266, 6111.96944015277, 6119.3197658210165, 6120.571559990847, 5996.670183139882, 6052.878942421215, 6101.3079194897555, 6105.511559075263, 6106.969465152646, 4819.614430845524, 4860.027994401134, 4860.246661767432, 4874.2444921037295, 4876.366072410564, 4275.315949448073, 4352.151330490524, 4404.031113155101, 4453.688241823042, 4465.0400163964905, 4734.597559207506, 5592.7762889484775, 5653.434656534314, 5657.660540367591, 5659.971700141556, 6081.057436097412, 6132.906245166601, 6211.844703882439, 6245.219347581552, 6251.53113516479, 5604.4395560444445, 5641.548676105902, 5689.2319536862, 5691.9715401423555, 5724.055530837449, 5334.7065911374475, 5342.545883599938, 5344.144936810092, 5348.03735327637, 5364.22218778275, 6256.812295631138, 6334.809955701337, 6337.366263373626, 6353.174087368612, 6365.267994180728, 6094.54290928191, 6127.372786728296, 6138.449706860676, 6176.104464852661, 6249.96875015622, 6280.9685951569945, 6336.081268216175, 6342.714577843916, 6343.365663433804, 6344.048392741126, 5107.412647545431, 6055.061465472859, 6062.636241825328, 6123.540734445023, 6124.014786914997, 6122.0, 6181.443670069715, 6188.319284878749, 6192.566520343533, 6203.503719702323, 5747.827565173052, 5991.2510936132985, 6015.368386319374, 6016.278046634432, 6035.336113027652, 5816.70916454184, 5952.8809423812145, 5985.832762611249, 5990.131430942576, 6081.361457046946, 4688.780916961683, 4875.414950205998, 5738.311402631711, 5860.501857342174, 5871.823845284648, 6021.909671354845, 6028.4272994065495, 6045.335013148462, 6060.2424696912885, 6061.212042434456, 5714.428557144252, 5773.191753154646, 5820.796272130455, 5823.970880145572, 5865.120231965068], "value": 5589.34469086266, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [452.66728954218644, 479.4893438488011, 510.6706174517436, 512.0475914798476, 514.5986130817961, 29968.400631987373, 40319.0, 41426.96432589183, 41620.58379416205, 43399.915002124966, 42538.7873060635, 42544.78727606365, 42611.573884261146, 42630.78684606573, 42937.7853110734, 9560.569774360152, 9738.367006144577, 9752.756181095494, 9755.0, 9763.316567840255, 8920.553972301392, 9219.0, 9611.855822162674, 9706.902930970671, 9715.0, 9656.75858103546, 9734.756631084208, 9739.123478886899, 9739.41563506192, 9766.902330976705, 9606.9519652402, 9677.951610241975, 9701.854472182922, 9750.853737193947, 9755.951220243924, 7840.275139469308, 7895.763127106138, 7900.130985591581, 7911.248431399008, 7919.168487308865, 40559.7972010141, 40844.79577602123, 40933.181336373105, 41002.1799564007, 41065.0, 3387.7289816814596, 3456.4642480415578, 3499.0552550811394, 3673.779573225615, 3920.8039598019986, 30278.697213027808, 30672.0, 31784.523232151536, 31990.360192796015, 32204.83897580497, 3157.921051973696, 3238.4656531672244, 3269.6566860479543, 3323.418401779679, 3354.2788300515263, 405097.0, 480353.0, 481686.0, 482104.0, 482793.0, 58323.70838145782, 58766.0, 58831.0, 58865.70567147137, 58884.0, 56897.14654280189, 56952.71523642355, 57000.0, 57056.42943570594, 57066.0, 15940.760888586681, 16148.111853847917, 16173.029618222945, 16191.514254572381, 16193.676126477405, 12518.248905065542, 12579.559715409916, 12585.874141258653, 12594.433250503565, 12611.432485538173, 15628.765568516481, 16430.83569164317, 16456.0, 16525.6694866102, 16704.916475417787, 6802.292750451016, 6803.523753337219, 6813.0, 6827.513354062011, 6904.171499420001, 881.7002219245487, 927.7495076329353, 938.474454305591, 966.8791401074866, 1037.0666400239693, 27596.586051209248, 28678.569821452278, 29291.414171716446, 29707.554386684216, 30018.148275913754, 990.5393991793851, 1030.525958059286, 1061.9150467962545, 1085.4084523934475, 1096.9122470202524, 14625.610566996118, 15376.846231537766, 15386.230688465532, 15633.452829151152, 15741.212939352987, 621108.0, 625789.0, 628449.0, 629208.0, 629571.0, 56992.0, 57035.71482142563, 57177.0, 57220.71389643025, 57221.427785722444, 1080.3733834376044, 1243.0863315463127, 1307.1503522710314, 1350.9189448633113, 1368.6236285021698, 29692.703072969856, 30752.077437676904, 31002.0, 31708.84145579257, 31771.0, 48442.03115937732, 49089.754551227015, 49113.017739644296, 49181.01637967149, 49322.0, 529034.0, 532914.0, 533024.0, 533774.3311283418, 534762.0, 31502.842485787423, 32534.0, 40285.992850178984, 41521.584784151775, 42254.0, 45788.08423831438, 46552.0, 46777.53222467868, 46793.2981005292, 46981.765091174326, 38173.61826381701, 38251.426228607146, 38280.0, 40085.0, 40291.798541007105, 35239.180865531205, 37904.24191516209, 39056.804715976235, 39447.60552394554, 39534.604653954244, 37196.0, 37257.0, 37274.81362593278, 37410.8129459351, 37522.0, 32566.674333256364, 33785.15537111494, 33957.32085358328, 34130.31739365248, 34211.82894085613, 24503.142390016263, 24585.0, 24622.876885615457, 24890.128845490322, 25232.665026955998], "value": 19708.89888594616, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.09500441359086, 363.97452178347515, 372.35582442374675, 377.6223776223776, 401.25567073079435, 14776.704465910676, 14977.625559361022, 15577.532674019769, 20687.379378618665, 20691.689624655643, 19033.90483047583, 20158.899205503953, 20817.0, 20869.68695469569, 21082.789172108274, 5630.014747419205, 5687.431256874311, 5982.97008514959, 5991.131285963533, 5993.220881285427, 5889.528837692996, 5974.402559744034, 6038.637681739087, 6043.214382130339, 6047.788327408542, 4119.423280740699, 4347.78261086946, 5773.740181691813, 5987.461128498434, 6087.20866287382, 6100.481459075984, 6110.847228819271, 6151.0, 6154.907676384858, 6229.563930524865, 5740.282464691913, 5791.160281759164, 5792.768289268424, 5798.768049278024, 5800.709964501758, 22262.88868555663, 22635.886820565793, 23597.174098906627, 23758.524829503487, 26145.607815882777, 2070.730804995341, 2205.878676672782, 2517.446161844391, 2586.935326616831, 2729.4541091781725, 9895.356801807931, 9917.107460328605, 9988.95005524975, 10422.062014418738, 10466.581336746522, 1761.4803632928317, 1821.726740988849, 1833.3949796567233, 1857.3963461874944, 1891.9905400473046, 133310.0, 133496.66503335038, 133574.6642533572, 140103.0, 140714.99999999898, 18422.723659145122, 19653.410397687807, 20500.589988200452, 20999.265025724028, 21134.788652113282, 33244.51199088141, 33337.99986000424, 33341.0, 33414.16464588405, 33422.66577334244, 10334.431606261727, 10495.52770125331, 10529.84205236922, 10724.89275107239, 10865.728356790985, 7117.359437650636, 7428.762722280779, 8697.086805885418, 8744.300455963508, 8912.955435222782, 9280.582373793195, 9442.339036267533, 9516.38143520669, 9555.9044409555, 9569.856452153223, 3003.5494675798805, 3284.6861255497993, 3579.8544465771224, 3642.18050938542, 3663.7618554794385, 833.5332213960202, 918.237862574062, 936.9390989585792, 956.364017928084, 979.7109852593502, 15455.995360301544, 21181.94090295479, 21451.14195432165, 21455.927203639756, 21573.136616591702, 722.9457790665721, 887.5695287785419, 894.9350273174902, 938.2447130060367, 991.3209451525728, 10283.43441110746, 10780.946095269474, 11150.330980141218, 11177.44112794357, 11254.549818007352, 538558.0, 560249.1987540118, 584432.0, 601644.0, 604269.6157345772, 113581.43209283901, 114880.0, 115410.42294788473, 132908.0, 137924.0, 959.5777857742571, 977.3061126600194, 985.0641890204191, 1000.0, 1000.8548760429843, 15884.68230635404, 19708.802911970695, 19828.306009290183, 19830.10764515629, 19891.701624475932, 44007.77996109999, 46203.0, 55962.0, 56153.03464378727, 56244.718776405854, 81833.0, 83612.0, 84871.45385638441, 85123.50832637514, 86892.0, 6611.570247933869, 7226.74706385295, 8078.272955434039, 8230.42387032902, 8379.45533540318, 10815.242932994615, 13020.739585208054, 13456.798148027983, 13549.983751218895, 13713.725725485634, 10856.294340868135, 10880.238383313408, 11361.54553817838, 11632.941835290769, 11680.766384672428, 10387.480625968521, 10495.790084198426, 11343.773124537629, 11593.18847680688, 11891.227070240753, 9996.850047249152, 11683.824742628698, 11767.88232117668, 11921.225120367148, 11923.0, 7793.805154871174, 8027.2775450209765, 8032.116467188488, 8495.235428811437, 8524.488530688179, 6864.759733409307, 9673.274504412339, 11395.943020284845, 11526.07791376671, 11628.418579071182], "value": 10312.71583425467, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1341.7722783652957, 1567.9999999999995, 1574.3151728997886, 1770.035330744744, 1790.862802120654, 7898.802529936755, 8393.07676155623, 8728.650853965843, 8997.865032024525, 9010.27917766577, 8845.513496757676, 8943.64225430982, 8953.373263871532, 8953.86569201461, 8960.372773905812, 2327.674125622414, 2368.3250273672006, 2369.763023697634, 2371.2530552875787, 2379.1791831817995, 1929.3729537900238, 2320.5938960681897, 2357.976420235793, 2362.0, 2363.161077817376, 2109.535902101543, 2200.0, 2328.196772113618, 2362.0669835415, 2364.609839376501, 2303.861768293891, 2319.18828410056, 2326.6510023496444, 2326.9650955235684, 2331.0326214620914, 2311.526137141882, 2331.9067237310487, 2332.825038122148, 2333.171724037976, 2335.1243283768586, 8857.689980850639, 9041.276697864156, 9090.545472726404, 9104.271658267322, 9115.088491150831, 5249.737513124329, 5453.809116680897, 5462.972685136589, 5468.207109969032, 5472.398036216013, 3132.5457808617853, 3566.5198942438856, 3629.727770417203, 3669.94495082574, 3708.369577171887, 1072.8390741388855, 1089.3627228071587, 1093.4532733633184, 1096.4901320885747, 1110.1618278199987, 11921.165518413714, 11939.985101266402, 11999.040076793835, 12002.759944801055, 12005.639830805003, 3598.8020658864016, 3792.3173828710546, 4305.892352691145, 4576.6109880660515, 4578.427696537933, 7772.0, 9237.261019118454, 9241.399309044891, 9242.0, 9250.90749092514, 4462.464504259445, 5278.762455689503, 5279.340082489689, 5279.41926388097, 5285.867853303621, 4049.5545489996075, 4429.68992170545, 4505.549445055468, 4604.194266003436, 4985.4266759323145, 3638.2541578976247, 3692.7968961707365, 3740.625937406292, 3791.848326066982, 3825.311443940062, 1163.4124766992784, 1167.2237961755507, 1168.8422063021553, 1176.5117476247433, 1178.8997935175435, 821.5358322547742, 835.1398059998212, 864.9394542382099, 910.4264313482464, 958.4441024205944, 4447.866564003085, 5721.342045664802, 5936.436038576328, 6042.667653279024, 6048.243969503812, 845.0031351706341, 921.6037104045325, 928.456852741154, 953.3326671330096, 964.7636329099415, 3317.419451595961, 3953.367461206193, 4033.6773058155277, 4073.9388909166387, 4074.8573799917453, 10804.459777011118, 11105.666829995112, 11186.664400068012, 11221.495032723547, 11239.55041798319, 10062.798744025078, 10490.947545262225, 10931.508082136323, 11171.32972021665, 11195.832062519068, 8131.512109273463, 8183.754487365389, 8215.260626543639, 8426.031006434216, 8470.788230294169, 5305.575553955751, 5721.0, 5837.182794408871, 5991.910121348096, 6046.758129674764, 9934.0, 10157.644482443078, 10465.633702820365, 10494.370337779757, 10512.789744205225, 9592.9040709592, 10244.385336879812, 10574.259801814107, 10604.416757078126, 10613.09788667972, 7037.296270372922, 7257.0, 7421.628918554159, 7569.6593653284635, 7707.614619268902, 8299.08710041907, 8370.414071014971, 8404.579771011548, 8460.915390846012, 8480.32157427392, 7528.134264559427, 7584.810379740551, 7622.69509219625, 7633.389328853567, 7687.461877668509, 5808.477237048517, 7400.5189662671755, 7454.850902982019, 7497.437692173, 7635.23647635232, 6853.383195512428, 6863.078338065349, 6939.514234003773, 6991.440684745106, 6991.937378473261, 4882.4629290777275, 4928.334674818853, 4965.776540055634, 5045.217991211368, 5178.585713143015, 4336.913261734685, 4967.627427942846, 5001.924971125363, 5061.443241243386, 5109.974450127875], "value": 4535.818127207592, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.3983114278016, 298.4240415997126, 306.6473555411277, 310.5264471680688, 314.22229980797545, 5256.632035757494, 5286.418493965665, 5290.947090529094, 5316.787328506855, 5330.946690533094, 4008.4588580541617, 4347.500037495689, 4748.723380867199, 5082.923756143651, 5213.400458947222, 3331.0506505645894, 3540.7516227195724, 3594.454384614615, 3737.9065523361996, 3810.2569998850236, 3983.4821473208444, 3989.9601003990024, 3992.3213053780783, 3998.620131087542, 4041.4746083009313, 3755.5681096673957, 3971.86098486553, 3977.264206121862, 3991.42124391963, 4002.779847108408, 3471.89584312471, 3974.3641017437362, 4013.5585085640555, 4022.5374081980654, 4037.9596204038025, 3088.3051313454293, 3245.415825151472, 3443.6212016678396, 3469.531613232232, 3472.4270495368232, 5158.174692049254, 5159.974200128975, 5207.463798179509, 5217.791288348461, 5227.581793456515, 4281.357796330544, 4545.0, 4560.589546940758, 4577.816887324503, 4602.723836569817, 4572.748498832563, 5056.91276375581, 5147.974260128675, 5508.749837595838, 5520.66875987442, 3397.456406974872, 3762.473253744478, 3766.6045065268027, 3788.507494025759, 3808.371618682914, 5011.523905228997, 5600.887982240332, 5810.767569297223, 5836.299644042742, 5861.0915308127305, 3824.7131465140224, 3831.3525184170517, 3849.268638958589, 3857.96142038576, 3875.8643447479203, 3548.716102711777, 3643.9453408198897, 3877.3990031545154, 3903.277893589638, 3926.6269704378037, 4339.63113135387, 4548.931766023512, 4549.909001819945, 4559.609319157623, 4582.679212455163, 4845.53967373099, 4873.805047798049, 5371.811986580451, 5583.692896890629, 5608.0, 1109.7003808971533, 1301.1932601786953, 1320.9762434113638, 1325.8597606058781, 1329.654289884637, 790.8497385496737, 794.9046114466302, 821.4351660087543, 846.3398549131687, 882.7395918204145, 4551.908961820811, 4703.717776933395, 4978.8257410990445, 5119.718415487184, 5171.4311425743135, 5610.663360198401, 5614.831555053355, 5627.649364152574, 5656.886862262814, 5713.685747283939, 4369.410129632459, 4441.578050085237, 4650.558196971282, 4704.341392205094, 4991.850244492671, 2383.74970628085, 2925.5611658251432, 2944.3522425066453, 2958.926026849346, 2962.3482833776534, 5076.6446348755235, 5277.894442111136, 5791.247137872049, 5810.854728631733, 5812.534997200214, 4285.100128972888, 5144.91956689092, 5249.005377956352, 5393.406725260218, 5704.600677952503, 4974.0, 5692.573057020657, 5768.423157684198, 5797.391273916262, 5842.707864606753, 4402.713823601455, 4724.551167639069, 5577.358603760457, 5594.41258667834, 5640.407757185437, 4647.395838540968, 5489.917651235155, 5533.335999680065, 5550.222968784454, 5556.333240011225, 4854.95145048545, 4871.44113883568, 5317.760700768397, 5779.8555036124435, 5843.444872737083, 3540.2211513466996, 3728.0241472019875, 3739.2895349883433, 3768.773873567595, 3770.1517158639085, 4640.930386044144, 4667.649926255476, 5123.487651234846, 5632.436756324335, 5643.915341269966, 4795.664303498722, 4994.600431965361, 5407.297051383295, 5464.28964234647, 5464.726763661881, 5185.585153187812, 5388.757505912165, 5445.455454454681, 5464.060258608137, 5498.477644623755, 4861.246506791453, 4896.4124305083615, 4925.916298414343, 5055.469175736642, 5309.309789727311, 4580.083983203306, 5369.0, 5481.766602514403, 5491.533219676213, 5497.945020549903, 4910.975445122752, 5205.984832957537, 5436.906790885459, 5437.646552974044, 5452.890942181055], "value": 3962.9190001062434, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [704.73220176333, 720.5568575326176, 728.202618133144, 731.1190016030683, 775.5075527040327, 15278.541643750692, 15859.127747973856, 15892.84107158928, 15898.841011589895, 15899.920500397511, 14149.646258843533, 14287.571372858805, 14304.213268270241, 14306.785398219035, 14341.56975290743, 660.5178219899476, 682.8941514065327, 684.4626967830254, 824.3940703582862, 839.4249938791917, 826.2604968553142, 843.9873401898976, 853.8377708235446, 854.9230569248766, 861.8448679237734, 911.1844898815556, 913.5614904845678, 914.8764916736255, 915.0209276074603, 916.5554705967601, 583.600233839821, 693.1024323501078, 908.6501696846728, 910.2126660438739, 912.3066469483174, 647.4205586000503, 660.7026837922906, 662.3178126529682, 665.8002599220263, 672.9495287853431, 15151.318190681446, 15177.317020734094, 15181.16503592313, 15186.240687965557, 15228.390864365412, 12660.493580256778, 13718.971077169252, 13746.862531374758, 14115.435382584685, 14500.637484062878, 13045.152065115737, 13055.21668699881, 13147.408366623526, 13238.470461181541, 13266.602001939957, 4433.202023635744, 4480.372747815309, 4555.202839503107, 4702.200625893606, 4708.4820669726305, 109386.0, 111319.88680113177, 111805.0, 113502.43248783785, 114384.0, 9083.45499270046, 11099.38953357557, 11194.944025279821, 11199.272047317061, 11473.942630286962, 21775.564488710137, 22572.88713556422, 22578.774212257995, 22597.661035084486, 22644.886775566018, 7698.461107722517, 7800.84398312042, 7901.209879012168, 8040.678372865137, 8126.065502467174, 4339.609435150788, 4366.956330436718, 4414.713043652152, 4434.800433980478, 4504.684672072989, 3342.5988881334397, 3396.847141878621, 3463.0476618929492, 3470.65293470656, 3488.1977145256733, 622.6544267931341, 626.9561130720806, 640.2445114764529, 648.7891435283552, 650.5023656902379, 534.8181618249814, 565.1833101168771, 594.8542607061298, 614.7878981751288, 620.8013435700623, 8311.6675332986, 9082.909513937562, 9105.68130115456, 9184.63261469547, 9261.259099272042, 568.091687817251, 594.2927915780208, 629.8897692903723, 630.5531670965908, 630.9526785491106, 4298.0, 4562.266338791238, 4575.81696732127, 4910.8234789076505, 5086.440491545927, 124758.0, 142818.0, 143758.2181336383, 157244.0, 163586.0, 79260.60369698114, 79345.20654793378, 112307.0, 114831.0, 132803.0, 31737.365252694355, 31750.682493174772, 33283.500747488804, 111536.44231778951, 113889.0, 9233.630654773733, 9940.602375904882, 9948.253880959102, 9975.900240997496, 9993.500324983575, 47362.0, 51050.0, 56648.300550983535, 58419.4158058431, 65170.67414662896, 93326.0, 94910.0, 95685.0, 99647.50176249072, 100748.0, 13295.80056299137, 13369.0, 13369.933150334185, 13442.327883605976, 13504.324783760576, 14473.92763036178, 14618.926905365404, 15021.624459388602, 15062.698746025237, 15385.923070385024, 13543.187408755506, 13958.0, 13974.930125349309, 14018.509352172625, 14114.50599229022, 13707.794383084462, 13794.310284485937, 14268.215248161036, 14418.495352662607, 14438.0, 13341.066125371131, 13455.932720336335, 13663.931680341535, 13824.447022119002, 14113.435462581792, 11391.430428478709, 11492.0, 11671.59149429766, 11683.0, 11707.88292117068, 11223.03739181247, 11398.0, 11619.767604648028, 11634.418279085843, 11663.650090497298], "value": 7678.141369152362, "unit": "score"}], "name": "dromaeo_css", "value": 7343.369042777256, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 114.0, 112.0, 114.0, 116.0, 160.0, 168.0, 113.0, 112.0], "value": 123.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 112.0, 114.0, 151.0, 116.0, 115.0, 118.0, 113.0, 145.0, 115.0], "value": 125.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 184.0, 185.0, 184.0, 183.0, 185.0, 180.0, 185.0, 181.0, 181.0], "value": 183.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 60.0, 88.0, 61.0, 59.0, 61.0, 58.0, 82.0, 58.0], "value": 66.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 72.0, 92.0, 71.0, 68.0, 69.0, 91.0, 67.0, 69.0, 93.0], "value": 77.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 86.0, 90.0, 129.0, 89.0, 85.0, 91.0, 87.0, 89.0, 85.0], "value": 92.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 102.0, 142.0, 95.0, 106.0, 148.0, 95.0, 95.0, 96.0, 94.0], "value": 106.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 84.0, 84.0, 86.0, 84.0, 87.0, 86.0, 86.0, 85.0], "value": 85.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 53.0, 55.0, 53.0, 55.0, 55.0, 54.0, 54.0, 55.0, 57.0], "value": 54.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 74.0, 63.0, 75.0, 72.0, 68.0, 68.0, 70.0, 69.0, 70.0], "value": 69.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 87.0, 86.0, 86.0, 85.0, 86.0, 90.0, 87.0, 87.0], "value": 86.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 159.0, 171.0, 140.0, 152.0, 156.0, 160.0, 246.0, 144.0], "value": 165.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 175.0, 174.0, 173.0, 175.0, 174.0, 177.0, 169.0, 173.0, 180.0], "value": 174.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 77.0, 89.0, 72.0, 68.0, 70.0, 70.0, 69.0, 68.0, 70.0], "value": 71.6, "unit": "score"}], "name": "kraken", "value": 1482.2000000000003, "alertThreshold": 2.0}]} 22:48:58 INFO - Return code: 0 22:48:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:48:59 INFO - # TBPL SUCCESS # 22:48:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:48:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:48:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:48:59 INFO - 2016-10-03 22:48:59.045 screenresolution[1667:9877] starting screenresolution argv=screenresolution get 22:48:59 INFO - 2016-10-03 22:48:59.048 screenresolution[1667:9877] Display 0: 1600x1200x32@60 22:48:59 INFO - 2016-10-03 22:48:59.054 screenresolution[1668:9880] starting screenresolution argv=screenresolution list 22:48:59 INFO - Available Modes on Display 0 22:48:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:48:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:48:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:48:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:48:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:48:59 INFO - Intel Iris: 22:48:59 INFO - Chipset Model: Intel Iris 22:48:59 INFO - Type: GPU 22:48:59 INFO - Bus: Built-In 22:48:59 INFO - VRAM (Dynamic, Max): 1536 MB 22:48:59 INFO - Vendor: Intel (0x8086) 22:48:59 INFO - Device ID: 0x0a2e 22:48:59 INFO - Revision ID: 0x0009 22:48:59 INFO - Displays: 22:48:59 INFO - Display: 22:48:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:48:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:48:59 INFO - Display Serial Number: 1600x1200 60 22:48:59 INFO - Main Display: Yes 22:48:59 INFO - Mirror: Off 22:48:59 INFO - Online: Yes 22:48:59 INFO - Rotation: Supported 22:48:59 INFO - Return code: 0 22:48:59 INFO - Running post-action listener: _resource_record_post_action 22:48:59 INFO - [mozharness: 2016-10-04 05:48:59.291269Z] Finished run-tests step (success) 22:48:59 INFO - Running post-run listener: _resource_record_post_run 22:48:59 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 38536192; Write bytes: 707403264; Read time: 378; Write time: 1971 22:48:59 INFO - TinderboxPrint: CPU usage
53.5% 22:48:59 INFO - TinderboxPrint: I/O read bytes / time
38,536,192 / 378 22:48:59 INFO - TinderboxPrint: I/O write bytes / time
707,403,264 / 1,971 22:48:59 INFO - TinderboxPrint: CPU idle
2,256.0 (46.5%) 22:48:59 INFO - TinderboxPrint: CPU system
969.2 (20.0%) 22:48:59 INFO - TinderboxPrint: CPU user
1,628.4 (33.5%) 22:48:59 INFO - TinderboxPrint: Swap in / out
445,927,424 / 0 22:48:59 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 397711872; Write bytes: 390715392; Read time: 29578; Write time: 818 22:48:59 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 36701184; Write bytes: 295712256; Read time: 359; Write time: 1109 22:48:59 INFO - Running post-run listener: _upload_blobber_files 22:48:59 INFO - Blob upload gear active. 22:48:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:48:59 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 22:48:59 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:48:59 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:49:00 INFO - (blobuploader) - INFO - Open directory for files ... 22:49:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:49:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:49:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:49:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:49:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:49:02 INFO - (blobuploader) - INFO - Done attempting. 22:49:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:49:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:49:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:49:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:49:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:49:03 INFO - (blobuploader) - INFO - Done attempting. 22:49:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:49:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:49:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:49:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:49:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:49:05 INFO - (blobuploader) - INFO - Done attempting. 22:49:05 INFO - (blobuploader) - INFO - Iteration through files over. 22:49:05 INFO - Return code: 0 22:49:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:49:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:49:05 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/48d0029bbee7304fea2fd44968d554d76ed4522c9eb666d621493302211c3ba0a8b1c5f20f83652b84b63d815c33ba92c088603b91a2e66bad9a0c263d5d023c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/cbf35a948549d4580f2cd42a73ea2af6154b73a2a38ee79e3584e739ef312c81d906ccce2c8b8b2eb04a18b2a3ca66b01362180049d2c08ea9aad9c7c98dd45b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8f68cd950b5d04db4eb0aeef27d07e09eea8a7b02c0267c6388db90da40ca42b01369f71e4801d7572b1609e2a8909dbfe4223164929f82038b3c1f472b8108f"} 22:49:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:49:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:49:05 INFO - Contents: 22:49:05 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/48d0029bbee7304fea2fd44968d554d76ed4522c9eb666d621493302211c3ba0a8b1c5f20f83652b84b63d815c33ba92c088603b91a2e66bad9a0c263d5d023c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/cbf35a948549d4580f2cd42a73ea2af6154b73a2a38ee79e3584e739ef312c81d906ccce2c8b8b2eb04a18b2a3ca66b01362180049d2c08ea9aad9c7c98dd45b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8f68cd950b5d04db4eb0aeef27d07e09eea8a7b02c0267c6388db90da40ca42b01369f71e4801d7572b1609e2a8909dbfe4223164929f82038b3c1f472b8108f"} 22:49:05 INFO - Running post-run listener: copy_logs_to_upload_dir 22:49:05 INFO - Copying logs to upload dir... 22:49:05 INFO - mkdir: /builds/slave/test/build/upload/logs 22:49:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.812782 ========= master_lag: 0.98 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-10-03 22:49:06.478551) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-03 22:49:06.486357) ========= 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.kXyTyVPiVf/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.vQKueimyk3/Listeners TMPDIR=/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/48d0029bbee7304fea2fd44968d554d76ed4522c9eb666d621493302211c3ba0a8b1c5f20f83652b84b63d815c33ba92c088603b91a2e66bad9a0c263d5d023c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/cbf35a948549d4580f2cd42a73ea2af6154b73a2a38ee79e3584e739ef312c81d906ccce2c8b8b2eb04a18b2a3ca66b01362180049d2c08ea9aad9c7c98dd45b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8f68cd950b5d04db4eb0aeef27d07e09eea8a7b02c0267c6388db90da40ca42b01369f71e4801d7572b1609e2a8909dbfe4223164929f82038b3c1f472b8108f"} build_url:https://queue.taskcluster.net/v1/task/W3kOJZY_Sjio8bvdDg6g7A/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010873 build_url: 'https://queue.taskcluster.net/v1/task/W3kOJZY_Sjio8bvdDg6g7A/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/48d0029bbee7304fea2fd44968d554d76ed4522c9eb666d621493302211c3ba0a8b1c5f20f83652b84b63d815c33ba92c088603b91a2e66bad9a0c263d5d023c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/cbf35a948549d4580f2cd42a73ea2af6154b73a2a38ee79e3584e739ef312c81d906ccce2c8b8b2eb04a18b2a3ca66b01362180049d2c08ea9aad9c7c98dd45b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8f68cd950b5d04db4eb0aeef27d07e09eea8a7b02c0267c6388db90da40ca42b01369f71e4801d7572b1609e2a8909dbfe4223164929f82038b3c1f472b8108f"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-03 22:49:06.525515) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 22:49:06.525830) ========= 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.kXyTyVPiVf/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.vQKueimyk3/Listeners TMPDIR=/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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.005325 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 22:49:06.575180) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-03 22:49:06.575503) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-03 22:49:06.575827) ========= ========= Total master_lag: 1.13 =========