builder: jamun_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0067 starttime: 1494956475.66 results: success (0) buildid: 20170516091120 builduid: ef52b03ed67846b0b93efb101c3f2386 revision: 28f2fe60bc037ea4c70a8d22c5bce4957d6cd193 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-16 10:41:15.659527) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-16 10:41:15.660013) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-16 10:41:15.660305) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-16 10:41:15.859222) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 10:41:15.859636) ========= 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.FJzejibaBZ/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.Ej0jJqM0pp/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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-05-16 10:41:15-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 12.9M=0.001s 2017-05-16 10:41:16 (12.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.544616 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 10:41:16.445581) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 10:41:16.445861) ========= 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.FJzejibaBZ/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.Ej0jJqM0pp/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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.027997 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 10:41:16.497986) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-16 10:41:16.498296) ========= bash -c 'python archiver_client.py mozharness --repo projects/jamun --rev 28f2fe60bc037ea4c70a8d22c5bce4957d6cd193 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/jamun --rev 28f2fe60bc037ea4c70a8d22c5bce4957d6cd193 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FJzejibaBZ/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.Ej0jJqM0pp/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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-05-16 10:41:16,605 truncating revision to first 12 chars 2017-05-16 10:41:16,605 Setting DEBUG logging. 2017-05-16 10:41:16,605 attempt 1/10 2017-05-16 10:41:16,605 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/jamun/28f2fe60bc03?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-16 10:41:18,271 unpacking tar archive at: jamun-28f2fe60bc03/testing/mozharness/ program finished with exit code 0 elapsedTime=1.928082 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-16 10:41:18.448897) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-16 10:41:18.449228) ========= script_repo_revision: 28f2fe60bc037ea4c70a8d22c5bce4957d6cd193 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-16 10:41:18.452227) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-16 10:41:18.452512) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-16 10:41:18.471557) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 41 mins, 26 secs) (at 2017-05-16 10:41:18.471961) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Jamun --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Jamun 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', 'Jamun', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Jamun'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FJzejibaBZ/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.Ej0jJqM0pp/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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 10:41:18 INFO - MultiFileLogger online at 20170516 10:41:18 in /builds/slave/test 10:41:18 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Jamun --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Jamun 10:41:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:41:18 INFO - {'append_to_log': False, 10:41:18 INFO - 'base_work_dir': '/builds/slave/test', 10:41:18 INFO - 'blob_upload_branch': 'Jamun', 10:41:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:41:18 INFO - 'branch': 'Jamun', 10:41:18 INFO - 'buildbot_json_path': 'buildprops.json', 10:41:18 INFO - 'config_files': ('talos/mac_config.py',), 10:41:18 INFO - 'default_actions': ('clobber', 10:41:18 INFO - 'read-buildbot-config', 10:41:18 INFO - 'download-and-extract', 10:41:18 INFO - 'populate-webroot', 10:41:18 INFO - 'create-virtualenv', 10:41:18 INFO - 'install', 10:41:18 INFO - 'run-tests'), 10:41:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:41:18 INFO - 'download_minidump_stackwalk': True, 10:41:18 INFO - 'download_symbols': 'ondemand', 10:41:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:41:18 INFO - 'tooltool.py': '/tools/tooltool.py', 10:41:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:41:18 INFO - '/tools/misc-python/virtualenv.py')}, 10:41:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:41:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:41:18 INFO - 'gecko_profile': False, 10:41:18 INFO - 'gecko_profile_interval': 0, 10:41:18 INFO - 'installer_path': 'installer.exe', 10:41:18 INFO - 'log_level': 'info', 10:41:18 INFO - 'log_name': 'talos', 10:41:18 INFO - 'log_to_console': True, 10:41:18 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:41:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:41:18 INFO - 'opt_config_files': (), 10:41:18 INFO - 'pip_index': False, 10:41:18 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:41:18 INFO - 'cmd': ('bash', 10:41:18 INFO - '-c', 10:41:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:41:18 INFO - 'enabled': True, 10:41:18 INFO - 'halt_on_failure': False, 10:41:18 INFO - 'name': 'check_screen_resolution'},), 10:41:18 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:41:18 INFO - 'cmd': ('bash', 10:41:18 INFO - '-c', 10:41:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:41:18 INFO - 'enabled': True, 10:41:18 INFO - 'halt_on_failure': False, 10:41:18 INFO - 'name': 'check_screen_resolution'},), 10:41:18 INFO - 'run_cmd_checks_enabled': True, 10:41:18 INFO - 'suite': 'dromaeojs-e10s', 10:41:18 INFO - 'system_bits': '32', 10:41:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:41:18 INFO - 'title': 't-yosemite-r7-0067', 10:41:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:41:18 INFO - 'use_talos_json': True, 10:41:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:41:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:41:18 INFO - 'work_dir': 'build'} 10:41:18 INFO - [mozharness: 2017-05-16 17:41:18.693461Z] Running clobber step. 10:41:18 INFO - Running pre-action listener: _resource_record_pre_action 10:41:18 INFO - Running main action method: clobber 10:41:18 INFO - rmtree: /builds/slave/test/build 10:41:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:41:21 INFO - Running post-action listener: _resource_record_post_action 10:41:21 INFO - [mozharness: 2017-05-16 17:41:21.199163Z] Finished clobber step (success) 10:41:21 INFO - [mozharness: 2017-05-16 17:41:21.199237Z] Running read-buildbot-config step. 10:41:21 INFO - Running pre-action listener: _resource_record_pre_action 10:41:21 INFO - Running main action method: read_buildbot_config 10:41:21 INFO - Using buildbot properties: 10:41:21 INFO - { 10:41:21 INFO - "project": "", 10:41:21 INFO - "product": "firefox", 10:41:21 INFO - "script_repo_revision": "production", 10:41:21 INFO - "builddir": "jamun_yosemite_r7_devedition_test-dromaeojs-e10s", 10:41:21 INFO - "repository": "", 10:41:21 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition jamun talos dromaeojs-e10s", 10:41:21 INFO - "buildid": "20170516091120", 10:41:21 INFO - "pgo_build": "False", 10:41:21 INFO - "basedir": "/builds/slave/test", 10:41:21 INFO - "buildnumber": 0, 10:41:21 INFO - "slavename": "t-yosemite-r7-0067", 10:41:21 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 10:41:21 INFO - "platform": "yosemite_r7_devedition", 10:41:21 INFO - "scheduler": "tests-jamun-macosx64-devedition-talos", 10:41:21 INFO - "branch": "jamun", 10:41:21 INFO - "repo_path": "projects/jamun", 10:41:21 INFO - "revision": "28f2fe60bc037ea4c70a8d22c5bce4957d6cd193", 10:41:21 INFO - "stage_platform": "macosx64-devedition", 10:41:21 INFO - "builduid": "ef52b03ed67846b0b93efb101c3f2386", 10:41:21 INFO - "slavebuilddir": "test" 10:41:21 INFO - } 10:41:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZnGg28K8R5GbTrQ62NXNgg/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 10:41:21 INFO - Running post-action listener: _resource_record_post_action 10:41:21 INFO - [mozharness: 2017-05-16 17:41:21.202505Z] Finished read-buildbot-config step (success) 10:41:21 INFO - [mozharness: 2017-05-16 17:41:21.202637Z] Running download-and-extract step. 10:41:21 INFO - Running pre-action listener: _resource_record_pre_action 10:41:21 INFO - Running main action method: download_and_extract 10:41:21 INFO - mkdir: /builds/slave/test/build/tests 10:41:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:21 INFO - trying https://queue.taskcluster.net/v1/task/ZnGg28K8R5GbTrQ62NXNgg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 10:41:21 INFO - Downloading https://queue.taskcluster.net/v1/task/ZnGg28K8R5GbTrQ62NXNgg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 10:41:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZnGg28K8R5GbTrQ62NXNgg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 10:41:22 INFO - Downloaded 1287 bytes. 10:41:22 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 10:41:22 INFO - Using the following test package requirements: 10:41:22 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:41:22 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 10:41:22 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 10:41:22 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:41:22 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 10:41:22 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:41:22 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 10:41:22 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 10:41:22 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:41:22 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 10:41:22 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 10:41:22 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:41:22 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 10:41:22 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:41:22 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 10:41:22 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:41:22 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 10:41:22 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:41:22 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 10:41:22 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 10:41:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZnGg28K8R5GbTrQ62NXNgg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 10:41:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZnGg28K8R5GbTrQ62NXNgg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 10:41:22 INFO - Fetch https://queue.taskcluster.net/v1/task/ZnGg28K8R5GbTrQ62NXNgg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 10:41:23 INFO - Expected file size: 51797063 10:41:23 INFO - Obtained file size: 51797063 10:41:39 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 10:41:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZnGg28K8R5GbTrQ62NXNgg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 10:41:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZnGg28K8R5GbTrQ62NXNgg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 10:41:39 INFO - Fetch https://queue.taskcluster.net/v1/task/ZnGg28K8R5GbTrQ62NXNgg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 10:41:42 INFO - Expected file size: 51797063 10:41:42 INFO - Obtained file size: 51797063 10:41:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZnGg28K8R5GbTrQ62NXNgg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 10:41:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZnGg28K8R5GbTrQ62NXNgg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 10:41:57 INFO - Fetch https://queue.taskcluster.net/v1/task/ZnGg28K8R5GbTrQ62NXNgg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 10:41:58 INFO - Expected file size: 14404449 10:41:58 INFO - Obtained file size: 14404449 10:41:58 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')]} 10:41:58 INFO - trying https://queue.taskcluster.net/v1/task/ZnGg28K8R5GbTrQ62NXNgg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 10:41:58 INFO - Downloading https://queue.taskcluster.net/v1/task/ZnGg28K8R5GbTrQ62NXNgg/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 10:41:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZnGg28K8R5GbTrQ62NXNgg/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 10:42:01 INFO - Downloaded 74766883 bytes. 10:42:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZnGg28K8R5GbTrQ62NXNgg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 10:42:01 INFO - mkdir: /builds/slave/test/properties 10:42:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:42:01 INFO - Writing to file /builds/slave/test/properties/build_url 10:42:01 INFO - Contents: 10:42:01 INFO - build_url:https://queue.taskcluster.net/v1/task/ZnGg28K8R5GbTrQ62NXNgg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 10:42:01 INFO - Running post-action listener: _resource_record_post_action 10:42:01 INFO - Running post-action listener: set_extra_try_arguments 10:42:01 INFO - [mozharness: 2017-05-16 17:42:01.670876Z] Finished download-and-extract step (success) 10:42:01 INFO - [mozharness: 2017-05-16 17:42:01.670980Z] Running populate-webroot step. 10:42:01 INFO - Running pre-action listener: _resource_record_pre_action 10:42:01 INFO - Running main action method: populate_webroot 10:42:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:42:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:42:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:42:01 INFO - '--tppagecycles', 10:42:01 INFO - '7'], 10:42:01 INFO - 'tests': ['tsvgm']}}, 10:42:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:42:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:42:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:42:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:42:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:42:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:42:01 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:42:01 INFO - 'talos_options': ['--disable-e10s'], 10:42:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:42:01 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:42:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:42:01 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:42:01 INFO - 'talos_options': ['--disable-e10s'], 10:42:01 INFO - 'tests': ['damp', 'tps']}, 10:42:01 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:42:01 INFO - 'tests': ['damp', 'tps']}, 10:42:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:42:01 INFO - 'tests': ['dromaeo_dom']}, 10:42:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:42:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:42:01 INFO - 'tests': ['basic_compositor_video']}, 10:42:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:42:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:42:01 INFO - 'tests': ['a11yr', 10:42:01 INFO - 'ts_paint', 10:42:01 INFO - 'tpaint', 10:42:01 INFO - 'sessionrestore', 10:42:01 INFO - 'sessionrestore_no_auto_restore', 10:42:01 INFO - 'tabpaint']}, 10:42:01 INFO - 'other-e10s': {'tests': ['a11yr', 10:42:01 INFO - 'ts_paint', 10:42:01 INFO - 'tpaint', 10:42:01 INFO - 'sessionrestore', 10:42:01 INFO - 'sessionrestore_no_auto_restore', 10:42:01 INFO - 'tabpaint']}, 10:42:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:42:01 INFO - 'ts_paint', 10:42:01 INFO - 'tpaint', 10:42:01 INFO - 'sessionrestore', 10:42:01 INFO - 'sessionrestore_no_auto_restore', 10:42:01 INFO - 'tabpaint']}, 10:42:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:42:01 INFO - 'ts_paint', 10:42:01 INFO - 'tpaint', 10:42:01 INFO - 'sessionrestore', 10:42:01 INFO - 'sessionrestore_no_auto_restore', 10:42:01 INFO - 'tabpaint']}, 10:42:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:42:01 INFO - 'tests': ['a11yr', 10:42:01 INFO - 'ts_paint', 10:42:01 INFO - 'tpaint', 10:42:01 INFO - 'sessionrestore', 10:42:01 INFO - 'sessionrestore_no_auto_restore', 10:42:01 INFO - 'tabpaint']}, 10:42:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:42:01 INFO - 'tests': ['a11yr', 10:42:01 INFO - 'ts_paint', 10:42:01 INFO - 'tpaint', 10:42:01 INFO - 'sessionrestore', 10:42:01 INFO - 'sessionrestore_no_auto_restore', 10:42:01 INFO - 'tabpaint']}, 10:42:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:42:01 INFO - 'tests': ['tsvgx', 10:42:01 INFO - 'tsvgr_opacity', 10:42:01 INFO - 'tart', 10:42:01 INFO - 'tscrollx', 10:42:01 INFO - 'cart', 10:42:01 INFO - 'tsvg_static']}, 10:42:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:42:01 INFO - 'tsvgr_opacity', 10:42:01 INFO - 'tart', 10:42:01 INFO - 'tscrollx', 10:42:01 INFO - 'cart', 10:42:01 INFO - 'tsvg_static']}, 10:42:01 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:42:01 INFO - 'talos_options': ['--disable-e10s'], 10:42:01 INFO - 'tests': ['tp5o']}, 10:42:01 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:42:01 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:42:01 INFO - 'talos_options': ['--disable-e10s', 10:42:01 INFO - '--xperf_path', 10:42:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:42:01 INFO - 'tests': ['tp5n']}, 10:42:01 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:42:01 INFO - 'talos_options': ['--xperf_path', 10:42:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:42:01 INFO - 'tests': ['tp5n']}}, 10:42:01 INFO - 'talos.zip': {'path': '', 10:42:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:42:01 INFO - Running post-action listener: _resource_record_post_action 10:42:01 INFO - [mozharness: 2017-05-16 17:42:01.689203Z] Finished populate-webroot step (success) 10:42:01 INFO - [mozharness: 2017-05-16 17:42:01.689334Z] Running create-virtualenv step. 10:42:01 INFO - Running pre-action listener: _resource_record_pre_action 10:42:01 INFO - Running main action method: create_virtualenv 10:42:01 INFO - Creating virtualenv /builds/slave/test/build/venv 10:42:01 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 10:42:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:42:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:42:01 INFO - Using real prefix '/tools/python27' 10:42:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:42:02 INFO - Installing distribute.............................................................................................................................................................................................done. 10:42:05 INFO - Installing pip.................done. 10:42:05 INFO - Return code: 0 10:42:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:42:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:42:05 INFO - Reading from file tmpfile_stdout 10:42:05 INFO - Output received: 10:42:05 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) 10:42:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:42: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')]} 10:42:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:42:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:42: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')]} 10:42:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:42:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:42:05 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 0x10eec2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eec5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f93cda340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eca0d30>, '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 0x10ebc7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec95be8>, '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.FJzejibaBZ/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.Ej0jJqM0pp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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 10:42:05 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 10:42:05 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 10:42:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJzejibaBZ/Render', 10:42:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:42:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:42:05 INFO - 'HOME': '/Users/cltbld', 10:42:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:42:05 INFO - 'LOGNAME': 'cltbld', 10:42:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:42:05 INFO - 'MOZ_NO_REMOTE': '1', 10:42:05 INFO - 'NO_EM_RESTART': '1', 10:42:05 INFO - 'PAGER': '/bin/cat', 10:42:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:42:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:42:05 INFO - 'PWD': '/builds/slave/test', 10:42:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:42:05 INFO - 'SHELL': '/bin/bash', 10:42:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ej0jJqM0pp/Listeners', 10:42:05 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 10:42:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:42:05 INFO - 'USER': 'cltbld', 10:42:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:42:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:42:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:42:05 INFO - 'XPC_FLAGS': '0x0', 10:42:05 INFO - 'XPC_SERVICE_NAME': '0', 10:42:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:42:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:42:05 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 10:42:05 INFO - Cleaning up... 10:42:05 INFO - Return code: 0 10:42:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:42: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')]} 10:42:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:42:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:42: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')]} 10:42:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:42:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:42:05 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 0x10eec2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eec5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f93cda340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eca0d30>, '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 0x10ebc7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec95be8>, '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.FJzejibaBZ/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.Ej0jJqM0pp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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 10:42:05 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 10:42:05 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 10:42:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJzejibaBZ/Render', 10:42:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:42:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:42:05 INFO - 'HOME': '/Users/cltbld', 10:42:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:42:05 INFO - 'LOGNAME': 'cltbld', 10:42:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:42:05 INFO - 'MOZ_NO_REMOTE': '1', 10:42:05 INFO - 'NO_EM_RESTART': '1', 10:42:05 INFO - 'PAGER': '/bin/cat', 10:42:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:42:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:42:05 INFO - 'PWD': '/builds/slave/test', 10:42:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:42:05 INFO - 'SHELL': '/bin/bash', 10:42:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ej0jJqM0pp/Listeners', 10:42:05 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 10:42:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:42:05 INFO - 'USER': 'cltbld', 10:42:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:42:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:42:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:42:05 INFO - 'XPC_FLAGS': '0x0', 10:42:05 INFO - 'XPC_SERVICE_NAME': '0', 10:42:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:42:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:42:06 INFO - Downloading/unpacking psutil>=3.1.1 10:42:06 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 10:42:06 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 10:42:06 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 10:42:06 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 10:42:06 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 10:42:06 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 10:44:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:44:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:44:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:44:39 INFO - Installing collected packages: psutil 10:44:39 INFO - Running setup.py install for psutil 10:44:39 INFO - building 'psutil._psutil_osx' extension 10:44:39 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 10:44:39 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 10:44:40 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 10:44:40 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 10:44:40 INFO - building 'psutil._psutil_posix' extension 10:44:40 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 10:44:40 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:44:40 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:44:40 INFO - ^ 10:44:40 INFO - 1 warning generated. 10:44:40 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 10:44:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:44:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:44:40 INFO - Successfully installed psutil 10:44:40 INFO - Cleaning up... 10:44:40 INFO - Return code: 0 10:44:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:44:40 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')]} 10:44:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:44:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:40 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')]} 10:44:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:44:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:44:40 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 0x10eec2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eec5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f93cda340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eca0d30>, '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 0x10ebc7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec95be8>, '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.FJzejibaBZ/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.Ej0jJqM0pp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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 10:44:40 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 10:44:40 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 10:44:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJzejibaBZ/Render', 10:44:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:44:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:44:40 INFO - 'HOME': '/Users/cltbld', 10:44:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:44:40 INFO - 'LOGNAME': 'cltbld', 10:44:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:44:40 INFO - 'MOZ_NO_REMOTE': '1', 10:44:40 INFO - 'NO_EM_RESTART': '1', 10:44:40 INFO - 'PAGER': '/bin/cat', 10:44:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:44:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:44:40 INFO - 'PWD': '/builds/slave/test', 10:44:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:44:40 INFO - 'SHELL': '/bin/bash', 10:44:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ej0jJqM0pp/Listeners', 10:44:40 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 10:44:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:44:40 INFO - 'USER': 'cltbld', 10:44:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:44:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:44:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:44:40 INFO - 'XPC_FLAGS': '0x0', 10:44:40 INFO - 'XPC_SERVICE_NAME': '0', 10:44:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:44:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:44:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:44:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:44:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:44:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:44:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:44:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:44:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:47:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:47:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:47:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:47:13 INFO - Installing collected packages: mozsystemmonitor 10:47:13 INFO - Running setup.py install for mozsystemmonitor 10:47:13 INFO - Successfully installed mozsystemmonitor 10:47:13 INFO - Cleaning up... 10:47:13 INFO - Return code: 0 10:47:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:47:13 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')]} 10:47:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:47:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:47:13 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')]} 10:47:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:47:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:47: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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10eec2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eec5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f93cda340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eca0d30>, '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 0x10ebc7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec95be8>, '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.FJzejibaBZ/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.Ej0jJqM0pp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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 10:47: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==2.5.1'] in /builds/slave/test/build 10:47: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==2.5.1 10:47:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJzejibaBZ/Render', 10:47:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:47:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:47:15 INFO - 'HOME': '/Users/cltbld', 10:47:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:47:15 INFO - 'LOGNAME': 'cltbld', 10:47:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:47:15 INFO - 'MOZ_NO_REMOTE': '1', 10:47:15 INFO - 'NO_EM_RESTART': '1', 10:47:15 INFO - 'PAGER': '/bin/cat', 10:47:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:47:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:47:15 INFO - 'PWD': '/builds/slave/test', 10:47:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:47:15 INFO - 'SHELL': '/bin/bash', 10:47:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ej0jJqM0pp/Listeners', 10:47:15 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 10:47:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:47:15 INFO - 'USER': 'cltbld', 10:47:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:47:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:47:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:47:15 INFO - 'XPC_FLAGS': '0x0', 10:47:15 INFO - 'XPC_SERVICE_NAME': '0', 10:47:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:47:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:47:15 INFO - Downloading/unpacking jsonschema==2.5.1 10:47:15 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 10:47:15 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 10:47:15 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 10:47:15 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 10:47:15 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 10:47:15 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 10:49:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:49:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:49: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 10:49: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 10:49: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 10:49: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 10:49: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 10:49: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 10:52:18 INFO - Downloading functools32-3.2.3-2.tar.gz 10:52:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:52:18 INFO - warning: no files found matching '*.txt' 10:52:18 INFO - no previously-included directories found matching 'build' 10:52:18 INFO - no previously-included directories found matching 'dist' 10:52:18 INFO - no previously-included directories found matching '.git*' 10:52:18 INFO - Installing collected packages: jsonschema, functools32 10:52:18 INFO - Running setup.py install for functools32 10:52:18 INFO - warning: no files found matching '*.txt' 10:52:18 INFO - no previously-included directories found matching 'build' 10:52:18 INFO - no previously-included directories found matching 'dist' 10:52:18 INFO - no previously-included directories found matching '.git*' 10:52:18 INFO - Successfully installed jsonschema functools32 10:52:18 INFO - Cleaning up... 10:52:18 INFO - Return code: 0 10:52:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:52:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:52:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:52:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:20 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 0x10eec2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eec5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f93cda340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eca0d30>, '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 0x10ebc7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec95be8>, '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.FJzejibaBZ/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.Ej0jJqM0pp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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 10:52:20 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 10:52:20 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 10:52:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJzejibaBZ/Render', 10:52:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:52:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:52:20 INFO - 'HOME': '/Users/cltbld', 10:52:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:52:20 INFO - 'LOGNAME': 'cltbld', 10:52:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:20 INFO - 'MOZ_NO_REMOTE': '1', 10:52:20 INFO - 'NO_EM_RESTART': '1', 10:52:20 INFO - 'PAGER': '/bin/cat', 10:52:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:52:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:20 INFO - 'PWD': '/builds/slave/test', 10:52:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:52:20 INFO - 'SHELL': '/bin/bash', 10:52:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ej0jJqM0pp/Listeners', 10:52:20 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 10:52:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:52:20 INFO - 'USER': 'cltbld', 10:52:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:52:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:52:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:20 INFO - 'XPC_FLAGS': '0x0', 10:52:20 INFO - 'XPC_SERVICE_NAME': '0', 10:52:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:52:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:52:20 INFO - Cleaning up... 10:52:20 INFO - Return code: 0 10:52:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:52:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:52:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:52:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:20 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 0x10eec2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eec5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f93cda340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eca0d30>, '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 0x10ebc7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec95be8>, '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.FJzejibaBZ/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.Ej0jJqM0pp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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 10:52:20 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 10:52:20 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 10:52:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJzejibaBZ/Render', 10:52:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:52:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:52:20 INFO - 'HOME': '/Users/cltbld', 10:52:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:52:20 INFO - 'LOGNAME': 'cltbld', 10:52:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:20 INFO - 'MOZ_NO_REMOTE': '1', 10:52:20 INFO - 'NO_EM_RESTART': '1', 10:52:20 INFO - 'PAGER': '/bin/cat', 10:52:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:52:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:20 INFO - 'PWD': '/builds/slave/test', 10:52:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:52:20 INFO - 'SHELL': '/bin/bash', 10:52:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ej0jJqM0pp/Listeners', 10:52:20 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 10:52:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:52:20 INFO - 'USER': 'cltbld', 10:52:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:52:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:52:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:20 INFO - 'XPC_FLAGS': '0x0', 10:52:20 INFO - 'XPC_SERVICE_NAME': '0', 10:52:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:52:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:20 INFO - Downloading/unpacking blobuploader==1.2.4 10:52:20 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 10:52:20 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 10:52:20 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 10:52:20 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 10:52:20 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 10:52:20 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 10:54:52 INFO - Downloading blobuploader-1.2.4.tar.gz 10:54:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:54:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:54: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 10:54: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 10:54: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 10:54: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 10:54: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 10:54: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 10:57:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:57:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:57:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:57:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:57:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:57:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:57:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:57:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:59:54 INFO - Downloading docopt-0.6.1.tar.gz 10:59:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:59:54 INFO - Installing collected packages: blobuploader, requests, docopt 10:59:54 INFO - Running setup.py install for blobuploader 10:59:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:59:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:59:55 INFO - Running setup.py install for requests 10:59:55 INFO - Running setup.py install for docopt 10:59:55 INFO - Successfully installed blobuploader requests docopt 10:59:55 INFO - Cleaning up... 10:59:55 INFO - Return code: 0 10:59:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:59: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')]} 10:59:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:59:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:59: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')]} 10:59:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:59:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:59:56 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 0x10eec2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eec5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f93cda340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eca0d30>, '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 0x10ebc7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec95be8>, '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.FJzejibaBZ/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.Ej0jJqM0pp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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 10:59:56 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 10:59:56 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 10:59:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJzejibaBZ/Render', 10:59:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:59:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:59:56 INFO - 'HOME': '/Users/cltbld', 10:59:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:59:56 INFO - 'LOGNAME': 'cltbld', 10:59:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:59:56 INFO - 'MOZ_NO_REMOTE': '1', 10:59:56 INFO - 'NO_EM_RESTART': '1', 10:59:56 INFO - 'PAGER': '/bin/cat', 10:59:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:59:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:59:56 INFO - 'PWD': '/builds/slave/test', 10:59:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:59:56 INFO - 'SHELL': '/bin/bash', 10:59:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ej0jJqM0pp/Listeners', 10:59:56 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 10:59:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:59:56 INFO - 'USER': 'cltbld', 10:59:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:59:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:59:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:59:56 INFO - 'XPC_FLAGS': '0x0', 10:59:56 INFO - 'XPC_SERVICE_NAME': '0', 10:59:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:59:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:59:56 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-uiCgtE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:59:56 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-qdffgV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:59:56 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-nkPRGu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:59:57 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-lTwUaU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:59:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:59:57 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-PJroxl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:59:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:59:57 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-oWiVPv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:59:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:59:57 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-vfm9sD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:59:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:59:57 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-TKxxoA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:59:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:59:57 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-HU1ojS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:59:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:59:57 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-wIMvmf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:59:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:59:57 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-LAA6Vk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:59:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:59:57 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-ICHCjM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:59:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:59:57 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-VvqnVv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:59:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:59:58 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-k5mRN1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:59:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:59:58 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-p33aau-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:59:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:59:58 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-yAdXWd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:59:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:59:58 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-2xLmWP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:59:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:59:58 INFO - Running setup.py install for manifestparser 10:59:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:59:58 INFO - Running setup.py install for mozcrash 10:59:58 INFO - Running setup.py install for mozdebug 10:59:58 INFO - Running setup.py install for mozdevice 10:59:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:59:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:59:59 INFO - Running setup.py install for mozfile 10:59:59 INFO - Running setup.py install for mozhttpd 10:59:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:59:59 INFO - Running setup.py install for mozinfo 10:59:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:59:59 INFO - Running setup.py install for mozInstall 10:59:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:59:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:59:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:59:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:59:59 INFO - Running setup.py install for mozleak 10:59:59 INFO - Running setup.py install for mozlog 10:59:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:59:59 INFO - Running setup.py install for moznetwork 11:00:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:00:00 INFO - Running setup.py install for mozprocess 11:00:00 INFO - Running setup.py install for mozprofile 11:00:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:00:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:00:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:00:00 INFO - Running setup.py install for mozrunner 11:00:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:00:00 INFO - Running setup.py install for mozscreenshot 11:00:00 INFO - Running setup.py install for moztest 11:00:00 INFO - Running setup.py install for mozversion 11:00:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:00:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:00:01 INFO - Cleaning up... 11:00:01 INFO - Return code: 0 11:00:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:00: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')]} 11:00:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:00:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:00: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')]} 11:00:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:00:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:00:01 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 0x10eec2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eec5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f93cda340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eca0d30>, '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 0x10ebc7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec95be8>, '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.FJzejibaBZ/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.Ej0jJqM0pp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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 11:00:01 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 11:00:01 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 11:00:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJzejibaBZ/Render', 11:00:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:00:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:00:01 INFO - 'HOME': '/Users/cltbld', 11:00:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:00:01 INFO - 'LOGNAME': 'cltbld', 11:00:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:00:01 INFO - 'MOZ_NO_REMOTE': '1', 11:00:01 INFO - 'NO_EM_RESTART': '1', 11:00:01 INFO - 'PAGER': '/bin/cat', 11:00:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:00:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:00:01 INFO - 'PWD': '/builds/slave/test', 11:00:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:00:01 INFO - 'SHELL': '/bin/bash', 11:00:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ej0jJqM0pp/Listeners', 11:00:01 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 11:00:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:00:01 INFO - 'USER': 'cltbld', 11:00:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:00:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:00:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:00:01 INFO - 'XPC_FLAGS': '0x0', 11:00:01 INFO - 'XPC_SERVICE_NAME': '0', 11:00:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:00:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:00:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:00:01 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-jgQZlI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:00:01 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)) 11:00:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:00:01 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-xv_MLG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:00:01 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)) 11:00:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:00:01 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-VRJ4_r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:00:01 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)) 11:00:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:00:01 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-YYTX48-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:00:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:00:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:00:01 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-mA_TN9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:00:01 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)) 11:00:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:00:01 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-I_K3F7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:00:01 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)) 11:00:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:00:01 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-lQtYh8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:00:02 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)) 11:00:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:00:02 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-QktYVz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:00:02 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)) 11:00:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:00:02 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-nc5iFA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:00:02 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)) 11:00:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:00:02 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-lFQt7Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:00:02 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)) 11:00:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:00:02 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-B0rdjW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:00:02 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)) 11:00:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:00:02 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-ToY8IW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:00:02 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)) 11:00:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:00:02 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-2aDQrM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:00:02 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)) 11:00:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:00:02 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-9ktnh9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:00:02 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)) 11:00:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:00:02 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-WnXZE6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:00:02 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)) 11:00:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:00:02 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-EZ_OOS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:00:03 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)) 11:00:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:00:03 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-GeBjDZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:00:03 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)) 11:00:03 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)) 11:00:03 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)) 11:00:03 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)) 11:00:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:00:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:00:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:00: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 11:00: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 11:00: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 11:00: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 11:00: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 11:00: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 11:02:35 INFO - Downloading blessings-1.6.tar.gz 11:02:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:02:35 INFO - Installing collected packages: blessings 11:02:35 INFO - Running setup.py install for blessings 11:02:35 INFO - Successfully installed blessings 11:02:35 INFO - Cleaning up... 11:02:35 INFO - Return code: 0 11:02:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:02:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:02:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:02:36 INFO - Reading from file tmpfile_stdout 11:02:36 INFO - Current package versions: 11:02:36 INFO - blessings == 1.6 11:02:36 INFO - blobuploader == 1.2.4 11:02:36 INFO - docopt == 0.6.1 11:02:36 INFO - functools32 == 3.2.3-2 11:02:36 INFO - jsonschema == 2.5.1 11:02:36 INFO - manifestparser == 1.1 11:02:36 INFO - mozInstall == 1.12 11:02:36 INFO - mozcrash == 1.0 11:02:36 INFO - mozdebug == 0.1 11:02:36 INFO - mozdevice == 0.49 11:02:36 INFO - mozfile == 1.2 11:02:36 INFO - mozhttpd == 0.7 11:02:36 INFO - mozinfo == 0.9 11:02:36 INFO - mozleak == 0.1 11:02:36 INFO - mozlog == 3.4 11:02:36 INFO - moznetwork == 0.27 11:02:36 INFO - mozprocess == 0.25 11:02:36 INFO - mozprofile == 0.28 11:02:36 INFO - mozrunner == 6.13 11:02:36 INFO - mozscreenshot == 0.1 11:02:36 INFO - mozsystemmonitor == 0.3 11:02:36 INFO - moztest == 0.8 11:02:36 INFO - mozversion == 1.4 11:02:36 INFO - psutil == 3.1.1 11:02:36 INFO - requests == 1.2.3 11:02:36 INFO - wsgiref == 0.1.2 11:02:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:02:36 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')]} 11:02:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:02:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02:36 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')]} 11:02:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:02:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02:37 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 0x10eec2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eec5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f93cda340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eca0d30>, '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 0x10ebc7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec95be8>, '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.FJzejibaBZ/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.Ej0jJqM0pp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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 11:02:37 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 11:02:37 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 11:02:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJzejibaBZ/Render', 11:02:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:02:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:02:37 INFO - 'HOME': '/Users/cltbld', 11:02:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:02:37 INFO - 'LOGNAME': 'cltbld', 11:02:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:02:37 INFO - 'MOZ_NO_REMOTE': '1', 11:02:37 INFO - 'NO_EM_RESTART': '1', 11:02:37 INFO - 'PAGER': '/bin/cat', 11:02:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:02:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:02:37 INFO - 'PWD': '/builds/slave/test', 11:02:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:02:37 INFO - 'SHELL': '/bin/bash', 11:02:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ej0jJqM0pp/Listeners', 11:02:37 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 11:02:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:02:37 INFO - 'USER': 'cltbld', 11:02:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:02:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:02:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:02:37 INFO - 'XPC_FLAGS': '0x0', 11:02:37 INFO - 'XPC_SERVICE_NAME': '0', 11:02:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:02:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:02:37 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)) 11:02:37 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)) 11:02:37 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)) 11:02:37 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)) 11:02:37 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)) 11:02:37 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)) 11:02:37 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)) 11:02:37 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)) 11:02:37 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)) 11:02:37 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)) 11:02:37 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)) 11:02:37 INFO - Cleaning up... 11:02:37 INFO - Return code: 0 11:02:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:02: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')]} 11:02:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:02:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02: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')]} 11:02:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:02:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02:37 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 0x10eec2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eec5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f93cda340>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eca0d30>, '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 0x10ebc7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec95be8>, '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.FJzejibaBZ/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.Ej0jJqM0pp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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 11:02:37 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 11:02:37 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 11:02:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJzejibaBZ/Render', 11:02:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:02:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:02:37 INFO - 'HOME': '/Users/cltbld', 11:02:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:02:37 INFO - 'LOGNAME': 'cltbld', 11:02:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:02:37 INFO - 'MOZ_NO_REMOTE': '1', 11:02:37 INFO - 'NO_EM_RESTART': '1', 11:02:37 INFO - 'PAGER': '/bin/cat', 11:02:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:02:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:02:37 INFO - 'PWD': '/builds/slave/test', 11:02:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:02:37 INFO - 'SHELL': '/bin/bash', 11:02:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ej0jJqM0pp/Listeners', 11:02:37 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 11:02:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:02:37 INFO - 'USER': 'cltbld', 11:02:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:02:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:02:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:02:37 INFO - 'XPC_FLAGS': '0x0', 11:02:37 INFO - 'XPC_SERVICE_NAME': '0', 11:02:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:02:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:02:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:02:37 INFO - Cleaning up... 11:02:37 INFO - Return code: 0 11:02:37 INFO - Running post-action listener: _resource_record_post_action 11:02:37 INFO - Running post-action listener: _start_resource_monitoring 11:02:37 INFO - Starting resource monitoring. 11:02:37 INFO - [mozharness: 2017-05-16 18:02:37.937765Z] Finished create-virtualenv step (success) 11:02:37 INFO - [mozharness: 2017-05-16 18:02:37.938762Z] Running install step. 11:02:37 INFO - Running pre-action listener: _resource_record_pre_action 11:02:37 INFO - Running main action method: install 11:02:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:02:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:02:38 INFO - Reading from file tmpfile_stdout 11:02:38 INFO - Detecting whether we're running mozinstall >=1.0... 11:02:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:02:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:02:38 INFO - Reading from file tmpfile_stdout 11:02:38 INFO - Output received: 11:02:38 INFO - Usage: mozinstall [options] installer 11:02:38 INFO - Options: 11:02:38 INFO - -h, --help show this help message and exit 11:02:38 INFO - -d DEST, --destination=DEST 11:02:38 INFO - Directory to install application into. [default: 11:02:38 INFO - "/builds/slave/test"] 11:02:38 INFO - --app=APP Application being installed. [default: firefox] 11:02:38 INFO - mkdir: /builds/slave/test/build/application 11:02:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 11:02:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 11:02:58 INFO - Reading from file tmpfile_stdout 11:02:58 INFO - Output received: 11:02:58 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 11:02:58 INFO - Running post-action listener: _resource_record_post_action 11:02:58 INFO - [mozharness: 2017-05-16 18:02:58.595806Z] Finished install step (success) 11:02:58 INFO - [mozharness: 2017-05-16 18:02:58.595967Z] Running run-tests step. 11:02:58 INFO - Running pre-action listener: _resource_record_pre_action 11:02:58 INFO - Running main action method: run_tests 11:02:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:02:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:02:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:02:58 INFO - 2017-05-16 11:02:58.610 screenresolution[1635:11009] starting screenresolution argv=screenresolution get 11:02:58 INFO - 2017-05-16 11:02:58.613 screenresolution[1635:11009] Display 0: 1600x1200x32@60 11:02:58 INFO - 2017-05-16 11:02:58.619 screenresolution[1636:11011] starting screenresolution argv=screenresolution list 11:02:58 INFO - Available Modes on Display 0 11:02:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:02:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:02:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:02:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:02:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:02:58 INFO - Intel Iris: 11:02:58 INFO - Chipset Model: Intel Iris 11:02:58 INFO - Type: GPU 11:02:58 INFO - Bus: Built-In 11:02:58 INFO - VRAM (Dynamic, Max): 1536 MB 11:02:58 INFO - Vendor: Intel (0x8086) 11:02:58 INFO - Device ID: 0x0a2e 11:02:58 INFO - Revision ID: 0x0009 11:02:58 INFO - Displays: 11:02:58 INFO - Display: 11:02:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:02:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:02:58 INFO - Display Serial Number: 1600x1200 60 11:02:58 INFO - Main Display: Yes 11:02:58 INFO - Mirror: Off 11:02:58 INFO - Online: Yes 11:02:58 INFO - Rotation: Supported 11:02:58 INFO - Return code: 0 11:02:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:02:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:02:58 INFO - Python 2.7.3 11:02:58 INFO - Return code: 0 11:02:58 INFO - grabbing minidump binary from tooltool 11:02:58 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')]} 11:02:58 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 0x10eca0d30>, '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 0x10ebc7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec95be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:02:58 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 11:02:58 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 11:02:58 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 11:02:58 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:02:58 INFO - Return code: 0 11:02:58 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:02:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:02:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:02:58 INFO - ENV: RUST_BACKTRACE is now 1 11:02:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:02:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:02:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Jamun', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZnGg28K8R5GbTrQ62NXNgg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0067', '--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 11:02:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Jamun --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZnGg28K8R5GbTrQ62NXNgg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0067 --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 11:02:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJzejibaBZ/Render', 11:02:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:02:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:02:58 INFO - 'HOME': '/Users/cltbld', 11:02:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:02:58 INFO - 'LOGNAME': 'cltbld', 11:02:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:02:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:02:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:02:58 INFO - 'MOZ_NO_REMOTE': '1', 11:02:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:02:58 INFO - 'NO_EM_RESTART': '1', 11:02:58 INFO - 'PAGER': '/bin/cat', 11:02:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:02:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:02:58 INFO - 'PWD': '/builds/slave/test', 11:02:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:02:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:02:58 INFO - 'RUST_BACKTRACE': '1', 11:02:58 INFO - 'SHELL': '/bin/bash', 11:02:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ej0jJqM0pp/Listeners', 11:02:58 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 11:02:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:02:58 INFO - 'USER': 'cltbld', 11:02:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:02:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:02:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:02:58 INFO - 'XPC_FLAGS': '0x0', 11:02:58 INFO - 'XPC_SERVICE_NAME': '0', 11:02:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:02:58 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Jamun', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZnGg28K8R5GbTrQ62NXNgg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0067', '--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 11:02:59 INFO - mozversion application_buildid: 20170516091120 11:02:59 INFO - mozversion application_changeset: 28f2fe60bc037ea4c70a8d22c5bce4957d6cd193 11:02:59 INFO - mozversion application_display_name: FirefoxDeveloperEdition 11:02:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:02:59 INFO - mozversion application_name: Firefox 11:02:59 INFO - mozversion application_remotingname: firefox-dev 11:02:59 INFO - mozversion application_repository: https://hg.mozilla.org/projects/jamun 11:02:59 INFO - mozversion application_vendor: Mozilla 11:02:59 INFO - mozversion application_version: 54.0 11:02:59 INFO - mozversion platform_buildid: 20170516091120 11:02:59 INFO - mozversion platform_changeset: 28f2fe60bc037ea4c70a8d22c5bce4957d6cd193 11:02:59 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/jamun 11:02:59 INFO - mozversion platform_version: 54.0 11:02:59 INFO - using testdate: 1494957779 11:02:59 INFO - actual date: 1494957779 11:02:59 INFO - starting webserver on 'localhost:49250' 11:02:59 INFO - SUITE-START | Running 2 tests 11:02:59 INFO - TEST-START | dromaeo_css 11:02:59 INFO - operating with platform_type : mac_ 11:02:59 INFO - Initialising browser for dromaeo_css test... 11:02:59 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/tmp2v6iiQ/profile http://localhost:49250/getInfo.html) 11:03:01 INFO - PROCESS | 1646 | 2017-05-16 11:03:01.982 plugin-container[1650:11198] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f4f, name = 'com.apple.tsm.portname' 11:03:01 INFO - PROCESS | 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:03:01 INFO - PROCESS | 1646 | 2017-05-16 11:03:01.982 plugin-container[1650:11198] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b1f, name = 'com.apple.CFPasteboardClient' 11:03:01 INFO - PROCESS | 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:03:01 INFO - PROCESS | 1646 | 2017-05-16 11:03:01.982 plugin-container[1650:11198] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:03:02 INFO - PROCESS | 1646 | __metrics Screen width/height:1600/1200 11:03:02 INFO - PROCESS | 1646 | colorDepth:24 11:03:02 INFO - PROCESS | 1646 | Browser inner width/height: 1024/689 11:03:02 INFO - PROCESS | 1646 | __metrics 11:03:02 ERROR - PROCESS | 1646 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 11:03:02 INFO - TEST-INFO | 1646: exit 0 11:03:02 INFO - Browser initialized. 11:03:02 INFO - Running cycle 1/1 for dromaeo_css test... 11:03:02 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/tmp2v6iiQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:03:04 INFO - PROCESS | 1652 | 2017-05-16 11:03:04.902 plugin-container[1653:11334] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4443, name = 'com.apple.tsm.portname' 11:03:04 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:03:04 INFO - PROCESS | 1652 | 2017-05-16 11:03:04.903 plugin-container[1653:11334] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x860f, name = 'com.apple.CFPasteboardClient' 11:03:04 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:03:04 INFO - PROCESS | 1652 | 2017-05-16 11:03:04.903 plugin-container[1653:11334] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:03:14 INFO - PROCESS | 1652 | RSS: Main: 202833920 11:03:14 INFO - PROCESS | 1652 | 11:06:19 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49250/tests/dromaeo/cssquery-ext.html) 11:06:21 INFO - PROCESS | 1652 | RSS: Main: 215728128 11:06:21 INFO - PROCESS | 1652 | 11:09:23 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-ext.html (next: http://localhost:49250/tests/dromaeo/cssquery-jquery.html) 11:09:24 INFO - PROCESS | 1652 | RSS: Main: 226734080 11:09:24 INFO - PROCESS | 1652 | 11:12:26 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49250/tests/dromaeo/cssquery-mootools.html) 11:12:26 INFO - PROCESS | 1652 | RSS: Main: 231165952 11:12:26 INFO - PROCESS | 1652 | 11:15:28 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49250/tests/dromaeo/cssquery-prototype.html) 11:15:29 INFO - PROCESS | 1652 | RSS: Main: 235106304 11:15:29 INFO - PROCESS | 1652 | 11:18:32 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49250/tests/dromaeo/cssquery-yui.html) 11:18:32 INFO - PROCESS | 1652 | RSS: Main: 237170688 11:18:32 INFO - PROCESS | 1652 | 11:21:35 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-yui.html (next: http://localhost:49250/tests/dromaeo/cssquery-dojo.html) 11:21:35 INFO - PROCESS | 1652 | RSS: Main: 235294720 11:21:35 INFO - PROCESS | 1652 | 11:21:35 INFO - PROCESS | 1652 | __start_tp_report 11:21:35 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 11:21:35 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 11:21:35 INFO - PROCESS | 1652 | |i|pagename|runs| 11:21:35 INFO - PROCESS | 1652 | |0;dojo.html;3022.788404811664;3449.551558297421;3466;3490.947635785463;3513.9985104245297;6114.113453549238;6115;6174.876502469959;6197.5041996640275;6221.284552276489;6120.63276203427;6163.568550201488;6189.721462534186;6207.689615519229;6215.62706237626;5461.99999999999;5540.667559946396;5542.362628297757;5562.9443705563035;5593.804216852411;5574.498295153436;5612.130119831432;5613.382527921945;5641.153826925953;5673.6879471629045;5381.704006279653;5408.594355423339;5609.831705048855;5613.915791263134;5622.156676498517;5473.781048758045;5485.50630443262;5487.835364939058;5494.1758736189495;5547.25112109868;5466.098093814516;5542.7782888684405;5565.970295495305;5586.41342659019;5649.576281778842;6247.750089996394;6251.249850017982;6295.68521573924;6300.4329610334835;6315.873682526324;6315.684215789238;6348.809535713936;6485.610863348167;6515.771947981798;6551.836204094888;5503.2020357048405;6418.711157997901;6494.707738151747;6503.382178693017;6583.506237032194;6240.7503699852405;6298.433141017331;6302.054691796267;6325.652089135096;6332.936670633327;6256.155419018465;6729.05793188954;6741.191057073084;6768.932310676929;6778.762743303961;6274.717637706314;6299.968500157562;6325.399087086719;6330.68346582669;6351.650659213788;6535.836604084936;6550.050242714779;6555.311692272338;6562.868742625121;6589.341065893468;6099.359567245376;6435.066915297256;6463.870722585616;6468.094466774655;6496.317886621837;6298.937010629927;6330.715117819709;6407;6512.413882750575;6516.902246466307;4702.435707715028;4996.575975846867;5111;5123.231515272739;5172.8965420691375;4636.513166117509;4636.744979026186;4653.6509761768;4664.55686709762;4677.766111694402;6149.7847575334645;6151.292601350812;6201.193844800147;6201.782937597162;6216.844578885564;6315.842103947346;6387.872242555123;6508.739650414025;6640.634765087966;6675.732970681215;6153.230846144232;6203.937960620336;6208.099825525273;6215.65813880241;6236.18929539166;5765.740541675635;5770.596058275881;5783.855403614859;5796.101604251347;5805.941940580625;6488.610683359014;6575.967120164369;6782.423494002867;6827.283135270725;6860.794176174722;6500.187476565429;6503.317151699003;6579.967100164468;6592.637404942774;6620.735170593122;6758.5606935550995;6804.353586409283;6834.077399551195;6843.281455447106;6855.760048398282;6324.494040476843;6515.02274658805;6538.444232240123;6566.0479230512265;6574.8356291093105;6410.935890641033;6486.318936511599;6505.8698826024165;6522.445592124533;6552.180977377828;6413.839654008687;6415;6421.133147025279;6428.4214420702365;6442.35576442352;6384.489240860813;6391;6413.454856337264;6428.614283143026;6463.612183269019;6072.392760723892;6097.847553811191;6155.6614386209185;6250.812475625738;6270;6388;6407.167068281094;6442.8389290268115;6468.15913931186;6481.189851268591;5277.815276465305;5954.838806432704;6160.907586386297;6217.595856269328;6282.717277722605 11:21:35 INFO - PROCESS | 1652 | |1;ext.html;692.9237783843777;790.2492631999602;805.4160733468235;831.9292860106891;955.3074021334529;38540;39351;40778.184436311254;40925.795371023174;41563.58436415635;39286.8035659822;39356.80321598395;39495.40756888649;39563;39563.80218098913;7996.160403157672;8127.878081828777;10938;11239.157063220251;11401.828972565418;11146.665600032013;11253.268537545033;11263;11263.324200547993;11295.49170287339;11278.041366483858;11283.605073822418;11309;11322.15083868709;11339.092872570174;11226;11255.606053788119;11263.831042534368;11272.774544509064;11281;10982.231243812936;11001.17491188156;11048.558057677683;11059.004689577896;11061.944690276498;34602.653973460445;34721.479177812354;34858.12854678653;34869.12827179315;34878.65121348779;3785.6782173515007;3794.4118661607217;3799.67702745267;3834.559025712023;3866.5360156781085;25587.74412255872;26986;26993.92024319025;27007.1897843065;27050.864745676146;3016.8793248270044;3150.8582113804932;3155.195425166568;3192.4413227685295;3280.2291461506525;319115;324861;371167;371445;374826;43194.568054319054;43365.91585210395;43369.78315108404;43383.915402114566;43421.56578434238;45604;45661;45776.855578610805;45869.77065114653;46134.30798538025;13688.65778355549;13751.243681597409;13769.724605507834;13783.586492405244;13820;12093.75812483763;12153.756924861453;12168.513259469522;12192.756144877052;12196.39018049094;11915.225510341974;11982.221155625031;12016.699582510333;12044.79552044806;12095.516179352904;4888.65779395446;5843.853903652443;5871.207387002785;5883.235179426647;5921.911171332434;1070.4433694478982;1107.7286064913988;1144.7710457908452;1148.115950717959;1183.479269121601;27683.58474622842;27959.137797537198;28180.168289061145;28386.290342741595;28522.00172993935;1028.8219988113567;1075.2849355178719;1170.040566735286;1242.3850194153952;1310.4365122997203;7497.512661677083;7875.251851074253;8386.077531471534;8579.099194584604;8673.869891951626;479183;496470;497120;498221;498656;58774.82450350969;59161.40838591645;59319.11021334684;59487.810243794884;59572;1414.504923276845;1466.8924961654097;1481.6814384907298;1484.0724547158027;1491.112787891203;13614.795778063535;16096.597585059999;16190.514284571482;16381;16639.334426623285;49012.509874900796;49552.256716148564;49937.500624993285;50038.74980625074;50111.99776004533;451406;455153.7242313767;477205;477786;482182;30089.54865676973;31071.378572427973;31951.881684140943;32796.67203327936;32802.671973280914;38948.83153505399;39826.00434989148;40292.79853600713;40961;41626.791866040476;27893.58159627648;28584.857075714488;28624.85687571549;29263.97576084827;29561;28883.133505994854;29018.854905725337;29055.854720727108;29820.254493637833;29874.103776886728;33294.66705333012;33959.83020084884;34014.48978265279;34271.97184084382;34551.30897382089;28945.276368090967;29437;29558.852205738833;29789.5531567031;30044.399112018073;22658.320250392513;22881.77118228863;23844.642330365405;24213.878930605235;24356.75643243545 11:21:35 INFO - PROCESS | 1652 | |2;jquery.html;334.5801019720251;356.4314917706258;394.26469634132354;435.7037214694009;445.12532872904745;16345.99999999997;20677.586448271024;21156.471088222803;22204.333869983886;22260.666090008643;22045.228417005408;22086.558268834615;22209.777902220972;22948;23209.071637134493;6866.347696968793;7137.536060156073;7168.677409516558;7174.8206294842785;7175.605341706204;6758.155230596176;6897.827554311158;6959.095317608704;6985.441164706811;7015.3335433134025;5638.04153293941;6956.304369563029;6974.0236366908675;6989.16130064393;7015.123109611299;5676.489115979561;6960.617166055865;7001.474889383291;7006.089208402875;7020.543664661781;6611.272759996396;6762.087118239024;6792.28680988494;6829.487788415889;6835.863282734367;27105.864470677716;27306;27412;27511.86244068787;27600;2927.312081660808;2929.22375570474;2943.1611990582796;2988.3126880817535;2993.5060714982;16294.918525407416;16618.6828184617;16976.15119244045;17017.82982170187;17118.91440542802;1757.1477833250863;2058.2731889869024;2074.3258441006583;2086.478380404899;2112.3979665795173;132529;133017;140381;140706;141182.29408852992;21861.234856779938;23509;23549;23772.405689857547;23804.52390952171;26372.8681356592;27717.7527011285;36499.81750091232;36776.08059798527;36808.263834723155;9364.625414983324;9896.406215627085;10436.530356133992;10486.632967846088;10608.363498190134;7344.926550734424;7884;9031.051739567381;9046.231070358961;9181.908180918106;9475.715728528156;9594.376365536218;9595.664151754656;9673.806523869483;9676.661316854017;4021.8135649983324;4172.624463798273;4253.191893540217;4436.82252709888;4466.888327791831;967.6081187119228;987.916027137687;990.2127808392345;996.6960077176386;1029.1972261635935;19603.411897643095;21714;22134.667979980313;22224;22269.3319200421;909.8453262945316;935.5602866652663;963.9662611808553;972.3290929258789;990.3562684255147;6759.155105611799;6956.617386043817;6959.8956015659805;7191.676374563157;7197.71209151628;790510;800507;810785;821895;824231;151256.48743512644;152438;153344;154735.45264547435;161348;1298.2729671384054;1315.9934200328937;1342.7512413455386;1356.2405053170257;1357.2060344698461;12317.322547259628;12356.62930112098;12618.305993170463;12684.619461416172;12722.681932951751;57780.133297999724;58580;60461.79076418359;62518.687406562676;65973.01040484493;85988;87186.70853008553;87829;88345.55827220823;88776;9371.390859594105;10951.34291942486;10999.725006874893;11353.262037967506;11792;11562.30626162433;11695.88304116982;12401.441935113122;12642.08095490401;12844.100912936005;10917.617883374043;10977.066949309397;11177.497012634289;11272.492737826982;11332.943335283271;10290.794184116126;10302.22733294991;10338.431386273827;10598.523066462181;10627.574897004342;10168.440735759605;10176.338537995007;10534.36793792375;10601.78079520865;11460.140489463156;11155.83266251023;11261.155413343868;11455.541778328774;11543.134264930331;11869.931706146488;10832.5125369357;11192.888071119185;11216.943915280372;11345.035671967646;11356.772864542498 11:21:35 INFO - PROCESS | 1652 | |3;mootools.html;1573.0325849602498;1598.2408356030887;1603.4387964212526;1613.5401410597979;1616.7251567233568;9491.762705932355;9803.656872009464;11132.165087618421;11870.990965767909;11889.940550297248;10707.143428525718;11078.169137314713;11090;11101.55593776248;11102.944485277583;2337.228714524203;2385.7852793248603;2398;2400.6519054737055;2405.7353691094054;2361.8700971446565;2375.7861792438675;2396.424858034077;2397.9040838366445;2412.4571971306405;2022.8570857465559;2355.081518207892;2375.9168429104984;2382.3924899150693;2391.5456063347997;2339.4502291961376;2360.480694247263;2361.4804742956608;2398.8290597009645;2404.182577923497;2332.953340933172;2348.248560460653;2353.729321128075;2356.8939397727145;2364.692589963294;9118;11306.604268850633;11314.547418103266;11402.885971140347;11405;5006.974965125151;5676.659400435987;5686.886262274773;5806.82579522615;5814.186013958049;3115.2367669920786;3357.8488967996254;3425.8972230833115;3507.9298414031578;3537.5047493350953;1190.6428071578491;1194.12828635096;1227.9508819647203;1242.142921384242;1245.7072587941827;16159.272832722438;16173.514794556182;16176.676466470723;16180.514584562365;16205.432809851598;4647.273858598373;4727.64542659302;4807.61539076873;4874.195757699939;4884.242942343943;12934;12934.805977910339;12965.870341296655;12971.610851674464;13004.154729942524;5801.6809075500405;5829.242198514166;5857.6192547485125;5878.177055212273;5880.617759845596;4362;4365.847195348148;4971.950280497221;5209.270702101709;5284.709340986283;3942.1130245695063;3995.340768773178;4023.517177938666;4070.4911886014247;4094.4267802507675;1051.8211903976342;1058.1006144777032;1077.7898309829666;1079.5411949921252;1080.475969154959;920.6501529418778;925.9259259259243;946.1579194516886;952.7189479103681;990.7622170678985;5425.945740542544;6458.483321334282;6828.2147553031045;6854.6229957351825;6918.720036793209;962.5427921737258;985.3004366899581;998.7615356957466;1034.4724190662666;1046.0794500839213;3672;4187.706860519735;4223.746575205498;4233.25917964355;4360.280553708602;13429.932850335686;13855.099418537762;13864.445422183;13946.860531394555;13993.3702983366;13815.585532434043;13833.308334583231;13853.307334633228;13880.722385552433;13891.652708682364;9847.409155450696;10178.847317290247;10196.796064078677;10210.489475526194;10217.386956782617;5514.420985796433;6778.93221067783;6879.793606191823;7053.612051337226;7157.391621712004;10425.843612345669;10431.009054139844;12392.998339822829;12452.688682783002;12713.93643031779;11191.328520288484;12973.286469244282;13170.47318107265;13387.263700496565;13406.463741450623;9065.864012039956;9354.485503297112;9597.992210817763;10203.744906377104;10610.787784244427;9980.550875210487;10104.949475252577;10107.292489525662;10168.745781355525;10197.541110650187;8851.159139881702;8876.467411955304;8888.822223555622;9095.818083638158;9147.268218542367;6600.867982640416;8106.675732970615;8610.182032706884;8699.782505437415;9027.593758281024;7038.331358520933;7472.850542989218;7781.883271751041;7876.212378762078;7930.405219608668;5896.292444906467;5897.321807992136;5928.555358348055;6032.638041717511;6109.725062372115;4910.803567857246;5476;5699.914501282401;5931.6441013539325;6278.372162783685 11:21:35 INFO - PROCESS | 1652 | |4;prototype.html;352.15834156366304;353.47685425570137;383.6087191065114;389.1302937933718;396.158163901709;4435.911281774366;4979.950200497994;5235.450277720842;5341.946580534199;5499.3675727291275;5228.555572776309;5263.236830659552;5290.93653475145;5319.654222475537;5345.732713364336;3988.5413177484525;4001.0997525556736;4065.6825609402504;4086.7139300248996;4390.714603550775;3890.5720370759336;4154.961259685078;4195.748255104703;4280.700350975433;4424.871657727275;4100.323446631302;4116.97941510292;4425.712328698624;4441.111777644468;4451.527808309507;3543.2382037861985;4022.939655905164;4085;4351.042770590465;4429.357743127244;3471.89584312471;3486.860525578974;3580.9561872408244;3582.8387722552548;4039.444813746729;4534.1158474097565;5157.252198431244;5167.819126330561;5171.974140129275;5228.346456692913;4232.830686772525;4532;4903.215485522299;5026.648134630577;5134.948650513485;5063.822766203202;5376.4085950545405;5574.498295153456;5631.522130262712;5682.8973279886295;4064.593540645941;4151.6886233532605;4345.630621397185;4517.470216678637;4551.249043907751;4955.653104282737;5115.851342436185;5771.624844385102;5923.17075609415;6121.602095863755;3970.7419017763755;4306.310990241546;4569.383133276965;4591.471980722193;4605.309203619484;4704.576588107018;4706.882327941828;4725.598324142417;4783.617310615143;4805.327254184417;4739.881502962385;4740.170091924261;5075.746212689424;5094.363204599425;5096.566791822663;4871.342368780241;4947.777350019258;5121.411037730634;5560.527355174774;5620.887582248332;1363.8295213098363;1498.3332417074516;1572.9764053539207;1598.5843680642884;1607.967840643204;1038.200585549138;1046.3355769086684;1153.4809335798925;1191.4042978510745;1225.326070661133;4388.341748737651;5090.872728181825;5098.898022039539;5128.897422051538;5295.152775555893;5521;5858.53131749459;5892.6759028253855;5915.526757859361;5917.846020026053;4421.491528474202;4527.34751815585;5246.8425947220885;5265.341832270966;5283.339582552181;2619.9869000654876;2791;2804.831710097401;3065.494193458099;3070.186400603842;5141.305923700253;5337.7989952261205;5538.280023596906;5782.219400380979;6043.519337762276;4980.9252861207115;5093.9745301274;5196.766145523385;5682.664573825148;6170.7223174957235;5299.4435584263865;5487.780488780404;5934.851628709231;6007.339192688801;6125.234345706786;5081.442989907154;5763.94236057634;5932.970335148297;5949.750552384048;5954;5017.57350625201;5171.044151224241;5755.266248531914;5869.383714709894;5948.839976204598;5084;5959.880802384015;6134.374390786535;6166.568340216321;6239;4391.736495810262;4436.489803672619;4998.687683538703;5104.7283958638045;5185.714785686823;4922.901541969212;5352.464753524772;5522.45966855235;5587.832365029056;5856.765729370948;5475.550403245929;5546;5584.832455026356;5680.801171958961;5845.473907348359;4636.559526844944;5169.586433085419;5342.839714808562;5573.27547418833;5783.392743761844;5013.949860501494;5511.5315198207;5522.806701765419;5535.418781027934;5558.6097977868285;4995.975020124876;5335.466453354634;5683.261176047087;5758.798442054508;5898;4591.77041147948;4816.097641431685;4903.166461701448;5343.572514198932;5547.751755854901 11:21:35 INFO - PROCESS | 1652 | |5;yui.html;720.7045111504284;725.2529894208967;733.1971491217122;825.7398919340408;844.923956843884;15328.310226039826;15854.445094421697;16266;16630.916845415755;16794.748078778826;14869.182194979272;15079.321430535625;15254.847451525482;15257;15462.69074618507;922.1976880114294;927.9953600231989;939.1500691873831;943.1841457139559;945.654835984864;950.0452045694102;951.600327862296;963.0658261486382;963.9228861691083;963.9614415423374;1008.5864795433873;1008.7377281906683;1008.793197394536;1010.206987514801;1012.7063151686002;989.6536212325665;999.5551979369184;1003.523326419952;1004.3321191407741;1004.8392257238806;737.8229224986019;749.1534565940452;749.8912657664663;750.5871770526189;751.7105914223009;16494.10317813455;16816;17009.149542522948;17011.914940425344;17211.741823872653;14189.78715319271;14214.786778198335;14223.715525689428;14295.356708948018;14368.568942931728;11360.943195284053;11755.471003804849;11877.643670689893;11906.46420911061;11920.523179072827;3647.854085836563;3652.995426257774;3657.6890964267805;3701.777893326409;3735.2342769732413;111398;115465;116673;116711.2493312601;118362;10766.838497422545;10769.81042278102;11175;11302.886971130349;11449.713757156138;18248.543786405447;19166.233350665818;19549.902250488656;19595.706064409045;19627.21491140367;8088.312493438124;8098.392620553482;8170.182981701782;8178.100408955011;8209.220124088202;4266.274733295348;4346.304591265382;4380.62764665007;4454.487733910577;4470.776461176928;3485.8082805445943;3539.734519911017;3561.4835848801777;3625.6011838697723;3625.8549658013385;612.626297958254;614.797116951405;622.1600838867583;624.572168064877;626.5144513002368;669.3239827773983;680.3094858718405;690.4165979747106;702.6592102830124;727.5598263050883;10179.84730229047;10371.429571373495;10424.791504170025;10600.787984240425;10646;666.8566258254401;692.2212510925209;699.3740602161038;712.4976891291627;712.654362634122;4903.730294833818;5036.597072234213;5039.571636410946;5043.823466178666;5044.772985215674;97958;98929;100767.49616251871;101599;102086;76401;77072.61463692646;79388;82406;83449.58275208707;73896.26103739001;74261.6286918562;75733.62133189409;76780;77172;9440.763980900532;10425;10521.684789401324;10829.945850270698;11028.172887033665;46121;47116.52883471258;48729.75635121802;49047.773805655146;49469.50530494793;63574;65124.674376627816;66177.00734489082;66849.66575167092;68619.31380686328;12643.810342845049;12689.87310126887;12721.8091728626;12871.549495767978;13286.933565332112;14719.264036798331;14731;14947.252637368305;15120;15542.766858497358;13518.391672374963;13703.862961370658;13963.170216695573;14078.788818167532;14249.857501425267;12930.806037909251;13632.727345452837;13833.10084844482;14097.506587269396;14599.85400145985;13090.934545327213;13218.339083045617;13719.519816806363;14162.929185354007;14244.145351278956;9885.93155893535;9888.406695598287;10046;10110.545025474019;10166.847497287694;9916.801663966824;11051;11162;11326.37704926204;11435.371054592077 11:21:35 INFO - PROCESS | 1652 | __end_tp_report 11:21:35 INFO - PROCESS | 1652 | __start_cc_report 11:21:35 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,380 11:21:35 INFO - PROCESS | 1652 | __end_cc_report 11:21:35 INFO - PROCESS | 1652 | __startTimestamp1494958895700__endTimestamp 11:21:35 INFO - PROCESS | 1652 | 11:21:35 INFO - PROCESS | 1652 | ------- Summary: start ------- 11:21:35 INFO - PROCESS | 1652 | Number of tests: 6 11:21:35 INFO - PROCESS | 1652 | 11:21:35 INFO - PROCESS | 1652 | [#0] dojo.html Cycles:165 Average:6060.88 Median:6295.69 stddev:690.38 (11.0%) stddev-sans-first:650.06 11:21:35 INFO - PROCESS | 1652 | Values: 3022.8 3449.6 3466.0 3490.9 3514.0 6114.1 6115.0 6174.9 6197.5 6221.3 6120.6 6163.6 6189.7 6207.7 6215.6 5462.0 5540.7 5542.4 5562.9 5593.8 5574.5 5612.1 5613.4 5641.2 5673.7 5381.7 5408.6 5609.8 5613.9 5622.2 5473.8 5485.5 5487.8 5494.2 5547.3 5466.1 5542.8 5566.0 5586.4 5649.6 6247.8 6251.2 6295.7 6300.4 6315.9 6315.7 6348.8 6485.6 6515.8 6551.8 5503.2 6418.7 6494.7 6503.4 6583.5 6240.8 6298.4 6302.1 6325.7 6332.9 6256.2 6729.1 6741.2 6768.9 6778.8 6274.7 6300.0 6325.4 6330.7 6351.7 6535.8 6550.1 6555.3 6562.9 6589.3 6099.4 6435.1 6463.9 6468.1 6496.3 6298.9 6330.7 6407.0 6512.4 6516.9 4702.4 4996.6 5111.0 5123.2 5172.9 4636.5 4636.7 4653.7 4664.6 4677.8 6149.8 6151.3 6201.2 6201.8 6216.8 6315.8 6387.9 6508.7 6640.6 6675.7 6153.2 6203.9 6208.1 6215.7 6236.2 5765.7 5770.6 5783.9 5796.1 5805.9 6488.6 6576.0 6782.4 6827.3 6860.8 6500.2 6503.3 6580.0 6592.6 6620.7 6758.6 6804.4 6834.1 6843.3 6855.8 6324.5 6515.0 6538.4 6566.0 6574.8 6410.9 6486.3 6505.9 6522.4 6552.2 6413.8 6415.0 6421.1 6428.4 6442.4 6384.5 6391.0 6413.5 6428.6 6463.6 6072.4 6097.8 6155.7 6250.8 6270.0 6388.0 6407.2 6442.8 6468.2 6481.2 5277.8 5954.8 6160.9 6217.6 6282.7 11:21:35 INFO - PROCESS | 1652 | 11:21:35 INFO - PROCESS | 1652 | [#1] ext.html Cycles:180 Average:56492.92 Median:17111.12 stddev:117940.01 (689.3%) stddev-sans-first:118196.45 11:21:35 INFO - PROCESS | 1652 | Values: 692.9 790.2 805.4 831.9 955.3 38540.0 39351.0 40778.2 40925.8 41563.6 39286.8 39356.8 39495.4 39563.0 39563.8 7996.2 8127.9 10938.0 11239.2 11401.8 11146.7 11253.3 11263.0 11263.3 11295.5 11278.0 11283.6 11309.0 11322.2 11339.1 11226.0 11255.6 11263.8 11272.8 11281.0 10982.2 11001.2 11048.6 11059.0 11061.9 34602.7 34721.5 34858.1 34869.1 34878.7 3785.7 3794.4 3799.7 3834.6 3866.5 25587.7 26986.0 26993.9 27007.2 27050.9 3016.9 3150.9 3155.2 3192.4 3280.2 319115.0 324861.0 371167.0 371445.0 374826.0 43194.6 43365.9 43369.8 43383.9 43421.6 45604.0 45661.0 45776.9 45869.8 46134.3 13688.7 13751.2 13769.7 13783.6 13820.0 12093.8 12153.8 12168.5 12192.8 12196.4 11915.2 11982.2 12016.7 12044.8 12095.5 4888.7 5843.9 5871.2 5883.2 5921.9 1070.4 1107.7 1144.8 1148.1 1183.5 27683.6 27959.1 28180.2 28386.3 28522.0 1028.8 1075.3 1170.0 1242.4 1310.4 7497.5 7875.3 8386.1 8579.1 8673.9 479183.0 496470.0 497120.0 498221.0 498656.0 58774.8 59161.4 59319.1 59487.8 59572.0 1414.5 1466.9 1481.7 1484.1 1491.1 13614.8 16096.6 16190.5 16381.0 16639.3 49012.5 49552.3 49937.5 50038.7 50112.0 451406.0 455153.7 477205.0 477786.0 482182.0 30089.5 31071.4 31951.9 32796.7 32802.7 38948.8 39826.0 40292.8 40961.0 41626.8 27893.6 28584.9 28624.9 29264.0 29561.0 28883.1 29018.9 29055.9 29820.3 29874.1 33294.7 33959.8 34014.5 34272.0 34551.3 28945.3 29437.0 29558.9 29789.6 30044.4 22658.3 22881.8 23844.6 24213.9 24356.8 11:21:35 INFO - PROCESS | 1652 | 11:21:35 INFO - PROCESS | 1652 | [#2] jquery.html Cycles:180 Average:44119.54 Median:27359.00 stddev:134277.03 (490.8%) stddev-sans-first:134613.46 11:21:35 INFO - PROCESS | 1652 | Values: 334.6 356.4 394.3 435.7 445.1 16346.0 20677.6 21156.5 22204.3 22260.7 22045.2 22086.6 22209.8 22948.0 23209.1 6866.3 7137.5 7168.7 7174.8 7175.6 6758.2 6897.8 6959.1 6985.4 7015.3 5638.0 6956.3 6974.0 6989.2 7015.1 5676.5 6960.6 7001.5 7006.1 7020.5 6611.3 6762.1 6792.3 6829.5 6835.9 27105.9 27306.0 27412.0 27511.9 27600.0 2927.3 2929.2 2943.2 2988.3 2993.5 16294.9 16618.7 16976.2 17017.8 17118.9 1757.1 2058.3 2074.3 2086.5 2112.4 132529.0 133017.0 140381.0 140706.0 141182.3 21861.2 23509.0 23549.0 23772.4 23804.5 26372.9 27717.8 36499.8 36776.1 36808.3 9364.6 9896.4 10436.5 10486.6 10608.4 7344.9 7884.0 9031.1 9046.2 9181.9 9475.7 9594.4 9595.7 9673.8 9676.7 4021.8 4172.6 4253.2 4436.8 4466.9 967.6 987.9 990.2 996.7 1029.2 19603.4 21714.0 22134.7 22224.0 22269.3 909.8 935.6 964.0 972.3 990.4 6759.2 6956.6 6959.9 7191.7 7197.7 790510.0 800507.0 810785.0 821895.0 824231.0 151256.5 152438.0 153344.0 154735.5 161348.0 1298.3 1316.0 1342.8 1356.2 1357.2 12317.3 12356.6 12618.3 12684.6 12722.7 57780.1 58580.0 60461.8 62518.7 65973.0 85988.0 87186.7 87829.0 88345.6 88776.0 9371.4 10951.3 10999.7 11353.3 11792.0 11562.3 11695.9 12401.4 12642.1 12844.1 10917.6 10977.1 11177.5 11272.5 11332.9 10290.8 10302.2 10338.4 10598.5 10627.6 10168.4 10176.3 10534.4 10601.8 11460.1 11155.8 11261.2 11455.5 11543.1 11869.9 10832.5 11192.9 11216.9 11345.0 11356.8 11:21:35 INFO - PROCESS | 1652 | 11:21:35 INFO - PROCESS | 1652 | [#3] mootools.html Cycles:180 Average:6777.04 Median:2402.42 stddev:4347.18 (181.0%) stddev-sans-first:4341.79 11:21:35 INFO - PROCESS | 1652 | Values: 1573.0 1598.2 1603.4 1613.5 1616.7 9491.8 9803.7 11132.2 11871.0 11889.9 10707.1 11078.2 11090.0 11101.6 11102.9 2337.2 2385.8 2398.0 2400.7 2405.7 2361.9 2375.8 2396.4 2397.9 2412.5 2022.9 2355.1 2375.9 2382.4 2391.5 2339.5 2360.5 2361.5 2398.8 2404.2 2333.0 2348.2 2353.7 2356.9 2364.7 9118.0 11306.6 11314.5 11402.9 11405.0 5007.0 5676.7 5686.9 5806.8 5814.2 3115.2 3357.8 3425.9 3507.9 3537.5 1190.6 1194.1 1228.0 1242.1 1245.7 16159.3 16173.5 16176.7 16180.5 16205.4 4647.3 4727.6 4807.6 4874.2 4884.2 12934.0 12934.8 12965.9 12971.6 13004.2 5801.7 5829.2 5857.6 5878.2 5880.6 4362.0 4365.8 4972.0 5209.3 5284.7 3942.1 3995.3 4023.5 4070.5 4094.4 1051.8 1058.1 1077.8 1079.5 1080.5 920.7 925.9 946.2 952.7 990.8 5425.9 6458.5 6828.2 6854.6 6918.7 962.5 985.3 998.8 1034.5 1046.1 3672.0 4187.7 4223.7 4233.3 4360.3 13429.9 13855.1 13864.4 13946.9 13993.4 13815.6 13833.3 13853.3 13880.7 13891.7 9847.4 10178.8 10196.8 10210.5 10217.4 5514.4 6778.9 6879.8 7053.6 7157.4 10425.8 10431.0 12393.0 12452.7 12713.9 11191.3 12973.3 13170.5 13387.3 13406.5 9065.9 9354.5 9598.0 10203.7 10610.8 9980.6 10104.9 10107.3 10168.7 10197.5 8851.2 8876.5 8888.8 9095.8 9147.3 6600.9 8106.7 8610.2 8699.8 9027.6 7038.3 7472.9 7781.9 7876.2 7930.4 5896.3 5897.3 5928.6 6032.6 6109.7 4910.8 5476.0 5699.9 5931.6 6278.4 11:21:35 INFO - PROCESS | 1652 | 11:21:35 INFO - PROCESS | 1652 | [#4] prototype.html Cycles:180 Average:4668.69 Median:5087.44 stddev:1323.93 (26.0%) stddev-sans-first:1287.39 11:21:35 INFO - PROCESS | 1652 | Values: 352.2 353.5 383.6 389.1 396.2 4435.9 4980.0 5235.5 5341.9 5499.4 5228.6 5263.2 5290.9 5319.7 5345.7 3988.5 4001.1 4065.7 4086.7 4390.7 3890.6 4155.0 4195.7 4280.7 4424.9 4100.3 4117.0 4425.7 4441.1 4451.5 3543.2 4022.9 4085.0 4351.0 4429.4 3471.9 3486.9 3581.0 3582.8 4039.4 4534.1 5157.3 5167.8 5172.0 5228.3 4232.8 4532.0 4903.2 5026.6 5134.9 5063.8 5376.4 5574.5 5631.5 5682.9 4064.6 4151.7 4345.6 4517.5 4551.2 4955.7 5115.9 5771.6 5923.2 6121.6 3970.7 4306.3 4569.4 4591.5 4605.3 4704.6 4706.9 4725.6 4783.6 4805.3 4739.9 4740.2 5075.7 5094.4 5096.6 4871.3 4947.8 5121.4 5560.5 5620.9 1363.8 1498.3 1573.0 1598.6 1608.0 1038.2 1046.3 1153.5 1191.4 1225.3 4388.3 5090.9 5098.9 5128.9 5295.2 5521.0 5858.5 5892.7 5915.5 5917.8 4421.5 4527.3 5246.8 5265.3 5283.3 2620.0 2791.0 2804.8 3065.5 3070.2 5141.3 5337.8 5538.3 5782.2 6043.5 4980.9 5094.0 5196.8 5682.7 6170.7 5299.4 5487.8 5934.9 6007.3 6125.2 5081.4 5763.9 5933.0 5949.8 5954.0 5017.6 5171.0 5755.3 5869.4 5948.8 5084.0 5959.9 6134.4 6166.6 6239.0 4391.7 4436.5 4998.7 5104.7 5185.7 4922.9 5352.5 5522.5 5587.8 5856.8 5475.6 5546.0 5584.8 5680.8 5845.5 4636.6 5169.6 5342.8 5573.3 5783.4 5013.9 5511.5 5522.8 5535.4 5558.6 4996.0 5335.5 5683.3 5758.8 5898.0 4591.8 4816.1 4903.2 5343.6 5547.8 11:21:35 INFO - PROCESS | 1652 | 11:21:35 INFO - PROCESS | 1652 | [#5] yui.html Cycles:180 Average:20374.17 Median:19611.46 stddev:29155.23 (148.7%) stddev-sans-first:29199.67 11:21:35 INFO - PROCESS | 1652 | Values: 720.7 725.3 733.2 825.7 844.9 15328.3 15854.4 16266.0 16630.9 16794.7 14869.2 15079.3 15254.8 15257.0 15462.7 922.2 928.0 939.2 943.2 945.7 950.0 951.6 963.1 963.9 964.0 1008.6 1008.7 1008.8 1010.2 1012.7 989.7 999.6 1003.5 1004.3 1004.8 737.8 749.2 749.9 750.6 751.7 16494.1 16816.0 17009.1 17011.9 17211.7 14189.8 14214.8 14223.7 14295.4 14368.6 11360.9 11755.5 11877.6 11906.5 11920.5 3647.9 3653.0 3657.7 3701.8 3735.2 111398.0 115465.0 116673.0 116711.2 118362.0 10766.8 10769.8 11175.0 11302.9 11449.7 18248.5 19166.2 19549.9 19595.7 19627.2 8088.3 8098.4 8170.2 8178.1 8209.2 4266.3 4346.3 4380.6 4454.5 4470.8 3485.8 3539.7 3561.5 3625.6 3625.9 612.6 614.8 622.2 624.6 626.5 669.3 680.3 690.4 702.7 727.6 10179.8 10371.4 10424.8 10600.8 10646.0 666.9 692.2 699.4 712.5 712.7 4903.7 5036.6 5039.6 5043.8 5044.8 97958.0 98929.0 100767.5 101599.0 102086.0 76401.0 77072.6 79388.0 82406.0 83449.6 73896.3 74261.6 75733.6 76780.0 77172.0 9440.8 10425.0 10521.7 10829.9 11028.2 46121.0 47116.5 48729.8 49047.8 49469.5 63574.0 65124.7 66177.0 66849.7 68619.3 12643.8 12689.9 12721.8 12871.5 13286.9 14719.3 14731.0 14947.3 15120.0 15542.8 13518.4 13703.9 13963.2 14078.8 14249.9 12930.8 13632.7 13833.1 14097.5 14599.9 13090.9 13218.3 13719.5 14162.9 14244.1 9885.9 9888.4 10046.0 10110.5 10166.8 9916.8 11051.0 11162.0 11326.4 11435.4 11:21:35 INFO - PROCESS | 1652 | -------- Summary: end -------- 11:21:35 INFO - PROCESS | 1652 | 11:21:36 INFO - TEST-INFO | 1652: exit 0 11:21:36 INFO - TEST-OK | dromaeo_css | took 1116977ms 11:21:36 INFO - TEST-START | kraken 11:21:36 INFO - operating with platform_type : mac_ 11:21:36 INFO - Initialising browser for kraken test... 11:21:36 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/tmpUBraj4/profile http://localhost:49250/getInfo.html) 11:21:38 INFO - PROCESS | 1658 | 2017-05-16 11:21:38.833 plugin-container[1660:13595] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x884b, name = 'com.apple.tsm.portname' 11:21:38 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:21:38 INFO - PROCESS | 1658 | 2017-05-16 11:21:38.834 plugin-container[1660:13595] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x884f, name = 'com.apple.CFPasteboardClient' 11:21:38 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:21:38 INFO - PROCESS | 1658 | 2017-05-16 11:21:38.834 plugin-container[1660:13595] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:21:38 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 11:21:38 INFO - PROCESS | 1658 | colorDepth:24 11:21:38 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 11:21:38 INFO - PROCESS | 1658 | __metrics 11:21:38 ERROR - PROCESS | 1658 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 11:21:39 INFO - TEST-INFO | 1658: exit 0 11:21:39 INFO - Browser initialized. 11:21:39 INFO - Running cycle 1/1 for kraken test... 11:21:39 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/tmpUBraj4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:21:41 INFO - PROCESS | 1661 | 2017-05-16 11:21:41.940 plugin-container[1662:13718] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c47, name = 'com.apple.tsm.portname' 11:21:41 INFO - PROCESS | 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:21:41 INFO - PROCESS | 1661 | 2017-05-16 11:21:41.940 plugin-container[1662:13718] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4513, name = 'com.apple.CFPasteboardClient' 11:21:41 INFO - PROCESS | 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:21:41 INFO - PROCESS | 1661 | 2017-05-16 11:21:41.940 plugin-container[1662:13718] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:21:51 INFO - PROCESS | 1661 | RSS: Main: 202682368 11:21:51 INFO - PROCESS | 1661 | 11:21:54 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-beat-detection) 11:21:55 INFO - PROCESS | 1661 | RSS: Main: 216150016 11:21:55 INFO - PROCESS | 1661 | 11:21:58 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-dft) 11:21:58 INFO - PROCESS | 1661 | RSS: Main: 224083968 11:21:58 INFO - PROCESS | 1661 | 11:22:02 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-fft) 11:22:03 INFO - PROCESS | 1661 | RSS: Main: 227909632 11:22:03 INFO - PROCESS | 1661 | 11:22:04 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-oscillator) 11:22:05 INFO - PROCESS | 1661 | RSS: Main: 228233216 11:22:05 INFO - PROCESS | 1661 | 11:22:07 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49250/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:22:08 INFO - PROCESS | 1661 | RSS: Main: 229515264 11:22:08 INFO - PROCESS | 1661 | 11:22:11 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49250/tests/kraken/driver.html?testName=imaging-darkroom) 11:22:11 INFO - PROCESS | 1661 | RSS: Main: 233271296 11:22:11 INFO - PROCESS | 1661 | 11:22:15 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49250/tests/kraken/driver.html?testName=imaging-desaturate) 11:22:15 INFO - PROCESS | 1661 | RSS: Main: 230277120 11:22:15 INFO - PROCESS | 1661 | 11:22:19 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49250/tests/kraken/driver.html?testName=json-parse-financial) 11:22:19 INFO - PROCESS | 1661 | RSS: Main: 231714816 11:22:19 INFO - PROCESS | 1661 | 11:22:21 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49250/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:22:22 INFO - PROCESS | 1661 | RSS: Main: 231825408 11:22:22 INFO - PROCESS | 1661 | 11:22:23 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:22:24 INFO - PROCESS | 1661 | RSS: Main: 231821312 11:22:24 INFO - PROCESS | 1661 | 11:22:26 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:22:27 INFO - PROCESS | 1661 | RSS: Main: 231870464 11:22:27 INFO - PROCESS | 1661 | 11:22:30 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:22:31 INFO - PROCESS | 1661 | RSS: Main: 232239104 11:22:31 INFO - PROCESS | 1661 | 11:22:34 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:22:35 INFO - PROCESS | 1661 | RSS: Main: 231985152 11:22:35 INFO - PROCESS | 1661 | 11:22:36 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49250/tests/kraken/driver.html?testName=ai-astar) 11:22:37 INFO - PROCESS | 1661 | RSS: Main: 235474944 11:22:37 INFO - PROCESS | 1661 | 11:22:37 INFO - PROCESS | 1661 | __start_tp_report 11:22:37 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 11:22:37 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 11:22:37 INFO - PROCESS | 1661 | |i|pagename|runs| 11:22:37 INFO - PROCESS | 1661 | |0;ai-astar;107;120;120;120;121;123;119;119;119;127 11:22:37 INFO - PROCESS | 1661 | |1;audio-beat-detection;135;112;113;148;130;109;143;143;164;117 11:22:37 INFO - PROCESS | 1661 | |2;audio-dft;276;256;204;200;205;204;197;204;204;200 11:22:37 INFO - PROCESS | 1661 | |3;audio-fft;87;60;64;89;63;57;60;61;73;61 11:22:37 INFO - PROCESS | 1661 | |4;audio-oscillator;82;71;86;76;73;72;96;102;102;113 11:22:37 INFO - PROCESS | 1661 | |5;imaging-gaussian-blur;106;91;93;99;102;91;100;111;90;91 11:22:37 INFO - PROCESS | 1661 | |6;imaging-darkroom;126;155;153;108;96;109;123;155;153;98 11:22:37 INFO - PROCESS | 1661 | |7;imaging-desaturate;95;92;77;74;143;142;145;92;91;90 11:22:37 INFO - PROCESS | 1661 | |8;json-parse-financial;57;79;104;85;69;70;70;69;68;70 11:22:37 INFO - PROCESS | 1661 | |9;json-stringify-tinderbox;64;58;60;72;61;63;58;52;69;85 11:22:37 INFO - PROCESS | 1661 | |10;stanford-crypto-aes;84;79;85;78;94;90;99;108;76;96 11:22:37 INFO - PROCESS | 1661 | |11;stanford-crypto-ccm;135;139;139;136;141;140;143;154;184;139 11:22:37 INFO - PROCESS | 1661 | |12;stanford-crypto-pbkdf2;233;222;164;172;199;225;163;166;211;237 11:22:37 INFO - PROCESS | 1661 | |13;stanford-crypto-sha256-iterative;66;64;60;69;91;85;88;70;66;70 11:22:37 INFO - PROCESS | 1661 | __end_tp_report 11:22:37 INFO - PROCESS | 1661 | __start_cc_report 11:22:37 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,1021 11:22:37 INFO - PROCESS | 1661 | __end_cc_report 11:22:37 INFO - PROCESS | 1661 | __startTimestamp1494958957055__endTimestamp 11:22:37 INFO - PROCESS | 1661 | 11:22:37 INFO - PROCESS | 1661 | ------- Summary: start ------- 11:22:37 INFO - PROCESS | 1661 | Number of tests: 14 11:22:37 INFO - PROCESS | 1661 | 11:22:37 INFO - PROCESS | 1661 | [#0] ai-astar Cycles:10 Average:119.50 Median:120.00 stddev:5.04 (4.2%) stddev-sans-first:2.62 11:22:37 INFO - PROCESS | 1661 | Values: 107.0 120.0 120.0 120.0 121.0 123.0 119.0 119.0 119.0 127.0 11:22:37 INFO - PROCESS | 1661 | 11:22:37 INFO - PROCESS | 1661 | [#1] audio-beat-detection Cycles:10 Average:131.40 Median:139.00 stddev:18.40 (13.2%) stddev-sans-first:19.47 11:22:37 INFO - PROCESS | 1661 | Values: 135.0 112.0 113.0 148.0 130.0 109.0 143.0 143.0 164.0 117.0 11:22:37 INFO - PROCESS | 1661 | 11:22:37 INFO - PROCESS | 1661 | [#2] audio-dft Cycles:10 Average:215.00 Median:204.00 stddev:27.41 (13.4%) stddev-sans-first:18.12 11:22:37 INFO - PROCESS | 1661 | Values: 276.0 256.0 204.0 200.0 205.0 204.0 197.0 204.0 204.0 200.0 11:22:37 INFO - PROCESS | 1661 | 11:22:37 INFO - PROCESS | 1661 | [#3] audio-fft Cycles:10 Average:67.50 Median:63.50 stddev:11.61 (18.3%) stddev-sans-first:9.94 11:22:37 INFO - PROCESS | 1661 | Values: 87.0 60.0 64.0 89.0 63.0 57.0 60.0 61.0 73.0 61.0 11:22:37 INFO - PROCESS | 1661 | 11:22:37 INFO - PROCESS | 1661 | [#4] audio-oscillator Cycles:10 Average:87.30 Median:74.50 stddev:15.02 (20.2%) stddev-sans-first:15.81 11:22:37 INFO - PROCESS | 1661 | Values: 82.0 71.0 86.0 76.0 73.0 72.0 96.0 102.0 102.0 113.0 11:22:37 INFO - PROCESS | 1661 | 11:22:37 INFO - PROCESS | 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:97.40 Median:91.00 stddev:7.35 (8.1%) stddev-sans-first:7.11 11:22:37 INFO - PROCESS | 1661 | Values: 106.0 91.0 93.0 99.0 102.0 91.0 100.0 111.0 90.0 91.0 11:22:37 INFO - PROCESS | 1661 | 11:22:37 INFO - PROCESS | 1661 | [#6] imaging-darkroom Cycles:10 Average:127.60 Median:154.00 stddev:24.54 (15.9%) stddev-sans-first:26.02 11:22:37 INFO - PROCESS | 1661 | Values: 126.0 155.0 153.0 108.0 96.0 109.0 123.0 155.0 153.0 98.0 11:22:37 INFO - PROCESS | 1661 | 11:22:37 INFO - PROCESS | 1661 | [#7] imaging-desaturate Cycles:10 Average:104.10 Median:90.50 stddev:27.91 (30.8%) stddev-sans-first:29.40 11:22:37 INFO - PROCESS | 1661 | Values: 95.0 92.0 77.0 74.0 143.0 142.0 145.0 92.0 91.0 90.0 11:22:37 INFO - PROCESS | 1661 | 11:22:37 INFO - PROCESS | 1661 | [#8] json-parse-financial Cycles:10 Average:74.10 Median:70.00 stddev:12.78 (18.3%) stddev-sans-first:11.96 11:22:37 INFO - PROCESS | 1661 | Values: 57.0 79.0 104.0 85.0 69.0 70.0 70.0 69.0 68.0 70.0 11:22:37 INFO - PROCESS | 1661 | 11:22:37 INFO - PROCESS | 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:64.20 Median:63.50 stddev:9.26 (14.6%) stddev-sans-first:9.82 11:22:37 INFO - PROCESS | 1661 | Values: 64.0 58.0 60.0 72.0 61.0 63.0 58.0 52.0 69.0 85.0 11:22:37 INFO - PROCESS | 1661 | 11:22:37 INFO - PROCESS | 1661 | [#10] stanford-crypto-aes Cycles:10 Average:88.90 Median:87.50 stddev:10.37 (11.8%) stddev-sans-first:10.84 11:22:37 INFO - PROCESS | 1661 | Values: 84.0 79.0 85.0 78.0 94.0 90.0 99.0 108.0 76.0 96.0 11:22:37 INFO - PROCESS | 1661 | 11:22:37 INFO - PROCESS | 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:145.00 Median:140.50 stddev:14.67 (10.4%) stddev-sans-first:15.10 11:22:37 INFO - PROCESS | 1661 | Values: 135.0 139.0 139.0 136.0 141.0 140.0 143.0 154.0 184.0 139.0 11:22:37 INFO - PROCESS | 1661 | 11:22:37 INFO - PROCESS | 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:199.20 Median:216.50 stddev:30.35 (14.0%) stddev-sans-first:29.62 11:22:37 INFO - PROCESS | 1661 | Values: 233.0 222.0 164.0 172.0 199.0 225.0 163.0 166.0 211.0 237.0 11:22:37 INFO - PROCESS | 1661 | 11:22:37 INFO - PROCESS | 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.90 Median:70.00 stddev:10.93 (15.6%) stddev-sans-first:11.30 11:22:37 INFO - PROCESS | 1661 | Values: 66.0 64.0 60.0 69.0 91.0 85.0 88.0 70.0 66.0 70.0 11:22:37 INFO - PROCESS | 1661 | -------- Summary: end -------- 11:22:37 INFO - PROCESS | 1661 | 11:22:37 INFO - TEST-INFO | 1661: exit 0 11:22:37 INFO - TEST-OK | kraken | took 61570ms 11:22:37 INFO - SUITE-END | took 1178s 11:22:38 INFO - Completed test suite (00:19:39) 11:22:38 INFO - javascript benchmark 11:22:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7708.771926231795, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3022.788404811664, 3449.551558297421, 3466.0, 3490.947635785463, 3513.9985104245297, 6114.113453549238, 6115.0, 6174.876502469959, 6197.5041996640275, 6221.284552276489, 6120.63276203427, 6163.568550201488, 6189.721462534186, 6207.689615519229, 6215.62706237626, 5461.99999999999, 5540.667559946396, 5542.362628297757, 5562.9443705563035, 5593.804216852411, 5574.498295153436, 5612.130119831432, 5613.382527921945, 5641.153826925953, 5673.6879471629045, 5381.704006279653, 5408.594355423339, 5609.831705048855, 5613.915791263134, 5622.156676498517, 5473.781048758045, 5485.50630443262, 5487.835364939058, 5494.1758736189495, 5547.25112109868, 5466.098093814516, 5542.7782888684405, 5565.970295495305, 5586.41342659019, 5649.576281778842, 6247.750089996394, 6251.249850017982, 6295.68521573924, 6300.4329610334835, 6315.873682526324, 6315.684215789238, 6348.809535713936, 6485.610863348167, 6515.771947981798, 6551.836204094888, 5503.2020357048405, 6418.711157997901, 6494.707738151747, 6503.382178693017, 6583.506237032194, 6240.7503699852405, 6298.433141017331, 6302.054691796267, 6325.652089135096, 6332.936670633327, 6256.155419018465, 6729.05793188954, 6741.191057073084, 6768.932310676929, 6778.762743303961, 6274.717637706314, 6299.968500157562, 6325.399087086719, 6330.68346582669, 6351.650659213788, 6535.836604084936, 6550.050242714779, 6555.311692272338, 6562.868742625121, 6589.341065893468, 6099.359567245376, 6435.066915297256, 6463.870722585616, 6468.094466774655, 6496.317886621837, 6298.937010629927, 6330.715117819709, 6407.0, 6512.413882750575, 6516.902246466307, 4702.435707715028, 4996.575975846867, 5111.0, 5123.231515272739, 5172.8965420691375, 4636.513166117509, 4636.744979026186, 4653.6509761768, 4664.55686709762, 4677.766111694402, 6149.7847575334645, 6151.292601350812, 6201.193844800147, 6201.782937597162, 6216.844578885564, 6315.842103947346, 6387.872242555123, 6508.739650414025, 6640.634765087966, 6675.732970681215, 6153.230846144232, 6203.937960620336, 6208.099825525273, 6215.65813880241, 6236.18929539166, 5765.740541675635, 5770.596058275881, 5783.855403614859, 5796.101604251347, 5805.941940580625, 6488.610683359014, 6575.967120164369, 6782.423494002867, 6827.283135270725, 6860.794176174722, 6500.187476565429, 6503.317151699003, 6579.967100164468, 6592.637404942774, 6620.735170593122, 6758.5606935550995, 6804.353586409283, 6834.077399551195, 6843.281455447106, 6855.760048398282, 6324.494040476843, 6515.02274658805, 6538.444232240123, 6566.0479230512265, 6574.8356291093105, 6410.935890641033, 6486.318936511599, 6505.8698826024165, 6522.445592124533, 6552.180977377828, 6413.839654008687, 6415.0, 6421.133147025279, 6428.4214420702365, 6442.35576442352, 6384.489240860813, 6391.0, 6413.454856337264, 6428.614283143026, 6463.612183269019, 6072.392760723892, 6097.847553811191, 6155.6614386209185, 6250.812475625738, 6270.0, 6388.0, 6407.167068281094, 6442.8389290268115, 6468.15913931186, 6481.189851268591, 5277.815276465305, 5954.838806432704, 6160.907586386297, 6217.595856269328, 6282.717277722605], "value": 6014.315203274976, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [692.9237783843777, 790.2492631999602, 805.4160733468235, 831.9292860106891, 955.3074021334529, 38540.0, 39351.0, 40778.184436311254, 40925.795371023174, 41563.58436415635, 39286.8035659822, 39356.80321598395, 39495.40756888649, 39563.0, 39563.80218098913, 7996.160403157672, 8127.878081828777, 10938.0, 11239.157063220251, 11401.828972565418, 11146.665600032013, 11253.268537545033, 11263.0, 11263.324200547993, 11295.49170287339, 11278.041366483858, 11283.605073822418, 11309.0, 11322.15083868709, 11339.092872570174, 11226.0, 11255.606053788119, 11263.831042534368, 11272.774544509064, 11281.0, 10982.231243812936, 11001.17491188156, 11048.558057677683, 11059.004689577896, 11061.944690276498, 34602.653973460445, 34721.479177812354, 34858.12854678653, 34869.12827179315, 34878.65121348779, 3785.6782173515007, 3794.4118661607217, 3799.67702745267, 3834.559025712023, 3866.5360156781085, 25587.74412255872, 26986.0, 26993.92024319025, 27007.1897843065, 27050.864745676146, 3016.8793248270044, 3150.8582113804932, 3155.195425166568, 3192.4413227685295, 3280.2291461506525, 319115.0, 324861.0, 371167.0, 371445.0, 374826.0, 43194.568054319054, 43365.91585210395, 43369.78315108404, 43383.915402114566, 43421.56578434238, 45604.0, 45661.0, 45776.855578610805, 45869.77065114653, 46134.30798538025, 13688.65778355549, 13751.243681597409, 13769.724605507834, 13783.586492405244, 13820.0, 12093.75812483763, 12153.756924861453, 12168.513259469522, 12192.756144877052, 12196.39018049094, 11915.225510341974, 11982.221155625031, 12016.699582510333, 12044.79552044806, 12095.516179352904, 4888.65779395446, 5843.853903652443, 5871.207387002785, 5883.235179426647, 5921.911171332434, 1070.4433694478982, 1107.7286064913988, 1144.7710457908452, 1148.115950717959, 1183.479269121601, 27683.58474622842, 27959.137797537198, 28180.168289061145, 28386.290342741595, 28522.00172993935, 1028.8219988113567, 1075.2849355178719, 1170.040566735286, 1242.3850194153952, 1310.4365122997203, 7497.512661677083, 7875.251851074253, 8386.077531471534, 8579.099194584604, 8673.869891951626, 479183.0, 496470.0, 497120.0, 498221.0, 498656.0, 58774.82450350969, 59161.40838591645, 59319.11021334684, 59487.810243794884, 59572.0, 1414.504923276845, 1466.8924961654097, 1481.6814384907298, 1484.0724547158027, 1491.112787891203, 13614.795778063535, 16096.597585059999, 16190.514284571482, 16381.0, 16639.334426623285, 49012.509874900796, 49552.256716148564, 49937.500624993285, 50038.74980625074, 50111.99776004533, 451406.0, 455153.7242313767, 477205.0, 477786.0, 482182.0, 30089.54865676973, 31071.378572427973, 31951.881684140943, 32796.67203327936, 32802.671973280914, 38948.83153505399, 39826.00434989148, 40292.79853600713, 40961.0, 41626.791866040476, 27893.58159627648, 28584.857075714488, 28624.85687571549, 29263.97576084827, 29561.0, 28883.133505994854, 29018.854905725337, 29055.854720727108, 29820.254493637833, 29874.103776886728, 33294.66705333012, 33959.83020084884, 34014.48978265279, 34271.97184084382, 34551.30897382089, 28945.276368090967, 29437.0, 29558.852205738833, 29789.5531567031, 30044.399112018073, 22658.320250392513, 22881.77118228863, 23844.642330365405, 24213.878930605235, 24356.75643243545], "value": 18188.145965732143, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [334.5801019720251, 356.4314917706258, 394.26469634132354, 435.7037214694009, 445.12532872904745, 16345.99999999997, 20677.586448271024, 21156.471088222803, 22204.333869983886, 22260.666090008643, 22045.228417005408, 22086.558268834615, 22209.777902220972, 22948.0, 23209.071637134493, 6866.347696968793, 7137.536060156073, 7168.677409516558, 7174.8206294842785, 7175.605341706204, 6758.155230596176, 6897.827554311158, 6959.095317608704, 6985.441164706811, 7015.3335433134025, 5638.04153293941, 6956.304369563029, 6974.0236366908675, 6989.16130064393, 7015.123109611299, 5676.489115979561, 6960.617166055865, 7001.474889383291, 7006.089208402875, 7020.543664661781, 6611.272759996396, 6762.087118239024, 6792.28680988494, 6829.487788415889, 6835.863282734367, 27105.864470677716, 27306.0, 27412.0, 27511.86244068787, 27600.0, 2927.312081660808, 2929.22375570474, 2943.1611990582796, 2988.3126880817535, 2993.5060714982, 16294.918525407416, 16618.6828184617, 16976.15119244045, 17017.82982170187, 17118.91440542802, 1757.1477833250863, 2058.2731889869024, 2074.3258441006583, 2086.478380404899, 2112.3979665795173, 132529.0, 133017.0, 140381.0, 140706.0, 141182.29408852992, 21861.234856779938, 23509.0, 23549.0, 23772.405689857547, 23804.52390952171, 26372.8681356592, 27717.7527011285, 36499.81750091232, 36776.08059798527, 36808.263834723155, 9364.625414983324, 9896.406215627085, 10436.530356133992, 10486.632967846088, 10608.363498190134, 7344.926550734424, 7884.0, 9031.051739567381, 9046.231070358961, 9181.908180918106, 9475.715728528156, 9594.376365536218, 9595.664151754656, 9673.806523869483, 9676.661316854017, 4021.8135649983324, 4172.624463798273, 4253.191893540217, 4436.82252709888, 4466.888327791831, 967.6081187119228, 987.916027137687, 990.2127808392345, 996.6960077176386, 1029.1972261635935, 19603.411897643095, 21714.0, 22134.667979980313, 22224.0, 22269.3319200421, 909.8453262945316, 935.5602866652663, 963.9662611808553, 972.3290929258789, 990.3562684255147, 6759.155105611799, 6956.617386043817, 6959.8956015659805, 7191.676374563157, 7197.71209151628, 790510.0, 800507.0, 810785.0, 821895.0, 824231.0, 151256.48743512644, 152438.0, 153344.0, 154735.45264547435, 161348.0, 1298.2729671384054, 1315.9934200328937, 1342.7512413455386, 1356.2405053170257, 1357.2060344698461, 12317.322547259628, 12356.62930112098, 12618.305993170463, 12684.619461416172, 12722.681932951751, 57780.133297999724, 58580.0, 60461.79076418359, 62518.687406562676, 65973.01040484493, 85988.0, 87186.70853008553, 87829.0, 88345.55827220823, 88776.0, 9371.390859594105, 10951.34291942486, 10999.725006874893, 11353.262037967506, 11792.0, 11562.30626162433, 11695.88304116982, 12401.441935113122, 12642.08095490401, 12844.100912936005, 10917.617883374043, 10977.066949309397, 11177.497012634289, 11272.492737826982, 11332.943335283271, 10290.794184116126, 10302.22733294991, 10338.431386273827, 10598.523066462181, 10627.574897004342, 10168.440735759605, 10176.338537995007, 10534.36793792375, 10601.78079520865, 11460.140489463156, 11155.83266251023, 11261.155413343868, 11455.541778328774, 11543.134264930331, 11869.931706146488, 10832.5125369357, 11192.888071119185, 11216.943915280372, 11345.035671967646, 11356.772864542498], "value": 11348.586417433171, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1573.0325849602498, 1598.2408356030887, 1603.4387964212526, 1613.5401410597979, 1616.7251567233568, 9491.762705932355, 9803.656872009464, 11132.165087618421, 11870.990965767909, 11889.940550297248, 10707.143428525718, 11078.169137314713, 11090.0, 11101.55593776248, 11102.944485277583, 2337.228714524203, 2385.7852793248603, 2398.0, 2400.6519054737055, 2405.7353691094054, 2361.8700971446565, 2375.7861792438675, 2396.424858034077, 2397.9040838366445, 2412.4571971306405, 2022.8570857465559, 2355.081518207892, 2375.9168429104984, 2382.3924899150693, 2391.5456063347997, 2339.4502291961376, 2360.480694247263, 2361.4804742956608, 2398.8290597009645, 2404.182577923497, 2332.953340933172, 2348.248560460653, 2353.729321128075, 2356.8939397727145, 2364.692589963294, 9118.0, 11306.604268850633, 11314.547418103266, 11402.885971140347, 11405.0, 5006.974965125151, 5676.659400435987, 5686.886262274773, 5806.82579522615, 5814.186013958049, 3115.2367669920786, 3357.8488967996254, 3425.8972230833115, 3507.9298414031578, 3537.5047493350953, 1190.6428071578491, 1194.12828635096, 1227.9508819647203, 1242.142921384242, 1245.7072587941827, 16159.272832722438, 16173.514794556182, 16176.676466470723, 16180.514584562365, 16205.432809851598, 4647.273858598373, 4727.64542659302, 4807.61539076873, 4874.195757699939, 4884.242942343943, 12934.0, 12934.805977910339, 12965.870341296655, 12971.610851674464, 13004.154729942524, 5801.6809075500405, 5829.242198514166, 5857.6192547485125, 5878.177055212273, 5880.617759845596, 4362.0, 4365.847195348148, 4971.950280497221, 5209.270702101709, 5284.709340986283, 3942.1130245695063, 3995.340768773178, 4023.517177938666, 4070.4911886014247, 4094.4267802507675, 1051.8211903976342, 1058.1006144777032, 1077.7898309829666, 1079.5411949921252, 1080.475969154959, 920.6501529418778, 925.9259259259243, 946.1579194516886, 952.7189479103681, 990.7622170678985, 5425.945740542544, 6458.483321334282, 6828.2147553031045, 6854.6229957351825, 6918.720036793209, 962.5427921737258, 985.3004366899581, 998.7615356957466, 1034.4724190662666, 1046.0794500839213, 3672.0, 4187.706860519735, 4223.746575205498, 4233.25917964355, 4360.280553708602, 13429.932850335686, 13855.099418537762, 13864.445422183, 13946.860531394555, 13993.3702983366, 13815.585532434043, 13833.308334583231, 13853.307334633228, 13880.722385552433, 13891.652708682364, 9847.409155450696, 10178.847317290247, 10196.796064078677, 10210.489475526194, 10217.386956782617, 5514.420985796433, 6778.93221067783, 6879.793606191823, 7053.612051337226, 7157.391621712004, 10425.843612345669, 10431.009054139844, 12392.998339822829, 12452.688682783002, 12713.93643031779, 11191.328520288484, 12973.286469244282, 13170.47318107265, 13387.263700496565, 13406.463741450623, 9065.864012039956, 9354.485503297112, 9597.992210817763, 10203.744906377104, 10610.787784244427, 9980.550875210487, 10104.949475252577, 10107.292489525662, 10168.745781355525, 10197.541110650187, 8851.159139881702, 8876.467411955304, 8888.822223555622, 9095.818083638158, 9147.268218542367, 6600.867982640416, 8106.675732970615, 8610.182032706884, 8699.782505437415, 9027.593758281024, 7038.331358520933, 7472.850542989218, 7781.883271751041, 7876.212378762078, 7930.405219608668, 5896.292444906467, 5897.321807992136, 5928.555358348055, 6032.638041717511, 6109.725062372115, 4910.803567857246, 5476.0, 5699.914501282401, 5931.6441013539325, 6278.372162783685], "value": 5143.85206106767, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.15834156366304, 353.47685425570137, 383.6087191065114, 389.1302937933718, 396.158163901709, 4435.911281774366, 4979.950200497994, 5235.450277720842, 5341.946580534199, 5499.3675727291275, 5228.555572776309, 5263.236830659552, 5290.93653475145, 5319.654222475537, 5345.732713364336, 3988.5413177484525, 4001.0997525556736, 4065.6825609402504, 4086.7139300248996, 4390.714603550775, 3890.5720370759336, 4154.961259685078, 4195.748255104703, 4280.700350975433, 4424.871657727275, 4100.323446631302, 4116.97941510292, 4425.712328698624, 4441.111777644468, 4451.527808309507, 3543.2382037861985, 4022.939655905164, 4085.0, 4351.042770590465, 4429.357743127244, 3471.89584312471, 3486.860525578974, 3580.9561872408244, 3582.8387722552548, 4039.444813746729, 4534.1158474097565, 5157.252198431244, 5167.819126330561, 5171.974140129275, 5228.346456692913, 4232.830686772525, 4532.0, 4903.215485522299, 5026.648134630577, 5134.948650513485, 5063.822766203202, 5376.4085950545405, 5574.498295153456, 5631.522130262712, 5682.8973279886295, 4064.593540645941, 4151.6886233532605, 4345.630621397185, 4517.470216678637, 4551.249043907751, 4955.653104282737, 5115.851342436185, 5771.624844385102, 5923.17075609415, 6121.602095863755, 3970.7419017763755, 4306.310990241546, 4569.383133276965, 4591.471980722193, 4605.309203619484, 4704.576588107018, 4706.882327941828, 4725.598324142417, 4783.617310615143, 4805.327254184417, 4739.881502962385, 4740.170091924261, 5075.746212689424, 5094.363204599425, 5096.566791822663, 4871.342368780241, 4947.777350019258, 5121.411037730634, 5560.527355174774, 5620.887582248332, 1363.8295213098363, 1498.3332417074516, 1572.9764053539207, 1598.5843680642884, 1607.967840643204, 1038.200585549138, 1046.3355769086684, 1153.4809335798925, 1191.4042978510745, 1225.326070661133, 4388.341748737651, 5090.872728181825, 5098.898022039539, 5128.897422051538, 5295.152775555893, 5521.0, 5858.53131749459, 5892.6759028253855, 5915.526757859361, 5917.846020026053, 4421.491528474202, 4527.34751815585, 5246.8425947220885, 5265.341832270966, 5283.339582552181, 2619.9869000654876, 2791.0, 2804.831710097401, 3065.494193458099, 3070.186400603842, 5141.305923700253, 5337.7989952261205, 5538.280023596906, 5782.219400380979, 6043.519337762276, 4980.9252861207115, 5093.9745301274, 5196.766145523385, 5682.664573825148, 6170.7223174957235, 5299.4435584263865, 5487.780488780404, 5934.851628709231, 6007.339192688801, 6125.234345706786, 5081.442989907154, 5763.94236057634, 5932.970335148297, 5949.750552384048, 5954.0, 5017.57350625201, 5171.044151224241, 5755.266248531914, 5869.383714709894, 5948.839976204598, 5084.0, 5959.880802384015, 6134.374390786535, 6166.568340216321, 6239.0, 4391.736495810262, 4436.489803672619, 4998.687683538703, 5104.7283958638045, 5185.714785686823, 4922.901541969212, 5352.464753524772, 5522.45966855235, 5587.832365029056, 5856.765729370948, 5475.550403245929, 5546.0, 5584.832455026356, 5680.801171958961, 5845.473907348359, 4636.559526844944, 5169.586433085419, 5342.839714808562, 5573.27547418833, 5783.392743761844, 5013.949860501494, 5511.5315198207, 5522.806701765419, 5535.418781027934, 5558.6097977868285, 4995.975020124876, 5335.466453354634, 5683.261176047087, 5758.798442054508, 5898.0, 4591.77041147948, 4816.097641431685, 4903.166461701448, 5343.572514198932, 5547.751755854901], "value": 4278.734022979255, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [720.7045111504284, 725.2529894208967, 733.1971491217122, 825.7398919340408, 844.923956843884, 15328.310226039826, 15854.445094421697, 16266.0, 16630.916845415755, 16794.748078778826, 14869.182194979272, 15079.321430535625, 15254.847451525482, 15257.0, 15462.69074618507, 922.1976880114294, 927.9953600231989, 939.1500691873831, 943.1841457139559, 945.654835984864, 950.0452045694102, 951.600327862296, 963.0658261486382, 963.9228861691083, 963.9614415423374, 1008.5864795433873, 1008.7377281906683, 1008.793197394536, 1010.206987514801, 1012.7063151686002, 989.6536212325665, 999.5551979369184, 1003.523326419952, 1004.3321191407741, 1004.8392257238806, 737.8229224986019, 749.1534565940452, 749.8912657664663, 750.5871770526189, 751.7105914223009, 16494.10317813455, 16816.0, 17009.149542522948, 17011.914940425344, 17211.741823872653, 14189.78715319271, 14214.786778198335, 14223.715525689428, 14295.356708948018, 14368.568942931728, 11360.943195284053, 11755.471003804849, 11877.643670689893, 11906.46420911061, 11920.523179072827, 3647.854085836563, 3652.995426257774, 3657.6890964267805, 3701.777893326409, 3735.2342769732413, 111398.0, 115465.0, 116673.0, 116711.2493312601, 118362.0, 10766.838497422545, 10769.81042278102, 11175.0, 11302.886971130349, 11449.713757156138, 18248.543786405447, 19166.233350665818, 19549.902250488656, 19595.706064409045, 19627.21491140367, 8088.312493438124, 8098.392620553482, 8170.182981701782, 8178.100408955011, 8209.220124088202, 4266.274733295348, 4346.304591265382, 4380.62764665007, 4454.487733910577, 4470.776461176928, 3485.8082805445943, 3539.734519911017, 3561.4835848801777, 3625.6011838697723, 3625.8549658013385, 612.626297958254, 614.797116951405, 622.1600838867583, 624.572168064877, 626.5144513002368, 669.3239827773983, 680.3094858718405, 690.4165979747106, 702.6592102830124, 727.5598263050883, 10179.84730229047, 10371.429571373495, 10424.791504170025, 10600.787984240425, 10646.0, 666.8566258254401, 692.2212510925209, 699.3740602161038, 712.4976891291627, 712.654362634122, 4903.730294833818, 5036.597072234213, 5039.571636410946, 5043.823466178666, 5044.772985215674, 97958.0, 98929.0, 100767.49616251871, 101599.0, 102086.0, 76401.0, 77072.61463692646, 79388.0, 82406.0, 83449.58275208707, 73896.26103739001, 74261.6286918562, 75733.62133189409, 76780.0, 77172.0, 9440.763980900532, 10425.0, 10521.684789401324, 10829.945850270698, 11028.172887033665, 46121.0, 47116.52883471258, 48729.75635121802, 49047.773805655146, 49469.50530494793, 63574.0, 65124.674376627816, 66177.00734489082, 66849.66575167092, 68619.31380686328, 12643.810342845049, 12689.87310126887, 12721.8091728626, 12871.549495767978, 13286.933565332112, 14719.264036798331, 14731.0, 14947.252637368305, 15120.0, 15542.766858497358, 13518.391672374963, 13703.862961370658, 13963.170216695573, 14078.788818167532, 14249.857501425267, 12930.806037909251, 13632.727345452837, 13833.10084844482, 14097.506587269396, 14599.85400145985, 13090.934545327213, 13218.339083045617, 13719.519816806363, 14162.929185354007, 14244.145351278956, 9885.93155893535, 9888.406695598287, 10046.0, 10110.545025474019, 10166.847497287694, 9916.801663966824, 11051.0, 11162.0, 11326.37704926204, 11435.371054592077], "value": 7679.841098495229, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1594.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 120.0, 120.0, 120.0, 121.0, 123.0, 119.0, 119.0, 119.0, 127.0], "value": 119.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 112.0, 113.0, 148.0, 130.0, 109.0, 143.0, 143.0, 164.0, 117.0], "value": 131.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [276.0, 256.0, 204.0, 200.0, 205.0, 204.0, 197.0, 204.0, 204.0, 200.0], "value": 215.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 60.0, 64.0, 89.0, 63.0, 57.0, 60.0, 61.0, 73.0, 61.0], "value": 67.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 71.0, 86.0, 76.0, 73.0, 72.0, 96.0, 102.0, 102.0, 113.0], "value": 87.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 91.0, 93.0, 99.0, 102.0, 91.0, 100.0, 111.0, 90.0, 91.0], "value": 97.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 155.0, 153.0, 108.0, 96.0, 109.0, 123.0, 155.0, 153.0, 98.0], "value": 127.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 92.0, 77.0, 74.0, 143.0, 142.0, 145.0, 92.0, 91.0, 90.0], "value": 104.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 79.0, 104.0, 85.0, 69.0, 70.0, 70.0, 69.0, 68.0, 70.0], "value": 74.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 58.0, 60.0, 72.0, 61.0, 63.0, 58.0, 52.0, 69.0, 85.0], "value": 64.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 79.0, 85.0, 78.0, 94.0, 90.0, 99.0, 108.0, 76.0, 96.0], "value": 88.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 139.0, 139.0, 136.0, 141.0, 140.0, 143.0, 154.0, 184.0, 139.0], "value": 145.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [233.0, 222.0, 164.0, 172.0, 199.0, 225.0, 163.0, 166.0, 211.0, 237.0], "value": 199.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 60.0, 69.0, 91.0, 85.0, 88.0, 70.0, 66.0, 70.0], "value": 72.9, "unit": "score"}]}]} 11:22:38 INFO - Return code: 0 11:22:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:22:38 ERROR - # TBPL SUCCESS # 11:22:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:22:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:22:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:22:38 INFO - 2017-05-16 11:22:38.398 screenresolution[1666:14103] starting screenresolution argv=screenresolution get 11:22:38 INFO - 2017-05-16 11:22:38.401 screenresolution[1666:14103] Display 0: 1600x1200x32@60 11:22:38 INFO - 2017-05-16 11:22:38.407 screenresolution[1667:14106] starting screenresolution argv=screenresolution list 11:22:38 INFO - Available Modes on Display 0 11:22:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:22:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:22:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:22:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:22:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:22:38 INFO - Intel Iris: 11:22:38 INFO - Chipset Model: Intel Iris 11:22:38 INFO - Type: GPU 11:22:38 INFO - Bus: Built-In 11:22:38 INFO - VRAM (Dynamic, Max): 1536 MB 11:22:38 INFO - Vendor: Intel (0x8086) 11:22:38 INFO - Device ID: 0x0a2e 11:22:38 INFO - Revision ID: 0x0009 11:22:38 INFO - Displays: 11:22:38 INFO - Display: 11:22:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:22:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:22:38 INFO - Display Serial Number: 1600x1200 60 11:22:38 INFO - Main Display: Yes 11:22:38 INFO - Mirror: Off 11:22:38 INFO - Online: Yes 11:22:38 INFO - Rotation: Supported 11:22:38 INFO - Return code: 0 11:22:38 INFO - Running post-action listener: _resource_record_post_action 11:22:38 INFO - [mozharness: 2017-05-16 18:22:38.641949Z] Finished run-tests step (success) 11:22:38 INFO - Running post-run listener: _resource_record_post_run 11:22:38 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 35282944; Write bytes: 512439296; Read time: 322; Write time: 1600 11:22:38 INFO - TinderboxPrint: CPU usage
54.3% 11:22:38 INFO - TinderboxPrint: I/O read bytes / time
35,282,944 / 322 11:22:38 INFO - TinderboxPrint: I/O write bytes / time
512,439,296 / 1,600 11:22:38 INFO - TinderboxPrint: CPU idle
2,192.0 (45.7%) 11:22:38 INFO - TinderboxPrint: CPU system
962.5 (20.0%) 11:22:38 INFO - TinderboxPrint: CPU user
1,647.1 (34.3%) 11:22:38 INFO - TinderboxPrint: Swap in / out
239,996,928 / 0 11:22:38 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 201824768; Write bytes: 199434240; Read time: 15625; Write time: 352 11:22:38 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 33497088; Write bytes: 302216192; Read time: 301; Write time: 1209 11:22:39 INFO - Running post-run listener: _upload_blobber_files 11:22:39 INFO - Blob upload gear active. 11:22:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:22:39 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 11:22:39 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', 'Jamun', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:22:39 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 Jamun -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:22:39 INFO - (blobuploader) - INFO - Open directory for files ... 11:22:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:22:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:22:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:22:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:22:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:22:40 INFO - (blobuploader) - INFO - Done attempting. 11:22:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:22:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:22:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:22:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:22:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:22:41 INFO - (blobuploader) - INFO - Done attempting. 11:22:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:22:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:22:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:22:42 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:22:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:22:42 INFO - (blobuploader) - INFO - Done attempting. 11:22:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:22:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:22:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:22:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:22:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:22:44 INFO - (blobuploader) - INFO - Done attempting. 11:22:44 INFO - (blobuploader) - INFO - Iteration through files over. 11:22:44 INFO - Return code: 0 11:22:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:22:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:22:44 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/732b53854aba5081ce28a8a327fe2e8033934a452f1277b2b3d18b67aa46a3023c7ee53f9a398569afe7d7d33606dbd88c7fccd05e4a63297e3897aa762ef341", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/a5d4a1ee8182d947b71e75392e7b75657288fa729ff6b5b86daff2d9c866f7942c1712ca1771490286a69ba4f9cd47020611ff66d97f7071f809c26b278b6bc5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/69b59fa827780243490b8dcd888afa18ff197cdfa09ec4bb9ed669e5985668bdfe20ac96eb9378906007c0c52f48d704b68aa6ffa8feb7c54a46e77f19f82b56", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/e604cac4506ac2ebb232a732965a979639104c4c7cc022cd656f73b60f087f59bc1ffd6fde918bd093786c27c8e5857130aad516f596dbfbebde445151c3728b"} 11:22:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:22:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:22:44 INFO - Contents: 11:22:44 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/732b53854aba5081ce28a8a327fe2e8033934a452f1277b2b3d18b67aa46a3023c7ee53f9a398569afe7d7d33606dbd88c7fccd05e4a63297e3897aa762ef341", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/a5d4a1ee8182d947b71e75392e7b75657288fa729ff6b5b86daff2d9c866f7942c1712ca1771490286a69ba4f9cd47020611ff66d97f7071f809c26b278b6bc5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/69b59fa827780243490b8dcd888afa18ff197cdfa09ec4bb9ed669e5985668bdfe20ac96eb9378906007c0c52f48d704b68aa6ffa8feb7c54a46e77f19f82b56", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/e604cac4506ac2ebb232a732965a979639104c4c7cc022cd656f73b60f087f59bc1ffd6fde918bd093786c27c8e5857130aad516f596dbfbebde445151c3728b"} 11:22:44 INFO - Running post-run listener: copy_logs_to_upload_dir 11:22:44 INFO - Copying logs to upload dir... 11:22:44 INFO - mkdir: /builds/slave/test/build/upload/logs 11:22:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=2486.123928 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 41 mins, 26 secs) (at 2017-05-16 11:22:44.655477) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-16 11:22:44.659757) ========= 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.FJzejibaBZ/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.Ej0jJqM0pp/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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/Jamun/sha512/732b53854aba5081ce28a8a327fe2e8033934a452f1277b2b3d18b67aa46a3023c7ee53f9a398569afe7d7d33606dbd88c7fccd05e4a63297e3897aa762ef341", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/a5d4a1ee8182d947b71e75392e7b75657288fa729ff6b5b86daff2d9c866f7942c1712ca1771490286a69ba4f9cd47020611ff66d97f7071f809c26b278b6bc5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/69b59fa827780243490b8dcd888afa18ff197cdfa09ec4bb9ed669e5985668bdfe20ac96eb9378906007c0c52f48d704b68aa6ffa8feb7c54a46e77f19f82b56", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/e604cac4506ac2ebb232a732965a979639104c4c7cc022cd656f73b60f087f59bc1ffd6fde918bd093786c27c8e5857130aad516f596dbfbebde445151c3728b"} build_url:https://queue.taskcluster.net/v1/task/ZnGg28K8R5GbTrQ62NXNgg/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011464 build_url: 'https://queue.taskcluster.net/v1/task/ZnGg28K8R5GbTrQ62NXNgg/artifacts/public/build/firefox-54.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/732b53854aba5081ce28a8a327fe2e8033934a452f1277b2b3d18b67aa46a3023c7ee53f9a398569afe7d7d33606dbd88c7fccd05e4a63297e3897aa762ef341", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/a5d4a1ee8182d947b71e75392e7b75657288fa729ff6b5b86daff2d9c866f7942c1712ca1771490286a69ba4f9cd47020611ff66d97f7071f809c26b278b6bc5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/69b59fa827780243490b8dcd888afa18ff197cdfa09ec4bb9ed669e5985668bdfe20ac96eb9378906007c0c52f48d704b68aa6ffa8feb7c54a46e77f19f82b56", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Jamun/sha512/e604cac4506ac2ebb232a732965a979639104c4c7cc022cd656f73b60f087f59bc1ffd6fde918bd093786c27c8e5857130aad516f596dbfbebde445151c3728b"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-16 11:22:44.696663) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 11:22:44.696997) ========= 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.FJzejibaBZ/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.Ej0jJqM0pp/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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.005361 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 11:22:44.745230) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-16 11:22:44.748600) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-16 11:22:44.749007) ========= ========= Total master_lag: 0.22 =========