builder: pine_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0030 starttime: 1491956216.71 results: success (0) buildid: 20170411150208 builduid: b6466c398afe47a8a77c7b4bb337bc4d revision: 32286c49912c0e68c22b57cc8ff85ee6470a90c1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-11 17:16:56.708703) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-11 17:16:56.709149) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-11 17:16:56.709450) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-11 17:16:56.748623) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 17:16:56.748920) ========= 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.KgsNtx65kq/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.OKpRdnCgDr/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 --2017-04-11 17:16:56-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.5M=0.001s 2017-04-11 17:16:56 (14.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.129734 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 17:16:56.902470) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 17:16:56.902790) ========= 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.KgsNtx65kq/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.OKpRdnCgDr/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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.029362 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 17:16:56.954113) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-11 17:16:56.954496) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev 32286c49912c0e68c22b57cc8ff85ee6470a90c1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/pine --rev 32286c49912c0e68c22b57cc8ff85ee6470a90c1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KgsNtx65kq/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.OKpRdnCgDr/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 2017-04-11 17:16:57,037 truncating revision to first 12 chars 2017-04-11 17:16:57,037 Setting DEBUG logging. 2017-04-11 17:16:57,037 attempt 1/10 2017-04-11 17:16:57,037 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/32286c49912c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-11 17:16:58,091 unpacking tar archive at: pine-32286c49912c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.619139 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-11 17:16:58.596993) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-11 17:16:58.597476) ========= script_repo_revision: 32286c49912c0e68c22b57cc8ff85ee6470a90c1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-11 17:16:58.597888) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-11 17:16:58.598168) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-11 17:16:58.618031) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-04-11 17:16:58.618334) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Pine', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Pine'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KgsNtx65kq/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.OKpRdnCgDr/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 17:16:58 INFO - MultiFileLogger online at 20170411 17:16:58 in /builds/slave/test 17:16:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 17:16:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:16:58 INFO - {'append_to_log': False, 17:16:58 INFO - 'base_work_dir': '/builds/slave/test', 17:16:58 INFO - 'blob_upload_branch': 'Pine', 17:16:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:16:58 INFO - 'branch': 'Pine', 17:16:58 INFO - 'buildbot_json_path': 'buildprops.json', 17:16:58 INFO - 'config_files': ('talos/mac_config.py',), 17:16:58 INFO - 'default_actions': ('clobber', 17:16:58 INFO - 'read-buildbot-config', 17:16:58 INFO - 'download-and-extract', 17:16:58 INFO - 'populate-webroot', 17:16:58 INFO - 'create-virtualenv', 17:16:58 INFO - 'install', 17:16:58 INFO - 'run-tests'), 17:16:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:16:58 INFO - 'download_minidump_stackwalk': True, 17:16:58 INFO - 'download_symbols': 'ondemand', 17:16:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:16:58 INFO - 'tooltool.py': '/tools/tooltool.py', 17:16:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:16:58 INFO - '/tools/misc-python/virtualenv.py')}, 17:16:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:16:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:16:58 INFO - 'gecko_profile': False, 17:16:58 INFO - 'gecko_profile_interval': 0, 17:16:58 INFO - 'installer_path': 'installer.exe', 17:16:58 INFO - 'log_level': 'info', 17:16:58 INFO - 'log_name': 'talos', 17:16:58 INFO - 'log_to_console': True, 17:16:58 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:16:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:16:58 INFO - 'opt_config_files': (), 17:16:58 INFO - 'pip_index': False, 17:16:58 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:16:58 INFO - 'cmd': ('bash', 17:16:58 INFO - '-c', 17:16:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:16:58 INFO - 'enabled': True, 17:16:58 INFO - 'halt_on_failure': False, 17:16:58 INFO - 'name': 'check_screen_resolution'},), 17:16:58 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:16:58 INFO - 'cmd': ('bash', 17:16:58 INFO - '-c', 17:16:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:16:58 INFO - 'enabled': True, 17:16:58 INFO - 'halt_on_failure': False, 17:16:58 INFO - 'name': 'check_screen_resolution'},), 17:16:58 INFO - 'run_cmd_checks_enabled': True, 17:16:58 INFO - 'suite': 'dromaeojs-e10s', 17:16:58 INFO - 'system_bits': '32', 17:16:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:16:58 INFO - 'title': 't-yosemite-r7-0030', 17:16:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:16:58 INFO - 'use_talos_json': True, 17:16:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:16:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:16:58 INFO - 'work_dir': 'build'} 17:16:58 INFO - [mozharness: 2017-04-12 00:16:58.812749Z] Running clobber step. 17:16:58 INFO - Running pre-action listener: _resource_record_pre_action 17:16:58 INFO - Running main action method: clobber 17:16:58 INFO - rmtree: /builds/slave/test/build 17:16:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:17:01 INFO - Running post-action listener: _resource_record_post_action 17:17:01 INFO - [mozharness: 2017-04-12 00:17:01.510051Z] Finished clobber step (success) 17:17:01 INFO - [mozharness: 2017-04-12 00:17:01.510235Z] Running read-buildbot-config step. 17:17:01 INFO - Running pre-action listener: _resource_record_pre_action 17:17:01 INFO - Running main action method: read_buildbot_config 17:17:01 INFO - Using buildbot properties: 17:17:01 INFO - { 17:17:01 INFO - "project": "", 17:17:01 INFO - "product": "firefox", 17:17:01 INFO - "script_repo_revision": "default", 17:17:01 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs-e10s", 17:17:01 INFO - "repository": "", 17:17:01 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs-e10s", 17:17:01 INFO - "buildid": "20170411150208", 17:17:01 INFO - "pgo_build": "False", 17:17:01 INFO - "basedir": "/builds/slave/test", 17:17:01 INFO - "buildnumber": 8, 17:17:01 INFO - "slavename": "t-yosemite-r7-0030", 17:17:01 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 17:17:01 INFO - "platform": "yosemite_r7", 17:17:01 INFO - "scheduler": "tests-pine-macosx64-talos", 17:17:01 INFO - "branch": "pine", 17:17:01 INFO - "repo_path": "projects/pine", 17:17:01 INFO - "revision": "32286c49912c0e68c22b57cc8ff85ee6470a90c1", 17:17:01 INFO - "stage_platform": "macosx64", 17:17:01 INFO - "builduid": "b6466c398afe47a8a77c7b4bb337bc4d", 17:17:01 INFO - "slavebuilddir": "test" 17:17:01 INFO - } 17:17:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/Aem_OVofRHSs4hlCu-s-2A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 17:17:01 INFO - Running post-action listener: _resource_record_post_action 17:17:01 INFO - [mozharness: 2017-04-12 00:17:01.513734Z] Finished read-buildbot-config step (success) 17:17:01 INFO - [mozharness: 2017-04-12 00:17:01.513852Z] Running download-and-extract step. 17:17:01 INFO - Running pre-action listener: _resource_record_pre_action 17:17:01 INFO - Running main action method: download_and_extract 17:17:01 INFO - mkdir: /builds/slave/test/build/tests 17:17:01 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')]} 17:17:01 INFO - trying https://queue.taskcluster.net/v1/task/Aem_OVofRHSs4hlCu-s-2A/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 17:17:01 INFO - Downloading https://queue.taskcluster.net/v1/task/Aem_OVofRHSs4hlCu-s-2A/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 17:17:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Aem_OVofRHSs4hlCu-s-2A/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 17:17:02 INFO - Downloaded 1325 bytes. 17:17:02 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 17:17:02 INFO - Using the following test package requirements: 17:17:02 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:17:02 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 17:17:02 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:17:02 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:17:02 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 17:17:02 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:17:02 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 17:17:02 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:17:02 INFO - u'jsshell-mac.zip'], 17:17:02 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:17:02 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 17:17:02 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:17:02 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:17:02 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 17:17:02 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:17:02 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 17:17:02 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:17:02 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 17:17:02 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:17:02 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 17:17:02 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:17:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Aem_OVofRHSs4hlCu-s-2A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 17:17:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Aem_OVofRHSs4hlCu-s-2A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:17:02 INFO - Fetch https://queue.taskcluster.net/v1/task/Aem_OVofRHSs4hlCu-s-2A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 17:17:04 INFO - Expected file size: 52581535 17:17:04 INFO - Obtained file size: 52581535 17:17:20 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 17:17:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Aem_OVofRHSs4hlCu-s-2A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 17:17:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Aem_OVofRHSs4hlCu-s-2A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:17:20 INFO - Fetch https://queue.taskcluster.net/v1/task/Aem_OVofRHSs4hlCu-s-2A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 17:17:22 INFO - Expected file size: 52581535 17:17:22 INFO - Obtained file size: 52581535 17:17:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Aem_OVofRHSs4hlCu-s-2A/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 17:17:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Aem_OVofRHSs4hlCu-s-2A/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 17:17:38 INFO - Fetch https://queue.taskcluster.net/v1/task/Aem_OVofRHSs4hlCu-s-2A/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 17:17:39 INFO - Expected file size: 14405596 17:17:39 INFO - Obtained file size: 14405596 17:17:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:17:39 INFO - trying https://queue.taskcluster.net/v1/task/Aem_OVofRHSs4hlCu-s-2A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:17:39 INFO - Downloading https://queue.taskcluster.net/v1/task/Aem_OVofRHSs4hlCu-s-2A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 17:17:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Aem_OVofRHSs4hlCu-s-2A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 17:17:41 INFO - Downloaded 71000911 bytes. 17:17:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Aem_OVofRHSs4hlCu-s-2A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:17:41 INFO - mkdir: /builds/slave/test/properties 17:17:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:17:41 INFO - Writing to file /builds/slave/test/properties/build_url 17:17:41 INFO - Contents: 17:17:41 INFO - build_url:https://queue.taskcluster.net/v1/task/Aem_OVofRHSs4hlCu-s-2A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:17:42 INFO - Running post-action listener: _resource_record_post_action 17:17:42 INFO - Running post-action listener: set_extra_try_arguments 17:17:42 INFO - [mozharness: 2017-04-12 00:17:42.548041Z] Finished download-and-extract step (success) 17:17:42 INFO - [mozharness: 2017-04-12 00:17:42.548161Z] Running populate-webroot step. 17:17:42 INFO - Running pre-action listener: _resource_record_pre_action 17:17:42 INFO - Running main action method: populate_webroot 17:17:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:17:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:17:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:17:42 INFO - '--tppagecycles', 17:17:42 INFO - '7'], 17:17:42 INFO - 'tests': ['tsvgm']}}, 17:17:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:17:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:17:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:17:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:17:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:17:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:17:42 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:17:42 INFO - 'talos_options': ['--disable-e10s'], 17:17:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:17:42 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:17:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:17:42 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:17:42 INFO - 'talos_options': ['--disable-e10s'], 17:17:42 INFO - 'tests': ['damp', 'tps']}, 17:17:42 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:17:42 INFO - 'tests': ['damp', 'tps']}, 17:17:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:17:42 INFO - 'tests': ['dromaeo_dom']}, 17:17:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:17:42 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:17:42 INFO - 'tests': ['basic_compositor_video']}, 17:17:42 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:17:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:17:42 INFO - 'tests': ['a11yr', 17:17:42 INFO - 'ts_paint', 17:17:42 INFO - 'tpaint', 17:17:42 INFO - 'sessionrestore', 17:17:42 INFO - 'sessionrestore_no_auto_restore', 17:17:42 INFO - 'tabpaint']}, 17:17:42 INFO - 'other-e10s': {'tests': ['a11yr', 17:17:42 INFO - 'ts_paint', 17:17:42 INFO - 'tpaint', 17:17:42 INFO - 'sessionrestore', 17:17:42 INFO - 'sessionrestore_no_auto_restore', 17:17:42 INFO - 'tabpaint']}, 17:17:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:17:42 INFO - 'ts_paint', 17:17:42 INFO - 'tpaint', 17:17:42 INFO - 'sessionrestore', 17:17:42 INFO - 'sessionrestore_no_auto_restore', 17:17:42 INFO - 'tabpaint']}, 17:17:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:17:42 INFO - 'ts_paint', 17:17:42 INFO - 'tpaint', 17:17:42 INFO - 'sessionrestore', 17:17:42 INFO - 'sessionrestore_no_auto_restore', 17:17:42 INFO - 'tabpaint']}, 17:17:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:17:42 INFO - 'tests': ['a11yr', 17:17:42 INFO - 'ts_paint', 17:17:42 INFO - 'tpaint', 17:17:42 INFO - 'sessionrestore', 17:17:42 INFO - 'sessionrestore_no_auto_restore', 17:17:42 INFO - 'tabpaint']}, 17:17:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:17:42 INFO - 'tests': ['a11yr', 17:17:42 INFO - 'ts_paint', 17:17:42 INFO - 'tpaint', 17:17:42 INFO - 'sessionrestore', 17:17:42 INFO - 'sessionrestore_no_auto_restore', 17:17:42 INFO - 'tabpaint']}, 17:17:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:17:42 INFO - 'tests': ['tsvgx', 17:17:42 INFO - 'tsvgr_opacity', 17:17:42 INFO - 'tart', 17:17:42 INFO - 'tscrollx', 17:17:42 INFO - 'cart', 17:17:42 INFO - 'tsvg_static']}, 17:17:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:17:42 INFO - 'tsvgr_opacity', 17:17:42 INFO - 'tart', 17:17:42 INFO - 'tscrollx', 17:17:42 INFO - 'cart', 17:17:42 INFO - 'tsvg_static']}, 17:17:42 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:17:42 INFO - 'talos_options': ['--disable-e10s'], 17:17:42 INFO - 'tests': ['tp5o']}, 17:17:42 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:17:42 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:17:42 INFO - 'talos_options': ['--disable-e10s', 17:17:42 INFO - '--xperf_path', 17:17:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:17:42 INFO - 'tests': ['tp5n']}, 17:17:42 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:17:42 INFO - 'talos_options': ['--xperf_path', 17:17:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:17:42 INFO - 'tests': ['tp5n']}}, 17:17:42 INFO - 'talos.zip': {'path': '', 17:17:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:17:42 INFO - Running post-action listener: _resource_record_post_action 17:17:42 INFO - [mozharness: 2017-04-12 00:17:42.566806Z] Finished populate-webroot step (success) 17:17:42 INFO - [mozharness: 2017-04-12 00:17:42.566935Z] Running create-virtualenv step. 17:17:42 INFO - Running pre-action listener: _resource_record_pre_action 17:17:42 INFO - Running main action method: create_virtualenv 17:17:42 INFO - Creating virtualenv /builds/slave/test/build/venv 17:17:42 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 17:17:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:17:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:17:42 INFO - Using real prefix '/tools/python27' 17:17:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:17:43 INFO - Installing distribute.............................................................................................................................................................................................done. 17:17:46 INFO - Installing pip.................done. 17:17:46 INFO - Return code: 0 17:17:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:17:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:17:46 INFO - Reading from file tmpfile_stdout 17:17:46 INFO - Output received: 17:17:46 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) 17:17:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:17:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:17:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:17:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107d7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc572d9c7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b5cd30>, '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 0x107a83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b51be8>, '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.KgsNtx65kq/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.OKpRdnCgDr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 17:17:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:17:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:17:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KgsNtx65kq/Render', 17:17:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:46 INFO - 'HOME': '/Users/cltbld', 17:17:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:46 INFO - 'LOGNAME': 'cltbld', 17:17:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:46 INFO - 'MOZ_NO_REMOTE': '1', 17:17:46 INFO - 'NO_EM_RESTART': '1', 17:17:46 INFO - 'PAGER': '/bin/cat', 17:17:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:46 INFO - 'PWD': '/builds/slave/test', 17:17:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:46 INFO - 'SHELL': '/bin/bash', 17:17:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OKpRdnCgDr/Listeners', 17:17:46 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 17:17:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:46 INFO - 'USER': 'cltbld', 17:17:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:46 INFO - 'XPC_FLAGS': '0x0', 17:17:46 INFO - 'XPC_SERVICE_NAME': '0', 17:17:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:46 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 17:17:46 INFO - Cleaning up... 17:17:46 INFO - Return code: 0 17:17:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:17:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:17:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:17:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107d7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc572d9c7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b5cd30>, '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 0x107a83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b51be8>, '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.KgsNtx65kq/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.OKpRdnCgDr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 17:17:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 17:17:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:17:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KgsNtx65kq/Render', 17:17:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:46 INFO - 'HOME': '/Users/cltbld', 17:17:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:46 INFO - 'LOGNAME': 'cltbld', 17:17:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:46 INFO - 'MOZ_NO_REMOTE': '1', 17:17:46 INFO - 'NO_EM_RESTART': '1', 17:17:46 INFO - 'PAGER': '/bin/cat', 17:17:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:46 INFO - 'PWD': '/builds/slave/test', 17:17:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:46 INFO - 'SHELL': '/bin/bash', 17:17:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OKpRdnCgDr/Listeners', 17:17:46 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 17:17:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:46 INFO - 'USER': 'cltbld', 17:17:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:46 INFO - 'XPC_FLAGS': '0x0', 17:17:46 INFO - 'XPC_SERVICE_NAME': '0', 17:17:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:47 INFO - Downloading/unpacking psutil>=3.1.1 17:17:47 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 17:17:47 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 17:17:47 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 17:17:47 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 17:17:47 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 17:17:47 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 17:17:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:17:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:17:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:17:50 INFO - Installing collected packages: psutil 17:17:50 INFO - Running setup.py install for psutil 17:17:50 INFO - building 'psutil._psutil_osx' extension 17:17:50 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 17:17:51 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 17:17:51 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 17:17:51 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 17:17:51 INFO - building 'psutil._psutil_posix' extension 17:17:51 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 17:17:51 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:17:51 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:17:51 INFO - ^ 17:17:51 INFO - 1 warning generated. 17:17:51 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 17:17:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:17:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:17:51 INFO - Successfully installed psutil 17:17:51 INFO - Cleaning up... 17:17:51 INFO - Return code: 0 17:17:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:17:51 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')]} 17:17:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:51 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')]} 17:17:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:51 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 0x107d7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc572d9c7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b5cd30>, '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 0x107a83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b51be8>, '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.KgsNtx65kq/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.OKpRdnCgDr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 17:17:51 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 17:17:51 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 17:17:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KgsNtx65kq/Render', 17:17:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:51 INFO - 'HOME': '/Users/cltbld', 17:17:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:51 INFO - 'LOGNAME': 'cltbld', 17:17:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:51 INFO - 'MOZ_NO_REMOTE': '1', 17:17:51 INFO - 'NO_EM_RESTART': '1', 17:17:51 INFO - 'PAGER': '/bin/cat', 17:17:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:51 INFO - 'PWD': '/builds/slave/test', 17:17:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:51 INFO - 'SHELL': '/bin/bash', 17:17:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OKpRdnCgDr/Listeners', 17:17:51 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 17:17:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:51 INFO - 'USER': 'cltbld', 17:17:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:51 INFO - 'XPC_FLAGS': '0x0', 17:17:51 INFO - 'XPC_SERVICE_NAME': '0', 17:17:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:17:52 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 17:17:52 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 17:17:52 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 17:17:52 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 17:17:52 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 17:17:52 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 17:17:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:17:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:17:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:17:54 INFO - Installing collected packages: mozsystemmonitor 17:17:54 INFO - Running setup.py install for mozsystemmonitor 17:17:54 INFO - Successfully installed mozsystemmonitor 17:17:54 INFO - Cleaning up... 17:17:55 INFO - Return code: 0 17:17:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:17:55 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')]} 17:17:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:55 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')]} 17:17:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:55 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 0x107d7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc572d9c7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b5cd30>, '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 0x107a83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b51be8>, '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.KgsNtx65kq/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.OKpRdnCgDr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 17:17:55 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 17:17:55 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 17:17:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KgsNtx65kq/Render', 17:17:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:55 INFO - 'HOME': '/Users/cltbld', 17:17:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:55 INFO - 'LOGNAME': 'cltbld', 17:17:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:55 INFO - 'MOZ_NO_REMOTE': '1', 17:17:55 INFO - 'NO_EM_RESTART': '1', 17:17:55 INFO - 'PAGER': '/bin/cat', 17:17:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:55 INFO - 'PWD': '/builds/slave/test', 17:17:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:55 INFO - 'SHELL': '/bin/bash', 17:17:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OKpRdnCgDr/Listeners', 17:17:55 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 17:17:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:55 INFO - 'USER': 'cltbld', 17:17:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:55 INFO - 'XPC_FLAGS': '0x0', 17:17:55 INFO - 'XPC_SERVICE_NAME': '0', 17:17:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:56 INFO - Downloading/unpacking jsonschema==2.5.1 17:17:56 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 17:17:56 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 17:17:56 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 17:17:56 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 17:17:56 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 17:17:56 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 17:17:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:17:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:17:59 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 17:17:59 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 17:17:59 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 17:17:59 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 17:17:59 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 17:17:59 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 17:17:59 INFO - Downloading functools32-3.2.3-2.tar.gz 17:17:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:18:00 INFO - warning: no files found matching '*.txt' 17:18:00 INFO - no previously-included directories found matching 'build' 17:18:00 INFO - no previously-included directories found matching 'dist' 17:18:00 INFO - no previously-included directories found matching '.git*' 17:18:00 INFO - Installing collected packages: jsonschema, functools32 17:18:00 INFO - Running setup.py install for functools32 17:18:00 INFO - warning: no files found matching '*.txt' 17:18:00 INFO - no previously-included directories found matching 'build' 17:18:00 INFO - no previously-included directories found matching 'dist' 17:18:00 INFO - no previously-included directories found matching '.git*' 17:18:00 INFO - Successfully installed jsonschema functools32 17:18:00 INFO - Cleaning up... 17:18:00 INFO - Return code: 0 17:18:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:18:00 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')]} 17:18:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:18:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:00 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')]} 17:18:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:18:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:00 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 0x107d7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc572d9c7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b5cd30>, '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 0x107a83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b51be8>, '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.KgsNtx65kq/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.OKpRdnCgDr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 17:18:00 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 17:18:00 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 17:18:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KgsNtx65kq/Render', 17:18:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:18:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:18:00 INFO - 'HOME': '/Users/cltbld', 17:18:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:18:00 INFO - 'LOGNAME': 'cltbld', 17:18:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:18:00 INFO - 'MOZ_NO_REMOTE': '1', 17:18:00 INFO - 'NO_EM_RESTART': '1', 17:18:00 INFO - 'PAGER': '/bin/cat', 17:18:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:18:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:18:00 INFO - 'PWD': '/builds/slave/test', 17:18:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:18:00 INFO - 'SHELL': '/bin/bash', 17:18:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OKpRdnCgDr/Listeners', 17:18:00 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 17:18:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:18:00 INFO - 'USER': 'cltbld', 17:18:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:18:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:18:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:18:00 INFO - 'XPC_FLAGS': '0x0', 17:18:00 INFO - 'XPC_SERVICE_NAME': '0', 17:18:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:18:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:18:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:18:00 INFO - Cleaning up... 17:18:00 INFO - Return code: 0 17:18:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:18:00 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')]} 17:18:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:18:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:00 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')]} 17:18:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:18:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:00 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 0x107d7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc572d9c7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b5cd30>, '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 0x107a83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b51be8>, '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.KgsNtx65kq/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.OKpRdnCgDr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 17:18:00 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 17:18:00 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 17:18:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KgsNtx65kq/Render', 17:18:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:18:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:18:00 INFO - 'HOME': '/Users/cltbld', 17:18:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:18:00 INFO - 'LOGNAME': 'cltbld', 17:18:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:18:00 INFO - 'MOZ_NO_REMOTE': '1', 17:18:00 INFO - 'NO_EM_RESTART': '1', 17:18:00 INFO - 'PAGER': '/bin/cat', 17:18:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:18:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:18:00 INFO - 'PWD': '/builds/slave/test', 17:18:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:18:00 INFO - 'SHELL': '/bin/bash', 17:18:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OKpRdnCgDr/Listeners', 17:18:00 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 17:18:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:18:00 INFO - 'USER': 'cltbld', 17:18:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:18:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:18:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:18:00 INFO - 'XPC_FLAGS': '0x0', 17:18:00 INFO - 'XPC_SERVICE_NAME': '0', 17:18:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:18:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:18:00 INFO - Downloading/unpacking blobuploader==1.2.4 17:18:00 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 17:18:00 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 17:18:00 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 17:18:00 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 17:18:00 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 17:18:00 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 17:18:03 INFO - Downloading blobuploader-1.2.4.tar.gz 17:18:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:18:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:18:03 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 17:18:03 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 17:18:03 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 17:18:03 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 17:18:03 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 17:18:03 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 17:18:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:18:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:18:04 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 17:18:04 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 17:18:04 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 17:18:04 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 17:18:04 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 17:18:04 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 17:18:04 INFO - Downloading docopt-0.6.1.tar.gz 17:18:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:18:04 INFO - Installing collected packages: blobuploader, requests, docopt 17:18:04 INFO - Running setup.py install for blobuploader 17:18:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:18:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:18:04 INFO - Running setup.py install for requests 17:18:05 INFO - Running setup.py install for docopt 17:18:05 INFO - Successfully installed blobuploader requests docopt 17:18:05 INFO - Cleaning up... 17:18:05 INFO - Return code: 0 17:18:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:18:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:18:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:18:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:18:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:18:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:05 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 0x107d7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc572d9c7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b5cd30>, '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 0x107a83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b51be8>, '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.KgsNtx65kq/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.OKpRdnCgDr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 17:18:05 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 17:18:05 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 17:18:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KgsNtx65kq/Render', 17:18:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:18:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:18:05 INFO - 'HOME': '/Users/cltbld', 17:18:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:18:05 INFO - 'LOGNAME': 'cltbld', 17:18:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:18:05 INFO - 'MOZ_NO_REMOTE': '1', 17:18:05 INFO - 'NO_EM_RESTART': '1', 17:18:05 INFO - 'PAGER': '/bin/cat', 17:18:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:18:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:18:05 INFO - 'PWD': '/builds/slave/test', 17:18:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:18:05 INFO - 'SHELL': '/bin/bash', 17:18:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OKpRdnCgDr/Listeners', 17:18:05 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 17:18:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:18:05 INFO - 'USER': 'cltbld', 17:18:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:18:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:18:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:18:05 INFO - 'XPC_FLAGS': '0x0', 17:18:05 INFO - 'XPC_SERVICE_NAME': '0', 17:18:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:18:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:18:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:18:05 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-fdB6Sh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:18:06 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-Xrq07Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:18:06 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-QicVvQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:18:06 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-ygXGLd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:18:06 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-FczWQz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:18:06 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-EM5JzU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:18:06 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-_KBgLI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:18:06 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-C3YiMh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:18:06 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-QqD9s7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:18:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:18:06 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-L2qTps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:18:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:18:07 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-r1OdlC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:18:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:18:07 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-48qY7F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:18:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:18:07 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-Vry3CA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:18:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:18:07 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-xs_gmU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:18:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:18:07 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-BLzefZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:18:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:18:07 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-40tlAd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:18:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:18:07 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-K3oKeh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:18:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:18:07 INFO - Running setup.py install for manifestparser 17:18:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:18:07 INFO - Running setup.py install for mozcrash 17:18:08 INFO - Running setup.py install for mozdebug 17:18:08 INFO - Running setup.py install for mozdevice 17:18:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:18:08 INFO - Running setup.py install for mozfile 17:18:08 INFO - Running setup.py install for mozhttpd 17:18:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:18:08 INFO - Running setup.py install for mozinfo 17:18:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:18:08 INFO - Running setup.py install for mozInstall 17:18:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:18:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:18:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:18:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:18:08 INFO - Running setup.py install for mozleak 17:18:09 INFO - Running setup.py install for mozlog 17:18:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:18:09 INFO - Running setup.py install for moznetwork 17:18:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:18:09 INFO - Running setup.py install for mozprocess 17:18:09 INFO - Running setup.py install for mozprofile 17:18:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:18:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:18:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:18:09 INFO - Running setup.py install for mozrunner 17:18:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:18:09 INFO - Running setup.py install for mozscreenshot 17:18:09 INFO - Running setup.py install for moztest 17:18:10 INFO - Running setup.py install for mozversion 17:18:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:18:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:18:10 INFO - Cleaning up... 17:18:10 INFO - Return code: 0 17:18:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:18:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:18:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:18:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:18:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:18:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:10 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 0x107d7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc572d9c7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b5cd30>, '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 0x107a83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b51be8>, '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.KgsNtx65kq/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.OKpRdnCgDr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 17:18:10 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 17:18:10 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 17:18:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KgsNtx65kq/Render', 17:18:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:18:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:18:10 INFO - 'HOME': '/Users/cltbld', 17:18:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:18:10 INFO - 'LOGNAME': 'cltbld', 17:18:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:18:10 INFO - 'MOZ_NO_REMOTE': '1', 17:18:10 INFO - 'NO_EM_RESTART': '1', 17:18:10 INFO - 'PAGER': '/bin/cat', 17:18:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:18:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:18:10 INFO - 'PWD': '/builds/slave/test', 17:18:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:18:10 INFO - 'SHELL': '/bin/bash', 17:18:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OKpRdnCgDr/Listeners', 17:18:10 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 17:18:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:18:10 INFO - 'USER': 'cltbld', 17:18:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:18:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:18:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:18:10 INFO - 'XPC_FLAGS': '0x0', 17:18:10 INFO - 'XPC_SERVICE_NAME': '0', 17:18:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:18:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:18:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:18:10 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-5t2L1U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:18:10 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)) 17:18:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:18:10 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-MMEvB6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:18:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:18:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:18:10 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-8BCjNJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:18:10 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)) 17:18:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:18:10 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-H2CQJ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:18:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 17:18:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:18:11 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-eyJ5TN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:18:11 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)) 17:18:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:18:11 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-KTRJJN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:18:11 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)) 17:18:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:18:11 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-pAEvoL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:18:11 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)) 17:18:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:18:11 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-tKqlV4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:18:11 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)) 17:18:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:18:11 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-MQRgLL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:18:11 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)) 17:18:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:18:11 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-F80bYN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:18:11 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)) 17:18:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:18:11 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-QJadR9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:18:11 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)) 17:18:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:18:11 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-PsJMk9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:18:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 17:18:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:18:11 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-VStL8Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:18:11 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)) 17:18:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:18:11 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-f_MmM0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:18:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 17:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:18:12 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-tMn_4a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:18:12 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)) 17:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:18:12 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-ECj66A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:18:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 17:18:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:18:12 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-_aQyj8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:18:12 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)) 17:18:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:18:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:18:12 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)) 17:18:12 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:18:12 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:18:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:18:12 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 17:18:12 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 17:18:12 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 17:18:12 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 17:18:12 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 17:18:12 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 17:18:15 INFO - Downloading blessings-1.6.tar.gz 17:18:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:18:15 INFO - Installing collected packages: blessings 17:18:15 INFO - Running setup.py install for blessings 17:18:15 INFO - Successfully installed blessings 17:18:15 INFO - Cleaning up... 17:18:15 INFO - Return code: 0 17:18:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:18:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:18:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:18:15 INFO - Reading from file tmpfile_stdout 17:18:15 INFO - Current package versions: 17:18:15 INFO - blessings == 1.6 17:18:15 INFO - blobuploader == 1.2.4 17:18:15 INFO - docopt == 0.6.1 17:18:15 INFO - functools32 == 3.2.3-2 17:18:15 INFO - jsonschema == 2.5.1 17:18:15 INFO - manifestparser == 1.1 17:18:15 INFO - mozInstall == 1.12 17:18:15 INFO - mozcrash == 1.0 17:18:15 INFO - mozdebug == 0.1 17:18:15 INFO - mozdevice == 0.50 17:18:15 INFO - mozfile == 1.2 17:18:15 INFO - mozhttpd == 0.7 17:18:15 INFO - mozinfo == 0.9 17:18:15 INFO - mozleak == 0.1 17:18:15 INFO - mozlog == 3.4 17:18:15 INFO - moznetwork == 0.27 17:18:15 INFO - mozprocess == 0.25 17:18:15 INFO - mozprofile == 0.28 17:18:15 INFO - mozrunner == 6.13 17:18:15 INFO - mozscreenshot == 0.1 17:18:15 INFO - mozsystemmonitor == 0.3 17:18:15 INFO - moztest == 0.8 17:18:15 INFO - mozversion == 1.4 17:18:15 INFO - psutil == 3.1.1 17:18:15 INFO - requests == 1.2.3 17:18:15 INFO - wsgiref == 0.1.2 17:18:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:18:15 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')]} 17:18:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:18:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:15 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')]} 17:18:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:18:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:15 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 0x107d7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc572d9c7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b5cd30>, '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 0x107a83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b51be8>, '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.KgsNtx65kq/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.OKpRdnCgDr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 17:18:15 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 17:18:15 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 17:18:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KgsNtx65kq/Render', 17:18:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:18:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:18:15 INFO - 'HOME': '/Users/cltbld', 17:18:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:18:15 INFO - 'LOGNAME': 'cltbld', 17:18:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:18:15 INFO - 'MOZ_NO_REMOTE': '1', 17:18:15 INFO - 'NO_EM_RESTART': '1', 17:18:15 INFO - 'PAGER': '/bin/cat', 17:18:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:18:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:18:15 INFO - 'PWD': '/builds/slave/test', 17:18:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:18:15 INFO - 'SHELL': '/bin/bash', 17:18:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OKpRdnCgDr/Listeners', 17:18:15 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 17:18:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:18:15 INFO - 'USER': 'cltbld', 17:18:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:18:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:18:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:18:15 INFO - 'XPC_FLAGS': '0x0', 17:18:15 INFO - 'XPC_SERVICE_NAME': '0', 17:18:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:18:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:18:15 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)) 17:18:15 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)) 17:18:15 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)) 17:18:15 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)) 17:18:15 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)) 17:18:15 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)) 17:18:15 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)) 17:18:15 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)) 17:18:15 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)) 17:18:15 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)) 17:18:15 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)) 17:18:15 INFO - Cleaning up... 17:18:15 INFO - Return code: 0 17:18:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:18:15 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')]} 17:18:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:18:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:15 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')]} 17:18:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:18:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:15 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 0x107d7eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d81768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc572d9c7d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b5cd30>, '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 0x107a83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b51be8>, '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.KgsNtx65kq/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.OKpRdnCgDr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 17:18:15 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 17:18:15 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 17:18:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KgsNtx65kq/Render', 17:18:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:18:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:18:15 INFO - 'HOME': '/Users/cltbld', 17:18:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:18:15 INFO - 'LOGNAME': 'cltbld', 17:18:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:18:15 INFO - 'MOZ_NO_REMOTE': '1', 17:18:15 INFO - 'NO_EM_RESTART': '1', 17:18:15 INFO - 'PAGER': '/bin/cat', 17:18:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:18:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:18:15 INFO - 'PWD': '/builds/slave/test', 17:18:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:18:15 INFO - 'SHELL': '/bin/bash', 17:18:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OKpRdnCgDr/Listeners', 17:18:15 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 17:18:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:18:15 INFO - 'USER': 'cltbld', 17:18:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:18:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:18:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:18:15 INFO - 'XPC_FLAGS': '0x0', 17:18:15 INFO - 'XPC_SERVICE_NAME': '0', 17:18:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:18:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:18:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:18:16 INFO - Cleaning up... 17:18:16 INFO - Return code: 0 17:18:16 INFO - Running post-action listener: _resource_record_post_action 17:18:16 INFO - Running post-action listener: _start_resource_monitoring 17:18:16 INFO - Starting resource monitoring. 17:18:16 INFO - [mozharness: 2017-04-12 00:18:16.271328Z] Finished create-virtualenv step (success) 17:18:16 INFO - [mozharness: 2017-04-12 00:18:16.272253Z] Running install step. 17:18:16 INFO - Running pre-action listener: _resource_record_pre_action 17:18:16 INFO - Running main action method: install 17:18:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:18:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:18:16 INFO - Reading from file tmpfile_stdout 17:18:16 INFO - Detecting whether we're running mozinstall >=1.0... 17:18:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:18:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:18:16 INFO - Reading from file tmpfile_stdout 17:18:16 INFO - Output received: 17:18:16 INFO - Usage: mozinstall [options] installer 17:18:16 INFO - Options: 17:18:16 INFO - -h, --help show this help message and exit 17:18:16 INFO - -d DEST, --destination=DEST 17:18:16 INFO - Directory to install application into. [default: 17:18:16 INFO - "/builds/slave/test"] 17:18:16 INFO - --app=APP Application being installed. [default: firefox] 17:18:16 INFO - mkdir: /builds/slave/test/build/application 17:18:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 17:18:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 17:18:36 INFO - Reading from file tmpfile_stdout 17:18:36 INFO - Output received: 17:18:36 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:18:36 INFO - Running post-action listener: _resource_record_post_action 17:18:36 INFO - [mozharness: 2017-04-12 00:18:36.806384Z] Finished install step (success) 17:18:36 INFO - [mozharness: 2017-04-12 00:18:36.806518Z] Running run-tests step. 17:18:36 INFO - Running pre-action listener: _resource_record_pre_action 17:18:36 INFO - Running main action method: run_tests 17:18:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:18:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:18:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:18:36 INFO - 2017-04-11 17:18:36.821 screenresolution[1621:7441] starting screenresolution argv=screenresolution get 17:18:36 INFO - 2017-04-11 17:18:36.823 screenresolution[1621:7441] Display 0: 1600x1200x32@60 17:18:36 INFO - 2017-04-11 17:18:36.829 screenresolution[1622:7444] starting screenresolution argv=screenresolution list 17:18:36 INFO - Available Modes on Display 0 17:18:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:18:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:18:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:18:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:18:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:18:37 INFO - Intel Iris: 17:18:37 INFO - Chipset Model: Intel Iris 17:18:37 INFO - Type: GPU 17:18:37 INFO - Bus: Built-In 17:18:37 INFO - VRAM (Dynamic, Max): 1536 MB 17:18:37 INFO - Vendor: Intel (0x8086) 17:18:37 INFO - Device ID: 0x0a2e 17:18:37 INFO - Revision ID: 0x0009 17:18:37 INFO - Displays: 17:18:37 INFO - Display: 17:18:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:18:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:18:37 INFO - Display Serial Number: 1600x1200 60 17:18:37 INFO - Main Display: Yes 17:18:37 INFO - Mirror: Off 17:18:37 INFO - Online: Yes 17:18:37 INFO - Rotation: Supported 17:18:37 INFO - Return code: 0 17:18:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:18:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:18:37 INFO - Python 2.7.3 17:18:37 INFO - Return code: 0 17:18:37 INFO - grabbing minidump binary from tooltool 17:18: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')]} 17:18:37 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 0x107b5cd30>, '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 0x107a83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b51be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:18:37 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 17:18:37 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 17:18:37 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 17:18:37 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:18:37 INFO - Return code: 0 17:18:37 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:18:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:18:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:18:37 INFO - ENV: RUST_BACKTRACE is now 1 17:18:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:18:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:18:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Aem_OVofRHSs4hlCu-s-2A/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0030', '--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 17:18:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Pine --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Aem_OVofRHSs4hlCu-s-2A/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0030 --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 17:18:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KgsNtx65kq/Render', 17:18:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:18:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:18:37 INFO - 'HOME': '/Users/cltbld', 17:18:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:18:37 INFO - 'LOGNAME': 'cltbld', 17:18:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:18:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:18:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:18:37 INFO - 'MOZ_NO_REMOTE': '1', 17:18:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:18:37 INFO - 'NO_EM_RESTART': '1', 17:18:37 INFO - 'PAGER': '/bin/cat', 17:18:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:18:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:18:37 INFO - 'PWD': '/builds/slave/test', 17:18:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:18:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:18:37 INFO - 'RUST_BACKTRACE': '1', 17:18:37 INFO - 'SHELL': '/bin/bash', 17:18:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OKpRdnCgDr/Listeners', 17:18:37 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 17:18:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:18:37 INFO - 'USER': 'cltbld', 17:18:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:18:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:18:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:18:37 INFO - 'XPC_FLAGS': '0x0', 17:18:37 INFO - 'XPC_SERVICE_NAME': '0', 17:18:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:18:37 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Aem_OVofRHSs4hlCu-s-2A/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0030', '--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 17:18:37 INFO - mozversion application_buildid: 20170411150208 17:18:37 INFO - mozversion application_changeset: 32286c49912c0e68c22b57cc8ff85ee6470a90c1 17:18:37 INFO - mozversion application_display_name: Nightly 17:18:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:18:37 INFO - mozversion application_name: Firefox 17:18:37 INFO - mozversion application_remotingname: firefox 17:18:37 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 17:18:37 INFO - mozversion application_vendor: Mozilla 17:18:37 INFO - mozversion application_version: 55.0a1 17:18:37 INFO - mozversion platform_buildid: 20170411150208 17:18:37 INFO - mozversion platform_changeset: 32286c49912c0e68c22b57cc8ff85ee6470a90c1 17:18:37 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 17:18:37 INFO - mozversion platform_version: 55.0a1 17:18:37 INFO - using testdate: 1491956317 17:18:37 INFO - actual date: 1491956317 17:18:37 INFO - starting webserver on 'localhost:49235' 17:18:37 INFO - SUITE-START | Running 2 tests 17:18:37 INFO - TEST-START | dromaeo_css 17:18:37 INFO - operating with platform_type : mac_ 17:18:37 INFO - Initialising browser for dromaeo_css test... 17:18:37 INFO - TEST-INFO | started process 1632 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmplENYgE/profile http://localhost:49235/getInfo.html) 17:18:40 INFO - PID 1632 | 2017-04-11 17:18:40.613 plugin-container[1634:7731] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 17:18:40 INFO - PID 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:18:40 INFO - PID 1632 | 2017-04-11 17:18:40.616 plugin-container[1634:7731] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 17:18:40 INFO - PID 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:18:40 INFO - PID 1632 | 2017-04-11 17:18:40.616 plugin-container[1634:7731] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:18:40 INFO - PID 1632 | __metrics Screen width/height:1600/1200 17:18:40 INFO - PID 1632 | colorDepth:24 17:18:40 INFO - PID 1632 | Browser inner width/height: 1024/689 17:18:40 INFO - PID 1632 | __metrics 17:18:40 ERROR - PID 1632 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3364: TypeError: this.tabs is undefined 17:18:41 INFO - TEST-INFO | 1632: exit 0 17:18:41 INFO - Browser initialized. 17:18:41 INFO - Running cycle 1/1 for dromaeo_css test... 17:18:41 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmplENYgE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:18:44 INFO - PID 1635 | 2017-04-11 17:18:44.656 plugin-container[1636:7851] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c43, name = 'com.apple.tsm.portname' 17:18:44 INFO - PID 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:18:44 INFO - PID 1635 | 2017-04-11 17:18:44.656 plugin-container[1636:7851] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9017, name = 'com.apple.CFPasteboardClient' 17:18:44 INFO - PID 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:18:44 INFO - PID 1635 | 2017-04-11 17:18:44.656 plugin-container[1636:7851] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:18:54 INFO - PID 1635 | RSS: Main: 235556864 17:18:54 INFO - PID 1635 | 17:21:58 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49235/tests/dromaeo/cssquery-ext.html) 17:22:00 INFO - PID 1635 | RSS: Main: 256765952 17:22:00 INFO - PID 1635 | 17:23:42 INFO - PID 1635 | ************************* 17:23:42 INFO - PID 1635 | A coding exception was thrown and uncaught in a Task. 17:23:42 INFO - PID 1635 | 17:23:42 ERROR - PID 1635 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:23:42 INFO - PID 1635 | Full stack: 17:23:42 INFO - PID 1635 | ************************* 17:23:42 INFO - PID 1635 | ************************* 17:23:42 INFO - PID 1635 | A coding exception was thrown and uncaught in a Task. 17:23:42 INFO - PID 1635 | 17:23:42 ERROR - PID 1635 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:23:42 INFO - PID 1635 | Full stack: 17:23:42 INFO - PID 1635 | ************************* 17:23:42 INFO - PID 1635 | ************************* 17:23:42 INFO - PID 1635 | A coding exception was thrown and uncaught in a Task. 17:23:42 INFO - PID 1635 | 17:23:42 ERROR - PID 1635 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:23:42 INFO - PID 1635 | Full stack: 17:23:42 INFO - PID 1635 | ************************* 17:23:42 INFO - PID 1635 | ************************* 17:23:42 INFO - PID 1635 | A coding exception was thrown and uncaught in a Task. 17:23:42 INFO - PID 1635 | 17:23:42 ERROR - PID 1635 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:23:42 INFO - PID 1635 | Full stack: 17:23:42 INFO - PID 1635 | ************************* 17:25:02 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-ext.html (next: http://localhost:49235/tests/dromaeo/cssquery-jquery.html) 17:25:03 INFO - PID 1635 | RSS: Main: 263155712 17:25:03 INFO - PID 1635 | 17:28:04 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49235/tests/dromaeo/cssquery-mootools.html) 17:28:05 INFO - PID 1635 | RSS: Main: 274235392 17:28:05 INFO - PID 1635 | 17:31:07 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49235/tests/dromaeo/cssquery-prototype.html) 17:31:07 INFO - PID 1635 | RSS: Main: 269783040 17:31:07 INFO - PID 1635 | 17:34:10 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49235/tests/dromaeo/cssquery-yui.html) 17:34:11 INFO - PID 1635 | RSS: Main: 280035328 17:34:11 INFO - PID 1635 | 17:37:13 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-yui.html (next: http://localhost:49235/tests/dromaeo/cssquery-dojo.html) 17:37:13 INFO - PID 1635 | RSS: Main: 273780736 17:37:13 INFO - PID 1635 | 17:37:13 INFO - PID 1635 | __start_tp_report 17:37:13 INFO - PID 1635 | _x_x_mozilla_page_load 17:37:13 INFO - PID 1635 | _x_x_mozilla_page_load_details 17:37:13 INFO - PID 1635 | |i|pagename|runs| 17:37:13 INFO - PID 1635 | |0;dojo.html;3013.743831774299;3253.6172126846086;3541.415666415042;3552.5559305086863;3570.9464358034634;6167.136600875881;6245.250569931603;6247.625142491448;6259.1863057802475;6296.614744756158;6158.507319414447;6191.349908259635;6214.844628884269;6256.499480041597;6270.216222972129;5392.2181283713835;5450.972745136269;5509.972450137744;5526.447355264461;5576.804811831586;5378.381486129106;5473.698946557938;5564.638298510604;5565.165225216209;5617.21359009739;5339.145736682112;5419.403865574784;5431.293931788881;5485.780568777244;5501;5361.839144825662;5393.78424863009;5451.727413629343;5512.586556008316;5537.972310138463;5398.973005134949;5401.081816091235;5402.162664786925;5426.67439953604;5464.23500709901;6009.609375390585;6215.2541694996435;6261.78083767066;6271.216097987752;6272.2159730033745;6455.419012288917;6546.6072035677535;6603.141591593062;6657.334266573353;6682.665866706694;6750.561213521155;6780.491463140284;6795.150606174228;6809.353111454404;6811.421029212523;6204.317525072239;6317.1156038154695;6333.841653958596;6337;6341.8414539636415;6642.66786660665;6782.118324617768;6838.829029274308;6909.481788865855;6941.861162776716;5239.345081864767;6336.968315158487;6415.550911436248;6526.771562995273;6596;6636.30318816527;6667.866642667216;6757.189137303555;6778.661066946633;6798.93201067983;6662.96668516664;6682;6682.298358672269;6689.866202676017;6696.363845434676;6433.710483028275;6510.479161667055;6689.832754181184;6694.464442844561;6724.66376681164;5210.713410762369;5346.8663283417445;5353.116735738559;5373.086575282211;5389.353277606712;4017;4756.167670657621;4762.76186190696;4775.928361074587;4817.253325734517;6139.492138333381;6310.432061114522;6329.082283068929;6346.11154438379;6355.3644635537;6641.568298060611;6735.057092007123;6744.898826517606;6778.2205046420295;6809.5233333666165;5337.973310133502;5439.755211015514;6296.811095667137;6366.140571022946;6391;6032.336442991267;6049.758009679564;6051.848703782441;6059.363766804422;6062.454379105901;6684.665766711742;6732.865342693217;6843.076184715199;6930.06444130036;6960.582365058112;6789;6797.456203503807;6812.489063320171;6848.554843935129;6873.072135261876;6866.313368663094;6903.5167538271835;6933.618650974245;6943.895841562279;6960.408365288805;6469.997150441689;6531.869362612816;6534.215894092739;6598.076269322202;6641.933580664131;6798.150231221098;6815.659217039228;6832.487563432862;6833;6837.076994605664;5885.411458854081;6339.5562310637815;6570.178727659043;6581.703823327866;6584.539082264196;6499.772507962199;6564.277929427663;6574.737010519717;6590.340965903371;6591;6331.96834015827;6341.048842673451;6449.774257900951;6453.838654033499;6460.35396460369;6308.612105336819;6567.802965911031;6590.703418346282;6612.867742645217;6632.701528431136;6267.717952692049;6337.461315788025;6390.105385246162;6410.61536307823;6417.7753778617525 17:37:13 INFO - PID 1635 | |1;ext.html;619.6808643548572;760.8402235530536;985.5417230987593;1129.7853407852508;1289.4262053386235;36788;38029;38060.42909356355;38090.0477488063;38486.80756596213;37699;38164.42753358702;38185.61814381855;38254.23491530168;38255.852324430314;8069.596520173997;8201.95899020507;8233.711820086299;8575.528345940971;8863.335249856287;8623.913760862406;8722.520261385622;8731.781705457352;8754.168354006391;8755.95622021889;8700.26047785939;8734.86897696535;8743;8789.560521973908;8799;8722.778810966438;8739.519326437043;8740.956295218546;8743;8749.868751968726;8534.914650853474;8562.186592273723;8569.95715021421;8576.828463430758;8590.055093939662;37278.44082338767;37477.437838432445;37534;37552.62447375518;37587.24825503475;3576.2489877125577;3632.891013269606;3639.2721455708966;3641.1443310821937;3644.180059486621;30887;30887.918922837816;30926.91755788559;30930;30950;3151.2752067024485;3172.191091271711;3186.3308705171944;3194.5048517479827;3201.711845933854;279032;280437;281156;281761;283650;39577.604223957394;40517;47572.28641570379;48837.75581122072;49006.26490602644;50412.49587504078;50439.747801261496;50516.74741626342;50588.49411505911;50758.238626420636;14557.636059098395;14581.708365832625;14663.56009319722;14673.633159170893;14688.55934321972;11682.357470339042;11819.231749936227;11821.468033938494;11859.525618975144;11904.464299106561;13468.191908485522;13529.932350338186;13570.914326853828;13621.387037583332;13676.863231367757;5973.1936188614845;5998.340182579913;6040.335563088057;6043.335233124353;6155.446009859134;901.8647202919483;953.1326492891435;1007.1892126837965;1051.821190397619;1083.788661211072;20307.578469506992;20664.380068597664;23811.642825357634;24251.272461826175;24291.514169716505;1037.444966942682;1138.9829152562718;1139.5783560082823;1168.7253495428567;1193.838831757719;12521.93739031299;12537.93731031339;12546.937265313614;12562.05784566143;12580.370981450891;350923;353463;354166;354620;354659;42809.78595107067;46050.618481445614;53285.46714532805;53876.73061634667;54010.91978160415;1495.835458099608;1502.2939218567255;1524.4892960858238;1559.142471640616;1565.831323616799;21679.80761058157;22044.88977555102;23855.761442385356;24216.273511794676;24360.390990225387;38539.42190867083;38551.807240963615;38617.42073868838;38655.226895462496;38713.22573548457;303777;304265;304755;305863;306014;26298.342541436617;34607.4808877862;34674.82662586772;35169.29661406809;35390.64609353874;43679.12641747211;44975.775121124185;44988.32517512306;45018;45186.54813451955;24524.75475245225;25545.744542554337;26325.23585023545;29337.853310734165;31402.900898468444;24253.636195457435;27160.456790864468;31309.686903131587;31518.527222092147;31909.840450797597;36323.45514817333;36536.45195322126;36588.45117323189;36654.900352989454;36686.899393018255;29026.854865725538;30143.09570712882;31150.37699246048;31664.366712665156;31737.841310793297;21088.051037703648;22474.101035959033;22930.731320735893;24194.18543609201;24255.75744242535 17:37:13 INFO - PID 1635 | |2;jquery.html;431.1592394830082;440.9074094440167;441.16255794615427;444.34015487001795;460.9585137337641;19775.011249437524;21324.786752132473;21802;21898.781012189873;21986.01062952167;18205.817941820576;21885.67171492429;22050.33848984533;22548.54902901941;23193.188238411658;5990.281166260055;6132.50939924805;6200.78297259596;6208.441240288374;6209.099680546318;5785.247917770684;6170.629762214259;6246.656433896134;6247.281562620288;6252.718627661766;4944.950550494503;6129.295131059918;6187.504999600022;6189.38106189382;6201.720922558473;6244.656543890084;6310.33741457145;6316.715747791361;6336.398042185985;6346.587471814363;5578.219049333096;5662.688552129631;6583.07836902834;6934.618595977219;6949.478789090838;28872.84508619653;29070.854645726635;29632;29789.70210297891;30419.69580304213;2327.569399661051;2372.6796882421;2375.572396968545;2380.214529205358;2384.403899025244;16816.915915420468;17310.04794736289;18341.541461463436;19123.948182849937;19352;2332.0788288626068;2337.544178885118;2400.7839294463583;2402.8318017738766;2415.792103948026;148900;159198;160815;161485.38514614938;162458.37541624552;20713.27503537399;25071.87464062668;25436.364090897507;26141.73858261393;26184.856000758715;41971.90140492963;42013.57986420097;42045.94885127896;42139.578604213566;42275;11124.888751112385;11304.830427543593;11344;11460;11678.24091434054;8817.07420720815;8896.59965301563;8910.242629376575;9053.637854485878;9076.591553380114;11523.193376463567;11609.36148511823;11642.6507204784;11677.416129193507;11771.470283837249;3875.748076375027;4336.761478118734;4378.890527736832;4468.173387923244;4481.7983190756495;761.3647065821393;844.4046946902419;877.8105666821492;921.7925966657449;922.6586163119689;17891.015994120447;19918.203271868962;20385.592288154156;21155;21310.573788524453;947.6732574395901;956.4548207521785;972.4602845420859;986.7385142937128;998.4658207858906;10457.26799124054;10703.946480267705;10719.14246860265;10763.977422144884;10876.238663293652;669486;677910;681318;690796;699552;156195.9423651877;157472.63791043143;157972;158933;158954.20522897312;1133.7732453509332;1162.2387336294742;1182.8403165572709;1185.8399116119385;1193.248253600209;16986.895851769594;17242.396516121877;17743.290268389635;17864.642707146046;17922.641547169245;51293.7435312821;56111;56662.7166864163;56675;57140.714446415855;81174.18825811666;87085.25829483249;89533.55233223792;91820;92923.60614590922;12123.515059397525;12688.74622507563;12703.301318427575;12907.419166137734;13132.606021819362;13546.86453135456;13611.387487563281;13645.931770341482;13833.584992450242;13843.4462621494;9980.351277166961;11435.656930292105;11793;11851.940740296588;12120.515179392725;9432.481213533321;10859.728506787393;10967.616133435611;10975.341479511255;11199.496022678835;11701.414929253333;11907.047436205256;12049.939750301543;12076.577319793765;12078.456469458719;12103.394830258629;12490;12792.232466052066;12927.15973461722;12945.029122815638;10636.946815265874;10948.890511094787;11885.5840045598;12000.099992500773;12073.456694448947 17:37:13 INFO - PID 1635 | |3;mootools.html;1321.0356439798948;1349.3051078694473;1423.0536693099089;1457.5918742752033;1476.5939366674168;11527.538898444063;11766.352850593214;11898.286102833836;11918.344491053;12168.391580420966;10289.228307876901;11437.714057148576;11658.475368608411;11921.940390298036;11967.222130561508;2492.7382624824404;2526.633638122471;2530.708968468631;2530.746925307473;2538.733432989528;2421.5520128776143;2485.0680994627014;2536.480021595578;2539.7587229213195;2540.720520742717;2075.865068770525;2118.6504226802635;2438.2197696737053;2485.7887079598254;2512.560301947152;2460.434100156956;2469.753024697534;2481.9875900620473;2503.849769013856;2515.559777039011;2436.792872605831;2489.464765075499;2495.6880389951257;2502.224310463762;2535.0240157539297;9780.706578802647;11640.650780476599;11741.884520970494;11834.940825295904;11932.403379830974;5717.914231286534;6274.901892168852;6524.706388212542;6763.154605674291;6771.627560484171;4131.814068366931;4391.187630288407;4482.753448560328;4508.188526065307;4517.728936263835;1257.2833484913604;1267.7908145156039;1279.8528169260562;1302.7459645369156;1319.7624427603027;14942.925285373503;15405.922970385076;16128.435504757277;16415.93296435728;16447.588810279718;5880.2943646761805;6029.638221706711;6222.3466536013975;6260.280067792171;6285.779997700059;13966.092204006707;14015.789763153562;14034.929825350808;14036.877049835988;14064.929675351763;6770.593764374154;6845.965770171217;6854.0404343391965;6855.622940738307;6858.348456896587;6026.427489388501;6140.478059365004;6193.81418557444;6210.223722034746;6218.968905155536;4751.714897106184;4856.83001094967;4870.220764677634;4947.925781113287;4951.356323677899;1471.3746657670447;1507.1108046252764;1521.37623574334;1541.204496761283;1543.729847276722;791.9722809701748;798.8137615640769;803.5419810707957;810.943233973628;832.1927730101792;4888.437829649564;4908.288298196741;5529.834104976857;6047.425494578008;6241.063840423973;528.6590149353646;596.3440215762713;596.3499785234116;608.3916083916084;610.6488768957867;4005.7997100144876;4196.790160491963;4312.956870431318;4405.537418571068;4494.775261236925;12844.807327889903;12984.545540906023;14512.637184070483;15107.54677359681;15117.54647360559;14540;14953.626159346104;15024.474143405147;15055.171965542;15094.39622415091;9315.39449935752;10672.306300090624;10892.564297428014;10918.672439826818;10950.726231844268;6244.438000579969;7016.099841827928;7060.505764596429;7085.893711594434;7109;12916.677083072622;12918.483260669467;12919.22484650924;12947.870521294666;13215.735685286048;11143.888561114285;13738.519151830038;13960.511382101578;13967.930160349133;13973.441062357391;9133.31500137479;9428.95285523568;9494.762630934281;9500.382475139093;9529.285303602119;10336.276460647683;10458.68623941283;10466.424346661006;10484.895151048391;10488.423136727553;8744.69393571222;8917.331200159884;8953.641854326015;9019.413738107001;9033.367664263438;7370.262973702587;8804.515751633722;8849.247813935888;8852.291816654524;8902.243309318781;7892.6053697313755;7966.721164759206;8030.6787728492545;8057.879131813144;8087.959560202161;5223.660462069954;5846.123081537803;5957.463828255304;5982.730777115127;6130.693465326805;5171.81898633546;5982.7307771149535;6119.173911522065;6224.657643829632;6235.937640623536 17:37:13 INFO - PID 1635 | |4;prototype.html;343.32022595261384;346.44049859476934;390.3403248510018;390.459213988626;404.15934855500564;6164.660943648098;6167.660778657172;6208.317085120639;6277.058441233818;6364.618122912629;5227.947720522794;6114.877702445949;6126.479249263818;6201.875752624511;6230.408111229437;3802.0534086083367;4153.314703073989;4429.357743127244;4516.367708520802;4546.113507865951;3734.9626503734885;4563.931541026871;4575.794089265974;4581.289900065479;4597.586217240448;3999.520057593093;4162.2924102902425;4518.593326600605;4529.094181163764;4543.863684089482;3739.551253849528;4129.946863549791;4327.17783621111;4373.300271956472;4439.845640133556;3441.9311613767586;3874.2638898609175;3898.4152377143655;3902.1025164212106;3918.1380096379035;4921.483244259372;6044.24446944132;6099.146119543268;6117.785877494311;6119.296280927706;4723.858284251443;5229.686218826882;5423.620346575742;5440.91838622421;5452.263944367499;6590.726199461178;6603.273639899608;6640.169978752656;6650.3682150195655;6668.466522678174;4408.0522687622;4420.823167073313;4421;4422;4435.534268901783;6711.3288671132495;6731.024001519752;6782.287859774751;6914.861702765967;6987;3600.4059330210744;3726.3478891194477;4464.933026004613;4477.6417886569;4477.955220447819;3801.885943421702;4553.089382123589;4567.1550763108935;4568.771561421916;4574.611158051603;4368.6068253856665;5161.845144645667;5356.0894647909945;5374.973125134349;5384.973075134678;6085.36103709113;6679.298673639198;6684.899726504106;6697.397234248941;6712.3417737333275;1368.9931550342383;1554.9067055976452;1555.2223888055971;1559.7504399296286;1568.7646852972146;872.4547158026234;979.9608015679436;1018.8828284747349;1026.958921643141;1039.7244730146517;6252.372485165739;6269;6298.338674439118;6298.590591611531;6300.558960872695;5743.368229494752;5953.523718102541;6714.8584740594215;6902.516823822385;6912.447004239649;5015.520421475673;5926.466618004314;6304.18045654062;6307.779227727007;6308.936910630835;2959.8520073996215;3526.8008876981435;3568.3398571264397;3603.441466572633;3616.5840928293055;6159.81520554384;6747.730090796412;7051.647417629098;7080.221175670672;7126;6086.452219300284;7002.949557566406;7038.225795162528;7130.607816570138;7144.821379465555;6714.9664251679405;6876.277990811093;7051.541649792747;7075.186353569303;7128.607926563985;6912.239653638197;6950.235474097946;6961.930380696128;6970.686319115551;7012.368886800008;6542.869142617216;6825.69284382194;6830.692618832065;6852.45180385558;6863.828404289933;5373.570114390761;6308.71610777507;6757.594544327356;7170.713171473083;7201.243869393638;3745.587985321558;4124.463819703419;4460.7323560586465;4474.888127796831;4530.411046563926;5933.673647949404;6566.770163044271;6833.726650933907;6859.142607174103;6927.376536111774;5707.571932105026;6626.867462650624;6662.900056499059;6680.799576012728;6698.196216454056;5884.823035392853;6042.305134909541;6053.697315134314;6275.090111933835;6646.268910419753;5545.724483368767;6352.809415717536;6514.6416947068365;6542.934570654233;6579.407853293035;5520.696361699985;6649.667516624053;6693.230278517838;6706.396424321834;6777.762778302736;6161.383861613803;6354.364563543609;6399.29651706005;6443.259025212161;6458.869697802959 17:37:13 INFO - PID 1635 | |5;yui.html;788.6056971514242;797.9680812767486;823.3536673711136;823.3948048184583;833.1626715151272;13571.389287482063;14061.648458788535;15578.766318505232;16039.198040098006;16074.910261665982;15863.206839658;16484.258208380623;16870.57823554412;16886.74669879953;17093.31626734932;947.5262368815593;970.8932017478072;975.7609385700513;976.4873441443228;978.5938835383307;988.9752756181081;989.8515222716615;990.5839547390113;993.1955116355739;999.5751805482677;1054.362110922892;1057.740853490896;1057.9206559508068;1058.5712786321515;1065.7761870007268;1044.185535282481;1051.7160366701025;1053.6470282455425;1053.7892421515728;1061.7451811565206;767.6008475592716;769.6305773228826;773.222910974467;773.3156156801215;780.5004796929969;13156.802647960289;15815.683686326209;16268.593285167846;16349.673006539922;16385.426510072208;15435.845641543554;15683.764743528742;16025.35898564056;16033;16105.27526261321;13074.346282685829;13119.606411807661;13603.047786654937;13785.241811700353;13804;3543.7696549724187;3544.4151714967;3545.2732189901008;3556.928861422783;3561.786292822439;105713.47143264234;109627;110241;110430.44784776025;110832.4458377711;12533.874661253452;13017.804732929013;13025.957923366108;13077.934610326887;13135.737285254432;23074.538509229722;23696;24044.39889002754;24080.51838963211;24265.39336516601;8535.829283414421;8746.69386571467;8787.165219304155;8795.868061979076;8808.77978050541;4703.341532185496;4713.858584242478;4743.762811859393;4777.713337199779;4780.11567859947;4052.4326594276827;4208.915821683549;4212.094399704017;4224.260754367973;4229.196452673982;915.5788337364753;940.7648087978006;943.3867985809142;945.2579724916045;951.4148798489018;566.8044524638993;636.8694417644465;667.2993356975156;667.3093348384399;680.9523333366716;10878.292910960763;11070.169737269576;11088.22382433238;11464.140189485823;11765.88234117665;589.7051474262869;619.1610367951452;647.513955472183;656.5108993799649;669.560445043161;5171.560417364491;5217.9478205217465;5266.605004624668;5277.1292736698015;5344.465553444625;100823;101720.49139754254;102253.86476811414;102405;104397.95602044034;54744;71080;83578.91052723731;87982.1201787974;94504;54512.90974180494;73295.90056149162;77933.61033194911;78959;80622;11425.942870285595;12099.213551119148;12150.967167790837;12224.511019559119;12299;32813;37694.68068617585;39418.802905985285;40775.18449630932;41760.16479670329;70438.29561704522;71214.64392678003;71316.64341678258;71402.78687458973;72538.63730681525;15032.924835375754;15365.69268614599;15819.129947852978;16031.599210019844;16044.358225670843;14354;16828.747568786723;17507.599392048352;17738.91130544339;17851.37520186787;15843;16143.596410089842;16156.67686646284;16281.51155465338;16330.101844398672;15050.698986020438;15529.68940621204;15819.288132033857;15904.840951590337;16084.034957902564;15057.247137642855;15595.532134035997;15706.842931570995;15720.921395393407;15972;10990.890091098987;10992.450377481255;11036.668899933014;11168.944155279172;11234.719132021764;10023.398596084258;11439.828402574134;12156;12194.756104877675;12196.390180490762 17:37:13 INFO - PID 1635 | __end_tp_report 17:37:13 INFO - PID 1635 | __start_cc_report 17:37:13 INFO - PID 1635 | _x_x_mozilla_cycle_collect,431 17:37:13 INFO - PID 1635 | __end_cc_report 17:37:13 INFO - PID 1635 | __startTimestamp1491957433633__endTimestamp 17:37:13 INFO - PID 1635 | 17:37:13 INFO - PID 1635 | ------- Summary: start ------- 17:37:13 INFO - PID 1635 | Number of tests: 6 17:37:13 INFO - PID 1635 | 17:37:13 INFO - PID 1635 | [#0] dojo.html Cycles:165 Average:6162.55 Median:6366.14 stddev:756.48 (11.9%) stddev-sans-first:717.34 17:37:13 INFO - PID 1635 | Values: 3013.7 3253.6 3541.4 3552.6 3570.9 6167.1 6245.3 6247.6 6259.2 6296.6 6158.5 6191.3 6214.8 6256.5 6270.2 5392.2 5451.0 5510.0 5526.4 5576.8 5378.4 5473.7 5564.6 5565.2 5617.2 5339.1 5419.4 5431.3 5485.8 5501.0 5361.8 5393.8 5451.7 5512.6 5538.0 5399.0 5401.1 5402.2 5426.7 5464.2 6009.6 6215.3 6261.8 6271.2 6272.2 6455.4 6546.6 6603.1 6657.3 6682.7 6750.6 6780.5 6795.2 6809.4 6811.4 6204.3 6317.1 6333.8 6337.0 6341.8 6642.7 6782.1 6838.8 6909.5 6941.9 5239.3 6337.0 6415.6 6526.8 6596.0 6636.3 6667.9 6757.2 6778.7 6798.9 6663.0 6682.0 6682.3 6689.9 6696.4 6433.7 6510.5 6689.8 6694.5 6724.7 5210.7 5346.9 5353.1 5373.1 5389.4 4017.0 4756.2 4762.8 4775.9 4817.3 6139.5 6310.4 6329.1 6346.1 6355.4 6641.6 6735.1 6744.9 6778.2 6809.5 5338.0 5439.8 6296.8 6366.1 6391.0 6032.3 6049.8 6051.8 6059.4 6062.5 6684.7 6732.9 6843.1 6930.1 6960.6 6789.0 6797.5 6812.5 6848.6 6873.1 6866.3 6903.5 6933.6 6943.9 6960.4 6470.0 6531.9 6534.2 6598.1 6641.9 6798.2 6815.7 6832.5 6833.0 6837.1 5885.4 6339.6 6570.2 6581.7 6584.5 6499.8 6564.3 6574.7 6590.3 6591.0 6332.0 6341.0 6449.8 6453.8 6460.4 6308.6 6567.8 6590.7 6612.9 6632.7 6267.7 6337.5 6390.1 6410.6 6417.8 17:37:13 INFO - PID 1635 | 17:37:13 INFO - PID 1635 | [#1] ext.html Cycles:180 Average:45900.98 Median:194426.82 stddev:82699.80 (42.5%) stddev-sans-first:82861.91 17:37:13 INFO - PID 1635 | Values: 619.7 760.8 985.5 1129.8 1289.4 36788.0 38029.0 38060.4 38090.0 38486.8 37699.0 38164.4 38185.6 38254.2 38255.9 8069.6 8202.0 8233.7 8575.5 8863.3 8623.9 8722.5 8731.8 8754.2 8756.0 8700.3 8734.9 8743.0 8789.6 8799.0 8722.8 8739.5 8741.0 8743.0 8749.9 8534.9 8562.2 8570.0 8576.8 8590.1 37278.4 37477.4 37534.0 37552.6 37587.2 3576.2 3632.9 3639.3 3641.1 3644.2 30887.0 30887.9 30926.9 30930.0 30950.0 3151.3 3172.2 3186.3 3194.5 3201.7 279032.0 280437.0 281156.0 281761.0 283650.0 39577.6 40517.0 47572.3 48837.8 49006.3 50412.5 50439.7 50516.7 50588.5 50758.2 14557.6 14581.7 14663.6 14673.6 14688.6 11682.4 11819.2 11821.5 11859.5 11904.5 13468.2 13529.9 13570.9 13621.4 13676.9 5973.2 5998.3 6040.3 6043.3 6155.4 901.9 953.1 1007.2 1051.8 1083.8 20307.6 20664.4 23811.6 24251.3 24291.5 1037.4 1139.0 1139.6 1168.7 1193.8 12521.9 12537.9 12546.9 12562.1 12580.4 350923.0 353463.0 354166.0 354620.0 354659.0 42809.8 46050.6 53285.5 53876.7 54010.9 1495.8 1502.3 1524.5 1559.1 1565.8 21679.8 22044.9 23855.8 24216.3 24360.4 38539.4 38551.8 38617.4 38655.2 38713.2 303777.0 304265.0 304755.0 305863.0 306014.0 26298.3 34607.5 34674.8 35169.3 35390.6 43679.1 44975.8 44988.3 45018.0 45186.5 24524.8 25545.7 26325.2 29337.9 31402.9 24253.6 27160.5 31309.7 31518.5 31909.8 36323.5 36536.5 36588.5 36654.9 36686.9 29026.9 30143.1 31150.4 31664.4 31737.8 21088.1 22474.1 22930.7 24194.2 24255.8 17:37:13 INFO - PID 1635 | 17:37:13 INFO - PID 1635 | [#2] jquery.html Cycles:180 Average:41951.43 Median:2334.81 stddev:114865.49 (4919.7%) stddev-sans-first:115145.41 17:37:13 INFO - PID 1635 | Values: 431.2 440.9 441.2 444.3 461.0 19775.0 21324.8 21802.0 21898.8 21986.0 18205.8 21885.7 22050.3 22548.5 23193.2 5990.3 6132.5 6200.8 6208.4 6209.1 5785.2 6170.6 6246.7 6247.3 6252.7 4945.0 6129.3 6187.5 6189.4 6201.7 6244.7 6310.3 6316.7 6336.4 6346.6 5578.2 5662.7 6583.1 6934.6 6949.5 28872.8 29070.9 29632.0 29789.7 30419.7 2327.6 2372.7 2375.6 2380.2 2384.4 16816.9 17310.0 18341.5 19123.9 19352.0 2332.1 2337.5 2400.8 2402.8 2415.8 148900.0 159198.0 160815.0 161485.4 162458.4 20713.3 25071.9 25436.4 26141.7 26184.9 41971.9 42013.6 42045.9 42139.6 42275.0 11124.9 11304.8 11344.0 11460.0 11678.2 8817.1 8896.6 8910.2 9053.6 9076.6 11523.2 11609.4 11642.7 11677.4 11771.5 3875.7 4336.8 4378.9 4468.2 4481.8 761.4 844.4 877.8 921.8 922.7 17891.0 19918.2 20385.6 21155.0 21310.6 947.7 956.5 972.5 986.7 998.5 10457.3 10703.9 10719.1 10764.0 10876.2 669486.0 677910.0 681318.0 690796.0 699552.0 156195.9 157472.6 157972.0 158933.0 158954.2 1133.8 1162.2 1182.8 1185.8 1193.2 16986.9 17242.4 17743.3 17864.6 17922.6 51293.7 56111.0 56662.7 56675.0 57140.7 81174.2 87085.3 89533.6 91820.0 92923.6 12123.5 12688.7 12703.3 12907.4 13132.6 13546.9 13611.4 13645.9 13833.6 13843.4 9980.4 11435.7 11793.0 11851.9 12120.5 9432.5 10859.7 10967.6 10975.3 11199.5 11701.4 11907.0 12049.9 12076.6 12078.5 12103.4 12490.0 12792.2 12927.2 12945.0 10636.9 10948.9 11885.6 12000.1 12073.5 17:37:13 INFO - PID 1635 | 17:37:13 INFO - PID 1635 | [#3] mootools.html Cycles:180 Average:7142.52 Median:2539.25 stddev:4497.12 (177.1%) stddev-sans-first:4488.46 17:37:13 INFO - PID 1635 | Values: 1321.0 1349.3 1423.1 1457.6 1476.6 11527.5 11766.4 11898.3 11918.3 12168.4 10289.2 11437.7 11658.5 11921.9 11967.2 2492.7 2526.6 2530.7 2530.7 2538.7 2421.6 2485.1 2536.5 2539.8 2540.7 2075.9 2118.7 2438.2 2485.8 2512.6 2460.4 2469.8 2482.0 2503.8 2515.6 2436.8 2489.5 2495.7 2502.2 2535.0 9780.7 11640.7 11741.9 11834.9 11932.4 5717.9 6274.9 6524.7 6763.2 6771.6 4131.8 4391.2 4482.8 4508.2 4517.7 1257.3 1267.8 1279.9 1302.7 1319.8 14942.9 15405.9 16128.4 16415.9 16447.6 5880.3 6029.6 6222.3 6260.3 6285.8 13966.1 14015.8 14034.9 14036.9 14064.9 6770.6 6846.0 6854.0 6855.6 6858.3 6026.4 6140.5 6193.8 6210.2 6219.0 4751.7 4856.8 4870.2 4947.9 4951.4 1471.4 1507.1 1521.4 1541.2 1543.7 792.0 798.8 803.5 810.9 832.2 4888.4 4908.3 5529.8 6047.4 6241.1 528.7 596.3 596.3 608.4 610.6 4005.8 4196.8 4313.0 4405.5 4494.8 12844.8 12984.5 14512.6 15107.5 15117.5 14540.0 14953.6 15024.5 15055.2 15094.4 9315.4 10672.3 10892.6 10918.7 10950.7 6244.4 7016.1 7060.5 7085.9 7109.0 12916.7 12918.5 12919.2 12947.9 13215.7 11143.9 13738.5 13960.5 13967.9 13973.4 9133.3 9429.0 9494.8 9500.4 9529.3 10336.3 10458.7 10466.4 10484.9 10488.4 8744.7 8917.3 8953.6 9019.4 9033.4 7370.3 8804.5 8849.2 8852.3 8902.2 7892.6 7966.7 8030.7 8057.9 8088.0 5223.7 5846.1 5957.5 5982.7 6130.7 5171.8 5982.7 6119.2 6224.7 6235.9 17:37:13 INFO - PID 1635 | 17:37:13 INFO - PID 1635 | [#4] prototype.html Cycles:180 Average:5312.50 Median:6107.01 stddev:1719.29 (28.2%) stddev-sans-first:1683.17 17:37:13 INFO - PID 1635 | Values: 343.3 346.4 390.3 390.5 404.2 6164.7 6167.7 6208.3 6277.1 6364.6 5227.9 6114.9 6126.5 6201.9 6230.4 3802.1 4153.3 4429.4 4516.4 4546.1 3735.0 4563.9 4575.8 4581.3 4597.6 3999.5 4162.3 4518.6 4529.1 4543.9 3739.6 4129.9 4327.2 4373.3 4439.8 3441.9 3874.3 3898.4 3902.1 3918.1 4921.5 6044.2 6099.1 6117.8 6119.3 4723.9 5229.7 5423.6 5440.9 5452.3 6590.7 6603.3 6640.2 6650.4 6668.5 4408.1 4420.8 4421.0 4422.0 4435.5 6711.3 6731.0 6782.3 6914.9 6987.0 3600.4 3726.3 4464.9 4477.6 4478.0 3801.9 4553.1 4567.2 4568.8 4574.6 4368.6 5161.8 5356.1 5375.0 5385.0 6085.4 6679.3 6684.9 6697.4 6712.3 1369.0 1554.9 1555.2 1559.8 1568.8 872.5 980.0 1018.9 1027.0 1039.7 6252.4 6269.0 6298.3 6298.6 6300.6 5743.4 5953.5 6714.9 6902.5 6912.4 5015.5 5926.5 6304.2 6307.8 6308.9 2959.9 3526.8 3568.3 3603.4 3616.6 6159.8 6747.7 7051.6 7080.2 7126.0 6086.5 7002.9 7038.2 7130.6 7144.8 6715.0 6876.3 7051.5 7075.2 7128.6 6912.2 6950.2 6961.9 6970.7 7012.4 6542.9 6825.7 6830.7 6852.5 6863.8 5373.6 6308.7 6757.6 7170.7 7201.2 3745.6 4124.5 4460.7 4474.9 4530.4 5933.7 6566.8 6833.7 6859.1 6927.4 5707.6 6626.9 6662.9 6680.8 6698.2 5884.8 6042.3 6053.7 6275.1 6646.3 5545.7 6352.8 6514.6 6542.9 6579.4 5520.7 6649.7 6693.2 6706.4 6777.8 6161.4 6354.4 6399.3 6443.3 6458.9 17:37:13 INFO - PID 1635 | 17:37:13 INFO - PID 1635 | [#5] yui.html Cycles:180 Average:20742.07 Median:16990.03 stddev:28560.06 (168.1%) stddev-sans-first:28600.88 17:37:13 INFO - PID 1635 | Values: 788.6 798.0 823.4 823.4 833.2 13571.4 14061.6 15578.8 16039.2 16074.9 15863.2 16484.3 16870.6 16886.7 17093.3 947.5 970.9 975.8 976.5 978.6 989.0 989.9 990.6 993.2 999.6 1054.4 1057.7 1057.9 1058.6 1065.8 1044.2 1051.7 1053.6 1053.8 1061.7 767.6 769.6 773.2 773.3 780.5 13156.8 15815.7 16268.6 16349.7 16385.4 15435.8 15683.8 16025.4 16033.0 16105.3 13074.3 13119.6 13603.0 13785.2 13804.0 3543.8 3544.4 3545.3 3556.9 3561.8 105713.5 109627.0 110241.0 110430.4 110832.4 12533.9 13017.8 13026.0 13077.9 13135.7 23074.5 23696.0 24044.4 24080.5 24265.4 8535.8 8746.7 8787.2 8795.9 8808.8 4703.3 4713.9 4743.8 4777.7 4780.1 4052.4 4208.9 4212.1 4224.3 4229.2 915.6 940.8 943.4 945.3 951.4 566.8 636.9 667.3 667.3 681.0 10878.3 11070.2 11088.2 11464.1 11765.9 589.7 619.2 647.5 656.5 669.6 5171.6 5217.9 5266.6 5277.1 5344.5 100823.0 101720.5 102253.9 102405.0 104398.0 54744.0 71080.0 83578.9 87982.1 94504.0 54512.9 73295.9 77933.6 78959.0 80622.0 11425.9 12099.2 12151.0 12224.5 12299.0 32813.0 37694.7 39418.8 40775.2 41760.2 70438.3 71214.6 71316.6 71402.8 72538.6 15032.9 15365.7 15819.1 16031.6 16044.4 14354.0 16828.7 17507.6 17738.9 17851.4 15843.0 16143.6 16156.7 16281.5 16330.1 15050.7 15529.7 15819.3 15904.8 16084.0 15057.2 15595.5 15706.8 15720.9 15972.0 10990.9 10992.5 11036.7 11168.9 11234.7 10023.4 11439.8 12156.0 12194.8 12196.4 17:37:13 INFO - PID 1635 | -------- Summary: end -------- 17:37:13 INFO - PID 1635 | 17:37:14 INFO - TEST-INFO | 1635: exit 0 17:37:14 INFO - TEST-OK | dromaeo_css | took 1116892ms 17:37:14 INFO - TEST-START | kraken 17:37:14 INFO - operating with platform_type : mac_ 17:37:14 INFO - Initialising browser for kraken test... 17:37:14 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmpDizj8y/profile http://localhost:49235/getInfo.html) 17:37:17 INFO - PID 1648 | 2017-04-11 17:37:17.126 plugin-container[1649:10300] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c43, name = 'com.apple.tsm.portname' 17:37:17 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:37:17 INFO - PID 1648 | 2017-04-11 17:37:17.129 plugin-container[1649:10300] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930f, name = 'com.apple.CFPasteboardClient' 17:37:17 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:37:17 INFO - PID 1648 | 2017-04-11 17:37:17.129 plugin-container[1649:10300] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:37:17 INFO - PID 1648 | __metrics Screen width/height:1600/1200 17:37:17 INFO - PID 1648 | colorDepth:24 17:37:17 INFO - PID 1648 | Browser inner width/height: 1024/689 17:37:17 INFO - PID 1648 | __metrics 17:37:17 ERROR - PID 1648 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3364: TypeError: this.tabs is undefined 17:37:18 INFO - TEST-INFO | 1648: exit 0 17:37:18 INFO - Browser initialized. 17:37:18 INFO - Running cycle 1/1 for kraken test... 17:37:18 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmpDizj8y/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:37:20 INFO - PID 1650 | 2017-04-11 17:37:20.804 plugin-container[1651:10425] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 17:37:20 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:37:20 INFO - PID 1650 | 2017-04-11 17:37:20.805 plugin-container[1651:10425] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 17:37:20 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:37:20 INFO - PID 1650 | 2017-04-11 17:37:20.805 plugin-container[1651:10425] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:37:30 INFO - PID 1650 | RSS: Main: 235229184 17:37:30 INFO - PID 1650 | 17:37:33 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection) 17:37:34 INFO - PID 1650 | RSS: Main: 250343424 17:37:34 INFO - PID 1650 | 17:37:37 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-dft) 17:37:37 INFO - PID 1650 | RSS: Main: 258981888 17:37:37 INFO - PID 1650 | 17:37:41 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-fft) 17:37:41 INFO - PID 1650 | RSS: Main: 261160960 17:37:41 INFO - PID 1650 | 17:37:43 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator) 17:37:44 INFO - PID 1650 | RSS: Main: 263290880 17:37:44 INFO - PID 1650 | 17:37:46 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:37:47 INFO - PID 1650 | RSS: Main: 263979008 17:37:47 INFO - PID 1650 | 17:37:50 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom) 17:37:50 INFO - PID 1650 | RSS: Main: 269430784 17:37:50 INFO - PID 1650 | 17:37:54 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate) 17:37:55 INFO - PID 1650 | RSS: Main: 267751424 17:37:55 INFO - PID 1650 | 17:37:58 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial) 17:37:58 INFO - PID 1650 | RSS: Main: 268951552 17:37:58 INFO - PID 1650 | 17:38:00 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:38:01 INFO - PID 1650 | RSS: Main: 268640256 17:38:01 INFO - PID 1650 | 17:38:02 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:38:03 INFO - PID 1650 | RSS: Main: 268709888 17:38:03 INFO - PID 1650 | 17:38:05 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:38:06 INFO - PID 1650 | RSS: Main: 268718080 17:38:06 INFO - PID 1650 | 17:38:09 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:38:10 INFO - PID 1650 | RSS: Main: 269254656 17:38:10 INFO - PID 1650 | 17:38:13 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:38:14 INFO - PID 1650 | RSS: Main: 268787712 17:38:14 INFO - PID 1650 | 17:38:16 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49235/tests/kraken/driver.html?testName=ai-astar) 17:38:16 INFO - PID 1650 | RSS: Main: 271888384 17:38:16 INFO - PID 1650 | 17:38:16 INFO - PID 1650 | __start_tp_report 17:38:16 INFO - PID 1650 | _x_x_mozilla_page_load 17:38:16 INFO - PID 1650 | _x_x_mozilla_page_load_details 17:38:16 INFO - PID 1650 | |i|pagename|runs| 17:38:16 INFO - PID 1650 | |0;ai-astar;152;181;122;123;125;125;127;125;122;145 17:38:16 INFO - PID 1650 | |1;audio-beat-detection;151;118;116;159;117;116;118;116;142;121 17:38:16 INFO - PID 1650 | |2;audio-dft;218;218;225;235;245;219;219;216;216;216 17:38:16 INFO - PID 1650 | |3;audio-fft;85;63;62;86;60;63;61;81;72;67 17:38:16 INFO - PID 1650 | |4;audio-oscillator;87;71;89;74;73;73;86;73;72;85 17:38:16 INFO - PID 1650 | |5;imaging-gaussian-blur;95;95;135;115;139;94;95;95;96;96 17:38:16 INFO - PID 1650 | |6;imaging-darkroom;103;150;104;104;134;163;104;159;160;103 17:38:16 INFO - PID 1650 | |7;imaging-desaturate;103;102;100;102;105;102;104;157;143;102 17:38:16 INFO - PID 1650 | |8;json-parse-financial;59;53;52;52;53;54;53;54;53;55 17:38:16 INFO - PID 1650 | |9;json-stringify-tinderbox;61;61;67;69;66;60;61;60;57;61 17:38:16 INFO - PID 1650 | |10;stanford-crypto-aes;88;88;91;97;114;80;105;118;114;115 17:38:16 INFO - PID 1650 | |11;stanford-crypto-ccm;170;173;146;146;173;151;150;165;207;174 17:38:16 INFO - PID 1650 | |12;stanford-crypto-pbkdf2;197;194;197;191;191;194;198;197;196;196 17:38:16 INFO - PID 1650 | |13;stanford-crypto-sha256-iterative;74;72;73;71;70;72;69;71;75;84 17:38:16 INFO - PID 1650 | __end_tp_report 17:38:16 INFO - PID 1650 | __start_cc_report 17:38:16 INFO - PID 1650 | _x_x_mozilla_cycle_collect,1017 17:38:16 INFO - PID 1650 | __end_cc_report 17:38:16 INFO - PID 1650 | __startTimestamp1491957496327__endTimestamp 17:38:16 INFO - PID 1650 | 17:38:16 INFO - PID 1650 | ------- Summary: start ------- 17:38:16 INFO - PID 1650 | Number of tests: 14 17:38:16 INFO - PID 1650 | 17:38:16 INFO - PID 1650 | [#0] ai-astar Cycles:10 Average:134.70 Median:126.00 stddev:19.29 (15.3%) stddev-sans-first:19.42 17:38:16 INFO - PID 1650 | Values: 152.0 181.0 122.0 123.0 125.0 125.0 127.0 125.0 122.0 145.0 17:38:16 INFO - PID 1650 | 17:38:16 INFO - PID 1650 | [#1] audio-beat-detection Cycles:10 Average:127.40 Median:119.50 stddev:16.61 (13.9%) stddev-sans-first:15.27 17:38:16 INFO - PID 1650 | Values: 151.0 118.0 116.0 159.0 117.0 116.0 118.0 116.0 142.0 121.0 17:38:16 INFO - PID 1650 | 17:38:16 INFO - PID 1650 | [#2] audio-dft Cycles:10 Average:222.70 Median:219.00 stddev:9.78 (4.5%) stddev-sans-first:10.22 17:38:16 INFO - PID 1650 | Values: 218.0 218.0 225.0 235.0 245.0 219.0 219.0 216.0 216.0 216.0 17:38:16 INFO - PID 1650 | 17:38:16 INFO - PID 1650 | [#3] audio-fft Cycles:10 Average:70.00 Median:69.50 stddev:10.32 (14.8%) stddev-sans-first:9.41 17:38:16 INFO - PID 1650 | Values: 85.0 63.0 62.0 86.0 60.0 63.0 61.0 81.0 72.0 67.0 17:38:16 INFO - PID 1650 | 17:38:16 INFO - PID 1650 | [#4] audio-oscillator Cycles:10 Average:78.30 Median:79.50 stddev:7.38 (9.3%) stddev-sans-first:7.12 17:38:16 INFO - PID 1650 | Values: 87.0 71.0 89.0 74.0 73.0 73.0 86.0 73.0 72.0 85.0 17:38:16 INFO - PID 1650 | 17:38:16 INFO - PID 1650 | [#5] imaging-gaussian-blur Cycles:10 Average:105.50 Median:95.00 stddev:17.75 (18.7%) stddev-sans-first:18.42 17:38:16 INFO - PID 1650 | Values: 95.0 95.0 135.0 115.0 139.0 94.0 95.0 95.0 96.0 96.0 17:38:16 INFO - PID 1650 | 17:38:16 INFO - PID 1650 | [#6] imaging-darkroom Cycles:10 Average:128.40 Median:142.00 stddev:27.30 (19.2%) stddev-sans-first:27.36 17:38:16 INFO - PID 1650 | Values: 103.0 150.0 104.0 104.0 134.0 163.0 104.0 159.0 160.0 103.0 17:38:16 INFO - PID 1650 | 17:38:16 INFO - PID 1650 | [#7] imaging-desaturate Cycles:10 Average:112.00 Median:103.50 stddev:20.34 (19.7%) stddev-sans-first:21.31 17:38:16 INFO - PID 1650 | Values: 103.0 102.0 100.0 102.0 105.0 102.0 104.0 157.0 143.0 102.0 17:38:16 INFO - PID 1650 | 17:38:16 INFO - PID 1650 | [#8] json-parse-financial Cycles:10 Average:53.80 Median:53.50 stddev:2.04 (3.8%) stddev-sans-first:0.97 17:38:16 INFO - PID 1650 | Values: 59.0 53.0 52.0 52.0 53.0 54.0 53.0 54.0 53.0 55.0 17:38:16 INFO - PID 1650 | 17:38:16 INFO - PID 1650 | [#9] json-stringify-tinderbox Cycles:10 Average:62.30 Median:61.00 stddev:3.74 (6.1%) stddev-sans-first:3.94 17:38:16 INFO - PID 1650 | Values: 61.0 61.0 67.0 69.0 66.0 60.0 61.0 60.0 57.0 61.0 17:38:16 INFO - PID 1650 | 17:38:16 INFO - PID 1650 | [#10] stanford-crypto-aes Cycles:10 Average:101.00 Median:84.00 stddev:13.88 (16.5%) stddev-sans-first:13.90 17:38:16 INFO - PID 1650 | Values: 88.0 88.0 91.0 97.0 114.0 80.0 105.0 118.0 114.0 115.0 17:38:16 INFO - PID 1650 | 17:38:16 INFO - PID 1650 | [#11] stanford-crypto-ccm Cycles:10 Average:165.50 Median:171.50 stddev:18.67 (10.9%) stddev-sans-first:19.74 17:38:16 INFO - PID 1650 | Values: 170.0 173.0 146.0 146.0 173.0 151.0 150.0 165.0 207.0 174.0 17:38:16 INFO - PID 1650 | 17:38:16 INFO - PID 1650 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:195.10 Median:196.50 stddev:2.51 (1.3%) stddev-sans-first:2.57 17:38:16 INFO - PID 1650 | Values: 197.0 194.0 197.0 191.0 191.0 194.0 198.0 197.0 196.0 196.0 17:38:16 INFO - PID 1650 | 17:38:16 INFO - PID 1650 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.10 Median:72.50 stddev:4.23 (5.8%) stddev-sans-first:4.47 17:38:16 INFO - PID 1650 | Values: 74.0 72.0 73.0 71.0 70.0 72.0 69.0 71.0 75.0 84.0 17:38:16 INFO - PID 1650 | -------- Summary: end -------- 17:38:16 INFO - PID 1650 | 17:38:17 INFO - TEST-INFO | 1650: exit 0 17:38:17 INFO - TEST-OK | kraken | took 63262ms 17:38:17 INFO - SUITE-END | took 1180s 17:38:17 INFO - Completed test suite (00:19:40) 17:38:17 INFO - javascript benchmark 17:38:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8007.468899274121, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3013.743831774299, 3253.6172126846086, 3541.415666415042, 3552.5559305086863, 3570.9464358034634, 6167.136600875881, 6245.250569931603, 6247.625142491448, 6259.1863057802475, 6296.614744756158, 6158.507319414447, 6191.349908259635, 6214.844628884269, 6256.499480041597, 6270.216222972129, 5392.2181283713835, 5450.972745136269, 5509.972450137744, 5526.447355264461, 5576.804811831586, 5378.381486129106, 5473.698946557938, 5564.638298510604, 5565.165225216209, 5617.21359009739, 5339.145736682112, 5419.403865574784, 5431.293931788881, 5485.780568777244, 5501.0, 5361.839144825662, 5393.78424863009, 5451.727413629343, 5512.586556008316, 5537.972310138463, 5398.973005134949, 5401.081816091235, 5402.162664786925, 5426.67439953604, 5464.23500709901, 6009.609375390585, 6215.2541694996435, 6261.78083767066, 6271.216097987752, 6272.2159730033745, 6455.419012288917, 6546.6072035677535, 6603.141591593062, 6657.334266573353, 6682.665866706694, 6750.561213521155, 6780.491463140284, 6795.150606174228, 6809.353111454404, 6811.421029212523, 6204.317525072239, 6317.1156038154695, 6333.841653958596, 6337.0, 6341.8414539636415, 6642.66786660665, 6782.118324617768, 6838.829029274308, 6909.481788865855, 6941.861162776716, 5239.345081864767, 6336.968315158487, 6415.550911436248, 6526.771562995273, 6596.0, 6636.30318816527, 6667.866642667216, 6757.189137303555, 6778.661066946633, 6798.93201067983, 6662.96668516664, 6682.0, 6682.298358672269, 6689.866202676017, 6696.363845434676, 6433.710483028275, 6510.479161667055, 6689.832754181184, 6694.464442844561, 6724.66376681164, 5210.713410762369, 5346.8663283417445, 5353.116735738559, 5373.086575282211, 5389.353277606712, 4017.0, 4756.167670657621, 4762.76186190696, 4775.928361074587, 4817.253325734517, 6139.492138333381, 6310.432061114522, 6329.082283068929, 6346.11154438379, 6355.3644635537, 6641.568298060611, 6735.057092007123, 6744.898826517606, 6778.2205046420295, 6809.5233333666165, 5337.973310133502, 5439.755211015514, 6296.811095667137, 6366.140571022946, 6391.0, 6032.336442991267, 6049.758009679564, 6051.848703782441, 6059.363766804422, 6062.454379105901, 6684.665766711742, 6732.865342693217, 6843.076184715199, 6930.06444130036, 6960.582365058112, 6789.0, 6797.456203503807, 6812.489063320171, 6848.554843935129, 6873.072135261876, 6866.313368663094, 6903.5167538271835, 6933.618650974245, 6943.895841562279, 6960.408365288805, 6469.997150441689, 6531.869362612816, 6534.215894092739, 6598.076269322202, 6641.933580664131, 6798.150231221098, 6815.659217039228, 6832.487563432862, 6833.0, 6837.076994605664, 5885.411458854081, 6339.5562310637815, 6570.178727659043, 6581.703823327866, 6584.539082264196, 6499.772507962199, 6564.277929427663, 6574.737010519717, 6590.340965903371, 6591.0, 6331.96834015827, 6341.048842673451, 6449.774257900951, 6453.838654033499, 6460.35396460369, 6308.612105336819, 6567.802965911031, 6590.703418346282, 6612.867742645217, 6632.701528431136, 6267.717952692049, 6337.461315788025, 6390.105385246162, 6410.61536307823, 6417.7753778617525], "value": 6108.73208479738, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [619.6808643548572, 760.8402235530536, 985.5417230987593, 1129.7853407852508, 1289.4262053386235, 36788.0, 38029.0, 38060.42909356355, 38090.0477488063, 38486.80756596213, 37699.0, 38164.42753358702, 38185.61814381855, 38254.23491530168, 38255.852324430314, 8069.596520173997, 8201.95899020507, 8233.711820086299, 8575.528345940971, 8863.335249856287, 8623.913760862406, 8722.520261385622, 8731.781705457352, 8754.168354006391, 8755.95622021889, 8700.26047785939, 8734.86897696535, 8743.0, 8789.560521973908, 8799.0, 8722.778810966438, 8739.519326437043, 8740.956295218546, 8743.0, 8749.868751968726, 8534.914650853474, 8562.186592273723, 8569.95715021421, 8576.828463430758, 8590.055093939662, 37278.44082338767, 37477.437838432445, 37534.0, 37552.62447375518, 37587.24825503475, 3576.2489877125577, 3632.891013269606, 3639.2721455708966, 3641.1443310821937, 3644.180059486621, 30887.0, 30887.918922837816, 30926.91755788559, 30930.0, 30950.0, 3151.2752067024485, 3172.191091271711, 3186.3308705171944, 3194.5048517479827, 3201.711845933854, 279032.0, 280437.0, 281156.0, 281761.0, 283650.0, 39577.604223957394, 40517.0, 47572.28641570379, 48837.75581122072, 49006.26490602644, 50412.49587504078, 50439.747801261496, 50516.74741626342, 50588.49411505911, 50758.238626420636, 14557.636059098395, 14581.708365832625, 14663.56009319722, 14673.633159170893, 14688.55934321972, 11682.357470339042, 11819.231749936227, 11821.468033938494, 11859.525618975144, 11904.464299106561, 13468.191908485522, 13529.932350338186, 13570.914326853828, 13621.387037583332, 13676.863231367757, 5973.1936188614845, 5998.340182579913, 6040.335563088057, 6043.335233124353, 6155.446009859134, 901.8647202919483, 953.1326492891435, 1007.1892126837965, 1051.821190397619, 1083.788661211072, 20307.578469506992, 20664.380068597664, 23811.642825357634, 24251.272461826175, 24291.514169716505, 1037.444966942682, 1138.9829152562718, 1139.5783560082823, 1168.7253495428567, 1193.838831757719, 12521.93739031299, 12537.93731031339, 12546.937265313614, 12562.05784566143, 12580.370981450891, 350923.0, 353463.0, 354166.0, 354620.0, 354659.0, 42809.78595107067, 46050.618481445614, 53285.46714532805, 53876.73061634667, 54010.91978160415, 1495.835458099608, 1502.2939218567255, 1524.4892960858238, 1559.142471640616, 1565.831323616799, 21679.80761058157, 22044.88977555102, 23855.761442385356, 24216.273511794676, 24360.390990225387, 38539.42190867083, 38551.807240963615, 38617.42073868838, 38655.226895462496, 38713.22573548457, 303777.0, 304265.0, 304755.0, 305863.0, 306014.0, 26298.342541436617, 34607.4808877862, 34674.82662586772, 35169.29661406809, 35390.64609353874, 43679.12641747211, 44975.775121124185, 44988.32517512306, 45018.0, 45186.54813451955, 24524.75475245225, 25545.744542554337, 26325.23585023545, 29337.853310734165, 31402.900898468444, 24253.636195457435, 27160.456790864468, 31309.686903131587, 31518.527222092147, 31909.840450797597, 36323.45514817333, 36536.45195322126, 36588.45117323189, 36654.900352989454, 36686.899393018255, 29026.854865725538, 30143.09570712882, 31150.37699246048, 31664.366712665156, 31737.841310793297, 21088.051037703648, 22474.101035959033, 22930.731320735893, 24194.18543609201, 24255.75744242535], "value": 17528.073773055556, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [431.1592394830082, 440.9074094440167, 441.16255794615427, 444.34015487001795, 460.9585137337641, 19775.011249437524, 21324.786752132473, 21802.0, 21898.781012189873, 21986.01062952167, 18205.817941820576, 21885.67171492429, 22050.33848984533, 22548.54902901941, 23193.188238411658, 5990.281166260055, 6132.50939924805, 6200.78297259596, 6208.441240288374, 6209.099680546318, 5785.247917770684, 6170.629762214259, 6246.656433896134, 6247.281562620288, 6252.718627661766, 4944.950550494503, 6129.295131059918, 6187.504999600022, 6189.38106189382, 6201.720922558473, 6244.656543890084, 6310.33741457145, 6316.715747791361, 6336.398042185985, 6346.587471814363, 5578.219049333096, 5662.688552129631, 6583.07836902834, 6934.618595977219, 6949.478789090838, 28872.84508619653, 29070.854645726635, 29632.0, 29789.70210297891, 30419.69580304213, 2327.569399661051, 2372.6796882421, 2375.572396968545, 2380.214529205358, 2384.403899025244, 16816.915915420468, 17310.04794736289, 18341.541461463436, 19123.948182849937, 19352.0, 2332.0788288626068, 2337.544178885118, 2400.7839294463583, 2402.8318017738766, 2415.792103948026, 148900.0, 159198.0, 160815.0, 161485.38514614938, 162458.37541624552, 20713.27503537399, 25071.87464062668, 25436.364090897507, 26141.73858261393, 26184.856000758715, 41971.90140492963, 42013.57986420097, 42045.94885127896, 42139.578604213566, 42275.0, 11124.888751112385, 11304.830427543593, 11344.0, 11460.0, 11678.24091434054, 8817.07420720815, 8896.59965301563, 8910.242629376575, 9053.637854485878, 9076.591553380114, 11523.193376463567, 11609.36148511823, 11642.6507204784, 11677.416129193507, 11771.470283837249, 3875.748076375027, 4336.761478118734, 4378.890527736832, 4468.173387923244, 4481.7983190756495, 761.3647065821393, 844.4046946902419, 877.8105666821492, 921.7925966657449, 922.6586163119689, 17891.015994120447, 19918.203271868962, 20385.592288154156, 21155.0, 21310.573788524453, 947.6732574395901, 956.4548207521785, 972.4602845420859, 986.7385142937128, 998.4658207858906, 10457.26799124054, 10703.946480267705, 10719.14246860265, 10763.977422144884, 10876.238663293652, 669486.0, 677910.0, 681318.0, 690796.0, 699552.0, 156195.9423651877, 157472.63791043143, 157972.0, 158933.0, 158954.20522897312, 1133.7732453509332, 1162.2387336294742, 1182.8403165572709, 1185.8399116119385, 1193.248253600209, 16986.895851769594, 17242.396516121877, 17743.290268389635, 17864.642707146046, 17922.641547169245, 51293.7435312821, 56111.0, 56662.7166864163, 56675.0, 57140.714446415855, 81174.18825811666, 87085.25829483249, 89533.55233223792, 91820.0, 92923.60614590922, 12123.515059397525, 12688.74622507563, 12703.301318427575, 12907.419166137734, 13132.606021819362, 13546.86453135456, 13611.387487563281, 13645.931770341482, 13833.584992450242, 13843.4462621494, 9980.351277166961, 11435.656930292105, 11793.0, 11851.940740296588, 12120.515179392725, 9432.481213533321, 10859.728506787393, 10967.616133435611, 10975.341479511255, 11199.496022678835, 11701.414929253333, 11907.047436205256, 12049.939750301543, 12076.577319793765, 12078.456469458719, 12103.394830258629, 12490.0, 12792.232466052066, 12927.15973461722, 12945.029122815638, 10636.946815265874, 10948.890511094787, 11885.5840045598, 12000.099992500773, 12073.456694448947], "value": 11762.516214889181, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1321.0356439798948, 1349.3051078694473, 1423.0536693099089, 1457.5918742752033, 1476.5939366674168, 11527.538898444063, 11766.352850593214, 11898.286102833836, 11918.344491053, 12168.391580420966, 10289.228307876901, 11437.714057148576, 11658.475368608411, 11921.940390298036, 11967.222130561508, 2492.7382624824404, 2526.633638122471, 2530.708968468631, 2530.746925307473, 2538.733432989528, 2421.5520128776143, 2485.0680994627014, 2536.480021595578, 2539.7587229213195, 2540.720520742717, 2075.865068770525, 2118.6504226802635, 2438.2197696737053, 2485.7887079598254, 2512.560301947152, 2460.434100156956, 2469.753024697534, 2481.9875900620473, 2503.849769013856, 2515.559777039011, 2436.792872605831, 2489.464765075499, 2495.6880389951257, 2502.224310463762, 2535.0240157539297, 9780.706578802647, 11640.650780476599, 11741.884520970494, 11834.940825295904, 11932.403379830974, 5717.914231286534, 6274.901892168852, 6524.706388212542, 6763.154605674291, 6771.627560484171, 4131.814068366931, 4391.187630288407, 4482.753448560328, 4508.188526065307, 4517.728936263835, 1257.2833484913604, 1267.7908145156039, 1279.8528169260562, 1302.7459645369156, 1319.7624427603027, 14942.925285373503, 15405.922970385076, 16128.435504757277, 16415.93296435728, 16447.588810279718, 5880.2943646761805, 6029.638221706711, 6222.3466536013975, 6260.280067792171, 6285.779997700059, 13966.092204006707, 14015.789763153562, 14034.929825350808, 14036.877049835988, 14064.929675351763, 6770.593764374154, 6845.965770171217, 6854.0404343391965, 6855.622940738307, 6858.348456896587, 6026.427489388501, 6140.478059365004, 6193.81418557444, 6210.223722034746, 6218.968905155536, 4751.714897106184, 4856.83001094967, 4870.220764677634, 4947.925781113287, 4951.356323677899, 1471.3746657670447, 1507.1108046252764, 1521.37623574334, 1541.204496761283, 1543.729847276722, 791.9722809701748, 798.8137615640769, 803.5419810707957, 810.943233973628, 832.1927730101792, 4888.437829649564, 4908.288298196741, 5529.834104976857, 6047.425494578008, 6241.063840423973, 528.6590149353646, 596.3440215762713, 596.3499785234116, 608.3916083916084, 610.6488768957867, 4005.7997100144876, 4196.790160491963, 4312.956870431318, 4405.537418571068, 4494.775261236925, 12844.807327889903, 12984.545540906023, 14512.637184070483, 15107.54677359681, 15117.54647360559, 14540.0, 14953.626159346104, 15024.474143405147, 15055.171965542, 15094.39622415091, 9315.39449935752, 10672.306300090624, 10892.564297428014, 10918.672439826818, 10950.726231844268, 6244.438000579969, 7016.099841827928, 7060.505764596429, 7085.893711594434, 7109.0, 12916.677083072622, 12918.483260669467, 12919.22484650924, 12947.870521294666, 13215.735685286048, 11143.888561114285, 13738.519151830038, 13960.511382101578, 13967.930160349133, 13973.441062357391, 9133.31500137479, 9428.95285523568, 9494.762630934281, 9500.382475139093, 9529.285303602119, 10336.276460647683, 10458.68623941283, 10466.424346661006, 10484.895151048391, 10488.423136727553, 8744.69393571222, 8917.331200159884, 8953.641854326015, 9019.413738107001, 9033.367664263438, 7370.262973702587, 8804.515751633722, 8849.247813935888, 8852.291816654524, 8902.243309318781, 7892.6053697313755, 7966.721164759206, 8030.6787728492545, 8057.879131813144, 8087.959560202161, 5223.660462069954, 5846.123081537803, 5957.463828255304, 5982.730777115127, 6130.693465326805, 5171.81898633546, 5982.7307771149535, 6119.173911522065, 6224.657643829632, 6235.937640623536], "value": 5371.038931214056, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.32022595261384, 346.44049859476934, 390.3403248510018, 390.459213988626, 404.15934855500564, 6164.660943648098, 6167.660778657172, 6208.317085120639, 6277.058441233818, 6364.618122912629, 5227.947720522794, 6114.877702445949, 6126.479249263818, 6201.875752624511, 6230.408111229437, 3802.0534086083367, 4153.314703073989, 4429.357743127244, 4516.367708520802, 4546.113507865951, 3734.9626503734885, 4563.931541026871, 4575.794089265974, 4581.289900065479, 4597.586217240448, 3999.520057593093, 4162.2924102902425, 4518.593326600605, 4529.094181163764, 4543.863684089482, 3739.551253849528, 4129.946863549791, 4327.17783621111, 4373.300271956472, 4439.845640133556, 3441.9311613767586, 3874.2638898609175, 3898.4152377143655, 3902.1025164212106, 3918.1380096379035, 4921.483244259372, 6044.24446944132, 6099.146119543268, 6117.785877494311, 6119.296280927706, 4723.858284251443, 5229.686218826882, 5423.620346575742, 5440.91838622421, 5452.263944367499, 6590.726199461178, 6603.273639899608, 6640.169978752656, 6650.3682150195655, 6668.466522678174, 4408.0522687622, 4420.823167073313, 4421.0, 4422.0, 4435.534268901783, 6711.3288671132495, 6731.024001519752, 6782.287859774751, 6914.861702765967, 6987.0, 3600.4059330210744, 3726.3478891194477, 4464.933026004613, 4477.6417886569, 4477.955220447819, 3801.885943421702, 4553.089382123589, 4567.1550763108935, 4568.771561421916, 4574.611158051603, 4368.6068253856665, 5161.845144645667, 5356.0894647909945, 5374.973125134349, 5384.973075134678, 6085.36103709113, 6679.298673639198, 6684.899726504106, 6697.397234248941, 6712.3417737333275, 1368.9931550342383, 1554.9067055976452, 1555.2223888055971, 1559.7504399296286, 1568.7646852972146, 872.4547158026234, 979.9608015679436, 1018.8828284747349, 1026.958921643141, 1039.7244730146517, 6252.372485165739, 6269.0, 6298.338674439118, 6298.590591611531, 6300.558960872695, 5743.368229494752, 5953.523718102541, 6714.8584740594215, 6902.516823822385, 6912.447004239649, 5015.520421475673, 5926.466618004314, 6304.18045654062, 6307.779227727007, 6308.936910630835, 2959.8520073996215, 3526.8008876981435, 3568.3398571264397, 3603.441466572633, 3616.5840928293055, 6159.81520554384, 6747.730090796412, 7051.647417629098, 7080.221175670672, 7126.0, 6086.452219300284, 7002.949557566406, 7038.225795162528, 7130.607816570138, 7144.821379465555, 6714.9664251679405, 6876.277990811093, 7051.541649792747, 7075.186353569303, 7128.607926563985, 6912.239653638197, 6950.235474097946, 6961.930380696128, 6970.686319115551, 7012.368886800008, 6542.869142617216, 6825.69284382194, 6830.692618832065, 6852.45180385558, 6863.828404289933, 5373.570114390761, 6308.71610777507, 6757.594544327356, 7170.713171473083, 7201.243869393638, 3745.587985321558, 4124.463819703419, 4460.7323560586465, 4474.888127796831, 4530.411046563926, 5933.673647949404, 6566.770163044271, 6833.726650933907, 6859.142607174103, 6927.376536111774, 5707.571932105026, 6626.867462650624, 6662.900056499059, 6680.799576012728, 6698.196216454056, 5884.823035392853, 6042.305134909541, 6053.697315134314, 6275.090111933835, 6646.268910419753, 5545.724483368767, 6352.809415717536, 6514.6416947068365, 6542.934570654233, 6579.407853293035, 5520.696361699985, 6649.667516624053, 6693.230278517838, 6706.396424321834, 6777.762778302736, 6161.383861613803, 6354.364563543609, 6399.29651706005, 6443.259025212161, 6458.869697802959], "value": 4767.9699320921545, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [788.6056971514242, 797.9680812767486, 823.3536673711136, 823.3948048184583, 833.1626715151272, 13571.389287482063, 14061.648458788535, 15578.766318505232, 16039.198040098006, 16074.910261665982, 15863.206839658, 16484.258208380623, 16870.57823554412, 16886.74669879953, 17093.31626734932, 947.5262368815593, 970.8932017478072, 975.7609385700513, 976.4873441443228, 978.5938835383307, 988.9752756181081, 989.8515222716615, 990.5839547390113, 993.1955116355739, 999.5751805482677, 1054.362110922892, 1057.740853490896, 1057.9206559508068, 1058.5712786321515, 1065.7761870007268, 1044.185535282481, 1051.7160366701025, 1053.6470282455425, 1053.7892421515728, 1061.7451811565206, 767.6008475592716, 769.6305773228826, 773.222910974467, 773.3156156801215, 780.5004796929969, 13156.802647960289, 15815.683686326209, 16268.593285167846, 16349.673006539922, 16385.426510072208, 15435.845641543554, 15683.764743528742, 16025.35898564056, 16033.0, 16105.27526261321, 13074.346282685829, 13119.606411807661, 13603.047786654937, 13785.241811700353, 13804.0, 3543.7696549724187, 3544.4151714967, 3545.2732189901008, 3556.928861422783, 3561.786292822439, 105713.47143264234, 109627.0, 110241.0, 110430.44784776025, 110832.4458377711, 12533.874661253452, 13017.804732929013, 13025.957923366108, 13077.934610326887, 13135.737285254432, 23074.538509229722, 23696.0, 24044.39889002754, 24080.51838963211, 24265.39336516601, 8535.829283414421, 8746.69386571467, 8787.165219304155, 8795.868061979076, 8808.77978050541, 4703.341532185496, 4713.858584242478, 4743.762811859393, 4777.713337199779, 4780.11567859947, 4052.4326594276827, 4208.915821683549, 4212.094399704017, 4224.260754367973, 4229.196452673982, 915.5788337364753, 940.7648087978006, 943.3867985809142, 945.2579724916045, 951.4148798489018, 566.8044524638993, 636.8694417644465, 667.2993356975156, 667.3093348384399, 680.9523333366716, 10878.292910960763, 11070.169737269576, 11088.22382433238, 11464.140189485823, 11765.88234117665, 589.7051474262869, 619.1610367951452, 647.513955472183, 656.5108993799649, 669.560445043161, 5171.560417364491, 5217.9478205217465, 5266.605004624668, 5277.1292736698015, 5344.465553444625, 100823.0, 101720.49139754254, 102253.86476811414, 102405.0, 104397.95602044034, 54744.0, 71080.0, 83578.91052723731, 87982.1201787974, 94504.0, 54512.90974180494, 73295.90056149162, 77933.61033194911, 78959.0, 80622.0, 11425.942870285595, 12099.213551119148, 12150.967167790837, 12224.511019559119, 12299.0, 32813.0, 37694.68068617585, 39418.802905985285, 40775.18449630932, 41760.16479670329, 70438.29561704522, 71214.64392678003, 71316.64341678258, 71402.78687458973, 72538.63730681525, 15032.924835375754, 15365.69268614599, 15819.129947852978, 16031.599210019844, 16044.358225670843, 14354.0, 16828.747568786723, 17507.599392048352, 17738.91130544339, 17851.37520186787, 15843.0, 16143.596410089842, 16156.67686646284, 16281.51155465338, 16330.101844398672, 15050.698986020438, 15529.68940621204, 15819.288132033857, 15904.840951590337, 16084.034957902564, 15057.247137642855, 15595.532134035997, 15706.842931570995, 15720.921395393407, 15972.0, 10990.890091098987, 10992.450377481255, 11036.668899933014, 11168.944155279172, 11234.719132021764, 10023.398596084258, 11439.828402574134, 12156.0, 12194.756104877675, 12196.390180490762], "value": 8172.629232269328, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1629.7999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 181.0, 122.0, 123.0, 125.0, 125.0, 127.0, 125.0, 122.0, 145.0], "value": 134.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 118.0, 116.0, 159.0, 117.0, 116.0, 118.0, 116.0, 142.0, 121.0], "value": 127.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [218.0, 218.0, 225.0, 235.0, 245.0, 219.0, 219.0, 216.0, 216.0, 216.0], "value": 222.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 63.0, 62.0, 86.0, 60.0, 63.0, 61.0, 81.0, 72.0, 67.0], "value": 70.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 89.0, 74.0, 73.0, 73.0, 86.0, 73.0, 72.0, 85.0], "value": 78.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 135.0, 115.0, 139.0, 94.0, 95.0, 95.0, 96.0, 96.0], "value": 105.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 150.0, 104.0, 104.0, 134.0, 163.0, 104.0, 159.0, 160.0, 103.0], "value": 128.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 102.0, 100.0, 102.0, 105.0, 102.0, 104.0, 157.0, 143.0, 102.0], "value": 112.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 53.0, 52.0, 52.0, 53.0, 54.0, 53.0, 54.0, 53.0, 55.0], "value": 53.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 67.0, 69.0, 66.0, 60.0, 61.0, 60.0, 57.0, 61.0], "value": 62.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 91.0, 97.0, 114.0, 80.0, 105.0, 118.0, 114.0, 115.0], "value": 101.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 173.0, 146.0, 146.0, 173.0, 151.0, 150.0, 165.0, 207.0, 174.0], "value": 165.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 194.0, 197.0, 191.0, 191.0, 194.0, 198.0, 197.0, 196.0, 196.0], "value": 195.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 72.0, 73.0, 71.0, 70.0, 72.0, 69.0, 71.0, 75.0, 84.0], "value": 73.1, "unit": "score"}]}]} 17:38:18 INFO - Return code: 0 17:38:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:38:18 ERROR - # TBPL SUCCESS # 17:38:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:38:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:38:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:38:18 INFO - 2017-04-11 17:38:18.131 screenresolution[1655:10820] starting screenresolution argv=screenresolution get 17:38:18 INFO - 2017-04-11 17:38:18.134 screenresolution[1655:10820] Display 0: 1600x1200x32@60 17:38:18 INFO - 2017-04-11 17:38:18.140 screenresolution[1656:10823] starting screenresolution argv=screenresolution list 17:38:18 INFO - Available Modes on Display 0 17:38:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:38:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:38:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:38:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:38:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:38:18 INFO - Intel Iris: 17:38:18 INFO - Chipset Model: Intel Iris 17:38:18 INFO - Type: GPU 17:38:18 INFO - Bus: Built-In 17:38:18 INFO - VRAM (Dynamic, Max): 1536 MB 17:38:18 INFO - Vendor: Intel (0x8086) 17:38:18 INFO - Device ID: 0x0a2e 17:38:18 INFO - Revision ID: 0x0009 17:38:18 INFO - Displays: 17:38:18 INFO - Display: 17:38:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:38:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:38:18 INFO - Display Serial Number: 1600x1200 60 17:38:18 INFO - Main Display: Yes 17:38:18 INFO - Mirror: Off 17:38:18 INFO - Online: Yes 17:38:18 INFO - Rotation: Supported 17:38:18 INFO - Return code: 0 17:38:18 INFO - Running post-action listener: _resource_record_post_action 17:38:18 INFO - [mozharness: 2017-04-12 00:38:18.374800Z] Finished run-tests step (success) 17:38:18 INFO - Running post-run listener: _resource_record_post_run 17:38:18 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 41556480; Write bytes: 525608960; Read time: 415; Write time: 1738 17:38:18 INFO - TinderboxPrint: CPU usage
54.2% 17:38:18 INFO - TinderboxPrint: I/O read bytes / time
41,556,480 / 415 17:38:18 INFO - TinderboxPrint: I/O write bytes / time
525,608,960 / 1,738 17:38:18 INFO - TinderboxPrint: CPU idle
2,200.1 (45.8%) 17:38:18 INFO - TinderboxPrint: CPU system
970.9 (20.2%) 17:38:18 INFO - TinderboxPrint: CPU user
1,634.8 (34.0%) 17:38:18 INFO - TinderboxPrint: Swap in / out
248,324,096 / 0 17:38:18 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 203794944; Write bytes: 205785088; Read time: 16010; Write time: 444 17:38:18 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 39545344; Write bytes: 312975360; Read time: 387; Write time: 1264 17:38:18 INFO - Running post-run listener: _upload_blobber_files 17:38:18 INFO - Blob upload gear active. 17:38:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:38:18 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 17:38:18 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', 'Pine', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:38:18 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 Pine -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:38:19 INFO - (blobuploader) - INFO - Open directory for files ... 17:38:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:38:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:38:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:38:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:38:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:38:20 INFO - (blobuploader) - INFO - Done attempting. 17:38:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:38:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:38:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:38:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:38:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:38:21 INFO - (blobuploader) - INFO - Done attempting. 17:38:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:38:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:38:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:38:22 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:38:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:38:22 INFO - (blobuploader) - INFO - Done attempting. 17:38:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:38:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:38:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:38:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:38:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:38:25 INFO - (blobuploader) - INFO - Done attempting. 17:38:25 INFO - (blobuploader) - INFO - Iteration through files over. 17:38:25 INFO - Return code: 0 17:38:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:38:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:38:25 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/28ec665b28bb9c056fdaea83911b0fd6cd0b6a5233c12524303cd995e2b19e81d31f9ba9ac53b620e412e421d180660d285f87947c66ad40f5274e8cacc1b31b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/7cb1a40245d2960338a1ad824a7b78dd81f108badd59c195ee0e3063fa0fbddde526b11379e7f1f5d37c8ce3f9f2ce2f84fb389b2e185320891a8ba5080b6399", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/dc37a465952113b255fce083109f1d40fa71f41964760c52562e9725f10785d19ff97fba827e58730c4648dec6bb8f99351551f978137473659d9ce576d9a292", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/4bbe4d85afc2626231c36636584111613ec4f9929dd3a48b84e77ec4bf1cd1fa24de11af55f414eccefdbd235f6b9607a4dc999840e61d8fea90dba8f42f396c"} 17:38:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:38:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:38:25 INFO - Contents: 17:38:25 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/28ec665b28bb9c056fdaea83911b0fd6cd0b6a5233c12524303cd995e2b19e81d31f9ba9ac53b620e412e421d180660d285f87947c66ad40f5274e8cacc1b31b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/7cb1a40245d2960338a1ad824a7b78dd81f108badd59c195ee0e3063fa0fbddde526b11379e7f1f5d37c8ce3f9f2ce2f84fb389b2e185320891a8ba5080b6399", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/dc37a465952113b255fce083109f1d40fa71f41964760c52562e9725f10785d19ff97fba827e58730c4648dec6bb8f99351551f978137473659d9ce576d9a292", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/4bbe4d85afc2626231c36636584111613ec4f9929dd3a48b84e77ec4bf1cd1fa24de11af55f414eccefdbd235f6b9607a4dc999840e61d8fea90dba8f42f396c"} 17:38:25 INFO - Running post-run listener: copy_logs_to_upload_dir 17:38:25 INFO - Copying logs to upload dir... 17:38:25 INFO - mkdir: /builds/slave/test/build/upload/logs 17:38:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1286.497180 ========= master_lag: -0.19 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-04-11 17:38:24.922316) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-11 17:38:24.925831) ========= 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.KgsNtx65kq/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.OKpRdnCgDr/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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/Pine/sha512/28ec665b28bb9c056fdaea83911b0fd6cd0b6a5233c12524303cd995e2b19e81d31f9ba9ac53b620e412e421d180660d285f87947c66ad40f5274e8cacc1b31b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/7cb1a40245d2960338a1ad824a7b78dd81f108badd59c195ee0e3063fa0fbddde526b11379e7f1f5d37c8ce3f9f2ce2f84fb389b2e185320891a8ba5080b6399", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/dc37a465952113b255fce083109f1d40fa71f41964760c52562e9725f10785d19ff97fba827e58730c4648dec6bb8f99351551f978137473659d9ce576d9a292", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/4bbe4d85afc2626231c36636584111613ec4f9929dd3a48b84e77ec4bf1cd1fa24de11af55f414eccefdbd235f6b9607a4dc999840e61d8fea90dba8f42f396c"} build_url:https://queue.taskcluster.net/v1/task/Aem_OVofRHSs4hlCu-s-2A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011231 build_url: 'https://queue.taskcluster.net/v1/task/Aem_OVofRHSs4hlCu-s-2A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/28ec665b28bb9c056fdaea83911b0fd6cd0b6a5233c12524303cd995e2b19e81d31f9ba9ac53b620e412e421d180660d285f87947c66ad40f5274e8cacc1b31b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/7cb1a40245d2960338a1ad824a7b78dd81f108badd59c195ee0e3063fa0fbddde526b11379e7f1f5d37c8ce3f9f2ce2f84fb389b2e185320891a8ba5080b6399", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/dc37a465952113b255fce083109f1d40fa71f41964760c52562e9725f10785d19ff97fba827e58730c4648dec6bb8f99351551f978137473659d9ce576d9a292", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/4bbe4d85afc2626231c36636584111613ec4f9929dd3a48b84e77ec4bf1cd1fa24de11af55f414eccefdbd235f6b9607a4dc999840e61d8fea90dba8f42f396c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-11 17:38:24.961861) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 17:38:24.962252) ========= 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.KgsNtx65kq/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.OKpRdnCgDr/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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.004918 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 17:38:25.011713) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-11 17:38:25.012082) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-11 17:38:25.012477) ========= ========= Total master_lag: -0.05 =========