builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0262 starttime: 1480600007.6 results: success (0) buildid: 20161201030205 builduid: c18f004833f44f70a99271428f991809 revision: cd4cdcc9ad6c45dad8b8d8c0d40e459db2bca8a1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-01 05:46:47.600860) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-01 05:46:47.601385) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-01 05:46:47.601678) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-01 05:46:47.634289) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-01 05:46:47.634704) ========= 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.OBHIyN7TA7/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.hHKMd3o5No/Listeners TMPDIR=/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-01 05:46:47-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 43.5M=0s 2016-12-01 05:46:47 (43.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.140634 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-01 05:46:47.804524) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-01 05:46:47.804854) ========= 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.OBHIyN7TA7/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.hHKMd3o5No/Listeners TMPDIR=/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/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.024036 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-01 05:46:47.853164) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-12-01 05:46:47.856545) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev cd4cdcc9ad6c45dad8b8d8c0d40e459db2bca8a1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev cd4cdcc9ad6c45dad8b8d8c0d40e459db2bca8a1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.OBHIyN7TA7/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.hHKMd3o5No/Listeners TMPDIR=/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-01 05:46:47,956 truncating revision to first 12 chars 2016-12-01 05:46:47,957 Setting DEBUG logging. 2016-12-01 05:46:47,957 attempt 1/10 2016-12-01 05:46:47,957 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/cd4cdcc9ad6c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-01 05:46:50,316 unpacking tar archive at: mozilla-central-cd4cdcc9ad6c/testing/mozharness/ program finished with exit code 0 elapsedTime=2.655157 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-12-01 05:46:50.540269) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-01 05:46:50.540599) ========= script_repo_revision: cd4cdcc9ad6c45dad8b8d8c0d40e459db2bca8a1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-01 05:46:50.541014) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-01 05:46:50.541307) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-01 05:46:50.562880) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-12-01 05:46:50.563234) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.OBHIyN7TA7/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.hHKMd3o5No/Listeners TMPDIR=/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/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 05:46:50 INFO - MultiFileLogger online at 20161201 05:46:50 in /builds/slave/test 05:46:50 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:46:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:46:50 INFO - {'append_to_log': False, 05:46:50 INFO - 'base_work_dir': '/builds/slave/test', 05:46:50 INFO - 'blob_upload_branch': 'Firefox', 05:46:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:46:50 INFO - 'branch': 'Firefox', 05:46:50 INFO - 'buildbot_json_path': 'buildprops.json', 05:46:50 INFO - 'config_files': ('talos/mac_config.py',), 05:46:50 INFO - 'default_actions': ('clobber', 05:46:50 INFO - 'read-buildbot-config', 05:46:50 INFO - 'download-and-extract', 05:46:50 INFO - 'populate-webroot', 05:46:50 INFO - 'create-virtualenv', 05:46:50 INFO - 'install', 05:46:50 INFO - 'run-tests'), 05:46:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:46:50 INFO - 'download_minidump_stackwalk': True, 05:46:50 INFO - 'download_symbols': 'ondemand', 05:46:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:46:50 INFO - 'tooltool.py': '/tools/tooltool.py', 05:46:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:46:50 INFO - '/tools/misc-python/virtualenv.py')}, 05:46:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:46:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:46:50 INFO - 'installer_path': 'installer.exe', 05:46:50 INFO - 'log_level': 'info', 05:46:50 INFO - 'log_name': 'talos', 05:46:50 INFO - 'log_to_console': True, 05:46:50 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:46:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:46:50 INFO - 'opt_config_files': (), 05:46:50 INFO - 'pip_index': False, 05:46:50 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:46:50 INFO - 'cmd': ('bash', 05:46:50 INFO - '-c', 05:46:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:46:50 INFO - 'enabled': True, 05:46:50 INFO - 'halt_on_failure': False, 05:46:50 INFO - 'name': 'check_screen_resolution'},), 05:46:50 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:46:50 INFO - 'cmd': ('bash', 05:46:50 INFO - '-c', 05:46:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:46:50 INFO - 'enabled': True, 05:46:50 INFO - 'halt_on_failure': False, 05:46:50 INFO - 'name': 'check_screen_resolution'},), 05:46:50 INFO - 'run_cmd_checks_enabled': True, 05:46:50 INFO - 'sps_profile': False, 05:46:50 INFO - 'sps_profile_interval': 0, 05:46:50 INFO - 'suite': 'dromaeojs-e10s', 05:46:50 INFO - 'system_bits': '32', 05:46:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:46:50 INFO - 'title': 't-yosemite-r7-0262', 05:46:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:46:50 INFO - 'use_talos_json': True, 05:46:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:46:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:46:50 INFO - 'work_dir': 'build'} 05:46:50 INFO - [mozharness: 2016-12-01 13:46:50.766775Z] Running clobber step. 05:46:50 INFO - Running pre-action listener: _resource_record_pre_action 05:46:50 INFO - Running main action method: clobber 05:46:50 INFO - rmtree: /builds/slave/test/build 05:46:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:46:53 INFO - Running post-action listener: _resource_record_post_action 05:46:53 INFO - [mozharness: 2016-12-01 13:46:53.199648Z] Finished clobber step (success) 05:46:53 INFO - [mozharness: 2016-12-01 13:46:53.199811Z] Running read-buildbot-config step. 05:46:53 INFO - Running pre-action listener: _resource_record_pre_action 05:46:53 INFO - Running main action method: read_buildbot_config 05:46:53 INFO - Using buildbot properties: 05:46:53 INFO - { 05:46:53 INFO - "project": "", 05:46:53 INFO - "product": "firefox", 05:46:53 INFO - "script_repo_revision": "production", 05:46:53 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:46:53 INFO - "repository": "", 05:46:53 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:46:53 INFO - "buildid": "20161201030205", 05:46:53 INFO - "stage_platform": "macosx64", 05:46:53 INFO - "basedir": "/builds/slave/test", 05:46:53 INFO - "nightly_build": "True", 05:46:53 INFO - "buildnumber": 188, 05:46:53 INFO - "slavename": "t-yosemite-r7-0262", 05:46:53 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 05:46:53 INFO - "platform": "yosemite_r7", 05:46:53 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:46:53 INFO - "branch": "mozilla-central", 05:46:53 INFO - "repo_path": "mozilla-central", 05:46:53 INFO - "revision": "cd4cdcc9ad6c45dad8b8d8c0d40e459db2bca8a1", 05:46:53 INFO - "pgo_build": "False", 05:46:53 INFO - "builduid": "c18f004833f44f70a99271428f991809", 05:46:53 INFO - "slavebuilddir": "test" 05:46:53 INFO - } 05:46:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/Ul3Yu49OQXOoTmQIagjHrQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 05:46:53 INFO - Running post-action listener: _resource_record_post_action 05:46:53 INFO - [mozharness: 2016-12-01 13:46:53.205701Z] Finished read-buildbot-config step (success) 05:46:53 INFO - [mozharness: 2016-12-01 13:46:53.205914Z] Running download-and-extract step. 05:46:53 INFO - Running pre-action listener: _resource_record_pre_action 05:46:53 INFO - Running main action method: download_and_extract 05:46:53 INFO - mkdir: /builds/slave/test/build/tests 05:46:53 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')]} 05:46:53 INFO - trying https://queue.taskcluster.net/v1/task/Ul3Yu49OQXOoTmQIagjHrQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 05:46:53 INFO - Downloading https://queue.taskcluster.net/v1/task/Ul3Yu49OQXOoTmQIagjHrQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 05:46:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ul3Yu49OQXOoTmQIagjHrQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 05:46:54 INFO - Downloaded 1199 bytes. 05:46:54 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 05:46:54 INFO - Using the following test package requirements: 05:46:54 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 05:46:54 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:46:54 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 05:46:54 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:46:54 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 05:46:54 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:46:54 INFO - u'jsshell-mac.zip'], 05:46:54 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:46:54 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 05:46:54 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 05:46:54 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:46:54 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 05:46:54 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:46:54 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 05:46:54 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:46:54 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 05:46:54 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:46:54 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 05:46:54 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:46:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ul3Yu49OQXOoTmQIagjHrQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 05:46:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ul3Yu49OQXOoTmQIagjHrQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:46:54 INFO - Fetch https://queue.taskcluster.net/v1/task/Ul3Yu49OQXOoTmQIagjHrQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 05:46:55 INFO - Expected file size: 16275629 05:46:55 INFO - Obtained file size: 16275629 05:47:02 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:47:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ul3Yu49OQXOoTmQIagjHrQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 05:47:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ul3Yu49OQXOoTmQIagjHrQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:47:02 INFO - Fetch https://queue.taskcluster.net/v1/task/Ul3Yu49OQXOoTmQIagjHrQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 05:47:02 INFO - Expected file size: 16275629 05:47:02 INFO - Obtained file size: 16275629 05:47:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ul3Yu49OQXOoTmQIagjHrQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 05:47:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ul3Yu49OQXOoTmQIagjHrQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:47:09 INFO - Fetch https://queue.taskcluster.net/v1/task/Ul3Yu49OQXOoTmQIagjHrQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 05:47:09 INFO - Expected file size: 14409702 05:47:09 INFO - Obtained file size: 14409702 05:47: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')]} 05:47:10 INFO - trying https://queue.taskcluster.net/v1/task/Ul3Yu49OQXOoTmQIagjHrQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 05:47:10 INFO - Downloading https://queue.taskcluster.net/v1/task/Ul3Yu49OQXOoTmQIagjHrQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 05:47:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ul3Yu49OQXOoTmQIagjHrQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 05:47:13 INFO - Downloaded 107751667 bytes. 05:47:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ul3Yu49OQXOoTmQIagjHrQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 05:47:13 INFO - mkdir: /builds/slave/test/properties 05:47:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:47:13 INFO - Writing to file /builds/slave/test/properties/build_url 05:47:13 INFO - Contents: 05:47:13 INFO - build_url:https://queue.taskcluster.net/v1/task/Ul3Yu49OQXOoTmQIagjHrQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 05:47:13 INFO - Running post-action listener: _resource_record_post_action 05:47:13 INFO - Running post-action listener: set_extra_try_arguments 05:47:13 INFO - [mozharness: 2016-12-01 13:47:13.777362Z] Finished download-and-extract step (success) 05:47:13 INFO - [mozharness: 2016-12-01 13:47:13.777494Z] Running populate-webroot step. 05:47:13 INFO - Running pre-action listener: _resource_record_pre_action 05:47:13 INFO - Running main action method: populate_webroot 05:47:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:47:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:47:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:47:13 INFO - '--tppagecycles', 05:47:13 INFO - '7'], 05:47:13 INFO - 'tests': ['tsvgm']}}, 05:47:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:47:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:47:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:47:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:47:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:47:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:47:13 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:47:13 INFO - 'talos_options': ['--disable-e10s'], 05:47:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:47:13 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:47:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:47:13 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:47:13 INFO - 'talos_options': ['--disable-e10s'], 05:47:13 INFO - 'tests': ['damp', 'tps']}, 05:47:13 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:47:13 INFO - 'tests': ['damp', 'tps']}, 05:47:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:47:13 INFO - 'tests': ['dromaeo_dom']}, 05:47:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:47:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:47:13 INFO - 'tests': ['basic_compositor_video']}, 05:47:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:47:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:47:13 INFO - 'tests': ['a11yr', 05:47:13 INFO - 'ts_paint', 05:47:13 INFO - 'tpaint', 05:47:13 INFO - 'sessionrestore', 05:47:13 INFO - 'sessionrestore_no_auto_restore', 05:47:13 INFO - 'tabpaint']}, 05:47:13 INFO - 'other-e10s': {'tests': ['a11yr', 05:47:13 INFO - 'ts_paint', 05:47:13 INFO - 'tpaint', 05:47:13 INFO - 'sessionrestore', 05:47:13 INFO - 'sessionrestore_no_auto_restore', 05:47:13 INFO - 'tabpaint']}, 05:47:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:47:13 INFO - 'ts_paint', 05:47:13 INFO - 'tpaint', 05:47:13 INFO - 'sessionrestore', 05:47:13 INFO - 'sessionrestore_no_auto_restore', 05:47:13 INFO - 'tabpaint']}, 05:47:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:47:13 INFO - 'ts_paint', 05:47:13 INFO - 'tpaint', 05:47:13 INFO - 'sessionrestore', 05:47:13 INFO - 'sessionrestore_no_auto_restore', 05:47:13 INFO - 'tabpaint']}, 05:47:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:47:13 INFO - 'tests': ['a11yr', 05:47:13 INFO - 'ts_paint', 05:47:13 INFO - 'tpaint', 05:47:13 INFO - 'sessionrestore', 05:47:13 INFO - 'sessionrestore_no_auto_restore', 05:47:13 INFO - 'tabpaint']}, 05:47:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:47:13 INFO - 'tests': ['a11yr', 05:47:13 INFO - 'ts_paint', 05:47:13 INFO - 'tpaint', 05:47:13 INFO - 'sessionrestore', 05:47:13 INFO - 'sessionrestore_no_auto_restore', 05:47:13 INFO - 'tabpaint']}, 05:47:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:47:13 INFO - 'tests': ['tsvgx', 05:47:13 INFO - 'tsvgr_opacity', 05:47:13 INFO - 'tart', 05:47:13 INFO - 'tscrollx', 05:47:13 INFO - 'cart', 05:47:13 INFO - 'tsvg_static']}, 05:47:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:47:13 INFO - 'tsvgr_opacity', 05:47:13 INFO - 'tart', 05:47:13 INFO - 'tscrollx', 05:47:13 INFO - 'cart', 05:47:13 INFO - 'tsvg_static']}, 05:47:13 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:47:13 INFO - 'talos_options': ['--disable-e10s'], 05:47:13 INFO - 'tests': ['tp5o']}, 05:47:13 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:47:13 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:47:13 INFO - 'talos_options': ['--disable-e10s', 05:47:13 INFO - '--xperf_path', 05:47:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:47:13 INFO - 'tests': ['tp5n']}, 05:47:13 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:47:13 INFO - 'talos_options': ['--xperf_path', 05:47:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:47:13 INFO - 'tests': ['tp5n']}}, 05:47:13 INFO - 'talos.zip': {'path': '', 05:47:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:47:13 INFO - Running post-action listener: _resource_record_post_action 05:47:13 INFO - [mozharness: 2016-12-01 13:47:13.795841Z] Finished populate-webroot step (success) 05:47:13 INFO - [mozharness: 2016-12-01 13:47:13.795984Z] Running create-virtualenv step. 05:47:13 INFO - Running pre-action listener: _resource_record_pre_action 05:47:13 INFO - Running main action method: create_virtualenv 05:47:13 INFO - Creating virtualenv /builds/slave/test/build/venv 05:47: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 05:47:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:47:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:47:13 INFO - Using real prefix '/tools/python27' 05:47:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:47:14 INFO - Installing distribute.............................................................................................................................................................................................done. 05:47:17 INFO - Installing pip.................done. 05:47:17 INFO - Return code: 0 05:47:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:47:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:47:17 INFO - Reading from file tmpfile_stdout 05:47:17 INFO - Output received: 05:47: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) 05:47:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:47: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')]} 05:47:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47: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')]} 05:47:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47: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 0x10faf53f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa6b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8e8c7fb00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f90bd30>, '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 0x10f772db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f903b20>, '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.OBHIyN7TA7/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.hHKMd3o5No/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/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 05:47: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 05:47: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 05:47:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OBHIyN7TA7/Render', 05:47:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:17 INFO - 'HOME': '/Users/cltbld', 05:47:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:17 INFO - 'LOGNAME': 'cltbld', 05:47:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:17 INFO - 'MOZ_NO_REMOTE': '1', 05:47:17 INFO - 'NO_EM_RESTART': '1', 05:47:17 INFO - 'PAGER': '/bin/cat', 05:47:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:17 INFO - 'PWD': '/builds/slave/test', 05:47:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:17 INFO - 'SHELL': '/bin/bash', 05:47:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hHKMd3o5No/Listeners', 05:47:17 INFO - 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', 05:47:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:17 INFO - 'USER': 'cltbld', 05:47:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:17 INFO - 'XPC_FLAGS': '0x0', 05:47:17 INFO - 'XPC_SERVICE_NAME': '0', 05:47:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47: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 05:47:18 INFO - Cleaning up... 05:47:18 INFO - Return code: 0 05:47:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:47: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')]} 05:47:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47: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')]} 05:47:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47: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 0x10faf53f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa6b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8e8c7fb00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f90bd30>, '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 0x10f772db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f903b20>, '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.OBHIyN7TA7/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.hHKMd3o5No/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/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 05:47: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 05:47: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 05:47:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OBHIyN7TA7/Render', 05:47:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:18 INFO - 'HOME': '/Users/cltbld', 05:47:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:18 INFO - 'LOGNAME': 'cltbld', 05:47:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:18 INFO - 'MOZ_NO_REMOTE': '1', 05:47:18 INFO - 'NO_EM_RESTART': '1', 05:47:18 INFO - 'PAGER': '/bin/cat', 05:47:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:18 INFO - 'PWD': '/builds/slave/test', 05:47:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:18 INFO - 'SHELL': '/bin/bash', 05:47:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hHKMd3o5No/Listeners', 05:47:18 INFO - 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', 05:47:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:18 INFO - 'USER': 'cltbld', 05:47:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:18 INFO - 'XPC_FLAGS': '0x0', 05:47:18 INFO - 'XPC_SERVICE_NAME': '0', 05:47:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47:18 INFO - Downloading/unpacking psutil>=3.1.1 05:47: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 05:47: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 05:47: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 05:47: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 05:47: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 05:47: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 05:47:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:47:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:47:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:47:21 INFO - Installing collected packages: psutil 05:47:21 INFO - Running setup.py install for psutil 05:47:21 INFO - building 'psutil._psutil_osx' extension 05:47: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 05:47: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 05:47: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 05:47: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 05:47:22 INFO - building 'psutil._psutil_posix' extension 05:47: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 05:47:22 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:47:22 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:47:22 INFO - ^ 05:47:22 INFO - 1 warning generated. 05:47: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 05:47:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:47:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:47:22 INFO - Successfully installed psutil 05:47:22 INFO - Cleaning up... 05:47:23 INFO - Return code: 0 05:47:23 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:47: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')]} 05:47:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47: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')]} 05:47:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47: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 0x10faf53f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa6b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8e8c7fb00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f90bd30>, '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 0x10f772db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f903b20>, '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.OBHIyN7TA7/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.hHKMd3o5No/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/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 05:47: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 05:47: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 05:47:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OBHIyN7TA7/Render', 05:47:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:23 INFO - 'HOME': '/Users/cltbld', 05:47:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:23 INFO - 'LOGNAME': 'cltbld', 05:47:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:23 INFO - 'MOZ_NO_REMOTE': '1', 05:47:23 INFO - 'NO_EM_RESTART': '1', 05:47:23 INFO - 'PAGER': '/bin/cat', 05:47:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:23 INFO - 'PWD': '/builds/slave/test', 05:47:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:23 INFO - 'SHELL': '/bin/bash', 05:47:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hHKMd3o5No/Listeners', 05:47:23 INFO - 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', 05:47:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:23 INFO - 'USER': 'cltbld', 05:47:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:23 INFO - 'XPC_FLAGS': '0x0', 05:47:23 INFO - 'XPC_SERVICE_NAME': '0', 05:47:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:47: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 05:47: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 05:47: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 05:47: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 05:47: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 05:47: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 05:47:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:47:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:47:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:47:26 INFO - Installing collected packages: mozsystemmonitor 05:47:26 INFO - Running setup.py install for mozsystemmonitor 05:47:26 INFO - Successfully installed mozsystemmonitor 05:47:26 INFO - Cleaning up... 05:47:26 INFO - Return code: 0 05:47:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:47: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')]} 05:47:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47: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')]} 05:47:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47: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 0x10faf53f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa6b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8e8c7fb00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f90bd30>, '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 0x10f772db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f903b20>, '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.OBHIyN7TA7/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.hHKMd3o5No/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/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 05:47: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 05:47: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 05:47:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OBHIyN7TA7/Render', 05:47:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:26 INFO - 'HOME': '/Users/cltbld', 05:47:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:26 INFO - 'LOGNAME': 'cltbld', 05:47:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:26 INFO - 'MOZ_NO_REMOTE': '1', 05:47:26 INFO - 'NO_EM_RESTART': '1', 05:47:26 INFO - 'PAGER': '/bin/cat', 05:47:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:26 INFO - 'PWD': '/builds/slave/test', 05:47:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:26 INFO - 'SHELL': '/bin/bash', 05:47:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hHKMd3o5No/Listeners', 05:47:26 INFO - 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', 05:47:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:26 INFO - 'USER': 'cltbld', 05:47:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:26 INFO - 'XPC_FLAGS': '0x0', 05:47:26 INFO - 'XPC_SERVICE_NAME': '0', 05:47:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47:26 INFO - Downloading/unpacking jsonschema==2.5.1 05:47: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 05:47: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 05:47: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 05:47: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 05:47: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 05:47: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 05:47:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:47:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:47:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:47:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:47:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:47:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:47:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:47:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:47:29 INFO - Downloading functools32-3.2.3-2.tar.gz 05:47:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:47:29 INFO - warning: no files found matching '*.txt' 05:47:29 INFO - no previously-included directories found matching 'build' 05:47:29 INFO - no previously-included directories found matching 'dist' 05:47:29 INFO - no previously-included directories found matching '.git*' 05:47:29 INFO - Installing collected packages: jsonschema, functools32 05:47:29 INFO - Running setup.py install for functools32 05:47:29 INFO - warning: no files found matching '*.txt' 05:47:29 INFO - no previously-included directories found matching 'build' 05:47:29 INFO - no previously-included directories found matching 'dist' 05:47:29 INFO - no previously-included directories found matching '.git*' 05:47:29 INFO - Successfully installed jsonschema functools32 05:47:29 INFO - Cleaning up... 05:47:29 INFO - Return code: 0 05:47:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:47: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')]} 05:47:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47: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')]} 05:47:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47: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 0x10faf53f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa6b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8e8c7fb00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f90bd30>, '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 0x10f772db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f903b20>, '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.OBHIyN7TA7/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.hHKMd3o5No/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/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 05:47: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 05:47: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 05:47:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OBHIyN7TA7/Render', 05:47:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:29 INFO - 'HOME': '/Users/cltbld', 05:47:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:29 INFO - 'LOGNAME': 'cltbld', 05:47:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:29 INFO - 'MOZ_NO_REMOTE': '1', 05:47:29 INFO - 'NO_EM_RESTART': '1', 05:47:29 INFO - 'PAGER': '/bin/cat', 05:47:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:29 INFO - 'PWD': '/builds/slave/test', 05:47:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:29 INFO - 'SHELL': '/bin/bash', 05:47:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hHKMd3o5No/Listeners', 05:47:29 INFO - 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', 05:47:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:29 INFO - 'USER': 'cltbld', 05:47:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:29 INFO - 'XPC_FLAGS': '0x0', 05:47:29 INFO - 'XPC_SERVICE_NAME': '0', 05:47:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:47:30 INFO - Cleaning up... 05:47:30 INFO - Return code: 0 05:47:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:47:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:47:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:30 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 0x10faf53f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa6b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8e8c7fb00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f90bd30>, '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 0x10f772db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f903b20>, '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.OBHIyN7TA7/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.hHKMd3o5No/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/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 05:47:30 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 05:47:30 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 05:47:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OBHIyN7TA7/Render', 05:47:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:30 INFO - 'HOME': '/Users/cltbld', 05:47:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:30 INFO - 'LOGNAME': 'cltbld', 05:47:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:30 INFO - 'MOZ_NO_REMOTE': '1', 05:47:30 INFO - 'NO_EM_RESTART': '1', 05:47:30 INFO - 'PAGER': '/bin/cat', 05:47:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:30 INFO - 'PWD': '/builds/slave/test', 05:47:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:30 INFO - 'SHELL': '/bin/bash', 05:47:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hHKMd3o5No/Listeners', 05:47:30 INFO - 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', 05:47:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:30 INFO - 'USER': 'cltbld', 05:47:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:30 INFO - 'XPC_FLAGS': '0x0', 05:47:30 INFO - 'XPC_SERVICE_NAME': '0', 05:47:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47:30 INFO - Downloading/unpacking blobuploader==1.2.4 05:47: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 05:47: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 05:47: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 05:47: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 05:47: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 05:47: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 05:47:33 INFO - Downloading blobuploader-1.2.4.tar.gz 05:47:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:47:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:47: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 05:47: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 05:47: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 05:47: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 05:47: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 05:47: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 05:47:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:47:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:47: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 05:47: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 05:47: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 05:47: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 05:47: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 05:47: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 05:47:34 INFO - Downloading docopt-0.6.1.tar.gz 05:47:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:47:34 INFO - Installing collected packages: blobuploader, requests, docopt 05:47:34 INFO - Running setup.py install for blobuploader 05:47:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:47:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:47:34 INFO - Running setup.py install for requests 05:47:34 INFO - Running setup.py install for docopt 05:47:35 INFO - Successfully installed blobuploader requests docopt 05:47:35 INFO - Cleaning up... 05:47:35 INFO - Return code: 0 05:47:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:47:35 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')]} 05:47:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:35 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')]} 05:47:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:35 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 0x10faf53f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa6b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8e8c7fb00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f90bd30>, '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 0x10f772db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f903b20>, '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.OBHIyN7TA7/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.hHKMd3o5No/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/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 05:47:35 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 05:47:35 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 05:47:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OBHIyN7TA7/Render', 05:47:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:35 INFO - 'HOME': '/Users/cltbld', 05:47:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:35 INFO - 'LOGNAME': 'cltbld', 05:47:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:35 INFO - 'MOZ_NO_REMOTE': '1', 05:47:35 INFO - 'NO_EM_RESTART': '1', 05:47:35 INFO - 'PAGER': '/bin/cat', 05:47:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:35 INFO - 'PWD': '/builds/slave/test', 05:47:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:35 INFO - 'SHELL': '/bin/bash', 05:47:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hHKMd3o5No/Listeners', 05:47:35 INFO - 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', 05:47:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:35 INFO - 'USER': 'cltbld', 05:47:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:35 INFO - 'XPC_FLAGS': '0x0', 05:47:35 INFO - 'XPC_SERVICE_NAME': '0', 05:47:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:47:35 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-ZgIG5R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:47:35 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-ngarnQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:47:35 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-dS9WiZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:47:35 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-h8Kp9X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:47:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:47:35 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-7bxZ7T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:47:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:47:36 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-Q1emLJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:47:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:47:36 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-BgeRMf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:47:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:47:36 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-jJFXDh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:47:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:47:36 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-yogH6i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:47:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:47:36 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-erT1yD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:47:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:47:36 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-29axK2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:47:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:47:36 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-krmTc5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:47:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:47:36 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-rYmkrW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:47:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:47:36 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-6thf7a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:47:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:47:36 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-wSq_LZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:47:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:47:37 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-RYcApe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:47:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:47:37 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-q8E6fD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:47:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:47:37 INFO - Running setup.py install for manifestparser 05:47:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:47:37 INFO - Running setup.py install for mozcrash 05:47:37 INFO - Running setup.py install for mozdebug 05:47:37 INFO - Running setup.py install for mozdevice 05:47:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:47:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:47:37 INFO - Running setup.py install for mozfile 05:47:38 INFO - Running setup.py install for mozhttpd 05:47:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:47:38 INFO - Running setup.py install for mozinfo 05:47:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:47:38 INFO - Running setup.py install for mozInstall 05:47:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:47:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:47:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:47:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:47:38 INFO - Running setup.py install for mozleak 05:47:38 INFO - Running setup.py install for mozlog 05:47:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:47:38 INFO - Running setup.py install for moznetwork 05:47:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:47:38 INFO - Running setup.py install for mozprocess 05:47:39 INFO - Running setup.py install for mozprofile 05:47:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:47:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:47:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:47:39 INFO - Running setup.py install for mozrunner 05:47:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:47:39 INFO - Running setup.py install for mozscreenshot 05:47:39 INFO - Running setup.py install for moztest 05:47:39 INFO - Running setup.py install for mozversion 05:47:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:47:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:47:39 INFO - Cleaning up... 05:47:39 INFO - Return code: 0 05:47:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:47: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')]} 05:47:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47: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')]} 05:47:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47: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 0x10faf53f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa6b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8e8c7fb00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f90bd30>, '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 0x10f772db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f903b20>, '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.OBHIyN7TA7/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.hHKMd3o5No/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/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 05:47: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 05:47: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 05:47:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OBHIyN7TA7/Render', 05:47:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:39 INFO - 'HOME': '/Users/cltbld', 05:47:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:39 INFO - 'LOGNAME': 'cltbld', 05:47:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:39 INFO - 'MOZ_NO_REMOTE': '1', 05:47:39 INFO - 'NO_EM_RESTART': '1', 05:47:39 INFO - 'PAGER': '/bin/cat', 05:47:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:39 INFO - 'PWD': '/builds/slave/test', 05:47:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:39 INFO - 'SHELL': '/bin/bash', 05:47:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hHKMd3o5No/Listeners', 05:47:39 INFO - 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', 05:47:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:39 INFO - 'USER': 'cltbld', 05:47:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:39 INFO - 'XPC_FLAGS': '0x0', 05:47:39 INFO - 'XPC_SERVICE_NAME': '0', 05:47:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:47:40 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-RttTu9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:47:40 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)) 05:47:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:47:40 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-_zozIE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:47:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:47:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:47:40 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-0wPwDT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:47:40 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)) 05:47:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:47:40 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-qvKjwI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:47:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:47:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:47:40 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-xZbQSH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:47: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)) 05:47:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:47:40 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-xF_bFH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:47: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)) 05:47:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:47:40 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-XApUid-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:47: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)) 05:47:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:47:40 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-GMVVVA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:47: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)) 05:47:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:47:40 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-yyujwH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:47:41 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)) 05:47:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:47:41 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-YM6yUW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:47:41 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)) 05:47:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:47:41 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-xJKTgt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:47:41 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)) 05:47:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:47:41 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-ePt8Oe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:47:41 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)) 05:47:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:47:41 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-8U6mLo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:47: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)) 05:47:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:47:41 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-atVMqc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:47:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:47:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:47:41 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-7d8hb3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:47: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)) 05:47:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:47:41 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-4jCcNI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:47: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)) 05:47:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:47:41 INFO - Running setup.py (path:/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/pip-QDdaEt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:47: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)) 05:47:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:47:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:47: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)) 05:47:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:47:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:47:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:47: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 05:47: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 05:47: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 05:47: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 05:47: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 05:47: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 05:47:44 INFO - Downloading blessings-1.6.tar.gz 05:47:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:47:44 INFO - Installing collected packages: blessings 05:47:44 INFO - Running setup.py install for blessings 05:47:45 INFO - Successfully installed blessings 05:47:45 INFO - Cleaning up... 05:47:45 INFO - Return code: 0 05:47:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:47:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:47:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:47:45 INFO - Reading from file tmpfile_stdout 05:47:45 INFO - Current package versions: 05:47:45 INFO - blessings == 1.6 05:47:45 INFO - blobuploader == 1.2.4 05:47:45 INFO - docopt == 0.6.1 05:47:45 INFO - functools32 == 3.2.3-2 05:47:45 INFO - jsonschema == 2.5.1 05:47:45 INFO - manifestparser == 1.1 05:47:45 INFO - mozInstall == 1.12 05:47:45 INFO - mozcrash == 1.0 05:47:45 INFO - mozdebug == 0.1 05:47:45 INFO - mozdevice == 0.49 05:47:45 INFO - mozfile == 1.2 05:47:45 INFO - mozhttpd == 0.7 05:47:45 INFO - mozinfo == 0.9 05:47:45 INFO - mozleak == 0.1 05:47:45 INFO - mozlog == 3.4 05:47:45 INFO - moznetwork == 0.27 05:47:45 INFO - mozprocess == 0.23 05:47:45 INFO - mozprofile == 0.28 05:47:45 INFO - mozrunner == 6.13 05:47:45 INFO - mozscreenshot == 0.1 05:47:45 INFO - mozsystemmonitor == 0.3 05:47:45 INFO - moztest == 0.7 05:47:45 INFO - mozversion == 1.4 05:47:45 INFO - psutil == 3.1.1 05:47:45 INFO - requests == 1.2.3 05:47:45 INFO - wsgiref == 0.1.2 05:47:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:47:45 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')]} 05:47:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:45 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')]} 05:47:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:45 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 0x10faf53f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa6b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8e8c7fb00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f90bd30>, '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 0x10f772db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f903b20>, '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.OBHIyN7TA7/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.hHKMd3o5No/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/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 05:47:45 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 05:47:45 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 05:47:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OBHIyN7TA7/Render', 05:47:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:45 INFO - 'HOME': '/Users/cltbld', 05:47:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:45 INFO - 'LOGNAME': 'cltbld', 05:47:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:45 INFO - 'MOZ_NO_REMOTE': '1', 05:47:45 INFO - 'NO_EM_RESTART': '1', 05:47:45 INFO - 'PAGER': '/bin/cat', 05:47:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:45 INFO - 'PWD': '/builds/slave/test', 05:47:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:45 INFO - 'SHELL': '/bin/bash', 05:47:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hHKMd3o5No/Listeners', 05:47:45 INFO - 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', 05:47:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:45 INFO - 'USER': 'cltbld', 05:47:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:45 INFO - 'XPC_FLAGS': '0x0', 05:47:45 INFO - 'XPC_SERVICE_NAME': '0', 05:47:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47:46 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)) 05:47:46 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)) 05:47:46 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)) 05:47:46 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)) 05:47:46 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)) 05:47:46 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)) 05:47:46 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)) 05:47:46 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)) 05:47:46 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)) 05:47:46 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)) 05:47:46 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)) 05:47:46 INFO - Cleaning up... 05:47:46 INFO - Return code: 0 05:47:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:47:46 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')]} 05:47:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:46 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')]} 05:47:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:46 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 0x10faf53f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa6b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8e8c7fb00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f90bd30>, '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 0x10f772db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f903b20>, '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.OBHIyN7TA7/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.hHKMd3o5No/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/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 05:47:46 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 05:47:46 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 05:47:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OBHIyN7TA7/Render', 05:47:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:46 INFO - 'HOME': '/Users/cltbld', 05:47:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:46 INFO - 'LOGNAME': 'cltbld', 05:47:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:46 INFO - 'MOZ_NO_REMOTE': '1', 05:47:46 INFO - 'NO_EM_RESTART': '1', 05:47:46 INFO - 'PAGER': '/bin/cat', 05:47:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:46 INFO - 'PWD': '/builds/slave/test', 05:47:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:46 INFO - 'SHELL': '/bin/bash', 05:47:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hHKMd3o5No/Listeners', 05:47:46 INFO - 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', 05:47:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:46 INFO - 'USER': 'cltbld', 05:47:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:46 INFO - 'XPC_FLAGS': '0x0', 05:47:46 INFO - 'XPC_SERVICE_NAME': '0', 05:47:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:47:46 INFO - Cleaning up... 05:47:46 INFO - Return code: 0 05:47:46 INFO - Running post-action listener: _resource_record_post_action 05:47:46 INFO - Running post-action listener: _start_resource_monitoring 05:47:46 INFO - Starting resource monitoring. 05:47:46 INFO - [mozharness: 2016-12-01 13:47:46.487077Z] Finished create-virtualenv step (success) 05:47:46 INFO - [mozharness: 2016-12-01 13:47:46.488040Z] Running install step. 05:47:46 INFO - Running pre-action listener: _resource_record_pre_action 05:47:46 INFO - Running main action method: install 05:47:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:47:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:47:46 INFO - Reading from file tmpfile_stdout 05:47:46 INFO - Detecting whether we're running mozinstall >=1.0... 05:47:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:47:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:47:46 INFO - Reading from file tmpfile_stdout 05:47:46 INFO - Output received: 05:47:46 INFO - Usage: mozinstall [options] installer 05:47:46 INFO - Options: 05:47:46 INFO - -h, --help show this help message and exit 05:47:46 INFO - -d DEST, --destination=DEST 05:47:46 INFO - Directory to install application into. [default: 05:47:46 INFO - "/builds/slave/test"] 05:47:46 INFO - --app=APP Application being installed. [default: firefox] 05:47:46 INFO - mkdir: /builds/slave/test/build/application 05:47:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:47:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:48:19 INFO - Reading from file tmpfile_stdout 05:48:19 INFO - Output received: 05:48:19 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:48:19 INFO - Running post-action listener: _resource_record_post_action 05:48:19 INFO - [mozharness: 2016-12-01 13:48:19.891837Z] Finished install step (success) 05:48:19 INFO - [mozharness: 2016-12-01 13:48:19.892006Z] Running run-tests step. 05:48:19 INFO - Running pre-action listener: _resource_record_pre_action 05:48:19 INFO - Running main action method: run_tests 05:48:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:48:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:48:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:48:19 INFO - 2016-12-01 05:48:19.906 screenresolution[1622:7346] starting screenresolution argv=screenresolution get 05:48:19 INFO - 2016-12-01 05:48:19.909 screenresolution[1622:7346] Display 0: 1600x1200x32@60 05:48:19 INFO - 2016-12-01 05:48:19.914 screenresolution[1623:7348] starting screenresolution argv=screenresolution list 05:48:19 INFO - Available Modes on Display 0 05:48:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:48:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:48:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:48:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:48:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:48:20 INFO - Intel Iris: 05:48:20 INFO - Chipset Model: Intel Iris 05:48:20 INFO - Type: GPU 05:48:20 INFO - Bus: Built-In 05:48:20 INFO - VRAM (Dynamic, Max): 1536 MB 05:48:20 INFO - Vendor: Intel (0x8086) 05:48:20 INFO - Device ID: 0x0a2e 05:48:20 INFO - Revision ID: 0x0009 05:48:20 INFO - Displays: 05:48:20 INFO - Display: 05:48:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:48:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:48:20 INFO - Display Serial Number: 1600x1200 60 05:48:20 INFO - Main Display: Yes 05:48:20 INFO - Mirror: Off 05:48:20 INFO - Online: Yes 05:48:20 INFO - Rotation: Supported 05:48:20 INFO - Return code: 0 05:48:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:48:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:48:20 INFO - Python 2.7.3 05:48:20 INFO - Return code: 0 05:48:20 INFO - grabbing minidump binary from tooltool 05:48: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')]} 05:48: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 0x10f90bd30>, '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 0x10f772db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f903b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:48: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 05:48: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 05:48: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 05:48:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:48:20 INFO - Return code: 0 05:48:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:48:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:48:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:48:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:48:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:48:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ul3Yu49OQXOoTmQIagjHrQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0262', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:48:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Ul3Yu49OQXOoTmQIagjHrQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0262 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:48:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OBHIyN7TA7/Render', 05:48:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:48:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:48:20 INFO - 'HOME': '/Users/cltbld', 05:48:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:48:20 INFO - 'LOGNAME': 'cltbld', 05:48:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:48:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:48:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:48:20 INFO - 'MOZ_NO_REMOTE': '1', 05:48:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:48:20 INFO - 'NO_EM_RESTART': '1', 05:48:20 INFO - 'PAGER': '/bin/cat', 05:48:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:48:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:48:20 INFO - 'PWD': '/builds/slave/test', 05:48:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:48:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:48:20 INFO - 'SHELL': '/bin/bash', 05:48:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hHKMd3o5No/Listeners', 05:48:20 INFO - 'TMPDIR': '/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/', 05:48:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:48:20 INFO - 'USER': 'cltbld', 05:48:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:48:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:48:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:48:20 INFO - 'XPC_FLAGS': '0x0', 05:48:20 INFO - 'XPC_SERVICE_NAME': '0', 05:48:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:48:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ul3Yu49OQXOoTmQIagjHrQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0262', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:48:20 INFO - mozversion application_buildid: 20161201030205 05:48:20 INFO - mozversion application_changeset: cd4cdcc9ad6c45dad8b8d8c0d40e459db2bca8a1 05:48:20 INFO - mozversion application_display_name: Nightly 05:48:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:48:20 INFO - mozversion application_name: Firefox 05:48:20 INFO - mozversion application_remotingname: firefox 05:48:20 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:48:20 INFO - mozversion application_vendor: Mozilla 05:48:20 INFO - mozversion application_version: 53.0a1 05:48:20 INFO - mozversion platform_buildid: 20161201030205 05:48:20 INFO - mozversion platform_changeset: cd4cdcc9ad6c45dad8b8d8c0d40e459db2bca8a1 05:48:20 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:48:20 INFO - mozversion platform_version: 53.0a1 05:48:20 INFO - using testdate: 1480600100 05:48:20 INFO - actual date: 1480600100 05:48:20 INFO - starting webserver on 'localhost:49249' 05:48:20 INFO - SUITE-START | Running 2 tests 05:48:20 INFO - TEST-START | dromaeo_css 05:48:20 INFO - operating with platform_type : mac_ 05:48:20 INFO - Initialising browser for dromaeo_css test... 05:48:20 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/tmpPEL9hd/profile http://localhost:49249/getInfo.html) 05:48:23 INFO - PROCESS | 1633 | 2016-12-01 05:48:23.943 plugin-container[1636:7527] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9043, name = 'com.apple.tsm.portname' 05:48:23 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:48:23 INFO - PROCESS | 1633 | 2016-12-01 05:48:23.943 plugin-container[1636:7527] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4e07, name = 'com.apple.CFPasteboardClient' 05:48:23 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:48:23 INFO - PROCESS | 1633 | 2016-12-01 05:48:23.943 plugin-container[1636:7527] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:48:23 INFO - PROCESS | 1633 | __metrics Screen width/height:1600/1200 05:48:23 INFO - PROCESS | 1633 | colorDepth:24 05:48:23 INFO - PROCESS | 1633 | Browser inner width/height: 1024/689 05:48:23 INFO - PROCESS | 1633 | __metrics 05:48:24 ERROR - PROCESS | 1633 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3220: TypeError: this.tabs is undefined 05:48:24 INFO - TEST-INFO | 1633: exit 0 05:48:24 INFO - Browser initialized. 05:48:24 INFO - Running cycle 1/1 for dromaeo_css test... 05:48:24 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/tmpPEL9hd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:48:27 INFO - PROCESS | 1637 | 2016-12-01 05:48:27.682 plugin-container[1638:7647] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 05:48:27 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:48:27 INFO - PROCESS | 1637 | 2016-12-01 05:48:27.683 plugin-container[1638:7647] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471b, name = 'com.apple.CFPasteboardClient' 05:48:27 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:48:27 INFO - PROCESS | 1637 | 2016-12-01 05:48:27.683 plugin-container[1638:7647] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:48:37 INFO - PROCESS | 1637 | RSS: Main: 242970624 05:48:37 INFO - PROCESS | 1637 | 05:49:26 INFO - PROCESS | 1637 | 1480600166857 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] 05:51:39 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49249/tests/dromaeo/cssquery-ext.html) 05:51:41 INFO - PROCESS | 1637 | RSS: Main: 251273216 05:51:41 INFO - PROCESS | 1637 | 05:54:44 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-ext.html (next: http://localhost:49249/tests/dromaeo/cssquery-jquery.html) 05:54:44 INFO - PROCESS | 1637 | RSS: Main: 260853760 05:54:44 INFO - PROCESS | 1637 | 05:57:46 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49249/tests/dromaeo/cssquery-mootools.html) 05:57:47 INFO - PROCESS | 1637 | RSS: Main: 267296768 05:57:47 INFO - PROCESS | 1637 | 06:00:49 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49249/tests/dromaeo/cssquery-prototype.html) 06:00:50 INFO - PROCESS | 1637 | RSS: Main: 265854976 06:00:50 INFO - PROCESS | 1637 | 06:03:52 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49249/tests/dromaeo/cssquery-yui.html) 06:03:53 INFO - PROCESS | 1637 | RSS: Main: 271429632 06:03:53 INFO - PROCESS | 1637 | 06:06:56 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-yui.html (next: http://localhost:49249/tests/dromaeo/cssquery-dojo.html) 06:06:57 INFO - PROCESS | 1637 | RSS: Main: 273936384 06:06:57 INFO - PROCESS | 1637 | 06:06:57 INFO - PROCESS | 1637 | __start_tp_report 06:06:57 INFO - PROCESS | 1637 | _x_x_mozilla_page_load 06:06:57 INFO - PROCESS | 1637 | _x_x_mozilla_page_load_details 06:06:57 INFO - PROCESS | 1637 | |i|pagename|runs| 06:06:57 INFO - PROCESS | 1637 | |0;dojo.html;3239.352129574085;3252.5121231815224;3302.3890580242655;3302.8843990460336;3310.86756529739;5522.392536820951;5572.777088916444;5583.162525621149;5620.494155526012;5665.405132461094;5383.380911195214;5471.863203419916;5483.533899618527;5535.363433205182;5546.029444847156;4956;4974.278729584208;5070.847874563769;5080.466551012146;5083.618728595352;5062.936783275498;5138.820141295056;5164;5165.664231824938;5171.56041736451;4515.209838278304;4977.104121258172;5149.047426226141;5152.201408781645;5153.2785410042625;5149.201873709581;5150.330457040598;5153.330067091273;5153.56194723449;5163.277141200235;4256.7445953242595;4991.750412479398;5094.668846525001;5106.106431374532;5110.259012443212;5592.077307244341;5619.887602247933;5655.688937108457;5666.631668941505;5716.228309178291;5873.530117590582;6077.969610151965;6098.756049758004;6125.264968203844;6138.631682099043;6008.068749343859;6037.154798328237;6054.394560543955;6135.417135372132;6160.4455598995855;5595.468430499096;5666.235058267163;5679;5685;5757.539396848284;6251.28110267316;6260.874782504325;6268.905966410507;6270.059491076375;6281.96859015702;5768.74040668171;5950.256217972754;5954.315253745788;5974.820755377345;5990.73041713124;5995.490383317379;6061.636301821816;6101.725422356005;6130.417610327012;6173.074038894202;6050.848728781815;6085.382350600777;6123.923256659767;6127.647695152957;6142.6314421134875;6078.027515597483;6121.84695382619;6122.724477398528;6129.3870612939245;6130.1724267224245;4724.889650932028;4758.119747846659;4777.307290442866;4781.737004464719;4785.736784476887;4296.5273819879785;4345.109252603209;4364.214441400578;4403.339499075164;4410.33844923264;5510.393856675762;5600.915986260209;5635.520980716685;5650.3219613646625;5701.515371193495;5977.970110149421;5985.431384018511;6199.721012554446;6209.037599172136;6214.130021796952;5525.861853453615;5570.637908535932;5577.330720313588;5578.776848925998;5588.972055139699;4452.532484089189;4508.616767574727;4979.128652485801;5184.403793563713;5275.235090911796;5194.506521880416;6226.501879849601;6269.467095296951;6295.370462953668;6300.432961033529;6202.72087756043;6222.377762223741;6230.314665386713;6236.906446403217;6263.96868015657;6111.96944015277;6114.419025732044;6122.265328160649;6124.938750612437;6141.969290153521;6001;6002.0996850472775;6013;6018.51851851842;6052;6049.727762250621;6086.482648974887;6096.603720758137;6123.357047509948;6128.63228206309;4803;5732.140178973187;5743.167240750151;5783.103618939072;5801.042827933513;5839.3868643793485;5857.4435428634215;5873.089671100986;5916.73374698131;6011.759529618941;5741.11012793001;5801.477866991991;5888.558358123244;5895.616784908967;5926.733297001559;5952.107183922273;5957.987142185752;6003.669798161143;6025.548083893813;6026.517878569792;5054.1660625997765;5672.14917762339;5695.117256825199;5787.102999035156;5792.073268277056 06:06:57 INFO - PROCESS | 1637 | |1;ext.html;480.22443753338354;511.095361210657;521.6504941689068;526.8919871426358;532.7549327309438;38770.80614596927;39953.200935981266;40045;40186;40256;41454;41794.582054179446;41813.790931045376;41877.99999999993;41945.58054419455;8292;8523.872141917846;9076.500792456412;9256.00995785906;9271.860922086174;10771.299865508756;11108.444577771119;11147.052500537464;11301.604443844468;11310.09519238459;11204.927088488615;11258.493367798468;11259.54961801527;11264.493097810619;11315.207935444521;11074.335539867634;11140.888591114106;11148.275362101518;11155.665330040112;11167.162462815322;10890.40102794346;11013.779724405547;11019.173561982803;11028.889711102867;11033.558657653684;38985;39288.607113929065;39374.80312598447;39456;39461.605383946364;3260.054584170611;3291.2594666200152;3374.6625337466303;3408.471686888536;3459.377312083824;29031.41937161266;29463;29632.851835740898;30109.849450752605;30341.78632854683;3029.9848500757575;3051.9847400762856;3058.1590062732926;3135.827529485877;3197.328561002192;447948;453313;455109;457687;458887.9999999967;57904.71047644735;58272;58335;58380.7080964601;58391;61503;61588;61838.69080654568;61899.38100618936;61924.38075619186;15507.767383489258;15711.057336559843;15766.448174313844;15773.763393549107;15777.763333550007;11038;11290.548378064785;11981.76036479283;12132.393380330948;12318.938405308096;16772.161391930356;16776.328946842234;16823.663526729397;16869.493915182564;17018.14909254557;5963.970180149072;6250.499960003189;6309.842253943688;6785.117934668461;6791.185057793096;843.4854738609444;846.4709556527171;1003.3127307794039;1005.8843233028149;1042.9165666746642;25244.621330680053;25975.831087601102;27869.024584139457;29597.26006849846;29831;1045.4772613693153;1064;1069.3263244156133;1152.9250598711058;1182.899453546441;13058.673533161556;14948.626284342978;15071.698566028837;15075.924620376827;15517.224138793015;777331;811641;813227;815159.8484015203;816645;55100.72449637806;55453.722731386086;55497.167542486895;55649.60875978133;55652.72173639187;1341.7115320206008;1344.751221024094;1375.8211432513908;1376.9655758605916;1377.1668140774887;22849.65725514152;24048.15831445891;29264.70735292705;29615.703842962157;30020;43377.56622433735;46255.999999998654;47693;47955.760221198674;48174.79563010813;455082.7245863882;461062;462996;463346;463750;22991.195308164133;23608.645870311615;24729.752702473466;24949.251522454357;25122.74877251204;42958.78520607377;44316.55683443124;45833;46299.53700462952;46514.76742616265;28709.138725837423;38183.236335273694;38243.23513529769;38614.613853861105;39131;36939.07652308714;37012.81493592515;37709.81145094257;37981.67064152742;38432.231355372176;36687.816560918094;37458.06354841042;37690;37933;38066.619333807415;30608.84695576508;33534.66465335315;33564.32871342511;33582;33724.32551349008;22520.887395562917;24904.75095249024;25362.239132825307;25536.61695074538;25555.744442555337 06:06:57 INFO - PROCESS | 1637 | |2;jquery.html;369.72824973644384;372.2090557565174;377.6412408212198;381.18236382958537;399.8760384280873;18300.725489117674;19029.71455428168;19105.904470477628;19392.709109363353;19583.902080489577;19290.228390864348;19454.805451945474;19829.603407931834;19887.50281242966;20142.697859532083;4117.21772863155;5912.704364781744;6120.785772497963;6195.225596800411;6471.449926756231;5885;6408.743650253984;6413.134226879358;6415.582987105847;6420.165378500788;6250.624962502242;6289.088082228097;6389.520785941049;6428.871422571546;6445.2587952385375;6108.114323423123;6351.428371446569;6405.19935008124;6424.38968298011;6425.646589437579;6132.386761323832;6228.470580000705;6237.469815065725;6240.438360547572;6243.937560624381;24601.293857675642;25449.363765905815;25883;26482;26712.599311010155;2333.696619439462;2342.730585982617;2482.863442510661;2542.7711505964367;3237.1281221761333;13813.102148360324;14828.925855370762;16096.517104486884;16242.187890605423;16923.3230670773;1934.564722937342;1985.4143027806688;2010.3265406088979;2018.4247489465579;2034.8260679862065;119475;135517.3224133883;136007;137795;140496;15101.622459438382;19410.611787764166;20435.773853568833;20475.38573842787;20581.89709051475;32211.51682724761;32927.176820579676;33055.50416743751;33132.01207927532;33175.33649327;10060.446675432922;10272.897271027343;10299.639512617023;10464.901185375425;10585.364878107339;7220.783376498714;8453.196946290092;8481.067082620906;8605.741827745052;8645.654173833102;7784.610769461504;8488.490690558463;8511.65953361871;9047.954760226157;9497.240220782322;3699.167687270343;3789.2610940866816;3799.259144466858;3800.942985855159;3820.3887378019385;694.5207806613522;821.8643923752634;905.7101727447285;906.9501177435304;969.5152423788106;18893.527661808566;19587.804121958598;20034.69947950782;20125.49686257855;20176.798232017492;884.0673089890228;885.9734207973771;908.2643059122132;931.8229536388064;956.818204541135;10124.898751012543;10637.840432393521;10761.461926903623;10806.406462449113;11413.714657133638;488055;497699;512656;521535;532665;108286;112713;113701;117324;118985.62028759376;949.7483166960761;983.1102851919024;995.1193194023197;1006.4112494190834;1008.6016023670755;17425.73861392055;17878.731819022465;18049;18257.269709211483;18344.66546650189;43352;46346;52598.477159899194;54731.179032313754;55542.72228638831;77264.84102738576;77661;77931;78339;78760.60619697094;7686.478617650115;7762.184970578008;7901.407394445325;7911.485753426009;8032.236937490929;10799.67600971972;12240.265584064984;12282.877171228174;12463.501459941863;12523.624291271277;8818.29453643694;10428.791424171626;10619.575216991234;10814.837777433188;10979.847116052699;10193.74515637115;10407.94796026015;10417.427041512487;10434;10599.62901298451;10704.518296676513;10909.563617455213;10965.83551246716;11052.778944420907;11161.832572511581;7632.885506717293;7827.647755850887;7900.52511641741;7924.960375198087;8058.637361318638;8007.079185893696;9387.10822471864;10609.628662996758;10725.249232553646;10793.56825726962 06:06:57 INFO - PROCESS | 1637 | |3;mootools.html;1474.69768697417;1485.331600779649;1555.2068445093;1684.326269492203;1817.8636602254835;7116.5018448708615;7471.85056298875;7720.30517253447;8002.31980281676;8454.873176902343;7885.645145968431;8210.137935516774;8213.589320533963;8289.088200297978;8291.212334828197;1956.520652440147;2278.829087818416;2309.711286089239;2315.907363705458;2319.118734880743;2304.539092181562;2331.40549159964;2347.6713260143592;2349.7650234976536;2349.859008459498;1908.570571621381;2288.5880541502524;2304.7119110111234;2315.131825565413;2316.343814172866;2268.3081660093667;2285.074544809355;2294.655801629752;2310.722713274401;2311.8959646815933;2302.056156975632;2311.179531266393;2317.2237300504353;2326.3253656439783;2327.453048533593;8279.50322980623;8374.665013399457;8418.663253469855;8447.239748422671;8452.57737113142;4735;5062.1141300272675;5217.426083130853;5233.764480598345;5405.5135037846385;2762.4337010912714;3184;3516.894493165209;3517.9120521986897;3522.1018640246575;1037.0977249792654;1063.1919740996898;1077.4774234496263;1090.7109615951779;1095.7972775036644;9143.21132054723;10800.783984320347;10897.910208979118;10941.015308622262;10947.452627368679;4462.062966776948;4521.841735539306;4616.538346165356;4625.32932724753;4719.9292010619865;9507.429554226768;9507.477088760184;9548.713538593714;9566.61733530665;9608.423494590346;5189.6886186828915;5210.817621383233;5213.556847667915;5217.295665085165;5225.294585231021;4046.7976601169826;4634.281686338623;4650.744209068534;4768.7615619218905;4774.7135171889795;3756.549214094326;3763.7929913855;3775.1505911170316;3849.9807500962315;3874.1476875087437;1090.9181811364178;1257.2079589858513;1262.9115961882765;1272.5037235478171;1275.1137959118323;872.5680788009976;874.711345256064;893.9016708162097;918.081918081918;918.3984490158956;5573.5819813514145;5769.48074673273;5905.084711869667;5918.201042859159;5987.371326010793;840.7898025493627;856.8115014696757;873.4191762477881;875.3216257400438;921.2860033474111;3882.611738826095;3886.6113388660906;3889.416587511839;3982.5420076691553;3998.34027385484;9768.316217864824;10009.749756256151;10031.147352474976;10113.494325283706;10118.291719579705;9988.350757200758;10004.249681273934;10039.749006274902;10058;10078.798424031625;7487.288707572772;7747.690092396354;7883.881741773878;7897.091834438998;7898.44710870245;5849.913225955715;5875.441833026027;5922.052471604523;5963.880722385441;6079.483243924316;9331.766705832137;9373.484458354584;9490.857637135587;9498.95250523743;9532.094451027142;9325.580348884181;9453.905460945303;9589.664361747584;9622.66320678801;9685.079917407835;7178.28217178278;7225.421966242583;7260.89108663381;7302.96348518254;7331.120265568166;7840.725574604861;7904.446688731734;7917.643706033127;7919.326857217201;7934.404919631166;7082.5396349237135;7197.568145911262;7231.4937954342695;7328.560286382621;7343.8531229376185;5959.612625179349;7060.54106483077;7124.287571242834;7141.357277845019;7210.96394518024;6698.497612678971;6719.059331693665;6763.560368576027;6787.423069039187;6816.591004539743;4883.731394773322;4919.901601968012;4961;5005.449400566008;5036.420811606566;4136.152088821845;4735.810567577259;4878.63410244226;5116.795328186831;5126.871828204325 06:06:57 INFO - PROCESS | 1637 | |4;prototype.html;254.25249765688878;266.85989855325954;310.29253302470363;319.1844836442888;320.3273126434488;5326.414094449608;5376.892462150755;5388.676679399229;5420.512153906147;5446.046941785186;5038;5318.680879147254;5323.574114070875;5353.518183363496;5382.461753824616;3188.740447523227;3967.761934283938;3990.9401358979567;3995.5205375355;4048.615381538764;4042.575529569397;4044.312466880637;4058.715889887709;4065;4081.4081958116053;3599.892003239907;3828.808559572024;4034.4150098235737;4034.9394759078637;4076.32740597801;3687.3731465650903;3730.2725968436166;4022.5172979242384;4070.9389359159636;4085.775282359469;3531.2760884018717;3544.769589976643;3544.8404821783083;3545.2377738786286;3572.7499075064757;5142.331496905378;5159.484051594848;5187.766550505236;5188.195829646411;5215.183800747592;3885.5337359516757;4607.834317870629;4671.1739397664605;4682.367880336145;4684.789184486673;5355.812546560891;5612.859678508029;5644.322681278232;5654.236678048452;5667.971660141673;3635.1457407509215;3904.4338570907344;3910.7262491625597;3912.1002169501176;3913.589073147307;5086.694798312114;5180.430152683201;5895.528357731371;5898.498627616657;5925.377835327315;3894.805259737002;3938.0309845077463;3956.584558621361;3964.365701487748;3971.4042893566198;3787.6401741834484;4023.3361495353524;4024.6377825995214;4057.208844275338;4070.674346052309;3943.38877473992;4447.997601618959;4502;4665.323528088476;4729.976350118227;4896.869861610211;5002.724850133205;5153.072446959508;5497.642653227527;5676.631018983835;1220.7680540697238;1299.6750812296925;1304.3955934200985;1312.4447529677363;1318.2288361308742;757.9734709285149;830.6013113705335;897.2059727141527;905.9954700226589;914.2731528434911;4747.715137091785;4803.78382972767;4864.294677271776;5076.695398276115;5194.662346947436;4899.240617704261;5228.555572776357;5407.323729643745;5897.941020589825;5918.319393269743;4881.609471242292;4991.051700176955;5278.287431196816;5303.7348132593215;5312.814051508179;2710.2546799629863;2864.994503847315;2898.202994176618;2898.7101289870843;2910.4324656692165;5042.503960539483;5313.724706070515;5627.5779316551425;5652.491275785199;5688.488036076773;5346.5455436287575;5666.518345940642;5691.68695721731;5725.427457254241;5726.198332233491;4732.408448943882;5377.247185394126;5468.343798744176;5692.31692196939;5818.44724751148;5219.582433405242;5506.862328441821;5514.889702206014;5529.253550770594;5533.7786488540005;4773.164696178224;5019.52314530119;5157.564494534622;5483.683915860244;5487.506124448819;5304.522592966651;5638.815848671661;5639.689817060101;5656.745446454837;5677.432256774455;3377.003783883711;3463.7748546344405;3606.2967721294094;3733.9439908400855;3765.45400916871;4394.802233899546;5119.718415487184;5164.3802743671;5312.6530285919625;5580.553555715452;4519.23173060587;4876.4161688013155;5107.948920510896;5377.27406800077;5383.600263931485;5017.974910125426;5077.746112694276;5136.922946155885;5337.692265395003;5339.839804805862;4480;4670.772950479821;4891.853244402673;4911.386076740408;5233.833530714015;5312.707801070978;5471.781128754965;5559.833205003856;5671.262735844314;5676.120201368794;5283.441384791573;5523.392426832965;5547.753159970219;5577.832665020056;5587.860303492283 06:06:57 INFO - PROCESS | 1637 | |5;yui.html;628.2053202698587;693.7155766135884;701.8210356359127;721.5201890742658;760.6460500309448;14441.927790361047;15249.771253431207;15278.541643750677;15315.927885048051;15530.456434024798;14082.155070695766;14173.574792756233;14225.573232803006;14229.075110117836;14232.644183895407;667.6027763480746;688.9896651550231;827.7889138269733;849.9107593702665;874.5889431966965;833.2500749325595;849.7195925344653;852.880596716457;853.4239388412797;853.9573021348939;908.3686837647838;921.8432866412693;923.4966943016038;925.1303774452027;925.6158694141957;916.2715641065336;916.4180745226796;917.7613820406742;918.0773322810566;918.393860052369;674.6694119881272;682.3756263019332;682.6757290287123;683.1835956032543;684.0355099309949;15062.322195501229;15255.694886102327;15384.692306153926;15413.075215487106;15457.227138643022;12878.678033049155;14420;14747.631309217248;14854.2572871356;14874.256287185597;12630.242185468902;12825.358732063452;13008.609741707764;13217.801732974012;13471.124376915566;4486.8878278042985;4540.7502587357685;4552.044070745114;4708.646851486118;4713.29300604912;109275;111286;112275.43862280637;113740.43129784381;115247.98408262627;8875.556222188994;10663.200259980533;10677.519511621995;10691.465426728633;10804.459777011118;20168.193272268945;20181.091850866746;20215.29246476366;20220;20223.797762022485;6947;7187.56874587516;7308;7382.409407247407;7510.1738808731;4280.036991676848;4315.417418648442;4340.978295108504;4403.053343531092;4482.386340917267;3091.7990330628886;3383.052745231339;3411.5053317269353;3418.29924865402;3422.7261819054415;516.8010316028281;589.0369246282273;635.5995722694673;638.5466318913531;645.470714014513;440.6430791059252;590.9970450147807;598.1476396135489;600.474584738354;601.7592962814822;7641.777315629583;8429.367797415218;8692.869606955901;8777.648894044294;8876.362457286834;595.9940400596025;599.9700014999232;608.2846373227457;611.3855575146949;614.8124821929265;4236.796865629855;4254.787260636956;4418;4796.25658023007;4843.878903027453;82169.76745348824;83448;87962.68055979165;89005.55497222602;91223.54388228017;78838;79391;83758;84949;85426.14573854182;73423;78068;79695.60152199202;81266.78099828625;82516.76224856632;9598.66404675833;9845.030085916951;9898.158656514277;9917.305788595018;9920.007999200023;33210.66789332076;46878.53121468878;49998;51859;52960;59194.40805591889;71199;72880.27119728948;74311.6284418596;74651.62674186594;12274.263544187377;12515.87484125147;12628.684282893002;12974.545890893773;13178.868211318148;13954.511592094228;14649.414023438943;14727.852721472647;14763.704725905207;15043.172625505702;12370.072244581512;13451.932740336235;13585.320733963064;13762.24307663048;14342.995990280997;13157.34213289351;13540.458381664623;14017;14021.439142434596;14131.64670883236;12128;13627.250501222528;13629.795553066513;13935.495479285422;14021;10871.67384978452;11047.668569942914;11408.942955285502;11409.771804563696;11878.524859005543;10668.786624267626;11317.547298108313;11444.256123351955;11455.312681239153;11737.706557336134 06:06:57 INFO - PROCESS | 1637 | __end_tp_report 06:06:57 INFO - PROCESS | 1637 | __start_cc_report 06:06:57 INFO - PROCESS | 1637 | _x_x_mozilla_cycle_collect,383 06:06:57 INFO - PROCESS | 1637 | __end_cc_report 06:06:57 INFO - PROCESS | 1637 | __startTimestamp1480601217003__endTimestamp 06:06:57 INFO - PROCESS | 1637 | 06:06:57 INFO - PROCESS | 1637 | ------- Summary: start ------- 06:06:57 INFO - PROCESS | 1637 | Number of tests: 6 06:06:57 INFO - PROCESS | 1637 | 06:06:57 INFO - PROCESS | 1637 | [#0] dojo.html Cycles:165 Average:5585.52 Median:5757.54 stddev:656.10 (11.4%) stddev-sans-first:631.77 06:06:57 INFO - PROCESS | 1637 | Values: 3239.4 3252.5 3302.4 3302.9 3310.9 5522.4 5572.8 5583.2 5620.5 5665.4 5383.4 5471.9 5483.5 5535.4 5546.0 4956.0 4974.3 5070.8 5080.5 5083.6 5062.9 5138.8 5164.0 5165.7 5171.6 4515.2 4977.1 5149.0 5152.2 5153.3 5149.2 5150.3 5153.3 5153.6 5163.3 4256.7 4991.8 5094.7 5106.1 5110.3 5592.1 5619.9 5655.7 5666.6 5716.2 5873.5 6078.0 6098.8 6125.3 6138.6 6008.1 6037.2 6054.4 6135.4 6160.4 5595.5 5666.2 5679.0 5685.0 5757.5 6251.3 6260.9 6268.9 6270.1 6282.0 5768.7 5950.3 5954.3 5974.8 5990.7 5995.5 6061.6 6101.7 6130.4 6173.1 6050.8 6085.4 6123.9 6127.6 6142.6 6078.0 6121.8 6122.7 6129.4 6130.2 4724.9 4758.1 4777.3 4781.7 4785.7 4296.5 4345.1 4364.2 4403.3 4410.3 5510.4 5600.9 5635.5 5650.3 5701.5 5978.0 5985.4 6199.7 6209.0 6214.1 5525.9 5570.6 5577.3 5578.8 5589.0 4452.5 4508.6 4979.1 5184.4 5275.2 5194.5 6226.5 6269.5 6295.4 6300.4 6202.7 6222.4 6230.3 6236.9 6264.0 6112.0 6114.4 6122.3 6124.9 6142.0 6001.0 6002.1 6013.0 6018.5 6052.0 6049.7 6086.5 6096.6 6123.4 6128.6 4803.0 5732.1 5743.2 5783.1 5801.0 5839.4 5857.4 5873.1 5916.7 6011.8 5741.1 5801.5 5888.6 5895.6 5926.7 5952.1 5958.0 6003.7 6025.5 6026.5 5054.2 5672.1 5695.1 5787.1 5792.1 06:06:57 INFO - PROCESS | 1637 | 06:06:57 INFO - PROCESS | 1637 | [#1] ext.html Cycles:180 Average:69960.44 Median:18549.49 stddev:160669.62 (866.2%) stddev-sans-first:161035.65 06:06:57 INFO - PROCESS | 1637 | Values: 480.2 511.1 521.7 526.9 532.8 38770.8 39953.2 40045.0 40186.0 40256.0 41454.0 41794.6 41813.8 41878.0 41945.6 8292.0 8523.9 9076.5 9256.0 9271.9 10771.3 11108.4 11147.1 11301.6 11310.1 11204.9 11258.5 11259.5 11264.5 11315.2 11074.3 11140.9 11148.3 11155.7 11167.2 10890.4 11013.8 11019.2 11028.9 11033.6 38985.0 39288.6 39374.8 39456.0 39461.6 3260.1 3291.3 3374.7 3408.5 3459.4 29031.4 29463.0 29632.9 30109.8 30341.8 3030.0 3052.0 3058.2 3135.8 3197.3 447948.0 453313.0 455109.0 457687.0 458888.0 57904.7 58272.0 58335.0 58380.7 58391.0 61503.0 61588.0 61838.7 61899.4 61924.4 15507.8 15711.1 15766.4 15773.8 15777.8 11038.0 11290.5 11981.8 12132.4 12318.9 16772.2 16776.3 16823.7 16869.5 17018.1 5964.0 6250.5 6309.8 6785.1 6791.2 843.5 846.5 1003.3 1005.9 1042.9 25244.6 25975.8 27869.0 29597.3 29831.0 1045.5 1064.0 1069.3 1152.9 1182.9 13058.7 14948.6 15071.7 15075.9 15517.2 777331.0 811641.0 813227.0 815159.8 816645.0 55100.7 55453.7 55497.2 55649.6 55652.7 1341.7 1344.8 1375.8 1377.0 1377.2 22849.7 24048.2 29264.7 29615.7 30020.0 43377.6 46256.0 47693.0 47955.8 48174.8 455082.7 461062.0 462996.0 463346.0 463750.0 22991.2 23608.6 24729.8 24949.3 25122.7 42958.8 44316.6 45833.0 46299.5 46514.8 28709.1 38183.2 38243.2 38614.6 39131.0 36939.1 37012.8 37709.8 37981.7 38432.2 36687.8 37458.1 37690.0 37933.0 38066.6 30608.8 33534.7 33564.3 33582.0 33724.3 22520.9 24904.8 25362.2 25536.6 25555.7 06:06:57 INFO - PROCESS | 1637 | 06:06:57 INFO - PROCESS | 1637 | [#2] jquery.html Cycles:180 Average:33530.07 Median:32991.34 stddev:86073.66 (260.9%) stddev-sans-first:86279.11 06:06:57 INFO - PROCESS | 1637 | Values: 369.7 372.2 377.6 381.2 399.9 18300.7 19029.7 19105.9 19392.7 19583.9 19290.2 19454.8 19829.6 19887.5 20142.7 4117.2 5912.7 6120.8 6195.2 6471.4 5885.0 6408.7 6413.1 6415.6 6420.2 6250.6 6289.1 6389.5 6428.9 6445.3 6108.1 6351.4 6405.2 6424.4 6425.6 6132.4 6228.5 6237.5 6240.4 6243.9 24601.3 25449.4 25883.0 26482.0 26712.6 2333.7 2342.7 2482.9 2542.8 3237.1 13813.1 14828.9 16096.5 16242.2 16923.3 1934.6 1985.4 2010.3 2018.4 2034.8 119475.0 135517.3 136007.0 137795.0 140496.0 15101.6 19410.6 20435.8 20475.4 20581.9 32211.5 32927.2 33055.5 33132.0 33175.3 10060.4 10272.9 10299.6 10464.9 10585.4 7220.8 8453.2 8481.1 8605.7 8645.7 7784.6 8488.5 8511.7 9048.0 9497.2 3699.2 3789.3 3799.3 3800.9 3820.4 694.5 821.9 905.7 907.0 969.5 18893.5 19587.8 20034.7 20125.5 20176.8 884.1 886.0 908.3 931.8 956.8 10124.9 10637.8 10761.5 10806.4 11413.7 488055.0 497699.0 512656.0 521535.0 532665.0 108286.0 112713.0 113701.0 117324.0 118985.6 949.7 983.1 995.1 1006.4 1008.6 17425.7 17878.7 18049.0 18257.3 18344.7 43352.0 46346.0 52598.5 54731.2 55542.7 77264.8 77661.0 77931.0 78339.0 78760.6 7686.5 7762.2 7901.4 7911.5 8032.2 10799.7 12240.3 12282.9 12463.5 12523.6 8818.3 10428.8 10619.6 10814.8 10979.8 10193.7 10407.9 10417.4 10434.0 10599.6 10704.5 10909.6 10965.8 11052.8 11161.8 7632.9 7827.6 7900.5 7925.0 8058.6 8007.1 9387.1 10609.6 10725.2 10793.6 06:06:57 INFO - PROCESS | 1637 | 06:06:57 INFO - PROCESS | 1637 | [#3] mootools.html Cycles:180 Average:5456.57 Median:5212.19 stddev:2979.59 (57.2%) stddev-sans-first:2972.93 06:06:57 INFO - PROCESS | 1637 | Values: 1474.7 1485.3 1555.2 1684.3 1817.9 7116.5 7471.9 7720.3 8002.3 8454.9 7885.6 8210.1 8213.6 8289.1 8291.2 1956.5 2278.8 2309.7 2315.9 2319.1 2304.5 2331.4 2347.7 2349.8 2349.9 1908.6 2288.6 2304.7 2315.1 2316.3 2268.3 2285.1 2294.7 2310.7 2311.9 2302.1 2311.2 2317.2 2326.3 2327.5 8279.5 8374.7 8418.7 8447.2 8452.6 4735.0 5062.1 5217.4 5233.8 5405.5 2762.4 3184.0 3516.9 3517.9 3522.1 1037.1 1063.2 1077.5 1090.7 1095.8 9143.2 10800.8 10897.9 10941.0 10947.5 4462.1 4521.8 4616.5 4625.3 4719.9 9507.4 9507.5 9548.7 9566.6 9608.4 5189.7 5210.8 5213.6 5217.3 5225.3 4046.8 4634.3 4650.7 4768.8 4774.7 3756.5 3763.8 3775.2 3850.0 3874.1 1090.9 1257.2 1262.9 1272.5 1275.1 872.6 874.7 893.9 918.1 918.4 5573.6 5769.5 5905.1 5918.2 5987.4 840.8 856.8 873.4 875.3 921.3 3882.6 3886.6 3889.4 3982.5 3998.3 9768.3 10009.7 10031.1 10113.5 10118.3 9988.4 10004.2 10039.7 10058.0 10078.8 7487.3 7747.7 7883.9 7897.1 7898.4 5849.9 5875.4 5922.1 5963.9 6079.5 9331.8 9373.5 9490.9 9499.0 9532.1 9325.6 9453.9 9589.7 9622.7 9685.1 7178.3 7225.4 7260.9 7303.0 7331.1 7840.7 7904.4 7917.6 7919.3 7934.4 7082.5 7197.6 7231.5 7328.6 7343.9 5959.6 7060.5 7124.3 7141.4 7211.0 6698.5 6719.1 6763.6 6787.4 6816.6 4883.7 4919.9 4961.0 5005.4 5036.4 4136.2 4735.8 4878.6 5116.8 5126.9 06:06:57 INFO - PROCESS | 1637 | 06:06:57 INFO - PROCESS | 1637 | [#4] prototype.html Cycles:180 Average:4465.12 Median:5059.60 stddev:1345.84 (26.6%) stddev-sans-first:1311.97 06:06:57 INFO - PROCESS | 1637 | Values: 254.3 266.9 310.3 319.2 320.3 5326.4 5376.9 5388.7 5420.5 5446.0 5038.0 5318.7 5323.6 5353.5 5382.5 3188.7 3967.8 3990.9 3995.5 4048.6 4042.6 4044.3 4058.7 4065.0 4081.4 3599.9 3828.8 4034.4 4034.9 4076.3 3687.4 3730.3 4022.5 4070.9 4085.8 3531.3 3544.8 3544.8 3545.2 3572.7 5142.3 5159.5 5187.8 5188.2 5215.2 3885.5 4607.8 4671.2 4682.4 4684.8 5355.8 5612.9 5644.3 5654.2 5668.0 3635.1 3904.4 3910.7 3912.1 3913.6 5086.7 5180.4 5895.5 5898.5 5925.4 3894.8 3938.0 3956.6 3964.4 3971.4 3787.6 4023.3 4024.6 4057.2 4070.7 3943.4 4448.0 4502.0 4665.3 4730.0 4896.9 5002.7 5153.1 5497.6 5676.6 1220.8 1299.7 1304.4 1312.4 1318.2 758.0 830.6 897.2 906.0 914.3 4747.7 4803.8 4864.3 5076.7 5194.7 4899.2 5228.6 5407.3 5897.9 5918.3 4881.6 4991.1 5278.3 5303.7 5312.8 2710.3 2865.0 2898.2 2898.7 2910.4 5042.5 5313.7 5627.6 5652.5 5688.5 5346.5 5666.5 5691.7 5725.4 5726.2 4732.4 5377.2 5468.3 5692.3 5818.4 5219.6 5506.9 5514.9 5529.3 5533.8 4773.2 5019.5 5157.6 5483.7 5487.5 5304.5 5638.8 5639.7 5656.7 5677.4 3377.0 3463.8 3606.3 3733.9 3765.5 4394.8 5119.7 5164.4 5312.7 5580.6 4519.2 4876.4 5107.9 5377.3 5383.6 5018.0 5077.7 5136.9 5337.7 5339.8 4480.0 4670.8 4891.9 4911.4 5233.8 5312.7 5471.8 5559.8 5671.3 5676.1 5283.4 5523.4 5547.8 5577.8 5587.9 06:06:57 INFO - PROCESS | 1637 | 06:06:57 INFO - PROCESS | 1637 | [#5] yui.html Cycles:180 Average:20010.78 Median:4546.40 stddev:28516.43 (627.2%) stddev-sans-first:28559.29 06:06:57 INFO - PROCESS | 1637 | Values: 628.2 693.7 701.8 721.5 760.6 14441.9 15249.8 15278.5 15315.9 15530.5 14082.2 14173.6 14225.6 14229.1 14232.6 667.6 689.0 827.8 849.9 874.6 833.3 849.7 852.9 853.4 854.0 908.4 921.8 923.5 925.1 925.6 916.3 916.4 917.8 918.1 918.4 674.7 682.4 682.7 683.2 684.0 15062.3 15255.7 15384.7 15413.1 15457.2 12878.7 14420.0 14747.6 14854.3 14874.3 12630.2 12825.4 13008.6 13217.8 13471.1 4486.9 4540.8 4552.0 4708.6 4713.3 109275.0 111286.0 112275.4 113740.4 115248.0 8875.6 10663.2 10677.5 10691.5 10804.5 20168.2 20181.1 20215.3 20220.0 20223.8 6947.0 7187.6 7308.0 7382.4 7510.2 4280.0 4315.4 4341.0 4403.1 4482.4 3091.8 3383.1 3411.5 3418.3 3422.7 516.8 589.0 635.6 638.5 645.5 440.6 591.0 598.1 600.5 601.8 7641.8 8429.4 8692.9 8777.6 8876.4 596.0 600.0 608.3 611.4 614.8 4236.8 4254.8 4418.0 4796.3 4843.9 82169.8 83448.0 87962.7 89005.6 91223.5 78838.0 79391.0 83758.0 84949.0 85426.1 73423.0 78068.0 79695.6 81266.8 82516.8 9598.7 9845.0 9898.2 9917.3 9920.0 33210.7 46878.5 49998.0 51859.0 52960.0 59194.4 71199.0 72880.3 74311.6 74651.6 12274.3 12515.9 12628.7 12974.5 13178.9 13954.5 14649.4 14727.9 14763.7 15043.2 12370.1 13451.9 13585.3 13762.2 14343.0 13157.3 13540.5 14017.0 14021.4 14131.6 12128.0 13627.3 13629.8 13935.5 14021.0 10871.7 11047.7 11408.9 11409.8 11878.5 10668.8 11317.5 11444.3 11455.3 11737.7 06:06:57 INFO - PROCESS | 1637 | -------- Summary: end -------- 06:06:57 INFO - PROCESS | 1637 | 06:06:57 INFO - TEST-INFO | 1637: exit 0 06:06:57 INFO - TEST-OK | dromaeo_css | took 1117168ms 06:06:57 INFO - TEST-START | kraken 06:06:57 INFO - operating with platform_type : mac_ 06:06:57 INFO - Initialising browser for kraken test... 06:06:57 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/tmpNIlQ3c/profile http://localhost:49249/getInfo.html) 06:07:00 INFO - PROCESS | 1649 | 2016-12-01 06:07:00.826 plugin-container[1651:10065] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 06:07:00 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:07:00 INFO - PROCESS | 1649 | 2016-12-01 06:07:00.826 plugin-container[1651:10065] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 06:07:00 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:07:00 INFO - PROCESS | 1649 | 2016-12-01 06:07:00.826 plugin-container[1651:10065] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:07:00 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 06:07:00 INFO - PROCESS | 1649 | colorDepth:24 06:07:00 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 06:07:00 INFO - PROCESS | 1649 | __metrics 06:07:01 ERROR - PROCESS | 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3220: TypeError: this.tabs is undefined 06:07:01 INFO - TEST-INFO | 1649: exit 0 06:07:01 INFO - Browser initialized. 06:07:01 INFO - Running cycle 1/1 for kraken test... 06:07:01 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/tmpNIlQ3c/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:07:04 INFO - PROCESS | 1652 | 2016-12-01 06:07:04.626 plugin-container[1653:10182] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 06:07:04 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:07:04 INFO - PROCESS | 1652 | 2016-12-01 06:07:04.627 plugin-container[1653:10182] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 06:07:04 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:07:04 INFO - PROCESS | 1652 | 2016-12-01 06:07:04.627 plugin-container[1653:10182] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:07:14 INFO - PROCESS | 1652 | RSS: Main: 243175424 06:07:14 INFO - PROCESS | 1652 | 06:07:17 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection) 06:07:18 INFO - PROCESS | 1652 | RSS: Main: 255315968 06:07:18 INFO - PROCESS | 1652 | 06:07:20 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-dft) 06:07:21 INFO - PROCESS | 1652 | RSS: Main: 263036928 06:07:21 INFO - PROCESS | 1652 | 06:07:24 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-fft) 06:07:25 INFO - PROCESS | 1652 | RSS: Main: 265584640 06:07:25 INFO - PROCESS | 1652 | 06:07:27 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator) 06:07:27 INFO - PROCESS | 1652 | RSS: Main: 266784768 06:07:27 INFO - PROCESS | 1652 | 06:07:29 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:07:30 INFO - PROCESS | 1652 | RSS: Main: 268492800 06:07:30 INFO - PROCESS | 1652 | 06:07:33 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom) 06:07:34 INFO - PROCESS | 1652 | RSS: Main: 274104320 06:07:34 INFO - PROCESS | 1652 | 06:07:37 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate) 06:07:38 INFO - PROCESS | 1652 | RSS: Main: 273154048 06:07:38 INFO - PROCESS | 1652 | 06:07:41 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial) 06:07:41 INFO - PROCESS | 1652 | RSS: Main: 273313792 06:07:41 INFO - PROCESS | 1652 | 06:07:43 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:07:44 INFO - PROCESS | 1652 | RSS: Main: 272592896 06:07:44 INFO - PROCESS | 1652 | 06:07:46 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:07:46 INFO - PROCESS | 1652 | RSS: Main: 272605184 06:07:46 INFO - PROCESS | 1652 | 06:07:48 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:07:49 INFO - PROCESS | 1652 | RSS: Main: 272637952 06:07:49 INFO - PROCESS | 1652 | 06:07:52 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:07:53 INFO - PROCESS | 1652 | RSS: Main: 272859136 06:07:53 INFO - PROCESS | 1652 | 06:07:56 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:07:57 INFO - PROCESS | 1652 | RSS: Main: 272777216 06:07:57 INFO - PROCESS | 1652 | 06:07:58 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49249/tests/kraken/driver.html?testName=ai-astar) 06:07:59 INFO - PROCESS | 1652 | RSS: Main: 274665472 06:07:59 INFO - PROCESS | 1652 | 06:07:59 INFO - PROCESS | 1652 | __start_tp_report 06:07:59 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 06:07:59 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 06:07:59 INFO - PROCESS | 1652 | |i|pagename|runs| 06:07:59 INFO - PROCESS | 1652 | |0;ai-astar;150;173;149;119;117;118;117;120;116;103 06:07:59 INFO - PROCESS | 1652 | |1;audio-beat-detection;146;116;120;153;117;119;123;118;138;117 06:07:59 INFO - PROCESS | 1652 | |2;audio-dft;193;210;253;250;202;197;197;200;201;197 06:07:59 INFO - PROCESS | 1652 | |3;audio-fft;87;59;60;98;60;51;84;82;74;53 06:07:59 INFO - PROCESS | 1652 | |4;audio-oscillator;88;69;85;64;74;78;81;70;62;87 06:07:59 INFO - PROCESS | 1652 | |5;imaging-gaussian-blur;88;88;89;89;135;120;90;75;139;131 06:07:59 INFO - PROCESS | 1652 | |6;imaging-darkroom;112;141;99;98;100;97;124;175;99;136 06:07:59 INFO - PROCESS | 1652 | |7;imaging-desaturate;139;107;141;149;93;91;93;92;143;146 06:07:59 INFO - PROCESS | 1652 | |8;json-parse-financial;48;49;82;85;87;86;67;58;58;57 06:07:59 INFO - PROCESS | 1652 | |9;json-stringify-tinderbox;85;78;70;86;78;74;68;70;76;72 06:07:59 INFO - PROCESS | 1652 | |10;stanford-crypto-aes;90;89;86;90;89;89;88;89;90;89 06:07:59 INFO - PROCESS | 1652 | |11;stanford-crypto-ccm;150;152;151;149;150;147;168;171;196;176 06:07:59 INFO - PROCESS | 1652 | |12;stanford-crypto-pbkdf2;179;183;181;159;167;224;250;190;177;180 06:07:59 INFO - PROCESS | 1652 | |13;stanford-crypto-sha256-iterative;73;71;73;73;69;69;84;93;92;72 06:07:59 INFO - PROCESS | 1652 | __end_tp_report 06:07:59 INFO - PROCESS | 1652 | __start_cc_report 06:07:59 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,955 06:07:59 INFO - PROCESS | 1652 | __end_cc_report 06:07:59 INFO - PROCESS | 1652 | __startTimestamp1480601279131__endTimestamp 06:07:59 INFO - PROCESS | 1652 | 06:07:59 INFO - PROCESS | 1652 | ------- Summary: start ------- 06:07:59 INFO - PROCESS | 1652 | Number of tests: 14 06:07:59 INFO - PROCESS | 1652 | 06:07:59 INFO - PROCESS | 1652 | [#0] ai-astar Cycles:10 Average:128.20 Median:119.50 stddev:21.62 (18.1%) stddev-sans-first:21.44 06:07:59 INFO - PROCESS | 1652 | Values: 150.0 173.0 149.0 119.0 117.0 118.0 117.0 120.0 116.0 103.0 06:07:59 INFO - PROCESS | 1652 | 06:07:59 INFO - PROCESS | 1652 | [#1] audio-beat-detection Cycles:10 Average:126.70 Median:121.50 stddev:13.70 (11.3%) stddev-sans-first:12.62 06:07:59 INFO - PROCESS | 1652 | Values: 146.0 116.0 120.0 153.0 117.0 119.0 123.0 118.0 138.0 117.0 06:07:59 INFO - PROCESS | 1652 | 06:07:59 INFO - PROCESS | 1652 | [#2] audio-dft Cycles:10 Average:210.00 Median:201.50 stddev:22.34 (11.1%) stddev-sans-first:22.83 06:07:59 INFO - PROCESS | 1652 | Values: 193.0 210.0 253.0 250.0 202.0 197.0 197.0 200.0 201.0 197.0 06:07:59 INFO - PROCESS | 1652 | 06:07:59 INFO - PROCESS | 1652 | [#3] audio-fft Cycles:10 Average:70.80 Median:78.00 stddev:16.31 (20.9%) stddev-sans-first:16.21 06:07:59 INFO - PROCESS | 1652 | Values: 87.0 59.0 60.0 98.0 60.0 51.0 84.0 82.0 74.0 53.0 06:07:59 INFO - PROCESS | 1652 | 06:07:59 INFO - PROCESS | 1652 | [#4] audio-oscillator Cycles:10 Average:75.80 Median:79.50 stddev:9.45 (11.9%) stddev-sans-first:8.93 06:07:59 INFO - PROCESS | 1652 | Values: 88.0 69.0 85.0 64.0 74.0 78.0 81.0 70.0 62.0 87.0 06:07:59 INFO - PROCESS | 1652 | 06:07:59 INFO - PROCESS | 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:104.40 Median:88.00 stddev:23.96 (27.2%) stddev-sans-first:24.67 06:07:59 INFO - PROCESS | 1652 | Values: 88.0 88.0 89.0 89.0 135.0 120.0 90.0 75.0 139.0 131.0 06:07:59 INFO - PROCESS | 1652 | 06:07:59 INFO - PROCESS | 1652 | [#6] imaging-darkroom Cycles:10 Average:118.10 Median:136.00 stddev:25.95 (19.1%) stddev-sans-first:27.43 06:07:59 INFO - PROCESS | 1652 | Values: 112.0 141.0 99.0 98.0 100.0 97.0 124.0 175.0 99.0 136.0 06:07:59 INFO - PROCESS | 1652 | 06:07:59 INFO - PROCESS | 1652 | [#7] imaging-desaturate Cycles:10 Average:119.40 Median:120.00 stddev:26.03 (21.7%) stddev-sans-first:26.62 06:07:59 INFO - PROCESS | 1652 | Values: 139.0 107.0 141.0 149.0 93.0 91.0 93.0 92.0 143.0 146.0 06:07:59 INFO - PROCESS | 1652 | 06:07:59 INFO - PROCESS | 1652 | [#8] json-parse-financial Cycles:10 Average:67.70 Median:74.50 stddev:15.82 (21.2%) stddev-sans-first:15.09 06:07:59 INFO - PROCESS | 1652 | Values: 48.0 49.0 82.0 85.0 87.0 86.0 67.0 58.0 58.0 57.0 06:07:59 INFO - PROCESS | 1652 | 06:07:59 INFO - PROCESS | 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:75.70 Median:77.00 stddev:6.18 (8.0%) stddev-sans-first:5.57 06:07:59 INFO - PROCESS | 1652 | Values: 85.0 78.0 70.0 86.0 78.0 74.0 68.0 70.0 76.0 72.0 06:07:59 INFO - PROCESS | 1652 | 06:07:59 INFO - PROCESS | 1652 | [#10] stanford-crypto-aes Cycles:10 Average:88.90 Median:89.00 stddev:1.20 (1.3%) stddev-sans-first:1.20 06:07:59 INFO - PROCESS | 1652 | Values: 90.0 89.0 86.0 90.0 89.0 89.0 88.0 89.0 90.0 89.0 06:07:59 INFO - PROCESS | 1652 | 06:07:59 INFO - PROCESS | 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:161.00 Median:160.00 stddev:16.20 (10.1%) stddev-sans-first:16.69 06:07:59 INFO - PROCESS | 1652 | Values: 150.0 152.0 151.0 149.0 150.0 147.0 168.0 171.0 196.0 176.0 06:07:59 INFO - PROCESS | 1652 | 06:07:59 INFO - PROCESS | 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.00 Median:182.00 stddev:27.40 (15.1%) stddev-sans-first:28.82 06:07:59 INFO - PROCESS | 1652 | Values: 179.0 183.0 181.0 159.0 167.0 224.0 250.0 190.0 177.0 180.0 06:07:59 INFO - PROCESS | 1652 | 06:07:59 INFO - PROCESS | 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.90 Median:73.00 stddev:9.23 (12.6%) stddev-sans-first:9.68 06:07:59 INFO - PROCESS | 1652 | Values: 73.0 71.0 73.0 73.0 69.0 69.0 84.0 93.0 92.0 72.0 06:07:59 INFO - PROCESS | 1652 | -------- Summary: end -------- 06:07:59 INFO - PROCESS | 1652 | 06:08:00 INFO - TEST-INFO | 1652: exit 0 06:08:00 INFO - TEST-OK | kraken | took 62549ms 06:08:00 INFO - SUITE-END | took 1179s 06:08:00 INFO - Completed test suite (00:19:39) 06:08:00 INFO - javascript benchmark 06:08:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7272.1867970377325, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3239.352129574085, 3252.5121231815224, 3302.3890580242655, 3302.8843990460336, 3310.86756529739, 5522.392536820951, 5572.777088916444, 5583.162525621149, 5620.494155526012, 5665.405132461094, 5383.380911195214, 5471.863203419916, 5483.533899618527, 5535.363433205182, 5546.029444847156, 4956.0, 4974.278729584208, 5070.847874563769, 5080.466551012146, 5083.618728595352, 5062.936783275498, 5138.820141295056, 5164.0, 5165.664231824938, 5171.56041736451, 4515.209838278304, 4977.104121258172, 5149.047426226141, 5152.201408781645, 5153.2785410042625, 5149.201873709581, 5150.330457040598, 5153.330067091273, 5153.56194723449, 5163.277141200235, 4256.7445953242595, 4991.750412479398, 5094.668846525001, 5106.106431374532, 5110.259012443212, 5592.077307244341, 5619.887602247933, 5655.688937108457, 5666.631668941505, 5716.228309178291, 5873.530117590582, 6077.969610151965, 6098.756049758004, 6125.264968203844, 6138.631682099043, 6008.068749343859, 6037.154798328237, 6054.394560543955, 6135.417135372132, 6160.4455598995855, 5595.468430499096, 5666.235058267163, 5679.0, 5685.0, 5757.539396848284, 6251.28110267316, 6260.874782504325, 6268.905966410507, 6270.059491076375, 6281.96859015702, 5768.74040668171, 5950.256217972754, 5954.315253745788, 5974.820755377345, 5990.73041713124, 5995.490383317379, 6061.636301821816, 6101.725422356005, 6130.417610327012, 6173.074038894202, 6050.848728781815, 6085.382350600777, 6123.923256659767, 6127.647695152957, 6142.6314421134875, 6078.027515597483, 6121.84695382619, 6122.724477398528, 6129.3870612939245, 6130.1724267224245, 4724.889650932028, 4758.119747846659, 4777.307290442866, 4781.737004464719, 4785.736784476887, 4296.5273819879785, 4345.109252603209, 4364.214441400578, 4403.339499075164, 4410.33844923264, 5510.393856675762, 5600.915986260209, 5635.520980716685, 5650.3219613646625, 5701.515371193495, 5977.970110149421, 5985.431384018511, 6199.721012554446, 6209.037599172136, 6214.130021796952, 5525.861853453615, 5570.637908535932, 5577.330720313588, 5578.776848925998, 5588.972055139699, 4452.532484089189, 4508.616767574727, 4979.128652485801, 5184.403793563713, 5275.235090911796, 5194.506521880416, 6226.501879849601, 6269.467095296951, 6295.370462953668, 6300.432961033529, 6202.72087756043, 6222.377762223741, 6230.314665386713, 6236.906446403217, 6263.96868015657, 6111.96944015277, 6114.419025732044, 6122.265328160649, 6124.938750612437, 6141.969290153521, 6001.0, 6002.0996850472775, 6013.0, 6018.51851851842, 6052.0, 6049.727762250621, 6086.482648974887, 6096.603720758137, 6123.357047509948, 6128.63228206309, 4803.0, 5732.140178973187, 5743.167240750151, 5783.103618939072, 5801.042827933513, 5839.3868643793485, 5857.4435428634215, 5873.089671100986, 5916.73374698131, 6011.759529618941, 5741.11012793001, 5801.477866991991, 5888.558358123244, 5895.616784908967, 5926.733297001559, 5952.107183922273, 5957.987142185752, 6003.669798161143, 6025.548083893813, 6026.517878569792, 5054.1660625997765, 5672.14917762339, 5695.117256825199, 5787.102999035156, 5792.073268277056], "value": 5542.948507537942, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [480.22443753338354, 511.095361210657, 521.6504941689068, 526.8919871426358, 532.7549327309438, 38770.80614596927, 39953.200935981266, 40045.0, 40186.0, 40256.0, 41454.0, 41794.582054179446, 41813.790931045376, 41877.99999999993, 41945.58054419455, 8292.0, 8523.872141917846, 9076.500792456412, 9256.00995785906, 9271.860922086174, 10771.299865508756, 11108.444577771119, 11147.052500537464, 11301.604443844468, 11310.09519238459, 11204.927088488615, 11258.493367798468, 11259.54961801527, 11264.493097810619, 11315.207935444521, 11074.335539867634, 11140.888591114106, 11148.275362101518, 11155.665330040112, 11167.162462815322, 10890.40102794346, 11013.779724405547, 11019.173561982803, 11028.889711102867, 11033.558657653684, 38985.0, 39288.607113929065, 39374.80312598447, 39456.0, 39461.605383946364, 3260.054584170611, 3291.2594666200152, 3374.6625337466303, 3408.471686888536, 3459.377312083824, 29031.41937161266, 29463.0, 29632.851835740898, 30109.849450752605, 30341.78632854683, 3029.9848500757575, 3051.9847400762856, 3058.1590062732926, 3135.827529485877, 3197.328561002192, 447948.0, 453313.0, 455109.0, 457687.0, 458887.9999999967, 57904.71047644735, 58272.0, 58335.0, 58380.7080964601, 58391.0, 61503.0, 61588.0, 61838.69080654568, 61899.38100618936, 61924.38075619186, 15507.767383489258, 15711.057336559843, 15766.448174313844, 15773.763393549107, 15777.763333550007, 11038.0, 11290.548378064785, 11981.76036479283, 12132.393380330948, 12318.938405308096, 16772.161391930356, 16776.328946842234, 16823.663526729397, 16869.493915182564, 17018.14909254557, 5963.970180149072, 6250.499960003189, 6309.842253943688, 6785.117934668461, 6791.185057793096, 843.4854738609444, 846.4709556527171, 1003.3127307794039, 1005.8843233028149, 1042.9165666746642, 25244.621330680053, 25975.831087601102, 27869.024584139457, 29597.26006849846, 29831.0, 1045.4772613693153, 1064.0, 1069.3263244156133, 1152.9250598711058, 1182.899453546441, 13058.673533161556, 14948.626284342978, 15071.698566028837, 15075.924620376827, 15517.224138793015, 777331.0, 811641.0, 813227.0, 815159.8484015203, 816645.0, 55100.72449637806, 55453.722731386086, 55497.167542486895, 55649.60875978133, 55652.72173639187, 1341.7115320206008, 1344.751221024094, 1375.8211432513908, 1376.9655758605916, 1377.1668140774887, 22849.65725514152, 24048.15831445891, 29264.70735292705, 29615.703842962157, 30020.0, 43377.56622433735, 46255.999999998654, 47693.0, 47955.760221198674, 48174.79563010813, 455082.7245863882, 461062.0, 462996.0, 463346.0, 463750.0, 22991.195308164133, 23608.645870311615, 24729.752702473466, 24949.251522454357, 25122.74877251204, 42958.78520607377, 44316.55683443124, 45833.0, 46299.53700462952, 46514.76742616265, 28709.138725837423, 38183.236335273694, 38243.23513529769, 38614.613853861105, 39131.0, 36939.07652308714, 37012.81493592515, 37709.81145094257, 37981.67064152742, 38432.231355372176, 36687.816560918094, 37458.06354841042, 37690.0, 37933.0, 38066.619333807415, 30608.84695576508, 33534.66465335315, 33564.32871342511, 33582.0, 33724.32551349008, 22520.887395562917, 24904.75095249024, 25362.239132825307, 25536.61695074538, 25555.744442555337], "value": 19594.55128163406, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.72824973644384, 372.2090557565174, 377.6412408212198, 381.18236382958537, 399.8760384280873, 18300.725489117674, 19029.71455428168, 19105.904470477628, 19392.709109363353, 19583.902080489577, 19290.228390864348, 19454.805451945474, 19829.603407931834, 19887.50281242966, 20142.697859532083, 4117.21772863155, 5912.704364781744, 6120.785772497963, 6195.225596800411, 6471.449926756231, 5885.0, 6408.743650253984, 6413.134226879358, 6415.582987105847, 6420.165378500788, 6250.624962502242, 6289.088082228097, 6389.520785941049, 6428.871422571546, 6445.2587952385375, 6108.114323423123, 6351.428371446569, 6405.19935008124, 6424.38968298011, 6425.646589437579, 6132.386761323832, 6228.470580000705, 6237.469815065725, 6240.438360547572, 6243.937560624381, 24601.293857675642, 25449.363765905815, 25883.0, 26482.0, 26712.599311010155, 2333.696619439462, 2342.730585982617, 2482.863442510661, 2542.7711505964367, 3237.1281221761333, 13813.102148360324, 14828.925855370762, 16096.517104486884, 16242.187890605423, 16923.3230670773, 1934.564722937342, 1985.4143027806688, 2010.3265406088979, 2018.4247489465579, 2034.8260679862065, 119475.0, 135517.3224133883, 136007.0, 137795.0, 140496.0, 15101.622459438382, 19410.611787764166, 20435.773853568833, 20475.38573842787, 20581.89709051475, 32211.51682724761, 32927.176820579676, 33055.50416743751, 33132.01207927532, 33175.33649327, 10060.446675432922, 10272.897271027343, 10299.639512617023, 10464.901185375425, 10585.364878107339, 7220.783376498714, 8453.196946290092, 8481.067082620906, 8605.741827745052, 8645.654173833102, 7784.610769461504, 8488.490690558463, 8511.65953361871, 9047.954760226157, 9497.240220782322, 3699.167687270343, 3789.2610940866816, 3799.259144466858, 3800.942985855159, 3820.3887378019385, 694.5207806613522, 821.8643923752634, 905.7101727447285, 906.9501177435304, 969.5152423788106, 18893.527661808566, 19587.804121958598, 20034.69947950782, 20125.49686257855, 20176.798232017492, 884.0673089890228, 885.9734207973771, 908.2643059122132, 931.8229536388064, 956.818204541135, 10124.898751012543, 10637.840432393521, 10761.461926903623, 10806.406462449113, 11413.714657133638, 488055.0, 497699.0, 512656.0, 521535.0, 532665.0, 108286.0, 112713.0, 113701.0, 117324.0, 118985.62028759376, 949.7483166960761, 983.1102851919024, 995.1193194023197, 1006.4112494190834, 1008.6016023670755, 17425.73861392055, 17878.731819022465, 18049.0, 18257.269709211483, 18344.66546650189, 43352.0, 46346.0, 52598.477159899194, 54731.179032313754, 55542.72228638831, 77264.84102738576, 77661.0, 77931.0, 78339.0, 78760.60619697094, 7686.478617650115, 7762.184970578008, 7901.407394445325, 7911.485753426009, 8032.236937490929, 10799.67600971972, 12240.265584064984, 12282.877171228174, 12463.501459941863, 12523.624291271277, 8818.29453643694, 10428.791424171626, 10619.575216991234, 10814.837777433188, 10979.847116052699, 10193.74515637115, 10407.94796026015, 10417.427041512487, 10434.0, 10599.62901298451, 10704.518296676513, 10909.563617455213, 10965.83551246716, 11052.778944420907, 11161.832572511581, 7632.885506717293, 7827.647755850887, 7900.52511641741, 7924.960375198087, 8058.637361318638, 8007.079185893696, 9387.10822471864, 10609.628662996758, 10725.249232553646, 10793.56825726962], "value": 10380.9446314773, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1474.69768697417, 1485.331600779649, 1555.2068445093, 1684.326269492203, 1817.8636602254835, 7116.5018448708615, 7471.85056298875, 7720.30517253447, 8002.31980281676, 8454.873176902343, 7885.645145968431, 8210.137935516774, 8213.589320533963, 8289.088200297978, 8291.212334828197, 1956.520652440147, 2278.829087818416, 2309.711286089239, 2315.907363705458, 2319.118734880743, 2304.539092181562, 2331.40549159964, 2347.6713260143592, 2349.7650234976536, 2349.859008459498, 1908.570571621381, 2288.5880541502524, 2304.7119110111234, 2315.131825565413, 2316.343814172866, 2268.3081660093667, 2285.074544809355, 2294.655801629752, 2310.722713274401, 2311.8959646815933, 2302.056156975632, 2311.179531266393, 2317.2237300504353, 2326.3253656439783, 2327.453048533593, 8279.50322980623, 8374.665013399457, 8418.663253469855, 8447.239748422671, 8452.57737113142, 4735.0, 5062.1141300272675, 5217.426083130853, 5233.764480598345, 5405.5135037846385, 2762.4337010912714, 3184.0, 3516.894493165209, 3517.9120521986897, 3522.1018640246575, 1037.0977249792654, 1063.1919740996898, 1077.4774234496263, 1090.7109615951779, 1095.7972775036644, 9143.21132054723, 10800.783984320347, 10897.910208979118, 10941.015308622262, 10947.452627368679, 4462.062966776948, 4521.841735539306, 4616.538346165356, 4625.32932724753, 4719.9292010619865, 9507.429554226768, 9507.477088760184, 9548.713538593714, 9566.61733530665, 9608.423494590346, 5189.6886186828915, 5210.817621383233, 5213.556847667915, 5217.295665085165, 5225.294585231021, 4046.7976601169826, 4634.281686338623, 4650.744209068534, 4768.7615619218905, 4774.7135171889795, 3756.549214094326, 3763.7929913855, 3775.1505911170316, 3849.9807500962315, 3874.1476875087437, 1090.9181811364178, 1257.2079589858513, 1262.9115961882765, 1272.5037235478171, 1275.1137959118323, 872.5680788009976, 874.711345256064, 893.9016708162097, 918.081918081918, 918.3984490158956, 5573.5819813514145, 5769.48074673273, 5905.084711869667, 5918.201042859159, 5987.371326010793, 840.7898025493627, 856.8115014696757, 873.4191762477881, 875.3216257400438, 921.2860033474111, 3882.611738826095, 3886.6113388660906, 3889.416587511839, 3982.5420076691553, 3998.34027385484, 9768.316217864824, 10009.749756256151, 10031.147352474976, 10113.494325283706, 10118.291719579705, 9988.350757200758, 10004.249681273934, 10039.749006274902, 10058.0, 10078.798424031625, 7487.288707572772, 7747.690092396354, 7883.881741773878, 7897.091834438998, 7898.44710870245, 5849.913225955715, 5875.441833026027, 5922.052471604523, 5963.880722385441, 6079.483243924316, 9331.766705832137, 9373.484458354584, 9490.857637135587, 9498.95250523743, 9532.094451027142, 9325.580348884181, 9453.905460945303, 9589.664361747584, 9622.66320678801, 9685.079917407835, 7178.28217178278, 7225.421966242583, 7260.89108663381, 7302.96348518254, 7331.120265568166, 7840.725574604861, 7904.446688731734, 7917.643706033127, 7919.326857217201, 7934.404919631166, 7082.5396349237135, 7197.568145911262, 7231.4937954342695, 7328.560286382621, 7343.8531229376185, 5959.612625179349, 7060.54106483077, 7124.287571242834, 7141.357277845019, 7210.96394518024, 6698.497612678971, 6719.059331693665, 6763.560368576027, 6787.423069039187, 6816.591004539743, 4883.731394773322, 4919.901601968012, 4961.0, 5005.449400566008, 5036.420811606566, 4136.152088821845, 4735.810567577259, 4878.63410244226, 5116.795328186831, 5126.871828204325], "value": 4419.604036874611, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [254.25249765688878, 266.85989855325954, 310.29253302470363, 319.1844836442888, 320.3273126434488, 5326.414094449608, 5376.892462150755, 5388.676679399229, 5420.512153906147, 5446.046941785186, 5038.0, 5318.680879147254, 5323.574114070875, 5353.518183363496, 5382.461753824616, 3188.740447523227, 3967.761934283938, 3990.9401358979567, 3995.5205375355, 4048.615381538764, 4042.575529569397, 4044.312466880637, 4058.715889887709, 4065.0, 4081.4081958116053, 3599.892003239907, 3828.808559572024, 4034.4150098235737, 4034.9394759078637, 4076.32740597801, 3687.3731465650903, 3730.2725968436166, 4022.5172979242384, 4070.9389359159636, 4085.775282359469, 3531.2760884018717, 3544.769589976643, 3544.8404821783083, 3545.2377738786286, 3572.7499075064757, 5142.331496905378, 5159.484051594848, 5187.766550505236, 5188.195829646411, 5215.183800747592, 3885.5337359516757, 4607.834317870629, 4671.1739397664605, 4682.367880336145, 4684.789184486673, 5355.812546560891, 5612.859678508029, 5644.322681278232, 5654.236678048452, 5667.971660141673, 3635.1457407509215, 3904.4338570907344, 3910.7262491625597, 3912.1002169501176, 3913.589073147307, 5086.694798312114, 5180.430152683201, 5895.528357731371, 5898.498627616657, 5925.377835327315, 3894.805259737002, 3938.0309845077463, 3956.584558621361, 3964.365701487748, 3971.4042893566198, 3787.6401741834484, 4023.3361495353524, 4024.6377825995214, 4057.208844275338, 4070.674346052309, 3943.38877473992, 4447.997601618959, 4502.0, 4665.323528088476, 4729.976350118227, 4896.869861610211, 5002.724850133205, 5153.072446959508, 5497.642653227527, 5676.631018983835, 1220.7680540697238, 1299.6750812296925, 1304.3955934200985, 1312.4447529677363, 1318.2288361308742, 757.9734709285149, 830.6013113705335, 897.2059727141527, 905.9954700226589, 914.2731528434911, 4747.715137091785, 4803.78382972767, 4864.294677271776, 5076.695398276115, 5194.662346947436, 4899.240617704261, 5228.555572776357, 5407.323729643745, 5897.941020589825, 5918.319393269743, 4881.609471242292, 4991.051700176955, 5278.287431196816, 5303.7348132593215, 5312.814051508179, 2710.2546799629863, 2864.994503847315, 2898.202994176618, 2898.7101289870843, 2910.4324656692165, 5042.503960539483, 5313.724706070515, 5627.5779316551425, 5652.491275785199, 5688.488036076773, 5346.5455436287575, 5666.518345940642, 5691.68695721731, 5725.427457254241, 5726.198332233491, 4732.408448943882, 5377.247185394126, 5468.343798744176, 5692.31692196939, 5818.44724751148, 5219.582433405242, 5506.862328441821, 5514.889702206014, 5529.253550770594, 5533.7786488540005, 4773.164696178224, 5019.52314530119, 5157.564494534622, 5483.683915860244, 5487.506124448819, 5304.522592966651, 5638.815848671661, 5639.689817060101, 5656.745446454837, 5677.432256774455, 3377.003783883711, 3463.7748546344405, 3606.2967721294094, 3733.9439908400855, 3765.45400916871, 4394.802233899546, 5119.718415487184, 5164.3802743671, 5312.6530285919625, 5580.553555715452, 4519.23173060587, 4876.4161688013155, 5107.948920510896, 5377.27406800077, 5383.600263931485, 5017.974910125426, 5077.746112694276, 5136.922946155885, 5337.692265395003, 5339.839804805862, 4480.0, 4670.772950479821, 4891.853244402673, 4911.386076740408, 5233.833530714015, 5312.707801070978, 5471.781128754965, 5559.833205003856, 5671.262735844314, 5676.120201368794, 5283.441384791573, 5523.392426832965, 5547.753159970219, 5577.832665020056, 5587.860303492283], "value": 4028.5920283862138, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [628.2053202698587, 693.7155766135884, 701.8210356359127, 721.5201890742658, 760.6460500309448, 14441.927790361047, 15249.771253431207, 15278.541643750677, 15315.927885048051, 15530.456434024798, 14082.155070695766, 14173.574792756233, 14225.573232803006, 14229.075110117836, 14232.644183895407, 667.6027763480746, 688.9896651550231, 827.7889138269733, 849.9107593702665, 874.5889431966965, 833.2500749325595, 849.7195925344653, 852.880596716457, 853.4239388412797, 853.9573021348939, 908.3686837647838, 921.8432866412693, 923.4966943016038, 925.1303774452027, 925.6158694141957, 916.2715641065336, 916.4180745226796, 917.7613820406742, 918.0773322810566, 918.393860052369, 674.6694119881272, 682.3756263019332, 682.6757290287123, 683.1835956032543, 684.0355099309949, 15062.322195501229, 15255.694886102327, 15384.692306153926, 15413.075215487106, 15457.227138643022, 12878.678033049155, 14420.0, 14747.631309217248, 14854.2572871356, 14874.256287185597, 12630.242185468902, 12825.358732063452, 13008.609741707764, 13217.801732974012, 13471.124376915566, 4486.8878278042985, 4540.7502587357685, 4552.044070745114, 4708.646851486118, 4713.29300604912, 109275.0, 111286.0, 112275.43862280637, 113740.43129784381, 115247.98408262627, 8875.556222188994, 10663.200259980533, 10677.519511621995, 10691.465426728633, 10804.459777011118, 20168.193272268945, 20181.091850866746, 20215.29246476366, 20220.0, 20223.797762022485, 6947.0, 7187.56874587516, 7308.0, 7382.409407247407, 7510.1738808731, 4280.036991676848, 4315.417418648442, 4340.978295108504, 4403.053343531092, 4482.386340917267, 3091.7990330628886, 3383.052745231339, 3411.5053317269353, 3418.29924865402, 3422.7261819054415, 516.8010316028281, 589.0369246282273, 635.5995722694673, 638.5466318913531, 645.470714014513, 440.6430791059252, 590.9970450147807, 598.1476396135489, 600.474584738354, 601.7592962814822, 7641.777315629583, 8429.367797415218, 8692.869606955901, 8777.648894044294, 8876.362457286834, 595.9940400596025, 599.9700014999232, 608.2846373227457, 611.3855575146949, 614.8124821929265, 4236.796865629855, 4254.787260636956, 4418.0, 4796.25658023007, 4843.878903027453, 82169.76745348824, 83448.0, 87962.68055979165, 89005.55497222602, 91223.54388228017, 78838.0, 79391.0, 83758.0, 84949.0, 85426.14573854182, 73423.0, 78068.0, 79695.60152199202, 81266.78099828625, 82516.76224856632, 9598.66404675833, 9845.030085916951, 9898.158656514277, 9917.305788595018, 9920.007999200023, 33210.66789332076, 46878.53121468878, 49998.0, 51859.0, 52960.0, 59194.40805591889, 71199.0, 72880.27119728948, 74311.6284418596, 74651.62674186594, 12274.263544187377, 12515.87484125147, 12628.684282893002, 12974.545890893773, 13178.868211318148, 13954.511592094228, 14649.414023438943, 14727.852721472647, 14763.704725905207, 15043.172625505702, 12370.072244581512, 13451.932740336235, 13585.320733963064, 13762.24307663048, 14342.995990280997, 13157.34213289351, 13540.458381664623, 14017.0, 14021.439142434596, 14131.64670883236, 12128.0, 13627.250501222528, 13629.795553066513, 13935.495479285422, 14021.0, 10871.67384978452, 11047.668569942914, 11408.942955285502, 11409.771804563696, 11878.524859005543, 10668.786624267626, 11317.547298108313, 11444.256123351955, 11455.312681239153, 11737.706557336134], "value": 7367.012826407867, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1612.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 173.0, 149.0, 119.0, 117.0, 118.0, 117.0, 120.0, 116.0, 103.0], "value": 128.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 116.0, 120.0, 153.0, 117.0, 119.0, 123.0, 118.0, 138.0, 117.0], "value": 126.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 210.0, 253.0, 250.0, 202.0, 197.0, 197.0, 200.0, 201.0, 197.0], "value": 210.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 59.0, 60.0, 98.0, 60.0, 51.0, 84.0, 82.0, 74.0, 53.0], "value": 70.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 69.0, 85.0, 64.0, 74.0, 78.0, 81.0, 70.0, 62.0, 87.0], "value": 75.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 89.0, 89.0, 135.0, 120.0, 90.0, 75.0, 139.0, 131.0], "value": 104.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 141.0, 99.0, 98.0, 100.0, 97.0, 124.0, 175.0, 99.0, 136.0], "value": 118.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 107.0, 141.0, 149.0, 93.0, 91.0, 93.0, 92.0, 143.0, 146.0], "value": 119.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 49.0, 82.0, 85.0, 87.0, 86.0, 67.0, 58.0, 58.0, 57.0], "value": 67.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 78.0, 70.0, 86.0, 78.0, 74.0, 68.0, 70.0, 76.0, 72.0], "value": 75.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 86.0, 90.0, 89.0, 89.0, 88.0, 89.0, 90.0, 89.0], "value": 88.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 152.0, 151.0, 149.0, 150.0, 147.0, 168.0, 171.0, 196.0, 176.0], "value": 161.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 183.0, 181.0, 159.0, 167.0, 224.0, 250.0, 190.0, 177.0, 180.0], "value": 189.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 71.0, 73.0, 73.0, 69.0, 69.0, 84.0, 93.0, 92.0, 72.0], "value": 76.9, "unit": "score"}]}]} 06:08:00 INFO - Return code: 0 06:08:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:08:00 ERROR - # TBPL SUCCESS # 06:08:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:08:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:08:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:08:00 INFO - 2016-12-01 06:08:00.429 screenresolution[1657:10577] starting screenresolution argv=screenresolution get 06:08:00 INFO - 2016-12-01 06:08:00.434 screenresolution[1657:10577] Display 0: 1600x1200x32@60 06:08:00 INFO - 2016-12-01 06:08:00.440 screenresolution[1658:10580] starting screenresolution argv=screenresolution list 06:08:00 INFO - Available Modes on Display 0 06:08:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:08:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:08:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:08:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:08:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:08:00 INFO - Intel Iris: 06:08:00 INFO - Chipset Model: Intel Iris 06:08:00 INFO - Type: GPU 06:08:00 INFO - Bus: Built-In 06:08:00 INFO - VRAM (Dynamic, Max): 1536 MB 06:08:00 INFO - Vendor: Intel (0x8086) 06:08:00 INFO - Device ID: 0x0a2e 06:08:00 INFO - Revision ID: 0x0009 06:08:00 INFO - Displays: 06:08:00 INFO - Display: 06:08:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:08:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:08:00 INFO - Display Serial Number: 1600x1200 60 06:08:00 INFO - Main Display: Yes 06:08:00 INFO - Mirror: Off 06:08:00 INFO - Online: Yes 06:08:00 INFO - Rotation: Supported 06:08:00 INFO - Return code: 0 06:08:00 INFO - Running post-action listener: _resource_record_post_action 06:08:00 INFO - [mozharness: 2016-12-01 14:08:00.681667Z] Finished run-tests step (success) 06:08:00 INFO - Running post-run listener: _resource_record_post_run 06:08:00 INFO - Total resource usage - Wall time: 1213s; CPU: 55.0%; Read bytes: 38926336; Write bytes: 692552704; Read time: 248; Write time: 2027 06:08:00 INFO - TinderboxPrint: CPU usage
55.0% 06:08:00 INFO - TinderboxPrint: I/O read bytes / time
38,926,336 / 248 06:08:00 INFO - TinderboxPrint: I/O write bytes / time
692,552,704 / 2,027 06:08:00 INFO - TinderboxPrint: CPU idle
2,185.0 (45.0%) 06:08:00 INFO - TinderboxPrint: CPU system
986.1 (20.3%) 06:08:00 INFO - TinderboxPrint: CPU user
1,682.6 (34.7%) 06:08:00 INFO - TinderboxPrint: Swap in / out
422,838,272 / 0 06:08:00 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 376738304; Write bytes: 370533888; Read time: 28988; Write time: 764 06:08:00 INFO - run-tests - Wall time: 1181s; CPU: 55.0%; Read bytes: 36820992; Write bytes: 305569280; Read time: 228; Write time: 1215 06:08:01 INFO - Running post-run listener: _upload_blobber_files 06:08:01 INFO - Blob upload gear active. 06:08:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:08:01 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 06:08:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:08:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:08:01 INFO - (blobuploader) - INFO - Open directory for files ... 06:08:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:08:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:08:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:08:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:08:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:08:02 INFO - (blobuploader) - INFO - Done attempting. 06:08:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:08:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:08:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:08:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:08:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:08:03 INFO - (blobuploader) - INFO - Done attempting. 06:08:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:08:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:08:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:08:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:08:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:08:06 INFO - (blobuploader) - INFO - Done attempting. 06:08:06 INFO - (blobuploader) - INFO - Iteration through files over. 06:08:06 INFO - Return code: 0 06:08:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:08:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:08:06 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f6831d39e9480fb408226c341220908790a1141ee0e1aa8d759ad3bcfb2feaebd54835cde8dd404d9c12f527d67d44631cdc38890f3cb6a9599ca4df33e25eb1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/216017b5887653fd20502b7bfe58b4d383dcb3a1b42e6754d653ed932b8ec33dbbe2728a5cc8f07db67847d6ca9130987e7f62b22b4ec1d441f43797ac34d7b8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/70531b861ddc6946d7c2a28607027b3650c0d8020d0f8ab8d0c7a36f48c9b84f4f1634645a0f7ae3a440ee5c9454658ab05858a469e4007c1ba130e83299342a"} 06:08:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:08:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:08:06 INFO - Contents: 06:08:06 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f6831d39e9480fb408226c341220908790a1141ee0e1aa8d759ad3bcfb2feaebd54835cde8dd404d9c12f527d67d44631cdc38890f3cb6a9599ca4df33e25eb1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/216017b5887653fd20502b7bfe58b4d383dcb3a1b42e6754d653ed932b8ec33dbbe2728a5cc8f07db67847d6ca9130987e7f62b22b4ec1d441f43797ac34d7b8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/70531b861ddc6946d7c2a28607027b3650c0d8020d0f8ab8d0c7a36f48c9b84f4f1634645a0f7ae3a440ee5c9454658ab05858a469e4007c1ba130e83299342a"} 06:08:06 INFO - Running post-run listener: copy_logs_to_upload_dir 06:08:06 INFO - Copying logs to upload dir... 06:08:06 INFO - mkdir: /builds/slave/test/build/upload/logs 06:08:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.312352 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-12-01 06:08:06.932340) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-01 06:08:06.939587) ========= 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.OBHIyN7TA7/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.hHKMd3o5No/Listeners TMPDIR=/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f6831d39e9480fb408226c341220908790a1141ee0e1aa8d759ad3bcfb2feaebd54835cde8dd404d9c12f527d67d44631cdc38890f3cb6a9599ca4df33e25eb1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/216017b5887653fd20502b7bfe58b4d383dcb3a1b42e6754d653ed932b8ec33dbbe2728a5cc8f07db67847d6ca9130987e7f62b22b4ec1d441f43797ac34d7b8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/70531b861ddc6946d7c2a28607027b3650c0d8020d0f8ab8d0c7a36f48c9b84f4f1634645a0f7ae3a440ee5c9454658ab05858a469e4007c1ba130e83299342a"} build_url:https://queue.taskcluster.net/v1/task/Ul3Yu49OQXOoTmQIagjHrQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011088 build_url: 'https://queue.taskcluster.net/v1/task/Ul3Yu49OQXOoTmQIagjHrQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f6831d39e9480fb408226c341220908790a1141ee0e1aa8d759ad3bcfb2feaebd54835cde8dd404d9c12f527d67d44631cdc38890f3cb6a9599ca4df33e25eb1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/216017b5887653fd20502b7bfe58b4d383dcb3a1b42e6754d653ed932b8ec33dbbe2728a5cc8f07db67847d6ca9130987e7f62b22b4ec1d441f43797ac34d7b8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/70531b861ddc6946d7c2a28607027b3650c0d8020d0f8ab8d0c7a36f48c9b84f4f1634645a0f7ae3a440ee5c9454658ab05858a469e4007c1ba130e83299342a"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-01 06:08:06.978041) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-01 06:08:06.978383) ========= 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.OBHIyN7TA7/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.hHKMd3o5No/Listeners TMPDIR=/var/folders/7s/mgsnjszs56ld075hs__4729c00000w/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.005101 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-01 06:08:07.027121) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-01 06:08:07.030466) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-01 06:08:07.030799) ========= ========= Total master_lag: 0.21 =========