builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0124 starttime: 1474980045.32 results: success (0) buildid: 20160927022852 builduid: a61632ae277541b285a783ec105deb2a revision: cb212903f7036117ddbb29b3bdbc390b161b1bb2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-27 05:40:45.318531) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-27 05:40:45.318920) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-27 05:40:45.319212) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-27 05:40:45.385849) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 05:40:45.386130) ========= 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.NhVgXCzjIc/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.okCY7deVCM/Listeners TMPDIR=/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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-09-27 05:40:45-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 13.9M=0.001s 2016-09-27 05:40:45 (13.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.460311 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 05:40:45.868932) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 05:40:45.869233) ========= 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.NhVgXCzjIc/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.okCY7deVCM/Listeners TMPDIR=/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.028674 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 05:40:45.918786) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-27 05:40:45.919196) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev cb212903f7036117ddbb29b3bdbc390b161b1bb2 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev cb212903f7036117ddbb29b3bdbc390b161b1bb2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NhVgXCzjIc/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.okCY7deVCM/Listeners TMPDIR=/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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-09-27 05:40:46,001 truncating revision to first 12 chars 2016-09-27 05:40:46,001 Setting DEBUG logging. 2016-09-27 05:40:46,001 attempt 1/10 2016-09-27 05:40:46,001 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/cb212903f703?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-27 05:40:47,032 unpacking tar archive at: fx-team-cb212903f703/testing/mozharness/ program finished with exit code 0 elapsedTime=1.283371 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-27 05:40:47.226779) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-27 05:40:47.227084) ========= script_repo_revision: cb212903f7036117ddbb29b3bdbc390b161b1bb2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-27 05:40:47.227594) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-27 05:40:47.227919) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-27 05:40:47.246656) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-27 05:40:47.246935) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NhVgXCzjIc/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.okCY7deVCM/Listeners TMPDIR=/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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:40:47 INFO - MultiFileLogger online at 20160927 05:40:47 in /builds/slave/test 05:40:47 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 05:40:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:40:47 INFO - {'append_to_log': False, 05:40:47 INFO - 'base_work_dir': '/builds/slave/test', 05:40:47 INFO - 'blob_upload_branch': 'Fx-Team', 05:40:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:40:47 INFO - 'branch': 'Fx-Team', 05:40:47 INFO - 'buildbot_json_path': 'buildprops.json', 05:40:47 INFO - 'config_files': ('talos/mac_config.py',), 05:40:47 INFO - 'default_actions': ('clobber', 05:40:47 INFO - 'read-buildbot-config', 05:40:47 INFO - 'download-and-extract', 05:40:47 INFO - 'populate-webroot', 05:40:47 INFO - 'create-virtualenv', 05:40:47 INFO - 'install', 05:40:47 INFO - 'run-tests'), 05:40:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:40:47 INFO - 'download_minidump_stackwalk': True, 05:40:47 INFO - 'download_symbols': 'ondemand', 05:40:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:40:47 INFO - 'tooltool.py': '/tools/tooltool.py', 05:40:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:40:47 INFO - '/tools/misc-python/virtualenv.py')}, 05:40:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:40:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:40:47 INFO - 'installer_path': 'installer.exe', 05:40:47 INFO - 'log_level': 'info', 05:40:47 INFO - 'log_name': 'talos', 05:40:47 INFO - 'log_to_console': True, 05:40:47 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:40:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:40:47 INFO - 'opt_config_files': (), 05:40:47 INFO - 'pip_index': False, 05:40:47 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:40:47 INFO - 'cmd': ('bash', 05:40:47 INFO - '-c', 05:40:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:40:47 INFO - 'enabled': True, 05:40:47 INFO - 'halt_on_failure': False, 05:40:47 INFO - 'name': 'check_screen_resolution'},), 05:40:47 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:40:47 INFO - 'cmd': ('bash', 05:40:47 INFO - '-c', 05:40:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:40:47 INFO - 'enabled': True, 05:40:47 INFO - 'halt_on_failure': False, 05:40:47 INFO - 'name': 'check_screen_resolution'},), 05:40:47 INFO - 'run_cmd_checks_enabled': True, 05:40:47 INFO - 'sps_profile': False, 05:40:47 INFO - 'sps_profile_interval': 0, 05:40:47 INFO - 'suite': 'dromaeojs-e10s', 05:40:47 INFO - 'system_bits': '32', 05:40:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:40:47 INFO - 'title': 't-yosemite-r7-0124', 05:40:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:40:47 INFO - 'use_talos_json': True, 05:40:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:40:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:40:47 INFO - 'work_dir': 'build'} 05:40:47 INFO - [mozharness: 2016-09-27 12:40:47.458882Z] Running clobber step. 05:40:47 INFO - Running pre-action listener: _resource_record_pre_action 05:40:47 INFO - Running main action method: clobber 05:40:47 INFO - rmtree: /builds/slave/test/build 05:40:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:40:49 INFO - Running post-action listener: _resource_record_post_action 05:40:49 INFO - [mozharness: 2016-09-27 12:40:49.585135Z] Finished clobber step (success) 05:40:49 INFO - [mozharness: 2016-09-27 12:40:49.585252Z] Running read-buildbot-config step. 05:40:49 INFO - Running pre-action listener: _resource_record_pre_action 05:40:49 INFO - Running main action method: read_buildbot_config 05:40:49 INFO - Using buildbot properties: 05:40:49 INFO - { 05:40:49 INFO - "project": "", 05:40:49 INFO - "product": "firefox", 05:40:49 INFO - "script_repo_revision": "production", 05:40:49 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 05:40:49 INFO - "repository": "", 05:40:49 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 05:40:49 INFO - "buildid": "20160927022852", 05:40:49 INFO - "pgo_build": "False", 05:40:49 INFO - "basedir": "/builds/slave/test", 05:40:49 INFO - "buildnumber": 131, 05:40:49 INFO - "slavename": "t-yosemite-r7-0124", 05:40:49 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 05:40:49 INFO - "platform": "yosemite_r7", 05:40:49 INFO - "scheduler": "tests-fx-team-macosx64-talos-8-3600", 05:40:49 INFO - "branch": "fx-team", 05:40:49 INFO - "repo_path": "integration/fx-team", 05:40:49 INFO - "revision": "cb212903f7036117ddbb29b3bdbc390b161b1bb2", 05:40:49 INFO - "stage_platform": "macosx64", 05:40:49 INFO - "builduid": "a61632ae277541b285a783ec105deb2a", 05:40:49 INFO - "slavebuilddir": "test" 05:40:49 INFO - } 05:40:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/NglRZBIiS2mAVv1RUgeeKg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 05:40:49 INFO - Running post-action listener: _resource_record_post_action 05:40:49 INFO - [mozharness: 2016-09-27 12:40:49.588494Z] Finished read-buildbot-config step (success) 05:40:49 INFO - [mozharness: 2016-09-27 12:40:49.588615Z] Running download-and-extract step. 05:40:49 INFO - Running pre-action listener: _resource_record_pre_action 05:40:49 INFO - Running main action method: download_and_extract 05:40:49 INFO - mkdir: /builds/slave/test/build/tests 05:40:49 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:40:49 INFO - trying https://queue.taskcluster.net/v1/task/NglRZBIiS2mAVv1RUgeeKg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 05:40:49 INFO - Downloading https://queue.taskcluster.net/v1/task/NglRZBIiS2mAVv1RUgeeKg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:40:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NglRZBIiS2mAVv1RUgeeKg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 05:40:50 INFO - Downloaded 1199 bytes. 05:40:50 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:40:50 INFO - Using the following test package requirements: 05:40:50 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:40:50 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:40:50 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 05:40:50 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:40:50 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 05:40:50 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:40:50 INFO - u'jsshell-mac.zip'], 05:40:50 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:40:50 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 05:40:50 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:40:50 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:40:50 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 05:40:50 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:40:50 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 05:40:50 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:40:50 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 05:40:50 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:40:50 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 05:40:50 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:40:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NglRZBIiS2mAVv1RUgeeKg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:40:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NglRZBIiS2mAVv1RUgeeKg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:40:50 INFO - Fetch https://queue.taskcluster.net/v1/task/NglRZBIiS2mAVv1RUgeeKg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:40:51 INFO - Expected file size: 16470506 05:40:58 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:40:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NglRZBIiS2mAVv1RUgeeKg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:40:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NglRZBIiS2mAVv1RUgeeKg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:40:58 INFO - Fetch https://queue.taskcluster.net/v1/task/NglRZBIiS2mAVv1RUgeeKg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:40:59 INFO - Expected file size: 16470506 05:41:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NglRZBIiS2mAVv1RUgeeKg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 05:41:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NglRZBIiS2mAVv1RUgeeKg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:41:05 INFO - Fetch https://queue.taskcluster.net/v1/task/NglRZBIiS2mAVv1RUgeeKg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 05:41:06 INFO - Expected file size: 14414057 05:41:07 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:41:07 INFO - trying https://queue.taskcluster.net/v1/task/NglRZBIiS2mAVv1RUgeeKg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:41:07 INFO - Downloading https://queue.taskcluster.net/v1/task/NglRZBIiS2mAVv1RUgeeKg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 05:41:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NglRZBIiS2mAVv1RUgeeKg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 05:41:11 INFO - Downloaded 111530590 bytes. 05:41:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NglRZBIiS2mAVv1RUgeeKg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:41:11 INFO - mkdir: /builds/slave/test/properties 05:41:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:41:11 INFO - Writing to file /builds/slave/test/properties/build_url 05:41:11 INFO - Contents: 05:41:11 INFO - build_url:https://queue.taskcluster.net/v1/task/NglRZBIiS2mAVv1RUgeeKg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:41:12 INFO - Running post-action listener: _resource_record_post_action 05:41:12 INFO - Running post-action listener: set_extra_try_arguments 05:41:12 INFO - [mozharness: 2016-09-27 12:41:12.137266Z] Finished download-and-extract step (success) 05:41:12 INFO - [mozharness: 2016-09-27 12:41:12.137425Z] Running populate-webroot step. 05:41:12 INFO - Running pre-action listener: _resource_record_pre_action 05:41:12 INFO - Running main action method: populate_webroot 05:41:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:41:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:41:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:41:12 INFO - '--tppagecycles', 05:41:12 INFO - '7'], 05:41:12 INFO - 'tests': ['tsvgm']}}, 05:41:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:41:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:41:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:41:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:41:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:41:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:41:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:41:12 INFO - 'talos_options': ['--disable-e10s'], 05:41:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:41:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:41:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:41:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:41:12 INFO - 'talos_options': ['--disable-e10s'], 05:41:12 INFO - 'tests': ['damp', 'tps']}, 05:41:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:41:12 INFO - 'tests': ['damp', 'tps']}, 05:41:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:41:12 INFO - 'tests': ['dromaeo_dom']}, 05:41:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:41:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:41:12 INFO - 'tests': ['basic_compositor_video']}, 05:41:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:41:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:41:12 INFO - 'tests': ['a11yr', 05:41:12 INFO - 'ts_paint', 05:41:12 INFO - 'tpaint', 05:41:12 INFO - 'sessionrestore', 05:41:12 INFO - 'sessionrestore_no_auto_restore', 05:41:12 INFO - 'tabpaint']}, 05:41:12 INFO - 'other-e10s': {'tests': ['a11yr', 05:41:12 INFO - 'ts_paint', 05:41:12 INFO - 'tpaint', 05:41:12 INFO - 'sessionrestore', 05:41:12 INFO - 'sessionrestore_no_auto_restore', 05:41:12 INFO - 'tabpaint']}, 05:41:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:41:12 INFO - 'ts_paint', 05:41:12 INFO - 'tpaint', 05:41:12 INFO - 'sessionrestore', 05:41:12 INFO - 'sessionrestore_no_auto_restore', 05:41:12 INFO - 'tabpaint']}, 05:41:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:41:12 INFO - 'ts_paint', 05:41:12 INFO - 'tpaint', 05:41:12 INFO - 'sessionrestore', 05:41:12 INFO - 'sessionrestore_no_auto_restore', 05:41:12 INFO - 'tabpaint']}, 05:41:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:41:12 INFO - 'tests': ['a11yr', 05:41:12 INFO - 'ts_paint', 05:41:12 INFO - 'tpaint', 05:41:12 INFO - 'sessionrestore', 05:41:12 INFO - 'sessionrestore_no_auto_restore', 05:41:12 INFO - 'tabpaint']}, 05:41:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:41:12 INFO - 'tests': ['a11yr', 05:41:12 INFO - 'ts_paint', 05:41:12 INFO - 'tpaint', 05:41:12 INFO - 'sessionrestore', 05:41:12 INFO - 'sessionrestore_no_auto_restore', 05:41:12 INFO - 'tabpaint']}, 05:41:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:41:12 INFO - 'tests': ['tsvgx', 05:41:12 INFO - 'tsvgr_opacity', 05:41:12 INFO - 'tart', 05:41:12 INFO - 'tscrollx', 05:41:12 INFO - 'cart']}, 05:41:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:41:12 INFO - 'tsvgr_opacity', 05:41:12 INFO - 'tart', 05:41:12 INFO - 'tscrollx', 05:41:12 INFO - 'cart']}, 05:41:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:41:12 INFO - 'talos_options': ['--disable-e10s'], 05:41:12 INFO - 'tests': ['tp5o']}, 05:41:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:41:12 INFO - 'tests': ['tp5o']}, 05:41:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:41:12 INFO - 'talos_options': ['--disable-e10s', 05:41:12 INFO - '--xperf_path', 05:41:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:41:12 INFO - 'tests': ['tp5n']}, 05:41:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:41:12 INFO - 'talos_options': ['--xperf_path', 05:41:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:41:12 INFO - 'tests': ['tp5n']}}, 05:41:12 INFO - 'talos.zip': {'path': '', 05:41:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:41:12 INFO - Running post-action listener: _resource_record_post_action 05:41:12 INFO - [mozharness: 2016-09-27 12:41:12.156233Z] Finished populate-webroot step (success) 05:41:12 INFO - [mozharness: 2016-09-27 12:41:12.156359Z] Running create-virtualenv step. 05:41:12 INFO - Running pre-action listener: _resource_record_pre_action 05:41:12 INFO - Running main action method: create_virtualenv 05:41:12 INFO - Creating virtualenv /builds/slave/test/build/venv 05:41:12 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:41:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:41:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:41:12 INFO - Using real prefix '/tools/python27' 05:41:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:41:13 INFO - Installing distribute.............................................................................................................................................................................................done. 05:41:15 INFO - Installing pip.................done. 05:41:15 INFO - Return code: 0 05:41:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:41:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:41:16 INFO - Reading from file tmpfile_stdout 05:41:16 INFO - Output received: 05:41:16 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:41:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:41:16 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:41:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:16 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:41:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:16 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 0x10af3d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeb3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec83d59110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad51d30>, '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 0x10ab77db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad49b20>, '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.NhVgXCzjIc/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.okCY7deVCM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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:41:16 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:41:16 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:41:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NhVgXCzjIc/Render', 05:41:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:41:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:41:16 INFO - 'HOME': '/Users/cltbld', 05:41:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:41:16 INFO - 'LOGNAME': 'cltbld', 05:41:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:16 INFO - 'MOZ_NO_REMOTE': '1', 05:41:16 INFO - 'NO_EM_RESTART': '1', 05:41:16 INFO - 'PAGER': '/bin/cat', 05:41:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:41:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:16 INFO - 'PWD': '/builds/slave/test', 05:41:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:41:16 INFO - 'SHELL': '/bin/bash', 05:41:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.okCY7deVCM/Listeners', 05:41:16 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 05:41:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:41:16 INFO - 'USER': 'cltbld', 05:41:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:41:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:41:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:16 INFO - 'XPC_FLAGS': '0x0', 05:41:16 INFO - 'XPC_SERVICE_NAME': '0', 05:41:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:41:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:16 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:41:16 INFO - Cleaning up... 05:41:16 INFO - Return code: 0 05:41:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:41:16 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:41:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:16 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:41:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:16 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 0x10af3d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeb3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec83d59110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad51d30>, '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 0x10ab77db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad49b20>, '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.NhVgXCzjIc/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.okCY7deVCM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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:41:16 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:41:16 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:41:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NhVgXCzjIc/Render', 05:41:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:41:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:41:16 INFO - 'HOME': '/Users/cltbld', 05:41:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:41:16 INFO - 'LOGNAME': 'cltbld', 05:41:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:16 INFO - 'MOZ_NO_REMOTE': '1', 05:41:16 INFO - 'NO_EM_RESTART': '1', 05:41:16 INFO - 'PAGER': '/bin/cat', 05:41:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:41:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:16 INFO - 'PWD': '/builds/slave/test', 05:41:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:41:16 INFO - 'SHELL': '/bin/bash', 05:41:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.okCY7deVCM/Listeners', 05:41:16 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 05:41:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:41:16 INFO - 'USER': 'cltbld', 05:41:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:41:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:41:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:16 INFO - 'XPC_FLAGS': '0x0', 05:41:16 INFO - 'XPC_SERVICE_NAME': '0', 05:41:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:41:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:16 INFO - Downloading/unpacking psutil>=3.1.1 05:41:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:41:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:41:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:41:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:41:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:41:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:41:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:41:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:41:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:41:20 INFO - Installing collected packages: psutil 05:41:20 INFO - Running setup.py install for psutil 05:41:20 INFO - building 'psutil._psutil_osx' extension 05:41:20 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:41:20 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:41:20 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:41:20 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:41:20 INFO - building 'psutil._psutil_posix' extension 05:41:20 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:41:21 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:41:21 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:41:21 INFO - ^ 05:41:21 INFO - 1 warning generated. 05:41:21 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:41:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:41:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:41:21 INFO - Successfully installed psutil 05:41:21 INFO - Cleaning up... 05:41:21 INFO - Return code: 0 05:41:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:41:21 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:41:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:21 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:41:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:21 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 0x10af3d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeb3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec83d59110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad51d30>, '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 0x10ab77db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad49b20>, '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.NhVgXCzjIc/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.okCY7deVCM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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:41:21 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:41:21 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:41:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NhVgXCzjIc/Render', 05:41:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:41:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:41:21 INFO - 'HOME': '/Users/cltbld', 05:41:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:41:21 INFO - 'LOGNAME': 'cltbld', 05:41:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:21 INFO - 'MOZ_NO_REMOTE': '1', 05:41:21 INFO - 'NO_EM_RESTART': '1', 05:41:21 INFO - 'PAGER': '/bin/cat', 05:41:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:41:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:21 INFO - 'PWD': '/builds/slave/test', 05:41:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:41:21 INFO - 'SHELL': '/bin/bash', 05:41:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.okCY7deVCM/Listeners', 05:41:21 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 05:41:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:41:21 INFO - 'USER': 'cltbld', 05:41:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:41:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:41:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:21 INFO - 'XPC_FLAGS': '0x0', 05:41:21 INFO - 'XPC_SERVICE_NAME': '0', 05:41:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:41:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:41:21 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:41:21 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:41:21 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:41:21 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:41:21 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:41:21 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:41:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:41:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:41:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:41:24 INFO - Installing collected packages: mozsystemmonitor 05:41:24 INFO - Running setup.py install for mozsystemmonitor 05:41:24 INFO - Successfully installed mozsystemmonitor 05:41:24 INFO - Cleaning up... 05:41:24 INFO - Return code: 0 05:41:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:41:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:41:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:41:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:24 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 0x10af3d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeb3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec83d59110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad51d30>, '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 0x10ab77db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad49b20>, '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.NhVgXCzjIc/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.okCY7deVCM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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:41:24 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:41:24 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:41:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NhVgXCzjIc/Render', 05:41:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:41:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:41:24 INFO - 'HOME': '/Users/cltbld', 05:41:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:41:24 INFO - 'LOGNAME': 'cltbld', 05:41:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:24 INFO - 'MOZ_NO_REMOTE': '1', 05:41:24 INFO - 'NO_EM_RESTART': '1', 05:41:24 INFO - 'PAGER': '/bin/cat', 05:41:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:41:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:24 INFO - 'PWD': '/builds/slave/test', 05:41:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:41:24 INFO - 'SHELL': '/bin/bash', 05:41:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.okCY7deVCM/Listeners', 05:41:24 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 05:41:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:41:24 INFO - 'USER': 'cltbld', 05:41:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:41:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:41:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:24 INFO - 'XPC_FLAGS': '0x0', 05:41:24 INFO - 'XPC_SERVICE_NAME': '0', 05:41:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:41:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:24 INFO - Downloading/unpacking jsonschema==2.5.1 05:41:24 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:41:24 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:41:24 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:41:24 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:41:24 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:41:24 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:41:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:41:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:41:27 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:41:27 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:41:27 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:41:27 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:41:27 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:41:27 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:41:27 INFO - Downloading functools32-3.2.3-2.tar.gz 05:41:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:41:27 INFO - warning: no files found matching '*.txt' 05:41:27 INFO - no previously-included directories found matching 'build' 05:41:27 INFO - no previously-included directories found matching 'dist' 05:41:27 INFO - no previously-included directories found matching '.git*' 05:41:27 INFO - Installing collected packages: jsonschema, functools32 05:41:27 INFO - Running setup.py install for functools32 05:41:27 INFO - warning: no files found matching '*.txt' 05:41:27 INFO - no previously-included directories found matching 'build' 05:41:27 INFO - no previously-included directories found matching 'dist' 05:41:27 INFO - no previously-included directories found matching '.git*' 05:41:27 INFO - Successfully installed jsonschema functools32 05:41:27 INFO - Cleaning up... 05:41:27 INFO - Return code: 0 05:41:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:41:27 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:41:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:27 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:41:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:27 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 0x10af3d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeb3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec83d59110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad51d30>, '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 0x10ab77db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad49b20>, '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.NhVgXCzjIc/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.okCY7deVCM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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:41:27 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:41:27 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:41:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NhVgXCzjIc/Render', 05:41:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:41:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:41:27 INFO - 'HOME': '/Users/cltbld', 05:41:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:41:27 INFO - 'LOGNAME': 'cltbld', 05:41:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:27 INFO - 'MOZ_NO_REMOTE': '1', 05:41:27 INFO - 'NO_EM_RESTART': '1', 05:41:27 INFO - 'PAGER': '/bin/cat', 05:41:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:41:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:27 INFO - 'PWD': '/builds/slave/test', 05:41:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:41:27 INFO - 'SHELL': '/bin/bash', 05:41:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.okCY7deVCM/Listeners', 05:41:27 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 05:41:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:41:27 INFO - 'USER': 'cltbld', 05:41:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:41:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:41:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:27 INFO - 'XPC_FLAGS': '0x0', 05:41:27 INFO - 'XPC_SERVICE_NAME': '0', 05:41:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:41:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:41:28 INFO - Cleaning up... 05:41:28 INFO - Return code: 0 05:41:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:41:28 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:41:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:28 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:41:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:28 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 0x10af3d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeb3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec83d59110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad51d30>, '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 0x10ab77db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad49b20>, '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.NhVgXCzjIc/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.okCY7deVCM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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:41:28 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:41:28 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:41:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NhVgXCzjIc/Render', 05:41:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:41:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:41:28 INFO - 'HOME': '/Users/cltbld', 05:41:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:41:28 INFO - 'LOGNAME': 'cltbld', 05:41:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:28 INFO - 'MOZ_NO_REMOTE': '1', 05:41:28 INFO - 'NO_EM_RESTART': '1', 05:41:28 INFO - 'PAGER': '/bin/cat', 05:41:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:41:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:28 INFO - 'PWD': '/builds/slave/test', 05:41:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:41:28 INFO - 'SHELL': '/bin/bash', 05:41:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.okCY7deVCM/Listeners', 05:41:28 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 05:41:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:41:28 INFO - 'USER': 'cltbld', 05:41:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:41:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:41:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:28 INFO - 'XPC_FLAGS': '0x0', 05:41:28 INFO - 'XPC_SERVICE_NAME': '0', 05:41:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:41:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:28 INFO - Downloading/unpacking blobuploader==1.2.4 05:41:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:41:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:41:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:41:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:41:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:41:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:41:30 INFO - Downloading blobuploader-1.2.4.tar.gz 05:41:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:41:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:41:31 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:41:31 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:41:31 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:41:31 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:41:31 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:41:31 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:41:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:41:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:41:31 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:41:31 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:41:31 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:41:31 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:41:31 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:41:31 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:41:32 INFO - Downloading docopt-0.6.1.tar.gz 05:41:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:41:32 INFO - Installing collected packages: blobuploader, requests, docopt 05:41:32 INFO - Running setup.py install for blobuploader 05:41:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:41:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:41:32 INFO - Running setup.py install for requests 05:41:32 INFO - Running setup.py install for docopt 05:41:33 INFO - Successfully installed blobuploader requests docopt 05:41:33 INFO - Cleaning up... 05:41:33 INFO - Return code: 0 05:41:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:41:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:41:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:41:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:33 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 0x10af3d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeb3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec83d59110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad51d30>, '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 0x10ab77db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad49b20>, '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.NhVgXCzjIc/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.okCY7deVCM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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:41:33 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:41:33 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:41:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NhVgXCzjIc/Render', 05:41:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:41:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:41:33 INFO - 'HOME': '/Users/cltbld', 05:41:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:41:33 INFO - 'LOGNAME': 'cltbld', 05:41:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:33 INFO - 'MOZ_NO_REMOTE': '1', 05:41:33 INFO - 'NO_EM_RESTART': '1', 05:41:33 INFO - 'PAGER': '/bin/cat', 05:41:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:41:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:33 INFO - 'PWD': '/builds/slave/test', 05:41:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:41:33 INFO - 'SHELL': '/bin/bash', 05:41:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.okCY7deVCM/Listeners', 05:41:33 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 05:41:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:41:33 INFO - 'USER': 'cltbld', 05:41:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:41:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:41:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:33 INFO - 'XPC_FLAGS': '0x0', 05:41:33 INFO - 'XPC_SERVICE_NAME': '0', 05:41:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:41:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:41:33 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-hJ_Oyu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:41:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:41:33 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-C02E30-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:41:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:41:33 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-tWtVBI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:41:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:41:33 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-bUS2TN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:41:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:41:33 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-o3ca9G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:41:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:41:33 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-Rk6g4i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:41:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:41:34 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-HxYctv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:41:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:41:34 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-eKgOf0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:41:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:41:34 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-oJ6KmU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:41:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:41:34 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-uoi6u6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:41:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:41:34 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-Nl5310-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:41:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:41:34 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-VIuZn2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:41:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:41:34 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-Wf8ufG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:41:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:41:34 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-KkZmKd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:41:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:41:34 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-mbyJW0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:41:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:41:34 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-Evw2V1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:41:35 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-334DrD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:41:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:41:35 INFO - Running setup.py install for manifestparser 05:41:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:41:35 INFO - Running setup.py install for mozcrash 05:41:35 INFO - Running setup.py install for mozdebug 05:41:35 INFO - Running setup.py install for mozdevice 05:41:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:41:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:41:35 INFO - Running setup.py install for mozfile 05:41:35 INFO - Running setup.py install for mozhttpd 05:41:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:41:36 INFO - Running setup.py install for mozinfo 05:41:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:41:36 INFO - Running setup.py install for mozInstall 05:41:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:41:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:41:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:41:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:41:36 INFO - Running setup.py install for mozleak 05:41:36 INFO - Running setup.py install for mozlog 05:41:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:41:36 INFO - Running setup.py install for moznetwork 05:41:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:41:36 INFO - Running setup.py install for mozprocess 05:41:36 INFO - Running setup.py install for mozprofile 05:41:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:41:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:41:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:41:37 INFO - Running setup.py install for mozrunner 05:41:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:41:37 INFO - Running setup.py install for mozscreenshot 05:41:37 INFO - Running setup.py install for moztest 05:41:37 INFO - Running setup.py install for mozversion 05:41:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:41:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:41:37 INFO - Cleaning up... 05:41:37 INFO - Return code: 0 05:41:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:41:37 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:41:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:37 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:41:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:37 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 0x10af3d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeb3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec83d59110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad51d30>, '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 0x10ab77db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad49b20>, '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.NhVgXCzjIc/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.okCY7deVCM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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:41:37 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:41:37 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:41:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NhVgXCzjIc/Render', 05:41:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:41:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:41:37 INFO - 'HOME': '/Users/cltbld', 05:41:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:41:37 INFO - 'LOGNAME': 'cltbld', 05:41:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:37 INFO - 'MOZ_NO_REMOTE': '1', 05:41:37 INFO - 'NO_EM_RESTART': '1', 05:41:37 INFO - 'PAGER': '/bin/cat', 05:41:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:41:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:37 INFO - 'PWD': '/builds/slave/test', 05:41:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:41:37 INFO - 'SHELL': '/bin/bash', 05:41:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.okCY7deVCM/Listeners', 05:41:37 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 05:41:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:41:37 INFO - 'USER': 'cltbld', 05:41:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:41:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:41:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:37 INFO - 'XPC_FLAGS': '0x0', 05:41:37 INFO - 'XPC_SERVICE_NAME': '0', 05:41:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:41:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:41:38 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-r3swd3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:41:38 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:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:41:38 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-VSrCST-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:41:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:41:38 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-0bYOj3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:41:38 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:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:41:38 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-LgSK3J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:41:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:41:38 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-tiMkMT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:41:38 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:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:41:38 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-MnVpXK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:41:38 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:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:41:38 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-5Xkr7l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:41:38 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:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:41:38 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-ZSVnPi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:41:38 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:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:41:38 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-O7vcO6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:41:38 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:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:41:38 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-loSZAv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:41:39 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:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:41:39 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-JbPnrl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:41:39 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:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:41:39 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-vPhLLf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:41:39 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:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:41:39 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-HA9Gfy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:41:39 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:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:41:39 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-fXqmtd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:41:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:41:39 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-MDC96U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:41:39 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:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:41:39 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-ElmzlV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:41:39 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:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:41:39 INFO - Running setup.py (path:/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/pip-zKiTWY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:41:39 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:41:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:41:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:41:39 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:41:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:41:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:41:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:41:39 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:41:39 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:41:39 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:41:39 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:41:39 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:41:39 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:41:42 INFO - Downloading blessings-1.6.tar.gz 05:41:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:41:42 INFO - Installing collected packages: blessings 05:41:42 INFO - Running setup.py install for blessings 05:41:42 INFO - Successfully installed blessings 05:41:42 INFO - Cleaning up... 05:41:42 INFO - Return code: 0 05:41:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:41:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:41:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:41:42 INFO - Reading from file tmpfile_stdout 05:41:42 INFO - Current package versions: 05:41:42 INFO - blessings == 1.6 05:41:42 INFO - blobuploader == 1.2.4 05:41:42 INFO - docopt == 0.6.1 05:41:42 INFO - functools32 == 3.2.3-2 05:41:42 INFO - jsonschema == 2.5.1 05:41:42 INFO - manifestparser == 1.1 05:41:42 INFO - mozInstall == 1.12 05:41:42 INFO - mozcrash == 0.17 05:41:42 INFO - mozdebug == 0.1 05:41:42 INFO - mozdevice == 0.48 05:41:42 INFO - mozfile == 1.2 05:41:42 INFO - mozhttpd == 0.7 05:41:42 INFO - mozinfo == 0.9 05:41:42 INFO - mozleak == 0.1 05:41:42 INFO - mozlog == 3.4 05:41:42 INFO - moznetwork == 0.27 05:41:42 INFO - mozprocess == 0.23 05:41:42 INFO - mozprofile == 0.28 05:41:42 INFO - mozrunner == 6.12 05:41:42 INFO - mozscreenshot == 0.1 05:41:42 INFO - mozsystemmonitor == 0.3 05:41:42 INFO - moztest == 0.7 05:41:42 INFO - mozversion == 1.4 05:41:42 INFO - psutil == 3.1.1 05:41:42 INFO - requests == 1.2.3 05:41:42 INFO - wsgiref == 0.1.2 05:41:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:41:42 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:41:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:42 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:41:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:42 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 0x10af3d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeb3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec83d59110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad51d30>, '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 0x10ab77db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad49b20>, '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.NhVgXCzjIc/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.okCY7deVCM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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:41:42 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:41:42 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:41:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NhVgXCzjIc/Render', 05:41:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:41:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:41:42 INFO - 'HOME': '/Users/cltbld', 05:41:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:41:42 INFO - 'LOGNAME': 'cltbld', 05:41:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:42 INFO - 'MOZ_NO_REMOTE': '1', 05:41:42 INFO - 'NO_EM_RESTART': '1', 05:41:42 INFO - 'PAGER': '/bin/cat', 05:41:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:41:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:42 INFO - 'PWD': '/builds/slave/test', 05:41:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:41:42 INFO - 'SHELL': '/bin/bash', 05:41:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.okCY7deVCM/Listeners', 05:41:42 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 05:41:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:41:42 INFO - 'USER': 'cltbld', 05:41:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:41:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:41:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:42 INFO - 'XPC_FLAGS': '0x0', 05:41:42 INFO - 'XPC_SERVICE_NAME': '0', 05:41:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:41:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:43 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:41:43 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:41:43 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:41:43 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:41:43 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:41:43 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:41:43 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:41:43 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:41:43 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:41:43 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:41:43 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:41:43 INFO - Cleaning up... 05:41:43 INFO - Return code: 0 05:41:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:41:43 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:41:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:43 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:41:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:43 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 0x10af3d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeb3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec83d59110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad51d30>, '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 0x10ab77db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad49b20>, '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.NhVgXCzjIc/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.okCY7deVCM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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:41:43 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:41:43 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:41:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NhVgXCzjIc/Render', 05:41:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:41:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:41:43 INFO - 'HOME': '/Users/cltbld', 05:41:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:41:43 INFO - 'LOGNAME': 'cltbld', 05:41:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:43 INFO - 'MOZ_NO_REMOTE': '1', 05:41:43 INFO - 'NO_EM_RESTART': '1', 05:41:43 INFO - 'PAGER': '/bin/cat', 05:41:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:41:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:43 INFO - 'PWD': '/builds/slave/test', 05:41:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:41:43 INFO - 'SHELL': '/bin/bash', 05:41:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.okCY7deVCM/Listeners', 05:41:43 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 05:41:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:41:43 INFO - 'USER': 'cltbld', 05:41:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:41:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:41:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:43 INFO - 'XPC_FLAGS': '0x0', 05:41:43 INFO - 'XPC_SERVICE_NAME': '0', 05:41:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:41:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:41:43 INFO - Cleaning up... 05:41:43 INFO - Return code: 0 05:41:43 INFO - Running post-action listener: _resource_record_post_action 05:41:43 INFO - Running post-action listener: _start_resource_monitoring 05:41:43 INFO - Starting resource monitoring. 05:41:43 INFO - [mozharness: 2016-09-27 12:41:43.501305Z] Finished create-virtualenv step (success) 05:41:43 INFO - [mozharness: 2016-09-27 12:41:43.502325Z] Running install step. 05:41:43 INFO - Running pre-action listener: _resource_record_pre_action 05:41:43 INFO - Running main action method: install 05:41:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:41:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:41:43 INFO - Reading from file tmpfile_stdout 05:41:43 INFO - Detecting whether we're running mozinstall >=1.0... 05:41:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:41:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:41:43 INFO - Reading from file tmpfile_stdout 05:41:43 INFO - Output received: 05:41:43 INFO - Usage: mozinstall [options] installer 05:41:43 INFO - Options: 05:41:43 INFO - -h, --help show this help message and exit 05:41:43 INFO - -d DEST, --destination=DEST 05:41:43 INFO - Directory to install application into. [default: 05:41:43 INFO - "/builds/slave/test"] 05:41:43 INFO - --app=APP Application being installed. [default: firefox] 05:41:43 INFO - mkdir: /builds/slave/test/build/application 05:41:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:41:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:42:17 INFO - Reading from file tmpfile_stdout 05:42:17 INFO - Output received: 05:42:17 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:42:17 INFO - Running post-action listener: _resource_record_post_action 05:42:17 INFO - [mozharness: 2016-09-27 12:42:17.770015Z] Finished install step (success) 05:42:17 INFO - [mozharness: 2016-09-27 12:42:17.770172Z] Running run-tests step. 05:42:17 INFO - Running pre-action listener: _resource_record_pre_action 05:42:17 INFO - Running main action method: run_tests 05:42:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:42:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:42:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:42:17 INFO - 2016-09-27 05:42:17.784 screenresolution[1646:9111] starting screenresolution argv=screenresolution get 05:42:17 INFO - 2016-09-27 05:42:17.787 screenresolution[1646:9111] Display 0: 1600x1200x32@60 05:42:17 INFO - 2016-09-27 05:42:17.793 screenresolution[1647:9113] starting screenresolution argv=screenresolution list 05:42:17 INFO - Available Modes on Display 0 05:42:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:42:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:42:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:42:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:42:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:42:18 INFO - Intel Iris: 05:42:18 INFO - Chipset Model: Intel Iris 05:42:18 INFO - Type: GPU 05:42:18 INFO - Bus: Built-In 05:42:18 INFO - VRAM (Dynamic, Max): 1536 MB 05:42:18 INFO - Vendor: Intel (0x8086) 05:42:18 INFO - Device ID: 0x0a2e 05:42:18 INFO - Revision ID: 0x0009 05:42:18 INFO - Displays: 05:42:18 INFO - Display: 05:42:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:42:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:42:18 INFO - Display Serial Number: 1600x1200 60 05:42:18 INFO - Main Display: Yes 05:42:18 INFO - Mirror: Off 05:42:18 INFO - Online: Yes 05:42:18 INFO - Rotation: Supported 05:42:18 INFO - Return code: 0 05:42:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:42:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:42:18 INFO - Python 2.7.3 05:42:18 INFO - Return code: 0 05:42:18 INFO - grabbing minidump binary from tooltool 05:42: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:42:18 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad51d30>, '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 0x10ab77db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad49b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:42:18 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:42:18 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:42:18 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:42:18 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:42:18 INFO - Return code: 0 05:42:18 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:42:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:42:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:42:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:42:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:42:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NglRZBIiS2mAVv1RUgeeKg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0124', '--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:42:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NglRZBIiS2mAVv1RUgeeKg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0124 --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:42:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NhVgXCzjIc/Render', 05:42:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:42:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:42:18 INFO - 'HOME': '/Users/cltbld', 05:42:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:42:18 INFO - 'LOGNAME': 'cltbld', 05:42:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:42:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:42:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:42:18 INFO - 'MOZ_NO_REMOTE': '1', 05:42:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:42:18 INFO - 'NO_EM_RESTART': '1', 05:42:18 INFO - 'PAGER': '/bin/cat', 05:42:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:42:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:42:18 INFO - 'PWD': '/builds/slave/test', 05:42:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:42:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:42:18 INFO - 'SHELL': '/bin/bash', 05:42:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.okCY7deVCM/Listeners', 05:42:18 INFO - 'TMPDIR': '/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/', 05:42:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:42:18 INFO - 'USER': 'cltbld', 05:42:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:42:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:42:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:42:18 INFO - 'XPC_FLAGS': '0x0', 05:42:18 INFO - 'XPC_SERVICE_NAME': '0', 05:42:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:42:18 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NglRZBIiS2mAVv1RUgeeKg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0124', '--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:42:18 INFO - mozversion application_buildid: 20160927022852 05:42:18 INFO - mozversion application_changeset: cb212903f7036117ddbb29b3bdbc390b161b1bb2 05:42:18 INFO - mozversion application_display_name: Nightly 05:42:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:42:18 INFO - mozversion application_name: Firefox 05:42:18 INFO - mozversion application_remotingname: firefox 05:42:18 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:42:18 INFO - mozversion application_vendor: Mozilla 05:42:18 INFO - mozversion application_version: 52.0a1 05:42:18 INFO - mozversion platform_buildid: 20160927022852 05:42:18 INFO - mozversion platform_changeset: cb212903f7036117ddbb29b3bdbc390b161b1bb2 05:42:18 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:42:18 INFO - mozversion platform_version: 52.0a1 05:42:18 INFO - using testdate: 1474980138 05:42:18 INFO - actual date: 1474980138 05:42:18 INFO - starting webserver on 'localhost:49273' 05:42:18 INFO - SUITE-START | Running 2 tests 05:42:18 INFO - TEST-START | dromaeo_css 05:42:18 INFO - operating with platform_type : mac_ 05:42:18 INFO - Initialising browser for dromaeo_css test... 05:42:18 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/tmpCTw02H/profile http://localhost:49273/getInfo.html) 05:42:21 INFO - PROCESS | 1657 | 2016-09-27 05:42:21.195 plugin-container[1659:9278] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b27, name = 'com.apple.tsm.portname' 05:42:21 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:42:21 INFO - PROCESS | 1657 | 2016-09-27 05:42:21.199 plugin-container[1659:9278] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d13, name = 'com.apple.CFPasteboardClient' 05:42:21 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:42:21 INFO - PROCESS | 1657 | 2016-09-27 05:42:21.199 plugin-container[1659:9278] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:42:21 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 05:42:21 INFO - PROCESS | 1657 | colorDepth:24 05:42:21 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 05:42:21 INFO - PROCESS | 1657 | __metrics 05:42:21 ERROR - PROCESS | 1657 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3067: TypeError: this.tabs is undefined 05:42:22 INFO - TEST-INFO | 1657: exit 0 05:42:22 INFO - Browser initialized. 05:42:22 INFO - Running cycle 1/1 for dromaeo_css test... 05:42:22 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/tmpCTw02H/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:42:24 INFO - PROCESS | 1660 | 2016-09-27 05:42:24.456 plugin-container[1661:9389] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x450f, name = 'com.apple.tsm.portname' 05:42:24 INFO - PROCESS | 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:42:24 INFO - PROCESS | 1660 | 2016-09-27 05:42:24.459 plugin-container[1661:9389] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0f, name = 'com.apple.CFPasteboardClient' 05:42:24 INFO - PROCESS | 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:42:24 INFO - PROCESS | 1660 | 2016-09-27 05:42:24.459 plugin-container[1661:9389] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:42:34 INFO - PROCESS | 1660 | RSS: Main: 239865856 05:42:34 INFO - PROCESS | 1660 | 05:43:23 INFO - PROCESS | 1660 | 1474980203749 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:45:35 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49273/tests/dromaeo/cssquery-ext.html) 05:45:36 INFO - PROCESS | 1660 | RSS: Main: 253702144 05:45:36 INFO - PROCESS | 1660 | 05:48:39 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-ext.html (next: http://localhost:49273/tests/dromaeo/cssquery-jquery.html) 05:48:39 INFO - PROCESS | 1660 | RSS: Main: 265617408 05:48:39 INFO - PROCESS | 1660 | 05:51:41 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49273/tests/dromaeo/cssquery-mootools.html) 05:51:42 INFO - PROCESS | 1660 | RSS: Main: 270290944 05:51:42 INFO - PROCESS | 1660 | 05:54:44 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49273/tests/dromaeo/cssquery-prototype.html) 05:54:44 INFO - PROCESS | 1660 | RSS: Main: 269922304 05:54:44 INFO - PROCESS | 1660 | 05:57:47 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49273/tests/dromaeo/cssquery-yui.html) 05:57:48 INFO - PROCESS | 1660 | RSS: Main: 275271680 05:57:48 INFO - PROCESS | 1660 | 06:00:51 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-yui.html (next: http://localhost:49273/tests/dromaeo/cssquery-dojo.html) 06:00:51 INFO - PROCESS | 1660 | RSS: Main: 278286336 06:00:51 INFO - PROCESS | 1660 | 06:00:51 INFO - PROCESS | 1660 | __start_tp_report 06:00:51 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 06:00:51 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 06:00:51 INFO - PROCESS | 1660 | |i|pagename|runs| 06:00:51 INFO - PROCESS | 1660 | |0;dojo.html;3006.684298148695;3339.2653616204425;3372.3423932333203;3412.0616830371628;3417.7607567470272;4544.568266014731;5538.726092998602;5549.084401073828;5577.8605534861645;5589.86025349366;5565.696160866574;5599.804006859761;5622.184783206443;5656.547476201904;5809.941900580993;4358.389825424443;4432.312991486325;4587.288970209606;4854.834839638496;4965.70891568193;4408.669349798778;5197.168453047513;5211.635185537014;5253.290805741233;5272.894542109175;5077.669951453144;5218.347706536683;5220.138677118271;5232.816851410183;5326.627136100475;5083.618728595333;5113.463086375951;5151.227315902607;5154.768035438415;5226.346706661668;4735.526447355272;5138.486151384832;5151.974240128813;5175.068487672217;5185.6888586684545;5480.342358916916;5591.440855914417;5594.412586678422;5663.207150998864;5663.49028587425;5813.273340832396;5875.412458754133;6014.0377539593455;6119.959572021066;6141.117811339996;6195.597286176384;6221.346758590328;6253.96873015632;6254.687265636746;6262.12330273762;5761.395053519362;5776.740046697909;5802.535797136219;5817.999999999957;5840.474357307863;6101.610781564156;6128.295246046761;6225.035119556476;6225.501959843202;6287.151234583364;4952;5708.771649133988;6050.092486127116;6096.329403765582;6100.267967843887;6144.815655530341;6163.1679723237685;6188.876222475616;6189.071639254148;6199.876002480015;5006.974965125151;5064.82273120439;5849.7952571660635;6007.008843540854;6024.42767937054;5180;5877.676727780014;6182.196314479179;6199.690015499207;6205;3976.403539469045;4831.492693267227;4851.805927762921;4852.4662287148385;4878.487758785347;4275.080857615628;4299.634531064832;4314.266574682311;4332.826686932551;4363.672724545671;4629.976850115728;5629.155626655953;5698.515626171739;5741.62679425836;5821.854453638693;6213.347598502182;6228.937710622927;6234.3453937336835;6247.875042499125;6254.718537665816;5771.480566749013;5794.304683438015;5828;5829.417058294137;5837.854053648693;5146.30524879144;5258.605604579595;5297.125974214211;5330.706811125426;5365.3293338332705;5170.663906846043;5949.613275137102;6224.408681175281;6277.968610157011;6301.275353334334;6194.38056194377;6218.160548326099;6222.346653601307;6236.875262494816;6238.594491358047;6158.784442544489;6211.689415529296;6220.440160385587;6248.218972628421;6264.49884009269;5074.391073071108;6078.331383547895;6152.938470615237;6159.5688301818445;6160.229971253593;6224.253089629274;6225.8132256032395;6231.158793562811;6234.594751341148;6254.593451425642;5571.554275657856;5742.770289188553;6098.481629061402;6100.908486372619;6102.7253773579405;5824;5985.880282394241;5994.460498555151;6109.16175461965;6120.264288666237;5277.472252774692;5587.217789509391;5796.855078623068;5868.853278667897;5891.617044892068;5844.620099693507;5911.290645122613;5913.172155898264;5988.910166347421;6075.574709770451;5899.941000589939;5913.438223368773;5919.763209471573;5920.319163296276;5947.256592925884 06:00:51 INFO - PROCESS | 1660 | |1;ext.html;489.61565171340493;504.63414024831997;535.2934126952422;539.8056699588147;545.342861851469;39156.80421597892;40706.59293407065;40767.59232407675;40773.79613101938;40949.18101637969;42560.14879702404;43012;43019;43139.78430107853;43162.35256471147;9675.903240967606;9816.411015339067;9833.16418104462;9870.259730520203;9893.851592226123;7881.566513841765;8621.741347759546;8670.219680228778;9380.671676491324;9848.60605575776;9818.214542836557;9830.4101753895;9854.40873547586;9872.950635246849;9885.357451765612;9783.87485439173;9795.755106122333;9829.459379734111;9838.508074596313;9849.965753595912;9570.617175312978;9625;9629.133377995944;9656.517174141265;9675.806483870354;40381;40447;40664.39003414951;40934.79532602348;40979.590204097876;3253.8210398428077;3419.7919211154685;3477.4436090225695;3723.6648701616987;3992.4210989406665;30198.698013020028;30469;31153.844230778926;31233;31320.686793132005;3117.1116231873807;3313.188268874117;3339.46568549031;3368.7473439492137;3380.8140552269615;488762;495375;499700;501132;501280;59717;59881.70059149677;60364;60398.490037749405;60408.46936479694;60996.69501652463;62392.68803655953;62651.68674156691;62937;62974;16050.117243551484;16263.512094637179;16271.186440677919;16287;16320.591985200464;12631.557895473798;12672.87327126717;12731.681707957376;12734.426950787236;12776.233425994471;16183.514494565183;16955.49133525996;16965.745513817303;16996.660066798595;17004.659906801793;3140.795848269855;5463.754131064112;6369.267534233622;6558.573692709959;6578;715.7581595931056;950.6387572722459;954.8615450759739;977.3549457358109;1033.4832583708146;30230.54654180189;30304.84847575792;30744.38511229763;30915.917942871893;31248.68751312503;871.3160169267095;932.8927173375013;1033.1734612310272;1071.665776108734;1139.5441823270758;15619.765703514457;15706;15741.685166296838;16059.518214453352;16995.915020424818;621532;646088;669951;676386.2361376422;682080;60620.78758424895;61717.69141154355;61754.38245617486;61920.071198932055;62243;1385.7436374270792;1402.52314213168;1411.4283715095607;1433.9426422943175;1434.2040167707023;30810;31919.521207181446;31926;32010.51984220192;32838.34323313473;52487.73756131195;52873.471265286855;52896.471035289156;53236.93526129533;53265.20102198393;519033;519915;521069;522282;522457;33326.16684582808;42685.5731442694;42806.35790463083;43709.50323974011;43909.12181756411;46744.29883551817;46922.06155876932;47574.28638570494;48074.759626201645;48179.75910120427;33795;39648.20703585855;39833.60166398415;40765.796171018956;41107.58892411038;37292.813535932146;39404.21191576095;39689.40465893072;40751.38872916968;41761.37357939694;25042.874785625954;33907;37600;38417.80791096138;38546.65086722063;25646.358841029123;30437.695623044372;34870.47694284537;35514.64485355217;35847.46228806622;22977.195798146986;23521.529569408172;26203.73796262013;26253.474930501667;26504.469910602067 06:00:51 INFO - PROCESS | 1660 | |2;jquery.html;366.69747458346865;371.2760117770347;373.3783250887273;383.04812540836036;402.88115006073207;19070.61858762824;20326;21216.681749773747;21288.57422851544;21335.67996480054;20015.099320530575;20250.59498810023;20271.39185824424;20454.97725113742;20707.482312942186;4727.881802954937;5987.55093367997;6120.69396530174;6157.661328626924;6185.536084793636;6045.183900173486;6059.878802423949;6158.445739883409;6163.198784158076;6172.47533959614;5813.912791308134;5975.970120149394;5986;6078.088286756978;6081.78713745019;5765.567582431292;5988.580799344048;6022.728977195993;6058.757649694007;6081.179040829497;5834.066549352084;5960.761569537213;5968.940310596925;5970.522358211333;6006.129111278884;12906;13049.543265985645;13149.934250328783;13340.799888001688;13388.19670819754;2127.1597718901103;2132.1897678881924;2268.2288022072576;2275.3628983884537;2400.459896523286;9481.952590237073;9873.90126098737;9924.305298629099;10003.199744020534;10431.113355364803;1400.964975875601;1654.561541191585;1716.8025677047174;1983.990080049605;1985.4738494299022;94659.52670236674;123492.38253808764;124471.13293300608;125973.74026259712;127014;11134.053605443629;11259.549618015188;11477.71305717347;11505.252158609663;11672.649820505398;14482.275886205647;14502.912281578923;14528.491502797351;14531.128132312095;14582.708345833236;7090.326418990187;8282.875756863652;8511.404201705946;8544.31645468361;8665.35009874262;8459.661613535513;8476.49141051539;8513.829723405497;8624.698135565224;8663.870041949376;4463.843765468193;4485.170243504962;4528.954710452853;4624.098300831306;4655.185342565038;1896.3836753055036;1943.5141214696325;1951.687729963199;1954.1597113241442;1954.540682939508;733.4865594084163;791.612110066075;912.539167720297;945.5650400815701;964.6479035152119;16695.497405233586;19818.50453738668;20824.47938801512;21387.03758330879;21524.892375538024;812.6627449608465;911.9224865886474;918.6600957645713;931.9347645664739;952.8570714392896;10263.384196948207;11344.829827552594;11355.829662554903;11427.60003399877;11432.199746017865;556286;572542;574032;595906;603406;81067.59466202749;82102.58948705219;113120;132454;144752.82870756948;949.6011675096476;986.9834070906834;1026.9691909242736;1028.4497793680346;1037.7872536130076;14424.350904209126;19825;20012.299569515;20388.28640997558;20571.58856822885;53400;53760.89271519199;54130.45869541254;54627.453725462234;55229.723851380484;53888.46111538991;78820.60589697016;79005.20994789978;79426.2057379442;79593;6506.25178104524;7918.129005809472;7954.403419743427;8216.63025163881;8298.460600060977;6772.05191273232;9094.999550049357;10133.89866101359;12491.188777627012;12841.229526228082;10697.304675196086;10822.621208257673;10914.345139291669;10967.232293739362;11248.325100493996;10522.579096836347;10728.141748660244;11048.944755276494;11237.60668376603;11386.772264554496;10978.835317470084;11273.774524509628;11431.428428578703;11475.540978360772;11500.25248358854;6568.8357791053695;7884.29041386278;7922.366210703015;8232.547209903274;8322.084570697098;7141.571505709674;8956.373053886426;10836.620718274822;11107.278026928547;11136.554537818396 06:00:51 INFO - PROCESS | 1660 | |3;mootools.html;1384.4462215113956;1389.305347326337;1610.3478091372997;1682.3691115831564;1846.363004763357;7977.56123413212;8271.048829384623;8502.234798868101;9076.909230907697;9102.635894564217;8493.235608795207;8821.691240806573;8829.690960816373;8939.508327042011;8956.238719708816;1963.2932144427994;2364.267077206063;2394.473215892501;2404.0864471500804;2404.927852164429;1971.3297478857185;2345.6247000479925;2358.50471401006;2372.015613520387;2400.7959323457526;1934.351992082647;2364.775346342095;2379.6549500322444;2394.1500767227644;2401.603735383668;2355.646653002046;2355.752645972174;2368.218487898989;2369.5142495788323;2381.9047238110456;2368.336865677613;2372.3001714494267;2373.4778348763416;2375.394274460019;2383.15398033699;8828.42615230008;8939.865902011476;8947.150020748019;8977.461352318816;9093.318001149873;5190.377154741417;5357.571394288448;5372.113601255788;5379.623426360156;5386.865328366783;3430.1939044324563;3488.7906725596545;3605.2068544920076;3610.530631017951;3616.945745813815;1050.369778133114;1084.1814430105221;1089.591403223791;1097.3251450357973;1111.1721767283425;11800.173987820855;11893.940530297294;11936.104792140537;11971.760564788743;12000.51997920082;4551.203539380661;4665.323528088476;4804.111239420648;4816.686915350491;4837.443693975238;9926.65256716026;10083.899161008296;10108.696739097839;10114.797704045877;10120.443375614264;5356.330458692663;5375.569954403638;5405.297311349578;5414.945850541523;5417.458254174551;4393.121375724868;4553.612942899825;4748.738819364969;4900.289458028566;4941.604671626261;3887.9028024299055;3907.9218415632095;3990.1620659661794;4019.2765302245875;4037.555868854424;1263.646179069862;1270.5045032437356;1271.815586739936;1293;1297.8118172864881;756.71623141322;814.2346194577078;820.5815034332534;909.8271328447706;911.125319693102;5762.30852297727;5939.792107276225;6047.334793172748;6063.757449701962;6099.207103076572;859.1494420523636;863.4571187851842;920.9309301802392;956.4171296504157;963.9878127965608;3753.549574051077;4007.779572123561;4030.2544029354663;4088.6524645405475;4110.095778928631;10225.948870255601;10539.314944528582;11023.228374013743;11045.226834121695;11070.889291107147;10457.68626941193;10811.6215932442;10966.451677416097;10966.780664386668;10977.725556861142;8001.719939802079;8104.5137291762685;8276.172382761795;8302.792430189294;8361;5964.254468191476;5971.164037034905;6063.333033366414;6087.969560152171;6127.754889804358;9917.752056198364;10150.644727434505;10155.7461063474;10187;10219.386836789816;10013.299069065095;10190.745231369276;10228.335158214692;10237.183709654324;10267.229957753349;5642;6543.691261747575;7058.505904586629;7679.462437629312;7886;7182.245864184394;8379.162083791573;8485;8610.870836937325;8628.78428039304;7462.291082347168;7549.6225188739245;7569.697212111454;7664.156942736406;7697.6151192441275;6346.90479642815;7328.5969271690565;7452.850942981002;7454.589997550187;7533.2466753324225;7100.254473280231;7108.715651373887;7195.100612423447;7208.17106032792;7213.639318034182;4066.064805094904;4795.664303498722;5004.474530174425;5010.04407399636;5152.690838549549;3995.62041606047;5037.420696619935;5103.081445339874;5122.615803814654;5168.612354073534 06:00:51 INFO - PROCESS | 1660 | |4;prototype.html;285.21850130642036;301.15676106900673;313.510922960182;314.81111333200073;315.55506735502945;3995.9999999999927;5455.863603409907;5468.398476167623;5525.778968841247;5533.418991005947;4460.821567137319;4469.486009108953;5332.626716129873;5513.448655134484;5543.1408131739545;3718.7768733876055;3830.935251798557;4232.407462955189;4286.078493123978;4308.806103725325;3654.835532401048;3695.2794205130012;3975.483187185662;4075.572064933184;4145.937810932838;4229.915401691965;4238.237117318881;4249.277622804131;4269.865067466267;4274.0125841483705;3654.3239500692457;3668.834902429397;4221.867956681871;4229.577042295746;4265.296226122686;3721.6278372162838;3746.906327341811;3749.581519919637;3758.718096142773;3766.510353654035;4816.253480710496;4947.431045429749;5387.892242155174;5415.6190171506405;5448.564114870801;4255.191513612434;4305.901994991257;4584;4693.47462074827;4747.026859493795;4838.588719958808;5061.759868832123;5176.7411629418375;5863.9706801466145;5901.23283973085;3354.295597924439;3927.960720392788;3999.340108882031;4017.995501124719;4110.23960567296;4790.42514898211;5083.669561478529;5322.387925388591;6167.259928808572;6219.222597175353;3675.2649470105553;4013.8595149169637;4015.6185162409524;4023.637872591481;4024.75851448914;3926.1755031443145;4045.5954404559307;4064.8577299794956;4065.8005888263033;4066.3900414938;4341.804618792162;4484.910301793946;4753.9049219016115;4757.429108506932;4777.737224452689;5041.294218809369;5834.358220595731;5883.764649413975;5902.2622172228475;5923.496502797224;1117.0002847451485;1286.6654669785923;1290.1742884553894;1341.4500054977411;1349.690799924072;836.3392919593452;862.1550880137376;897.4929165021741;922.2115091596588;924.1590152960907;4638.5593368629925;4927.728974906343;5037.848864534069;5383.515483606416;5478.260434841325;5973.074173503114;5977.880442391128;5982.611130276518;6000.309964354068;6015.180360471132;4580.770961451981;4939.7277252463555;5151.969606078799;5407.405185429599;5490.917636235459;2369.549785540776;2399.952000959971;2594.883230254643;2663.6803583570095;2685.609807271491;4940.530649588283;5124.128898087334;5132.640715149903;5179.16741611205;5217.373915130209;4864.805407783649;4887.926681099786;5089.8727531812;5091.9236211456855;5211.228182417996;4459.620932220732;4611.2852507861335;5044.772985215674;5204.635675502678;5234.63357564982;4179.770112643712;4483.955160448354;4525.974965451541;4737.573618374363;4810.062037902575;4411.602955733999;4576.4508259009135;4733.810647574059;4794.400699912511;4910.852674419773;4647.814807224271;5089.847304580869;5241.50205730455;5256.843494431219;5275;3135.5296705494357;3167.129039514152;3457.6542345765224;3471.9063494998995;3498.650134986481;4478.910421791611;4640.957978489454;4649.046945376257;4839.959408727;4842.394700662418;4492.989696903018;4508.233600287894;4711.939813541926;4867.415910090812;4868.90262194747;4134.627883490501;4252.34158678122;4916.8770780730765;4920;4985.93646178378;3858.9421158683203;3948.4927071309353;4508.887277818081;4649.256119021075;4676.7661616919695;4055.351143817093;4777.546133117348;4818.313590243387;4910.723211964986;4923;4091.6522095622204;4103.543242149113;4683.976580117214;4794.568488836021;4802.398201348989 06:00:51 INFO - PROCESS | 1660 | |5;yui.html;603.360437935788;689.0456717446339;710.6944014073949;716.570441968273;775.635451337871;15004.249787510622;15799.763003554956;15914.602134946632;15974.121423321712;15994.680106397866;14447;14493.5651930442;14579.125252484859;14588;15296.617584560392;811.2820154163549;841.6465084664425;849.902261239956;851.8083431227955;852.1435956863344;656.5765081522416;684.4455990647566;824.1922915542772;838.1995194589152;844.1684940333766;888.3648191543045;898.4564338575166;900.18083543975;902.1519771414881;903.056306160061;875.7810547363159;886.7916039730658;888.8844450221494;890.2655309369732;892.3619611977467;649.4349915573481;651.9967400162968;661.3750006244102;664.2792570061489;665.5540787672271;15940.282687278985;16056.438024669198;16073.83926160735;16083.91958040214;16120.838791612052;14310;14314.928425357806;14367.353469093916;14388.568342949728;14493.637659058608;11914.404279785977;12035.699107522381;12046.457909394097;12113.576024839176;12168.452419641137;3695.362320288048;3780.7920564368947;3785.659290663826;3788.147666774981;3923.489946306962;127291;127553;128537;131443;132151;10888.510017049251;10906.072983796466;10913.618023369145;11016.11871050314;11136.128582103229;19010.144543495575;21353.049685808175;22006.669899951514;22077.33767986963;22121.77878221197;7653.464257501922;7913.32736717374;7939.0870049945;8128.39037072222;8136.14570470104;4345.587169218919;4436.0906014267;4474.328850672425;4478.171538265431;4603.309503574424;3519.085037890167;3586.8342788593814;3634;3642.9635703643153;3644.7448678592773;654.656305439648;663.4195079305607;664.5879554676035;669.053289595227;678.4063944048958;581.7789240088824;648.9578177418547;659.4625380315124;674.3028331594791;681.5127184063418;9415.623375065057;9475.668351607661;9612.164411498625;9729.659461918798;9921.206303495703;521.7260938007577;666.4535081233439;672.8250654829682;672.9495287853431;678.8167194857459;4328.329108988112;4331.4152589400655;4822.107910036654;5118.334616499906;5158.329417175818;160200;162267.18866405828;167019;168056;172406;105806;133118.33440832733;136431.31784341214;136708;143725;81311.15410960706;97619;98428;99052;106713.4664326689;9386.48374339418;9741.074597913186;10120.493975301353;10127;10128.189744820544;49509.75245123752;59805.10292345531;60153.49616259628;60944;61431.464213395026;79558.2044179574;85168;90727.03700009867;90731.09268907226;93009;10160.847587286344;10602.886696896714;12944.870551294367;13006.284654344103;13240.337983100613;10875.782484350428;11801;13130.818226359674;14697.55907322782;15147.848521514643;13512.189268643913;13591.592252232449;13806.240656763974;13984;14271.14373137615;10964.739055008777;13119.934400327937;13413.798793017917;13693.178409295475;14083.225422601856;13367.398467069199;13403.731925361633;13538.458461661423;13678.589642310746;13738.793918091436;11212;11222.943885280521;11306.970309249593;11441.313521188755;11581.7104572383;8409.495430273959;9657.46883921391;11304.926032026944;11478.909503597144;11617 06:00:51 INFO - PROCESS | 1660 | __end_tp_report 06:00:51 INFO - PROCESS | 1660 | __start_cc_report 06:00:51 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,350 06:00:51 INFO - PROCESS | 1660 | __end_cc_report 06:00:51 INFO - PROCESS | 1660 | __startTimestamp1474981251316__endTimestamp 06:00:51 INFO - PROCESS | 1660 | 06:00:51 INFO - PROCESS | 1660 | ------- Summary: start ------- 06:00:51 INFO - PROCESS | 1660 | Number of tests: 6 06:00:51 INFO - PROCESS | 1660 | 06:00:51 INFO - PROCESS | 1660 | [#0] dojo.html Cycles:165 Average:5595.84 Median:5818.00 stddev:684.05 (11.8%) stddev-sans-first:655.30 06:00:51 INFO - PROCESS | 1660 | Values: 3006.7 3339.3 3372.3 3412.1 3417.8 4544.6 5538.7 5549.1 5577.9 5589.9 5565.7 5599.8 5622.2 5656.5 5809.9 4358.4 4432.3 4587.3 4854.8 4965.7 4408.7 5197.2 5211.6 5253.3 5272.9 5077.7 5218.3 5220.1 5232.8 5326.6 5083.6 5113.5 5151.2 5154.8 5226.3 4735.5 5138.5 5152.0 5175.1 5185.7 5480.3 5591.4 5594.4 5663.2 5663.5 5813.3 5875.4 6014.0 6120.0 6141.1 6195.6 6221.3 6254.0 6254.7 6262.1 5761.4 5776.7 5802.5 5818.0 5840.5 6101.6 6128.3 6225.0 6225.5 6287.2 4952.0 5708.8 6050.1 6096.3 6100.3 6144.8 6163.2 6188.9 6189.1 6199.9 5007.0 5064.8 5849.8 6007.0 6024.4 5180.0 5877.7 6182.2 6199.7 6205.0 3976.4 4831.5 4851.8 4852.5 4878.5 4275.1 4299.6 4314.3 4332.8 4363.7 4630.0 5629.2 5698.5 5741.6 5821.9 6213.3 6228.9 6234.3 6247.9 6254.7 5771.5 5794.3 5828.0 5829.4 5837.9 5146.3 5258.6 5297.1 5330.7 5365.3 5170.7 5949.6 6224.4 6278.0 6301.3 6194.4 6218.2 6222.3 6236.9 6238.6 6158.8 6211.7 6220.4 6248.2 6264.5 5074.4 6078.3 6152.9 6159.6 6160.2 6224.3 6225.8 6231.2 6234.6 6254.6 5571.6 5742.8 6098.5 6100.9 6102.7 5824.0 5985.9 5994.5 6109.2 6120.3 5277.5 5587.2 5796.9 5868.9 5891.6 5844.6 5911.3 5913.2 5988.9 6075.6 5899.9 5913.4 5919.8 5920.3 5947.3 06:00:51 INFO - PROCESS | 1660 | 06:00:51 INFO - PROCESS | 1660 | [#1] ext.html Cycles:180 Average:69949.26 Median:42160.76 stddev:150536.33 (357.1%) stddev-sans-first:150868.29 06:00:51 INFO - PROCESS | 1660 | Values: 489.6 504.6 535.3 539.8 545.3 39156.8 40706.6 40767.6 40773.8 40949.2 42560.1 43012.0 43019.0 43139.8 43162.4 9675.9 9816.4 9833.2 9870.3 9893.9 7881.6 8621.7 8670.2 9380.7 9848.6 9818.2 9830.4 9854.4 9873.0 9885.4 9783.9 9795.8 9829.5 9838.5 9850.0 9570.6 9625.0 9629.1 9656.5 9675.8 40381.0 40447.0 40664.4 40934.8 40979.6 3253.8 3419.8 3477.4 3723.7 3992.4 30198.7 30469.0 31153.8 31233.0 31320.7 3117.1 3313.2 3339.5 3368.7 3380.8 488762.0 495375.0 499700.0 501132.0 501280.0 59717.0 59881.7 60364.0 60398.5 60408.5 60996.7 62392.7 62651.7 62937.0 62974.0 16050.1 16263.5 16271.2 16287.0 16320.6 12631.6 12672.9 12731.7 12734.4 12776.2 16183.5 16955.5 16965.7 16996.7 17004.7 3140.8 5463.8 6369.3 6558.6 6578.0 715.8 950.6 954.9 977.4 1033.5 30230.5 30304.8 30744.4 30915.9 31248.7 871.3 932.9 1033.2 1071.7 1139.5 15619.8 15706.0 15741.7 16059.5 16995.9 621532.0 646088.0 669951.0 676386.2 682080.0 60620.8 61717.7 61754.4 61920.1 62243.0 1385.7 1402.5 1411.4 1433.9 1434.2 30810.0 31919.5 31926.0 32010.5 32838.3 52487.7 52873.5 52896.5 53236.9 53265.2 519033.0 519915.0 521069.0 522282.0 522457.0 33326.2 42685.6 42806.4 43709.5 43909.1 46744.3 46922.1 47574.3 48074.8 48179.8 33795.0 39648.2 39833.6 40765.8 41107.6 37292.8 39404.2 39689.4 40751.4 41761.4 25042.9 33907.0 37600.0 38417.8 38546.7 25646.4 30437.7 34870.5 35514.6 35847.5 22977.2 23521.5 26203.7 26253.5 26504.5 06:00:51 INFO - PROCESS | 1660 | 06:00:51 INFO - PROCESS | 1660 | [#2] jquery.html Cycles:180 Average:33558.89 Median:378.21 stddev:96859.02 (25609.6%) stddev-sans-first:97098.67 06:00:51 INFO - PROCESS | 1660 | Values: 366.7 371.3 373.4 383.0 402.9 19070.6 20326.0 21216.7 21288.6 21335.7 20015.1 20250.6 20271.4 20455.0 20707.5 4727.9 5987.6 6120.7 6157.7 6185.5 6045.2 6059.9 6158.4 6163.2 6172.5 5813.9 5976.0 5986.0 6078.1 6081.8 5765.6 5988.6 6022.7 6058.8 6081.2 5834.1 5960.8 5968.9 5970.5 6006.1 12906.0 13049.5 13149.9 13340.8 13388.2 2127.2 2132.2 2268.2 2275.4 2400.5 9482.0 9873.9 9924.3 10003.2 10431.1 1401.0 1654.6 1716.8 1984.0 1985.5 94659.5 123492.4 124471.1 125973.7 127014.0 11134.1 11259.5 11477.7 11505.3 11672.6 14482.3 14502.9 14528.5 14531.1 14582.7 7090.3 8282.9 8511.4 8544.3 8665.4 8459.7 8476.5 8513.8 8624.7 8663.9 4463.8 4485.2 4529.0 4624.1 4655.2 1896.4 1943.5 1951.7 1954.2 1954.5 733.5 791.6 912.5 945.6 964.6 16695.5 19818.5 20824.5 21387.0 21524.9 812.7 911.9 918.7 931.9 952.9 10263.4 11344.8 11355.8 11427.6 11432.2 556286.0 572542.0 574032.0 595906.0 603406.0 81067.6 82102.6 113120.0 132454.0 144752.8 949.6 987.0 1027.0 1028.4 1037.8 14424.4 19825.0 20012.3 20388.3 20571.6 53400.0 53760.9 54130.5 54627.5 55229.7 53888.5 78820.6 79005.2 79426.2 79593.0 6506.3 7918.1 7954.4 8216.6 8298.5 6772.1 9095.0 10133.9 12491.2 12841.2 10697.3 10822.6 10914.3 10967.2 11248.3 10522.6 10728.1 11048.9 11237.6 11386.8 10978.8 11273.8 11431.4 11475.5 11500.3 6568.8 7884.3 7922.4 8232.5 8322.1 7141.6 8956.4 10836.6 11107.3 11136.6 06:00:51 INFO - PROCESS | 1660 | 06:00:51 INFO - PROCESS | 1660 | [#3] mootools.html Cycles:180 Average:5733.92 Median:4799.89 stddev:3251.32 (67.7%) stddev-sans-first:3244.01 06:00:51 INFO - PROCESS | 1660 | Values: 1384.4 1389.3 1610.3 1682.4 1846.4 7977.6 8271.0 8502.2 9076.9 9102.6 8493.2 8821.7 8829.7 8939.5 8956.2 1963.3 2364.3 2394.5 2404.1 2404.9 1971.3 2345.6 2358.5 2372.0 2400.8 1934.4 2364.8 2379.7 2394.2 2401.6 2355.6 2355.8 2368.2 2369.5 2381.9 2368.3 2372.3 2373.5 2375.4 2383.2 8828.4 8939.9 8947.2 8977.5 9093.3 5190.4 5357.6 5372.1 5379.6 5386.9 3430.2 3488.8 3605.2 3610.5 3616.9 1050.4 1084.2 1089.6 1097.3 1111.2 11800.2 11893.9 11936.1 11971.8 12000.5 4551.2 4665.3 4804.1 4816.7 4837.4 9926.7 10083.9 10108.7 10114.8 10120.4 5356.3 5375.6 5405.3 5414.9 5417.5 4393.1 4553.6 4748.7 4900.3 4941.6 3887.9 3907.9 3990.2 4019.3 4037.6 1263.6 1270.5 1271.8 1293.0 1297.8 756.7 814.2 820.6 909.8 911.1 5762.3 5939.8 6047.3 6063.8 6099.2 859.1 863.5 920.9 956.4 964.0 3753.5 4007.8 4030.3 4088.7 4110.1 10225.9 10539.3 11023.2 11045.2 11070.9 10457.7 10811.6 10966.5 10966.8 10977.7 8001.7 8104.5 8276.2 8302.8 8361.0 5964.3 5971.2 6063.3 6088.0 6127.8 9917.8 10150.6 10155.7 10187.0 10219.4 10013.3 10190.7 10228.3 10237.2 10267.2 5642.0 6543.7 7058.5 7679.5 7886.0 7182.2 8379.2 8485.0 8610.9 8628.8 7462.3 7549.6 7569.7 7664.2 7697.6 6346.9 7328.6 7452.9 7454.6 7533.2 7100.3 7108.7 7195.1 7208.2 7213.6 4066.1 4795.7 5004.5 5010.0 5152.7 3995.6 5037.4 5103.1 5122.6 5168.6 06:00:51 INFO - PROCESS | 1660 | 06:00:51 INFO - PROCESS | 1660 | [#4] prototype.html Cycles:180 Average:4293.33 Median:4649.15 stddev:1279.00 (27.5%) stddev-sans-first:1246.70 06:00:51 INFO - PROCESS | 1660 | Values: 285.2 301.2 313.5 314.8 315.6 3996.0 5455.9 5468.4 5525.8 5533.4 4460.8 4469.5 5332.6 5513.4 5543.1 3718.8 3830.9 4232.4 4286.1 4308.8 3654.8 3695.3 3975.5 4075.6 4145.9 4229.9 4238.2 4249.3 4269.9 4274.0 3654.3 3668.8 4221.9 4229.6 4265.3 3721.6 3746.9 3749.6 3758.7 3766.5 4816.3 4947.4 5387.9 5415.6 5448.6 4255.2 4305.9 4584.0 4693.5 4747.0 4838.6 5061.8 5176.7 5864.0 5901.2 3354.3 3928.0 3999.3 4018.0 4110.2 4790.4 5083.7 5322.4 6167.3 6219.2 3675.3 4013.9 4015.6 4023.6 4024.8 3926.2 4045.6 4064.9 4065.8 4066.4 4341.8 4484.9 4753.9 4757.4 4777.7 5041.3 5834.4 5883.8 5902.3 5923.5 1117.0 1286.7 1290.2 1341.5 1349.7 836.3 862.2 897.5 922.2 924.2 4638.6 4927.7 5037.8 5383.5 5478.3 5973.1 5977.9 5982.6 6000.3 6015.2 4580.8 4939.7 5152.0 5407.4 5490.9 2369.5 2400.0 2594.9 2663.7 2685.6 4940.5 5124.1 5132.6 5179.2 5217.4 4864.8 4887.9 5089.9 5091.9 5211.2 4459.6 4611.3 5044.8 5204.6 5234.6 4179.8 4484.0 4526.0 4737.6 4810.1 4411.6 4576.5 4733.8 4794.4 4910.9 4647.8 5089.8 5241.5 5256.8 5275.0 3135.5 3167.1 3457.7 3471.9 3498.7 4478.9 4641.0 4649.0 4840.0 4842.4 4493.0 4508.2 4711.9 4867.4 4868.9 4134.6 4252.3 4916.9 4920.0 4985.9 3858.9 3948.5 4508.9 4649.3 4676.8 4055.4 4777.5 4818.3 4910.7 4923.0 4091.7 4103.5 4684.0 4794.6 4802.4 06:00:51 INFO - PROCESS | 1660 | 06:00:51 INFO - PROCESS | 1660 | [#5] yui.html Cycles:180 Average:25355.07 Median:3643.85 stddev:41685.23 (1144.0%) stddev-sans-first:41760.74 06:00:51 INFO - PROCESS | 1660 | Values: 603.4 689.0 710.7 716.6 775.6 15004.2 15799.8 15914.6 15974.1 15994.7 14447.0 14493.6 14579.1 14588.0 15296.6 811.3 841.6 849.9 851.8 852.1 656.6 684.4 824.2 838.2 844.2 888.4 898.5 900.2 902.2 903.1 875.8 886.8 888.9 890.3 892.4 649.4 652.0 661.4 664.3 665.6 15940.3 16056.4 16073.8 16083.9 16120.8 14310.0 14314.9 14367.4 14388.6 14493.6 11914.4 12035.7 12046.5 12113.6 12168.5 3695.4 3780.8 3785.7 3788.1 3923.5 127291.0 127553.0 128537.0 131443.0 132151.0 10888.5 10906.1 10913.6 11016.1 11136.1 19010.1 21353.0 22006.7 22077.3 22121.8 7653.5 7913.3 7939.1 8128.4 8136.1 4345.6 4436.1 4474.3 4478.2 4603.3 3519.1 3586.8 3634.0 3643.0 3644.7 654.7 663.4 664.6 669.1 678.4 581.8 649.0 659.5 674.3 681.5 9415.6 9475.7 9612.2 9729.7 9921.2 521.7 666.5 672.8 672.9 678.8 4328.3 4331.4 4822.1 5118.3 5158.3 160200.0 162267.2 167019.0 168056.0 172406.0 105806.0 133118.3 136431.3 136708.0 143725.0 81311.2 97619.0 98428.0 99052.0 106713.5 9386.5 9741.1 10120.5 10127.0 10128.2 49509.8 59805.1 60153.5 60944.0 61431.5 79558.2 85168.0 90727.0 90731.1 93009.0 10160.8 10602.9 12944.9 13006.3 13240.3 10875.8 11801.0 13130.8 14697.6 15147.8 13512.2 13591.6 13806.2 13984.0 14271.1 10964.7 13119.9 13413.8 13693.2 14083.2 13367.4 13403.7 13538.5 13678.6 13738.8 11212.0 11222.9 11307.0 11441.3 11581.7 8409.5 9657.5 11304.9 11478.9 11617.0 06:00:51 INFO - PROCESS | 1660 | -------- Summary: end -------- 06:00:51 INFO - PROCESS | 1660 | 06:00:52 INFO - TEST-INFO | 1660: exit 0 06:00:52 INFO - TEST-OK | dromaeo_css | took 1113678ms 06:00:52 INFO - TEST-START | kraken 06:00:52 INFO - operating with platform_type : mac_ 06:00:52 INFO - Initialising browser for kraken test... 06:00:52 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/tmpjE_oMw/profile http://localhost:49273/getInfo.html) 06:00:54 INFO - PROCESS | 1666 | 2016-09-27 06:00:54.777 plugin-container[1667:11367] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461b, name = 'com.apple.tsm.portname' 06:00:54 INFO - PROCESS | 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:00:54 INFO - PROCESS | 1666 | 2016-09-27 06:00:54.780 plugin-container[1667:11367] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f03, name = 'com.apple.CFPasteboardClient' 06:00:54 INFO - PROCESS | 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:00:54 INFO - PROCESS | 1666 | 2016-09-27 06:00:54.780 plugin-container[1667:11367] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:00:54 INFO - PROCESS | 1666 | __metrics Screen width/height:1600/1200 06:00:54 INFO - PROCESS | 1666 | colorDepth:24 06:00:54 INFO - PROCESS | 1666 | Browser inner width/height: 1024/689 06:00:54 INFO - PROCESS | 1666 | __metrics 06:00:54 ERROR - PROCESS | 1666 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3067: TypeError: this.tabs is undefined 06:00:55 INFO - TEST-INFO | 1666: exit 0 06:00:55 INFO - Browser initialized. 06:00:55 INFO - Running cycle 1/1 for kraken test... 06:00:55 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/T/tmpjE_oMw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:00:58 INFO - PROCESS | 1668 | 2016-09-27 06:00:58.170 plugin-container[1669:11478] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x881f, name = 'com.apple.tsm.portname' 06:00:58 INFO - PROCESS | 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:00:58 INFO - PROCESS | 1668 | 2016-09-27 06:00:58.172 plugin-container[1669:11478] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e03, name = 'com.apple.CFPasteboardClient' 06:00:58 INFO - PROCESS | 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:00:58 INFO - PROCESS | 1668 | 2016-09-27 06:00:58.172 plugin-container[1669:11478] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:01:07 INFO - PROCESS | 1668 | RSS: Main: 239955968 06:01:07 INFO - PROCESS | 1668 | 06:01:11 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection) 06:01:12 INFO - PROCESS | 1668 | RSS: Main: 253517824 06:01:12 INFO - PROCESS | 1668 | 06:01:14 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-dft) 06:01:15 INFO - PROCESS | 1668 | RSS: Main: 262041600 06:01:15 INFO - PROCESS | 1668 | 06:01:18 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-fft) 06:01:19 INFO - PROCESS | 1668 | RSS: Main: 262266880 06:01:19 INFO - PROCESS | 1668 | 06:01:21 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator) 06:01:21 INFO - PROCESS | 1668 | RSS: Main: 263622656 06:01:21 INFO - PROCESS | 1668 | 06:01:23 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:01:24 INFO - PROCESS | 1668 | RSS: Main: 264630272 06:01:24 INFO - PROCESS | 1668 | 06:01:27 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom) 06:01:28 INFO - PROCESS | 1668 | RSS: Main: 265240576 06:01:28 INFO - PROCESS | 1668 | 06:01:30 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate) 06:01:31 INFO - PROCESS | 1668 | RSS: Main: 265498624 06:01:31 INFO - PROCESS | 1668 | 06:01:34 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial) 06:01:34 INFO - PROCESS | 1668 | RSS: Main: 265650176 06:01:34 INFO - PROCESS | 1668 | 06:01:36 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:01:37 INFO - PROCESS | 1668 | RSS: Main: 265420800 06:01:37 INFO - PROCESS | 1668 | 06:01:38 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:01:39 INFO - PROCESS | 1668 | RSS: Main: 265527296 06:01:39 INFO - PROCESS | 1668 | 06:01:41 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:01:42 INFO - PROCESS | 1668 | RSS: Main: 265531392 06:01:42 INFO - PROCESS | 1668 | 06:01:45 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:01:46 INFO - PROCESS | 1668 | RSS: Main: 265568256 06:01:46 INFO - PROCESS | 1668 | 06:01:49 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:01:49 INFO - PROCESS | 1668 | RSS: Main: 265842688 06:01:49 INFO - PROCESS | 1668 | 06:01:51 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49273/tests/kraken/driver.html?testName=ai-astar) 06:01:51 INFO - PROCESS | 1668 | RSS: Main: 267649024 06:01:51 INFO - PROCESS | 1668 | 06:01:51 INFO - PROCESS | 1668 | __start_tp_report 06:01:51 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 06:01:51 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 06:01:51 INFO - PROCESS | 1668 | |i|pagename|runs| 06:01:51 INFO - PROCESS | 1668 | |0;ai-astar;119;174;116;115;118;135;173;154;116;116 06:01:51 INFO - PROCESS | 1668 | |1;audio-beat-detection;169;148;146;173;139;143;116;118;122;92 06:01:51 INFO - PROCESS | 1668 | |2;audio-dft;237;274;201;198;195;197;199;198;198;196 06:01:51 INFO - PROCESS | 1668 | |3;audio-fft;83;60;60;84;62;60;66;57;80;60 06:01:51 INFO - PROCESS | 1668 | |4;audio-oscillator;82;69;88;73;69;71;88;73;70;87 06:01:51 INFO - PROCESS | 1668 | |5;imaging-gaussian-blur;136;86;88;87;89;86;105;111;89;101 06:01:51 INFO - PROCESS | 1668 | |6;imaging-darkroom;99;99;98;99;98;98;115;99;95;98 06:01:51 INFO - PROCESS | 1668 | |7;imaging-desaturate;91;100;90;91;93;91;91;92;92;92 06:01:51 INFO - PROCESS | 1668 | |8;json-parse-financial;56;57;56;57;58;59;58;58;57;59 06:01:51 INFO - PROCESS | 1668 | |9;json-stringify-tinderbox;81;72;72;78;75;71;72;71;70;73 06:01:51 INFO - PROCESS | 1668 | |10;stanford-crypto-aes;88;88;114;104;89;89;88;91;88;87 06:01:51 INFO - PROCESS | 1668 | |11;stanford-crypto-ccm;138;137;137;136;137;150;139;142;220;143 06:01:51 INFO - PROCESS | 1668 | |12;stanford-crypto-pbkdf2;172;208;245;197;174;172;174;172;174;174 06:01:51 INFO - PROCESS | 1668 | |13;stanford-crypto-sha256-iterative;69;69;69;70;69;69;68;70;71;68 06:01:51 INFO - PROCESS | 1668 | __end_tp_report 06:01:51 INFO - PROCESS | 1668 | __start_cc_report 06:01:51 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,929 06:01:51 INFO - PROCESS | 1668 | __end_cc_report 06:01:51 INFO - PROCESS | 1668 | __startTimestamp1474981311611__endTimestamp 06:01:51 INFO - PROCESS | 1668 | 06:01:51 INFO - PROCESS | 1668 | ------- Summary: start ------- 06:01:51 INFO - PROCESS | 1668 | Number of tests: 14 06:01:51 INFO - PROCESS | 1668 | 06:01:51 INFO - PROCESS | 1668 | [#0] ai-astar Cycles:10 Average:133.60 Median:127.00 stddev:24.35 (19.2%) stddev-sans-first:25.24 06:01:51 INFO - PROCESS | 1668 | Values: 119.0 174.0 116.0 115.0 118.0 135.0 173.0 154.0 116.0 116.0 06:01:51 INFO - PROCESS | 1668 | 06:01:51 INFO - PROCESS | 1668 | [#1] audio-beat-detection Cycles:10 Average:136.60 Median:147.00 stddev:24.97 (17.0%) stddev-sans-first:23.57 06:01:51 INFO - PROCESS | 1668 | Values: 169.0 148.0 146.0 173.0 139.0 143.0 116.0 118.0 122.0 92.0 06:01:51 INFO - PROCESS | 1668 | 06:01:51 INFO - PROCESS | 1668 | [#2] audio-dft Cycles:10 Average:209.30 Median:198.50 stddev:25.91 (13.1%) stddev-sans-first:25.47 06:01:51 INFO - PROCESS | 1668 | Values: 237.0 274.0 201.0 198.0 195.0 197.0 199.0 198.0 198.0 196.0 06:01:51 INFO - PROCESS | 1668 | 06:01:51 INFO - PROCESS | 1668 | [#3] audio-fft Cycles:10 Average:67.20 Median:64.00 stddev:10.73 (16.8%) stddev-sans-first:9.74 06:01:51 INFO - PROCESS | 1668 | Values: 83.0 60.0 60.0 84.0 62.0 60.0 66.0 57.0 80.0 60.0 06:01:51 INFO - PROCESS | 1668 | 06:01:51 INFO - PROCESS | 1668 | [#4] audio-oscillator Cycles:10 Average:77.00 Median:77.50 stddev:8.25 (10.6%) stddev-sans-first:8.55 06:01:51 INFO - PROCESS | 1668 | Values: 82.0 69.0 88.0 73.0 69.0 71.0 88.0 73.0 70.0 87.0 06:01:51 INFO - PROCESS | 1668 | 06:01:51 INFO - PROCESS | 1668 | [#5] imaging-gaussian-blur Cycles:10 Average:97.80 Median:86.50 stddev:16.13 (18.6%) stddev-sans-first:9.49 06:01:51 INFO - PROCESS | 1668 | Values: 136.0 86.0 88.0 87.0 89.0 86.0 105.0 111.0 89.0 101.0 06:01:51 INFO - PROCESS | 1668 | 06:01:51 INFO - PROCESS | 1668 | [#6] imaging-darkroom Cycles:10 Average:99.80 Median:98.50 stddev:5.47 (5.6%) stddev-sans-first:5.80 06:01:51 INFO - PROCESS | 1668 | Values: 99.0 99.0 98.0 99.0 98.0 98.0 115.0 99.0 95.0 98.0 06:01:51 INFO - PROCESS | 1668 | 06:01:51 INFO - PROCESS | 1668 | [#7] imaging-desaturate Cycles:10 Average:92.30 Median:91.50 stddev:2.83 (3.1%) stddev-sans-first:2.96 06:01:51 INFO - PROCESS | 1668 | Values: 91.0 100.0 90.0 91.0 93.0 91.0 91.0 92.0 92.0 92.0 06:01:51 INFO - PROCESS | 1668 | 06:01:51 INFO - PROCESS | 1668 | [#8] json-parse-financial Cycles:10 Average:57.50 Median:58.00 stddev:1.08 (1.9%) stddev-sans-first:1.00 06:01:51 INFO - PROCESS | 1668 | Values: 56.0 57.0 56.0 57.0 58.0 59.0 58.0 58.0 57.0 59.0 06:01:51 INFO - PROCESS | 1668 | 06:01:51 INFO - PROCESS | 1668 | [#9] json-stringify-tinderbox Cycles:10 Average:73.50 Median:72.50 stddev:3.50 (4.8%) stddev-sans-first:2.45 06:01:51 INFO - PROCESS | 1668 | Values: 81.0 72.0 72.0 78.0 75.0 71.0 72.0 71.0 70.0 73.0 06:01:51 INFO - PROCESS | 1668 | 06:01:51 INFO - PROCESS | 1668 | [#10] stanford-crypto-aes Cycles:10 Average:92.60 Median:88.00 stddev:9.02 (10.3%) stddev-sans-first:9.41 06:01:51 INFO - PROCESS | 1668 | Values: 88.0 88.0 114.0 104.0 89.0 89.0 88.0 91.0 88.0 87.0 06:01:51 INFO - PROCESS | 1668 | 06:01:51 INFO - PROCESS | 1668 | [#11] stanford-crypto-ccm Cycles:10 Average:147.90 Median:140.50 stddev:25.68 (18.3%) stddev-sans-first:26.99 06:01:51 INFO - PROCESS | 1668 | Values: 138.0 137.0 137.0 136.0 137.0 150.0 139.0 142.0 220.0 143.0 06:01:51 INFO - PROCESS | 1668 | 06:01:51 INFO - PROCESS | 1668 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.20 Median:174.00 stddev:24.15 (13.9%) stddev-sans-first:25.06 06:01:51 INFO - PROCESS | 1668 | Values: 172.0 208.0 245.0 197.0 174.0 172.0 174.0 172.0 174.0 174.0 06:01:51 INFO - PROCESS | 1668 | 06:01:51 INFO - PROCESS | 1668 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.20 Median:69.00 stddev:0.92 (1.3%) stddev-sans-first:0.97 06:01:51 INFO - PROCESS | 1668 | Values: 69.0 69.0 69.0 70.0 69.0 69.0 68.0 70.0 71.0 68.0 06:01:51 INFO - PROCESS | 1668 | -------- Summary: end -------- 06:01:51 INFO - PROCESS | 1668 | 06:01:52 INFO - TEST-INFO | 1668: exit 0 06:01:52 INFO - TEST-OK | kraken | took 60411ms 06:01:52 INFO - SUITE-END | took 1174s 06:01:52 INFO - Completed test suite (00:19:34) 06:01:52 INFO - javascript benchmark 06:01:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7237.435029048745, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3006.684298148695, 3339.2653616204425, 3372.3423932333203, 3412.0616830371628, 3417.7607567470272, 4544.568266014731, 5538.726092998602, 5549.084401073828, 5577.8605534861645, 5589.86025349366, 5565.696160866574, 5599.804006859761, 5622.184783206443, 5656.547476201904, 5809.941900580993, 4358.389825424443, 4432.312991486325, 4587.288970209606, 4854.834839638496, 4965.70891568193, 4408.669349798778, 5197.168453047513, 5211.635185537014, 5253.290805741233, 5272.894542109175, 5077.669951453144, 5218.347706536683, 5220.138677118271, 5232.816851410183, 5326.627136100475, 5083.618728595333, 5113.463086375951, 5151.227315902607, 5154.768035438415, 5226.346706661668, 4735.526447355272, 5138.486151384832, 5151.974240128813, 5175.068487672217, 5185.6888586684545, 5480.342358916916, 5591.440855914417, 5594.412586678422, 5663.207150998864, 5663.49028587425, 5813.273340832396, 5875.412458754133, 6014.0377539593455, 6119.959572021066, 6141.117811339996, 6195.597286176384, 6221.346758590328, 6253.96873015632, 6254.687265636746, 6262.12330273762, 5761.395053519362, 5776.740046697909, 5802.535797136219, 5817.999999999957, 5840.474357307863, 6101.610781564156, 6128.295246046761, 6225.035119556476, 6225.501959843202, 6287.151234583364, 4952.0, 5708.771649133988, 6050.092486127116, 6096.329403765582, 6100.267967843887, 6144.815655530341, 6163.1679723237685, 6188.876222475616, 6189.071639254148, 6199.876002480015, 5006.974965125151, 5064.82273120439, 5849.7952571660635, 6007.008843540854, 6024.42767937054, 5180.0, 5877.676727780014, 6182.196314479179, 6199.690015499207, 6205.0, 3976.403539469045, 4831.492693267227, 4851.805927762921, 4852.4662287148385, 4878.487758785347, 4275.080857615628, 4299.634531064832, 4314.266574682311, 4332.826686932551, 4363.672724545671, 4629.976850115728, 5629.155626655953, 5698.515626171739, 5741.62679425836, 5821.854453638693, 6213.347598502182, 6228.937710622927, 6234.3453937336835, 6247.875042499125, 6254.718537665816, 5771.480566749013, 5794.304683438015, 5828.0, 5829.417058294137, 5837.854053648693, 5146.30524879144, 5258.605604579595, 5297.125974214211, 5330.706811125426, 5365.3293338332705, 5170.663906846043, 5949.613275137102, 6224.408681175281, 6277.968610157011, 6301.275353334334, 6194.38056194377, 6218.160548326099, 6222.346653601307, 6236.875262494816, 6238.594491358047, 6158.784442544489, 6211.689415529296, 6220.440160385587, 6248.218972628421, 6264.49884009269, 5074.391073071108, 6078.331383547895, 6152.938470615237, 6159.5688301818445, 6160.229971253593, 6224.253089629274, 6225.8132256032395, 6231.158793562811, 6234.594751341148, 6254.593451425642, 5571.554275657856, 5742.770289188553, 6098.481629061402, 6100.908486372619, 6102.7253773579405, 5824.0, 5985.880282394241, 5994.460498555151, 6109.16175461965, 6120.264288666237, 5277.472252774692, 5587.217789509391, 5796.855078623068, 5868.853278667897, 5891.617044892068, 5844.620099693507, 5911.290645122613, 5913.172155898264, 5988.910166347421, 6075.574709770451, 5899.941000589939, 5913.438223368773, 5919.763209471573, 5920.319163296276, 5947.256592925884], "value": 5552.745035014167, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [489.61565171340493, 504.63414024831997, 535.2934126952422, 539.8056699588147, 545.342861851469, 39156.80421597892, 40706.59293407065, 40767.59232407675, 40773.79613101938, 40949.18101637969, 42560.14879702404, 43012.0, 43019.0, 43139.78430107853, 43162.35256471147, 9675.903240967606, 9816.411015339067, 9833.16418104462, 9870.259730520203, 9893.851592226123, 7881.566513841765, 8621.741347759546, 8670.219680228778, 9380.671676491324, 9848.60605575776, 9818.214542836557, 9830.4101753895, 9854.40873547586, 9872.950635246849, 9885.357451765612, 9783.87485439173, 9795.755106122333, 9829.459379734111, 9838.508074596313, 9849.965753595912, 9570.617175312978, 9625.0, 9629.133377995944, 9656.517174141265, 9675.806483870354, 40381.0, 40447.0, 40664.39003414951, 40934.79532602348, 40979.590204097876, 3253.8210398428077, 3419.7919211154685, 3477.4436090225695, 3723.6648701616987, 3992.4210989406665, 30198.698013020028, 30469.0, 31153.844230778926, 31233.0, 31320.686793132005, 3117.1116231873807, 3313.188268874117, 3339.46568549031, 3368.7473439492137, 3380.8140552269615, 488762.0, 495375.0, 499700.0, 501132.0, 501280.0, 59717.0, 59881.70059149677, 60364.0, 60398.490037749405, 60408.46936479694, 60996.69501652463, 62392.68803655953, 62651.68674156691, 62937.0, 62974.0, 16050.117243551484, 16263.512094637179, 16271.186440677919, 16287.0, 16320.591985200464, 12631.557895473798, 12672.87327126717, 12731.681707957376, 12734.426950787236, 12776.233425994471, 16183.514494565183, 16955.49133525996, 16965.745513817303, 16996.660066798595, 17004.659906801793, 3140.795848269855, 5463.754131064112, 6369.267534233622, 6558.573692709959, 6578.0, 715.7581595931056, 950.6387572722459, 954.8615450759739, 977.3549457358109, 1033.4832583708146, 30230.54654180189, 30304.84847575792, 30744.38511229763, 30915.917942871893, 31248.68751312503, 871.3160169267095, 932.8927173375013, 1033.1734612310272, 1071.665776108734, 1139.5441823270758, 15619.765703514457, 15706.0, 15741.685166296838, 16059.518214453352, 16995.915020424818, 621532.0, 646088.0, 669951.0, 676386.2361376422, 682080.0, 60620.78758424895, 61717.69141154355, 61754.38245617486, 61920.071198932055, 62243.0, 1385.7436374270792, 1402.52314213168, 1411.4283715095607, 1433.9426422943175, 1434.2040167707023, 30810.0, 31919.521207181446, 31926.0, 32010.51984220192, 32838.34323313473, 52487.73756131195, 52873.471265286855, 52896.471035289156, 53236.93526129533, 53265.20102198393, 519033.0, 519915.0, 521069.0, 522282.0, 522457.0, 33326.16684582808, 42685.5731442694, 42806.35790463083, 43709.50323974011, 43909.12181756411, 46744.29883551817, 46922.06155876932, 47574.28638570494, 48074.759626201645, 48179.75910120427, 33795.0, 39648.20703585855, 39833.60166398415, 40765.796171018956, 41107.58892411038, 37292.813535932146, 39404.21191576095, 39689.40465893072, 40751.38872916968, 41761.37357939694, 25042.874785625954, 33907.0, 37600.0, 38417.80791096138, 38546.65086722063, 25646.358841029123, 30437.695623044372, 34870.47694284537, 35514.64485355217, 35847.46228806622, 22977.195798146986, 23521.529569408172, 26203.73796262013, 26253.474930501667, 26504.469910602067], "value": 20086.591712751597, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.69747458346865, 371.2760117770347, 373.3783250887273, 383.04812540836036, 402.88115006073207, 19070.61858762824, 20326.0, 21216.681749773747, 21288.57422851544, 21335.67996480054, 20015.099320530575, 20250.59498810023, 20271.39185824424, 20454.97725113742, 20707.482312942186, 4727.881802954937, 5987.55093367997, 6120.69396530174, 6157.661328626924, 6185.536084793636, 6045.183900173486, 6059.878802423949, 6158.445739883409, 6163.198784158076, 6172.47533959614, 5813.912791308134, 5975.970120149394, 5986.0, 6078.088286756978, 6081.78713745019, 5765.567582431292, 5988.580799344048, 6022.728977195993, 6058.757649694007, 6081.179040829497, 5834.066549352084, 5960.761569537213, 5968.940310596925, 5970.522358211333, 6006.129111278884, 12906.0, 13049.543265985645, 13149.934250328783, 13340.799888001688, 13388.19670819754, 2127.1597718901103, 2132.1897678881924, 2268.2288022072576, 2275.3628983884537, 2400.459896523286, 9481.952590237073, 9873.90126098737, 9924.305298629099, 10003.199744020534, 10431.113355364803, 1400.964975875601, 1654.561541191585, 1716.8025677047174, 1983.990080049605, 1985.4738494299022, 94659.52670236674, 123492.38253808764, 124471.13293300608, 125973.74026259712, 127014.0, 11134.053605443629, 11259.549618015188, 11477.71305717347, 11505.252158609663, 11672.649820505398, 14482.275886205647, 14502.912281578923, 14528.491502797351, 14531.128132312095, 14582.708345833236, 7090.326418990187, 8282.875756863652, 8511.404201705946, 8544.31645468361, 8665.35009874262, 8459.661613535513, 8476.49141051539, 8513.829723405497, 8624.698135565224, 8663.870041949376, 4463.843765468193, 4485.170243504962, 4528.954710452853, 4624.098300831306, 4655.185342565038, 1896.3836753055036, 1943.5141214696325, 1951.687729963199, 1954.1597113241442, 1954.540682939508, 733.4865594084163, 791.612110066075, 912.539167720297, 945.5650400815701, 964.6479035152119, 16695.497405233586, 19818.50453738668, 20824.47938801512, 21387.03758330879, 21524.892375538024, 812.6627449608465, 911.9224865886474, 918.6600957645713, 931.9347645664739, 952.8570714392896, 10263.384196948207, 11344.829827552594, 11355.829662554903, 11427.60003399877, 11432.199746017865, 556286.0, 572542.0, 574032.0, 595906.0, 603406.0, 81067.59466202749, 82102.58948705219, 113120.0, 132454.0, 144752.82870756948, 949.6011675096476, 986.9834070906834, 1026.9691909242736, 1028.4497793680346, 1037.7872536130076, 14424.350904209126, 19825.0, 20012.299569515, 20388.28640997558, 20571.58856822885, 53400.0, 53760.89271519199, 54130.45869541254, 54627.453725462234, 55229.723851380484, 53888.46111538991, 78820.60589697016, 79005.20994789978, 79426.2057379442, 79593.0, 6506.25178104524, 7918.129005809472, 7954.403419743427, 8216.63025163881, 8298.460600060977, 6772.05191273232, 9094.999550049357, 10133.89866101359, 12491.188777627012, 12841.229526228082, 10697.304675196086, 10822.621208257673, 10914.345139291669, 10967.232293739362, 11248.325100493996, 10522.579096836347, 10728.141748660244, 11048.944755276494, 11237.60668376603, 11386.772264554496, 10978.835317470084, 11273.774524509628, 11431.428428578703, 11475.540978360772, 11500.25248358854, 6568.8357791053695, 7884.29041386278, 7922.366210703015, 8232.547209903274, 8322.084570697098, 7141.571505709674, 8956.373053886426, 10836.620718274822, 11107.278026928547, 11136.554537818396], "value": 9228.93545542301, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1384.4462215113956, 1389.305347326337, 1610.3478091372997, 1682.3691115831564, 1846.363004763357, 7977.56123413212, 8271.048829384623, 8502.234798868101, 9076.909230907697, 9102.635894564217, 8493.235608795207, 8821.691240806573, 8829.690960816373, 8939.508327042011, 8956.238719708816, 1963.2932144427994, 2364.267077206063, 2394.473215892501, 2404.0864471500804, 2404.927852164429, 1971.3297478857185, 2345.6247000479925, 2358.50471401006, 2372.015613520387, 2400.7959323457526, 1934.351992082647, 2364.775346342095, 2379.6549500322444, 2394.1500767227644, 2401.603735383668, 2355.646653002046, 2355.752645972174, 2368.218487898989, 2369.5142495788323, 2381.9047238110456, 2368.336865677613, 2372.3001714494267, 2373.4778348763416, 2375.394274460019, 2383.15398033699, 8828.42615230008, 8939.865902011476, 8947.150020748019, 8977.461352318816, 9093.318001149873, 5190.377154741417, 5357.571394288448, 5372.113601255788, 5379.623426360156, 5386.865328366783, 3430.1939044324563, 3488.7906725596545, 3605.2068544920076, 3610.530631017951, 3616.945745813815, 1050.369778133114, 1084.1814430105221, 1089.591403223791, 1097.3251450357973, 1111.1721767283425, 11800.173987820855, 11893.940530297294, 11936.104792140537, 11971.760564788743, 12000.51997920082, 4551.203539380661, 4665.323528088476, 4804.111239420648, 4816.686915350491, 4837.443693975238, 9926.65256716026, 10083.899161008296, 10108.696739097839, 10114.797704045877, 10120.443375614264, 5356.330458692663, 5375.569954403638, 5405.297311349578, 5414.945850541523, 5417.458254174551, 4393.121375724868, 4553.612942899825, 4748.738819364969, 4900.289458028566, 4941.604671626261, 3887.9028024299055, 3907.9218415632095, 3990.1620659661794, 4019.2765302245875, 4037.555868854424, 1263.646179069862, 1270.5045032437356, 1271.815586739936, 1293.0, 1297.8118172864881, 756.71623141322, 814.2346194577078, 820.5815034332534, 909.8271328447706, 911.125319693102, 5762.30852297727, 5939.792107276225, 6047.334793172748, 6063.757449701962, 6099.207103076572, 859.1494420523636, 863.4571187851842, 920.9309301802392, 956.4171296504157, 963.9878127965608, 3753.549574051077, 4007.779572123561, 4030.2544029354663, 4088.6524645405475, 4110.095778928631, 10225.948870255601, 10539.314944528582, 11023.228374013743, 11045.226834121695, 11070.889291107147, 10457.68626941193, 10811.6215932442, 10966.451677416097, 10966.780664386668, 10977.725556861142, 8001.719939802079, 8104.5137291762685, 8276.172382761795, 8302.792430189294, 8361.0, 5964.254468191476, 5971.164037034905, 6063.333033366414, 6087.969560152171, 6127.754889804358, 9917.752056198364, 10150.644727434505, 10155.7461063474, 10187.0, 10219.386836789816, 10013.299069065095, 10190.745231369276, 10228.335158214692, 10237.183709654324, 10267.229957753349, 5642.0, 6543.691261747575, 7058.505904586629, 7679.462437629312, 7886.0, 7182.245864184394, 8379.162083791573, 8485.0, 8610.870836937325, 8628.78428039304, 7462.291082347168, 7549.6225188739245, 7569.697212111454, 7664.156942736406, 7697.6151192441275, 6346.90479642815, 7328.5969271690565, 7452.850942981002, 7454.589997550187, 7533.2466753324225, 7100.254473280231, 7108.715651373887, 7195.100612423447, 7208.17106032792, 7213.639318034182, 4066.064805094904, 4795.664303498722, 5004.474530174425, 5010.04407399636, 5152.690838549549, 3995.62041606047, 5037.420696619935, 5103.081445339874, 5122.615803814654, 5168.612354073534], "value": 4585.168272654259, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [285.21850130642036, 301.15676106900673, 313.510922960182, 314.81111333200073, 315.55506735502945, 3995.9999999999927, 5455.863603409907, 5468.398476167623, 5525.778968841247, 5533.418991005947, 4460.821567137319, 4469.486009108953, 5332.626716129873, 5513.448655134484, 5543.1408131739545, 3718.7768733876055, 3830.935251798557, 4232.407462955189, 4286.078493123978, 4308.806103725325, 3654.835532401048, 3695.2794205130012, 3975.483187185662, 4075.572064933184, 4145.937810932838, 4229.915401691965, 4238.237117318881, 4249.277622804131, 4269.865067466267, 4274.0125841483705, 3654.3239500692457, 3668.834902429397, 4221.867956681871, 4229.577042295746, 4265.296226122686, 3721.6278372162838, 3746.906327341811, 3749.581519919637, 3758.718096142773, 3766.510353654035, 4816.253480710496, 4947.431045429749, 5387.892242155174, 5415.6190171506405, 5448.564114870801, 4255.191513612434, 4305.901994991257, 4584.0, 4693.47462074827, 4747.026859493795, 4838.588719958808, 5061.759868832123, 5176.7411629418375, 5863.9706801466145, 5901.23283973085, 3354.295597924439, 3927.960720392788, 3999.340108882031, 4017.995501124719, 4110.23960567296, 4790.42514898211, 5083.669561478529, 5322.387925388591, 6167.259928808572, 6219.222597175353, 3675.2649470105553, 4013.8595149169637, 4015.6185162409524, 4023.637872591481, 4024.75851448914, 3926.1755031443145, 4045.5954404559307, 4064.8577299794956, 4065.8005888263033, 4066.3900414938, 4341.804618792162, 4484.910301793946, 4753.9049219016115, 4757.429108506932, 4777.737224452689, 5041.294218809369, 5834.358220595731, 5883.764649413975, 5902.2622172228475, 5923.496502797224, 1117.0002847451485, 1286.6654669785923, 1290.1742884553894, 1341.4500054977411, 1349.690799924072, 836.3392919593452, 862.1550880137376, 897.4929165021741, 922.2115091596588, 924.1590152960907, 4638.5593368629925, 4927.728974906343, 5037.848864534069, 5383.515483606416, 5478.260434841325, 5973.074173503114, 5977.880442391128, 5982.611130276518, 6000.309964354068, 6015.180360471132, 4580.770961451981, 4939.7277252463555, 5151.969606078799, 5407.405185429599, 5490.917636235459, 2369.549785540776, 2399.952000959971, 2594.883230254643, 2663.6803583570095, 2685.609807271491, 4940.530649588283, 5124.128898087334, 5132.640715149903, 5179.16741611205, 5217.373915130209, 4864.805407783649, 4887.926681099786, 5089.8727531812, 5091.9236211456855, 5211.228182417996, 4459.620932220732, 4611.2852507861335, 5044.772985215674, 5204.635675502678, 5234.63357564982, 4179.770112643712, 4483.955160448354, 4525.974965451541, 4737.573618374363, 4810.062037902575, 4411.602955733999, 4576.4508259009135, 4733.810647574059, 4794.400699912511, 4910.852674419773, 4647.814807224271, 5089.847304580869, 5241.50205730455, 5256.843494431219, 5275.0, 3135.5296705494357, 3167.129039514152, 3457.6542345765224, 3471.9063494998995, 3498.650134986481, 4478.910421791611, 4640.957978489454, 4649.046945376257, 4839.959408727, 4842.394700662418, 4492.989696903018, 4508.233600287894, 4711.939813541926, 4867.415910090812, 4868.90262194747, 4134.627883490501, 4252.34158678122, 4916.8770780730765, 4920.0, 4985.93646178378, 3858.9421158683203, 3948.4927071309353, 4508.887277818081, 4649.256119021075, 4676.7661616919695, 4055.351143817093, 4777.546133117348, 4818.313590243387, 4910.723211964986, 4923.0, 4091.6522095622204, 4103.543242149113, 4683.976580117214, 4794.568488836021, 4802.398201348989], "value": 3894.7309322608253, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [603.360437935788, 689.0456717446339, 710.6944014073949, 716.570441968273, 775.635451337871, 15004.249787510622, 15799.763003554956, 15914.602134946632, 15974.121423321712, 15994.680106397866, 14447.0, 14493.5651930442, 14579.125252484859, 14588.0, 15296.617584560392, 811.2820154163549, 841.6465084664425, 849.902261239956, 851.8083431227955, 852.1435956863344, 656.5765081522416, 684.4455990647566, 824.1922915542772, 838.1995194589152, 844.1684940333766, 888.3648191543045, 898.4564338575166, 900.18083543975, 902.1519771414881, 903.056306160061, 875.7810547363159, 886.7916039730658, 888.8844450221494, 890.2655309369732, 892.3619611977467, 649.4349915573481, 651.9967400162968, 661.3750006244102, 664.2792570061489, 665.5540787672271, 15940.282687278985, 16056.438024669198, 16073.83926160735, 16083.91958040214, 16120.838791612052, 14310.0, 14314.928425357806, 14367.353469093916, 14388.568342949728, 14493.637659058608, 11914.404279785977, 12035.699107522381, 12046.457909394097, 12113.576024839176, 12168.452419641137, 3695.362320288048, 3780.7920564368947, 3785.659290663826, 3788.147666774981, 3923.489946306962, 127291.0, 127553.0, 128537.0, 131443.0, 132151.0, 10888.510017049251, 10906.072983796466, 10913.618023369145, 11016.11871050314, 11136.128582103229, 19010.144543495575, 21353.049685808175, 22006.669899951514, 22077.33767986963, 22121.77878221197, 7653.464257501922, 7913.32736717374, 7939.0870049945, 8128.39037072222, 8136.14570470104, 4345.587169218919, 4436.0906014267, 4474.328850672425, 4478.171538265431, 4603.309503574424, 3519.085037890167, 3586.8342788593814, 3634.0, 3642.9635703643153, 3644.7448678592773, 654.656305439648, 663.4195079305607, 664.5879554676035, 669.053289595227, 678.4063944048958, 581.7789240088824, 648.9578177418547, 659.4625380315124, 674.3028331594791, 681.5127184063418, 9415.623375065057, 9475.668351607661, 9612.164411498625, 9729.659461918798, 9921.206303495703, 521.7260938007577, 666.4535081233439, 672.8250654829682, 672.9495287853431, 678.8167194857459, 4328.329108988112, 4331.4152589400655, 4822.107910036654, 5118.334616499906, 5158.329417175818, 160200.0, 162267.18866405828, 167019.0, 168056.0, 172406.0, 105806.0, 133118.33440832733, 136431.31784341214, 136708.0, 143725.0, 81311.15410960706, 97619.0, 98428.0, 99052.0, 106713.4664326689, 9386.48374339418, 9741.074597913186, 10120.493975301353, 10127.0, 10128.189744820544, 49509.75245123752, 59805.10292345531, 60153.49616259628, 60944.0, 61431.464213395026, 79558.2044179574, 85168.0, 90727.03700009867, 90731.09268907226, 93009.0, 10160.847587286344, 10602.886696896714, 12944.870551294367, 13006.284654344103, 13240.337983100613, 10875.782484350428, 11801.0, 13130.818226359674, 14697.55907322782, 15147.848521514643, 13512.189268643913, 13591.592252232449, 13806.240656763974, 13984.0, 14271.14373137615, 10964.739055008777, 13119.934400327937, 13413.798793017917, 13693.178409295475, 14083.225422601856, 13367.398467069199, 13403.731925361633, 13538.458461661423, 13678.589642310746, 13738.793918091436, 11212.0, 11222.943885280521, 11306.970309249593, 11441.313521188755, 11581.7104572383, 8409.495430273959, 9657.46883921391, 11304.926032026944, 11478.909503597144, 11617.0], "value": 7817.3781088579, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1540.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 174.0, 116.0, 115.0, 118.0, 135.0, 173.0, 154.0, 116.0, 116.0], "value": 133.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 148.0, 146.0, 173.0, 139.0, 143.0, 116.0, 118.0, 122.0, 92.0], "value": 136.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [237.0, 274.0, 201.0, 198.0, 195.0, 197.0, 199.0, 198.0, 198.0, 196.0], "value": 209.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 60.0, 60.0, 84.0, 62.0, 60.0, 66.0, 57.0, 80.0, 60.0], "value": 67.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 69.0, 88.0, 73.0, 69.0, 71.0, 88.0, 73.0, 70.0, 87.0], "value": 77.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 86.0, 88.0, 87.0, 89.0, 86.0, 105.0, 111.0, 89.0, 101.0], "value": 97.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 99.0, 98.0, 99.0, 98.0, 98.0, 115.0, 99.0, 95.0, 98.0], "value": 99.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 100.0, 90.0, 91.0, 93.0, 91.0, 91.0, 92.0, 92.0, 92.0], "value": 92.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 56.0, 57.0, 58.0, 59.0, 58.0, 58.0, 57.0, 59.0], "value": 57.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 72.0, 72.0, 78.0, 75.0, 71.0, 72.0, 71.0, 70.0, 73.0], "value": 73.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 114.0, 104.0, 89.0, 89.0, 88.0, 91.0, 88.0, 87.0], "value": 92.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 137.0, 137.0, 136.0, 137.0, 150.0, 139.0, 142.0, 220.0, 143.0], "value": 147.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 208.0, 245.0, 197.0, 174.0, 172.0, 174.0, 172.0, 174.0, 174.0], "value": 186.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 69.0, 70.0, 69.0, 69.0, 68.0, 70.0, 71.0, 68.0], "value": 69.2, "unit": "score"}]}]} 06:01:52 INFO - Return code: 0 06:01:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:01:52 ERROR - # TBPL SUCCESS # 06:01:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:01:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:01:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:01:52 INFO - 2016-09-27 06:01:52.956 screenresolution[1673:11878] starting screenresolution argv=screenresolution get 06:01:52 INFO - 2016-09-27 06:01:52.958 screenresolution[1673:11878] Display 0: 1600x1200x32@60 06:01:52 INFO - 2016-09-27 06:01:52.964 screenresolution[1674:11881] starting screenresolution argv=screenresolution list 06:01:52 INFO - Available Modes on Display 0 06:01:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:01:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:01:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:01:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:01:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:01:53 INFO - Intel Iris: 06:01:53 INFO - Chipset Model: Intel Iris 06:01:53 INFO - Type: GPU 06:01:53 INFO - Bus: Built-In 06:01:53 INFO - VRAM (Dynamic, Max): 1536 MB 06:01:53 INFO - Vendor: Intel (0x8086) 06:01:53 INFO - Device ID: 0x0a2e 06:01:53 INFO - Revision ID: 0x0009 06:01:53 INFO - Displays: 06:01:53 INFO - Display: 06:01:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:01:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:01:53 INFO - Display Serial Number: 1600x1200 60 06:01:53 INFO - Main Display: Yes 06:01:53 INFO - Mirror: Off 06:01:53 INFO - Online: Yes 06:01:53 INFO - Rotation: Supported 06:01:53 INFO - Return code: 0 06:01:53 INFO - Running post-action listener: _resource_record_post_action 06:01:53 INFO - [mozharness: 2016-09-27 13:01:53.208435Z] Finished run-tests step (success) 06:01:53 INFO - Running post-run listener: _resource_record_post_run 06:01:53 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 32043520; Write bytes: 708417024; Read time: 309; Write time: 2117 06:01:53 INFO - TinderboxPrint: CPU usage
54.4% 06:01:53 INFO - TinderboxPrint: I/O read bytes / time
32,043,520 / 309 06:01:53 INFO - TinderboxPrint: I/O write bytes / time
708,417,024 / 2,117 06:01:53 INFO - TinderboxPrint: CPU idle
2,206.2 (45.6%) 06:01:53 INFO - TinderboxPrint: CPU system
974.4 (20.1%) 06:01:53 INFO - TinderboxPrint: CPU user
1,657.1 (34.3%) 06:01:53 INFO - TinderboxPrint: Swap in / out
434,868,224 / 0 06:01:53 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 403667456; Write bytes: 398160384; Read time: 29704; Write time: 794 06:01:53 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 29831680; Write bytes: 297468928; Read time: 284; Write time: 1289 06:01:53 INFO - Running post-run listener: _upload_blobber_files 06:01:53 INFO - Blob upload gear active. 06:01:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:01:53 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:01:53 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:01:53 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:01:54 INFO - (blobuploader) - INFO - Open directory for files ... 06:01:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:01:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:01:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:01:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:01:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:01:55 INFO - (blobuploader) - INFO - Done attempting. 06:01:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:01:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:01:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:01:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:01:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:01:56 INFO - (blobuploader) - INFO - Done attempting. 06:01:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:01:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:01:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:01:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:01:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:01:59 INFO - (blobuploader) - INFO - Done attempting. 06:01:59 INFO - (blobuploader) - INFO - Iteration through files over. 06:01:59 INFO - Return code: 0 06:01:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:01:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:01:59 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fe519ef04d5c0e5d96cf338be43657ab7ebd48f5204c50199f73fac1c236f4c4f5c118d6bd612cfe6f6e0c1fa3d009d5c91b0d34d19cb1e450658fbc6f8e0c17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ae0c7ebb522581c6c1a3be745305d6b6f90b11ab6875ed6d7bef6a976358b79fdaeafee554ad10449055a9ba7f1aa720f52f9c06f2344e08a321ca39cf1cc2d4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2824e81d41e0998474019e1755c7a83eaa41b72c9fbfd2d991424a10f9cb57517418e2f4be498c12d6e96d2bab90c1744e5c4eaf7bdb3c674ebd1d4e01297fa2"} 06:01:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:01:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:01:59 INFO - Contents: 06:01:59 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fe519ef04d5c0e5d96cf338be43657ab7ebd48f5204c50199f73fac1c236f4c4f5c118d6bd612cfe6f6e0c1fa3d009d5c91b0d34d19cb1e450658fbc6f8e0c17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ae0c7ebb522581c6c1a3be745305d6b6f90b11ab6875ed6d7bef6a976358b79fdaeafee554ad10449055a9ba7f1aa720f52f9c06f2344e08a321ca39cf1cc2d4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2824e81d41e0998474019e1755c7a83eaa41b72c9fbfd2d991424a10f9cb57517418e2f4be498c12d6e96d2bab90c1744e5c4eaf7bdb3c674ebd1d4e01297fa2"} 06:01:59 INFO - Running post-run listener: copy_logs_to_upload_dir 06:01:59 INFO - Copying logs to upload dir... 06:01:59 INFO - mkdir: /builds/slave/test/build/upload/logs 06:01:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.826170 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-27 06:01:59.132371) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-27 06:01:59.136840) ========= 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.NhVgXCzjIc/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.okCY7deVCM/Listeners TMPDIR=/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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/Fx-Team/sha512/fe519ef04d5c0e5d96cf338be43657ab7ebd48f5204c50199f73fac1c236f4c4f5c118d6bd612cfe6f6e0c1fa3d009d5c91b0d34d19cb1e450658fbc6f8e0c17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ae0c7ebb522581c6c1a3be745305d6b6f90b11ab6875ed6d7bef6a976358b79fdaeafee554ad10449055a9ba7f1aa720f52f9c06f2344e08a321ca39cf1cc2d4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2824e81d41e0998474019e1755c7a83eaa41b72c9fbfd2d991424a10f9cb57517418e2f4be498c12d6e96d2bab90c1744e5c4eaf7bdb3c674ebd1d4e01297fa2"} build_url:https://queue.taskcluster.net/v1/task/NglRZBIiS2mAVv1RUgeeKg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011432 build_url: 'https://queue.taskcluster.net/v1/task/NglRZBIiS2mAVv1RUgeeKg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fe519ef04d5c0e5d96cf338be43657ab7ebd48f5204c50199f73fac1c236f4c4f5c118d6bd612cfe6f6e0c1fa3d009d5c91b0d34d19cb1e450658fbc6f8e0c17", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ae0c7ebb522581c6c1a3be745305d6b6f90b11ab6875ed6d7bef6a976358b79fdaeafee554ad10449055a9ba7f1aa720f52f9c06f2344e08a321ca39cf1cc2d4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2824e81d41e0998474019e1755c7a83eaa41b72c9fbfd2d991424a10f9cb57517418e2f4be498c12d6e96d2bab90c1744e5c4eaf7bdb3c674ebd1d4e01297fa2"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-27 06:01:59.177710) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 06:01:59.178071) ========= 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.NhVgXCzjIc/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.okCY7deVCM/Listeners TMPDIR=/var/folders/kg/2ls1v8cn01s24rdksn7m4cqw00000w/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.005137 ========= master_lag: 0.52 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 06:01:59.705839) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-27 06:01:59.706198) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-27 06:01:59.706615) ========= ========= Total master_lag: 0.68 =========