builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0246 starttime: 1494171942.21 results: success (0) buildid: 20170507080444 builduid: 71597242c5874d2cbc844225b6d92106 revision: 17d8a1e278a9c54a6fdda9d390abce4077e55b20 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-07 08:45:42.213901) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-07 08:45:42.214379) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-07 08:45:42.214692) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-07 08:45:42.285036) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-07 08:45:42.285347) ========= 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.Cnc81OCrjy/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.7vD782sYJo/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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-07 08:45:42-- 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% 8.23M=0.001s 2017-05-07 08:45:43 (8.23 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.747416 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-07 08:45:43.059684) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-07 08:45:43.060453) ========= 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.Cnc81OCrjy/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.7vD782sYJo/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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.021417 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-07 08:45:43.117051) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-07 08:45:43.118025) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 17d8a1e278a9c54a6fdda9d390abce4077e55b20 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 17d8a1e278a9c54a6fdda9d390abce4077e55b20 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Cnc81OCrjy/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.7vD782sYJo/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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-07 08:45:43,196 truncating revision to first 12 chars 2017-05-07 08:45:43,196 Setting DEBUG logging. 2017-05-07 08:45:43,196 attempt 1/10 2017-05-07 08:45:43,196 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/17d8a1e278a9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-07 08:45:43,866 unpacking tar archive at: mozilla-central-17d8a1e278a9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.943615 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-07 08:45:44.090438) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-07 08:45:44.090760) ========= script_repo_revision: 17d8a1e278a9c54a6fdda9d390abce4077e55b20 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-07 08:45:44.091277) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-07 08:45:44.091600) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-07 08:45:44.116435) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-05-07 08:45:44.116746) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Cnc81OCrjy/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.7vD782sYJo/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 08:45:44 INFO - MultiFileLogger online at 20170507 08:45:44 in /builds/slave/test 08:45:44 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:45:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:45:44 INFO - {'append_to_log': False, 08:45:44 INFO - 'base_work_dir': '/builds/slave/test', 08:45:44 INFO - 'blob_upload_branch': 'Firefox', 08:45:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:45:44 INFO - 'branch': 'Firefox', 08:45:44 INFO - 'buildbot_json_path': 'buildprops.json', 08:45:44 INFO - 'config_files': ('talos/mac_config.py',), 08:45:44 INFO - 'default_actions': ('clobber', 08:45:44 INFO - 'read-buildbot-config', 08:45:44 INFO - 'download-and-extract', 08:45:44 INFO - 'populate-webroot', 08:45:44 INFO - 'create-virtualenv', 08:45:44 INFO - 'install', 08:45:44 INFO - 'run-tests'), 08:45:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:45:44 INFO - 'download_minidump_stackwalk': True, 08:45:44 INFO - 'download_symbols': 'ondemand', 08:45:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:45:44 INFO - 'tooltool.py': '/tools/tooltool.py', 08:45:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:45:44 INFO - '/tools/misc-python/virtualenv.py')}, 08:45:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:45:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:45:44 INFO - 'gecko_profile': False, 08:45:44 INFO - 'gecko_profile_interval': 0, 08:45:44 INFO - 'installer_path': 'installer.exe', 08:45:44 INFO - 'log_level': 'info', 08:45:44 INFO - 'log_name': 'talos', 08:45:44 INFO - 'log_to_console': True, 08:45:44 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:45:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:45:44 INFO - 'opt_config_files': (), 08:45:44 INFO - 'pip_index': False, 08:45:44 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:45:44 INFO - 'cmd': ('bash', 08:45:44 INFO - '-c', 08:45:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:45:44 INFO - 'enabled': True, 08:45:44 INFO - 'halt_on_failure': False, 08:45:44 INFO - 'name': 'check_screen_resolution'},), 08:45:44 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:45:44 INFO - 'cmd': ('bash', 08:45:44 INFO - '-c', 08:45:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:45:44 INFO - 'enabled': True, 08:45:44 INFO - 'halt_on_failure': False, 08:45:44 INFO - 'name': 'check_screen_resolution'},), 08:45:44 INFO - 'run_cmd_checks_enabled': True, 08:45:44 INFO - 'suite': 'dromaeojs-e10s', 08:45:44 INFO - 'system_bits': '32', 08:45:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:45:44 INFO - 'title': 't-yosemite-r7-0246', 08:45:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:45:44 INFO - 'use_talos_json': True, 08:45:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:45:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:45:44 INFO - 'work_dir': 'build'} 08:45:44 INFO - [mozharness: 2017-05-07 15:45:44.315285Z] Running clobber step. 08:45:44 INFO - Running pre-action listener: _resource_record_pre_action 08:45:44 INFO - Running main action method: clobber 08:45:44 INFO - rmtree: /builds/slave/test/build 08:45:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:45:47 INFO - Running post-action listener: _resource_record_post_action 08:45:47 INFO - [mozharness: 2017-05-07 15:45:47.512084Z] Finished clobber step (success) 08:45:47 INFO - [mozharness: 2017-05-07 15:45:47.512266Z] Running read-buildbot-config step. 08:45:47 INFO - Running pre-action listener: _resource_record_pre_action 08:45:47 INFO - Running main action method: read_buildbot_config 08:45:47 INFO - Using buildbot properties: 08:45:47 INFO - { 08:45:47 INFO - "project": "", 08:45:47 INFO - "product": "firefox", 08:45:47 INFO - "script_repo_revision": "production", 08:45:47 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 08:45:47 INFO - "repository": "", 08:45:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 08:45:47 INFO - "buildid": "20170507080444", 08:45:47 INFO - "pgo_build": "False", 08:45:47 INFO - "basedir": "/builds/slave/test", 08:45:47 INFO - "buildnumber": 67, 08:45:47 INFO - "slavename": "t-yosemite-r7-0246", 08:45:47 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 08:45:47 INFO - "platform": "yosemite_r7", 08:45:47 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:45:47 INFO - "branch": "mozilla-central", 08:45:47 INFO - "repo_path": "mozilla-central", 08:45:47 INFO - "revision": "17d8a1e278a9c54a6fdda9d390abce4077e55b20", 08:45:47 INFO - "stage_platform": "macosx64", 08:45:47 INFO - "builduid": "71597242c5874d2cbc844225b6d92106", 08:45:47 INFO - "slavebuilddir": "test" 08:45:47 INFO - } 08:45:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/HymmffKfRrS1UZ2ObfgxVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 08:45:47 INFO - Running post-action listener: _resource_record_post_action 08:45:47 INFO - [mozharness: 2017-05-07 15:45:47.515369Z] Finished read-buildbot-config step (success) 08:45:47 INFO - [mozharness: 2017-05-07 15:45:47.515492Z] Running download-and-extract step. 08:45:47 INFO - Running pre-action listener: _resource_record_pre_action 08:45:47 INFO - Running main action method: download_and_extract 08:45:47 INFO - mkdir: /builds/slave/test/build/tests 08:45:47 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')]} 08:45:47 INFO - trying https://queue.taskcluster.net/v1/task/HymmffKfRrS1UZ2ObfgxVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 08:45:47 INFO - Downloading https://queue.taskcluster.net/v1/task/HymmffKfRrS1UZ2ObfgxVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 08:45:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HymmffKfRrS1UZ2ObfgxVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 08:45:49 INFO - Downloaded 1325 bytes. 08:45:49 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 08:45:49 INFO - Using the following test package requirements: 08:45:49 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:45:49 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 08:45:49 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 08:45:49 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:45:49 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 08:45:49 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:45:49 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 08:45:49 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:45:49 INFO - u'jsshell-mac.zip'], 08:45:49 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:45:49 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 08:45:49 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 08:45:49 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:45:49 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 08:45:49 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:45:49 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 08:45:49 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:45:49 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 08:45:49 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:45:49 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 08:45:49 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:45:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HymmffKfRrS1UZ2ObfgxVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 08:45:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HymmffKfRrS1UZ2ObfgxVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:45:49 INFO - Fetch https://queue.taskcluster.net/v1/task/HymmffKfRrS1UZ2ObfgxVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 08:45:50 INFO - Content-Length response header: 52703730 08:45:50 INFO - Bytes received: 52703730 08:46:07 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:46:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HymmffKfRrS1UZ2ObfgxVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 08:46:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HymmffKfRrS1UZ2ObfgxVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:46:07 INFO - Fetch https://queue.taskcluster.net/v1/task/HymmffKfRrS1UZ2ObfgxVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 08:46:09 INFO - Content-Length response header: 52703730 08:46:09 INFO - Bytes received: 52703730 08:46:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HymmffKfRrS1UZ2ObfgxVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 08:46:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HymmffKfRrS1UZ2ObfgxVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:46:25 INFO - Fetch https://queue.taskcluster.net/v1/task/HymmffKfRrS1UZ2ObfgxVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 08:46:25 INFO - Content-Length response header: 14408697 08:46:25 INFO - Bytes received: 14408697 08:46:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:26 INFO - trying https://queue.taskcluster.net/v1/task/HymmffKfRrS1UZ2ObfgxVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:46:26 INFO - Downloading https://queue.taskcluster.net/v1/task/HymmffKfRrS1UZ2ObfgxVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 08:46:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HymmffKfRrS1UZ2ObfgxVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 08:46:28 INFO - Downloaded 70884591 bytes. 08:46:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HymmffKfRrS1UZ2ObfgxVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:46:28 INFO - mkdir: /builds/slave/test/properties 08:46:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:46:28 INFO - Writing to file /builds/slave/test/properties/build_url 08:46:28 INFO - Contents: 08:46:28 INFO - build_url:https://queue.taskcluster.net/v1/task/HymmffKfRrS1UZ2ObfgxVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:46:28 INFO - Running post-action listener: _resource_record_post_action 08:46:28 INFO - Running post-action listener: set_extra_try_arguments 08:46:28 INFO - [mozharness: 2017-05-07 15:46:28.764971Z] Finished download-and-extract step (success) 08:46:28 INFO - [mozharness: 2017-05-07 15:46:28.765094Z] Running populate-webroot step. 08:46:28 INFO - Running pre-action listener: _resource_record_pre_action 08:46:28 INFO - Running main action method: populate_webroot 08:46:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:46:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:46:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:46:28 INFO - '--tppagecycles', 08:46:28 INFO - '7'], 08:46:28 INFO - 'tests': ['tsvgm']}}, 08:46:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:46:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:46:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:46:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:46:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:46:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:46:28 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:46:28 INFO - 'talos_options': ['--disable-e10s'], 08:46:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:46:28 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:46:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:46:28 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:46:28 INFO - 'talos_options': ['--disable-e10s'], 08:46:28 INFO - 'tests': ['damp', 'tps']}, 08:46:28 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:46:28 INFO - 'tests': ['damp', 'tps']}, 08:46:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:46:28 INFO - 'tests': ['dromaeo_dom']}, 08:46:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:46:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:46:28 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 08:46:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 08:46:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:46:28 INFO - 'tests': ['a11yr', 08:46:28 INFO - 'ts_paint', 08:46:28 INFO - 'tpaint', 08:46:28 INFO - 'sessionrestore', 08:46:28 INFO - 'sessionrestore_no_auto_restore', 08:46:28 INFO - 'tabpaint']}, 08:46:28 INFO - 'other-e10s': {'tests': ['a11yr', 08:46:28 INFO - 'ts_paint', 08:46:28 INFO - 'tpaint', 08:46:28 INFO - 'sessionrestore', 08:46:28 INFO - 'sessionrestore_no_auto_restore', 08:46:28 INFO - 'tabpaint']}, 08:46:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:46:28 INFO - 'ts_paint', 08:46:28 INFO - 'tpaint', 08:46:28 INFO - 'sessionrestore', 08:46:28 INFO - 'sessionrestore_no_auto_restore', 08:46:28 INFO - 'tabpaint']}, 08:46:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:46:28 INFO - 'ts_paint', 08:46:28 INFO - 'tpaint', 08:46:28 INFO - 'sessionrestore', 08:46:28 INFO - 'sessionrestore_no_auto_restore', 08:46:28 INFO - 'tabpaint']}, 08:46:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:46:28 INFO - 'tests': ['a11yr', 08:46:28 INFO - 'ts_paint', 08:46:28 INFO - 'tpaint', 08:46:28 INFO - 'sessionrestore', 08:46:28 INFO - 'sessionrestore_no_auto_restore', 08:46:28 INFO - 'tabpaint']}, 08:46:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:46:28 INFO - 'tests': ['a11yr', 08:46:28 INFO - 'ts_paint', 08:46:28 INFO - 'tpaint', 08:46:28 INFO - 'sessionrestore', 08:46:28 INFO - 'sessionrestore_no_auto_restore', 08:46:28 INFO - 'tabpaint']}, 08:46:28 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 08:46:28 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 08:46:28 INFO - 'bloom_basic_ref']}, 08:46:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:46:28 INFO - 'tests': ['tsvgx', 08:46:28 INFO - 'tsvgr_opacity', 08:46:28 INFO - 'tart', 08:46:28 INFO - 'tscrollx', 08:46:28 INFO - 'cart', 08:46:28 INFO - 'tsvg_static']}, 08:46:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:46:28 INFO - 'tsvgr_opacity', 08:46:28 INFO - 'tart', 08:46:28 INFO - 'tscrollx', 08:46:28 INFO - 'cart', 08:46:28 INFO - 'tsvg_static']}, 08:46:28 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:46:28 INFO - 'talos_options': ['--disable-e10s'], 08:46:28 INFO - 'tests': ['tp5o']}, 08:46:28 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:46:28 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:46:28 INFO - 'talos_options': ['--disable-e10s', 08:46:28 INFO - '--xperf_path', 08:46:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:46:28 INFO - 'tests': ['tp5n']}, 08:46:28 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:46:28 INFO - 'talos_options': ['--xperf_path', 08:46:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:46:28 INFO - 'tests': ['tp5n']}}, 08:46:28 INFO - 'talos.zip': {'path': '', 08:46:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:46:28 INFO - Running post-action listener: _resource_record_post_action 08:46:28 INFO - [mozharness: 2017-05-07 15:46:28.784868Z] Finished populate-webroot step (success) 08:46:28 INFO - [mozharness: 2017-05-07 15:46:28.785003Z] Running create-virtualenv step. 08:46:28 INFO - Running pre-action listener: _resource_record_pre_action 08:46:28 INFO - Running main action method: create_virtualenv 08:46:28 INFO - Creating virtualenv /builds/slave/test/build/venv 08:46:28 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 08:46:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:46:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:46:28 INFO - Using real prefix '/tools/python27' 08:46:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:46:29 INFO - Installing distribute.............................................................................................................................................................................................done. 08:46:32 INFO - Installing pip.................done. 08:46:32 INFO - Return code: 0 08:46:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:46:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:46:32 INFO - Reading from file tmpfile_stdout 08:46:32 INFO - Output received: 08:46:32 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) 08:46:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:46:32 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')]} 08:46:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:32 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')]} 08:46:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:32 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 0x10956db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109570768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcc9ceaea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10934bd30>, '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 0x109271db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109340be8>, '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.Cnc81OCrjy/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.7vD782sYJo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 08:46:32 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 08:46:32 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 08:46:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cnc81OCrjy/Render', 08:46:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:32 INFO - 'HOME': '/Users/cltbld', 08:46:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:32 INFO - 'LOGNAME': 'cltbld', 08:46:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:32 INFO - 'MOZ_NO_REMOTE': '1', 08:46:32 INFO - 'NO_EM_RESTART': '1', 08:46:32 INFO - 'PAGER': '/bin/cat', 08:46:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:32 INFO - 'PWD': '/builds/slave/test', 08:46:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:32 INFO - 'SHELL': '/bin/bash', 08:46:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7vD782sYJo/Listeners', 08:46:32 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 08:46:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:32 INFO - 'USER': 'cltbld', 08:46:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:32 INFO - 'XPC_FLAGS': '0x0', 08:46:32 INFO - 'XPC_SERVICE_NAME': '0', 08:46:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:33 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 08:46:33 INFO - Cleaning up... 08:46:33 INFO - Return code: 0 08:46:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:46:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:33 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 0x10956db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109570768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcc9ceaea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10934bd30>, '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 0x109271db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109340be8>, '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.Cnc81OCrjy/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.7vD782sYJo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 08:46:33 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 08:46:33 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 08:46:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cnc81OCrjy/Render', 08:46:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:33 INFO - 'HOME': '/Users/cltbld', 08:46:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:33 INFO - 'LOGNAME': 'cltbld', 08:46:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:33 INFO - 'MOZ_NO_REMOTE': '1', 08:46:33 INFO - 'NO_EM_RESTART': '1', 08:46:33 INFO - 'PAGER': '/bin/cat', 08:46:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:33 INFO - 'PWD': '/builds/slave/test', 08:46:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:33 INFO - 'SHELL': '/bin/bash', 08:46:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7vD782sYJo/Listeners', 08:46:33 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 08:46:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:33 INFO - 'USER': 'cltbld', 08:46:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:33 INFO - 'XPC_FLAGS': '0x0', 08:46:33 INFO - 'XPC_SERVICE_NAME': '0', 08:46:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:33 INFO - Downloading/unpacking psutil>=3.1.1 08:46:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:46:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:46:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:46:35 INFO - Installing collected packages: psutil 08:46:35 INFO - Running setup.py install for psutil 08:46:36 INFO - building 'psutil._psutil_osx' extension 08:46:36 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 08:46:36 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 08:46:36 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 08:46:36 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 08:46:36 INFO - building 'psutil._psutil_posix' extension 08:46:36 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 08:46:36 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:46:36 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:46:36 INFO - ^ 08:46:36 INFO - 1 warning generated. 08:46:36 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 08:46:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:46:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:46:36 INFO - Successfully installed psutil 08:46:36 INFO - Cleaning up... 08:46:36 INFO - Return code: 0 08:46:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:46: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')]} 08:46:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46: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')]} 08:46:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:36 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 0x10956db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109570768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcc9ceaea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10934bd30>, '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 0x109271db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109340be8>, '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.Cnc81OCrjy/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.7vD782sYJo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 08:46:36 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 08:46:36 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 08:46:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cnc81OCrjy/Render', 08:46:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:36 INFO - 'HOME': '/Users/cltbld', 08:46:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:36 INFO - 'LOGNAME': 'cltbld', 08:46:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:36 INFO - 'MOZ_NO_REMOTE': '1', 08:46:36 INFO - 'NO_EM_RESTART': '1', 08:46:36 INFO - 'PAGER': '/bin/cat', 08:46:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:36 INFO - 'PWD': '/builds/slave/test', 08:46:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:36 INFO - 'SHELL': '/bin/bash', 08:46:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7vD782sYJo/Listeners', 08:46:36 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 08:46:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:36 INFO - 'USER': 'cltbld', 08:46:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:36 INFO - 'XPC_FLAGS': '0x0', 08:46:36 INFO - 'XPC_SERVICE_NAME': '0', 08:46:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:46:37 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 08:46:37 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 08:46:37 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 08:46:37 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 08:46:37 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 08:46:37 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 08:46:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:46:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:46:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:46:38 INFO - Installing collected packages: mozsystemmonitor 08:46:38 INFO - Running setup.py install for mozsystemmonitor 08:46:38 INFO - Successfully installed mozsystemmonitor 08:46:38 INFO - Cleaning up... 08:46:38 INFO - Return code: 0 08:46:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:46:38 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')]} 08:46:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:38 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')]} 08:46:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:38 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 0x10956db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109570768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcc9ceaea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10934bd30>, '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 0x109271db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109340be8>, '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.Cnc81OCrjy/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.7vD782sYJo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 08:46:38 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 08:46:38 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 08:46:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cnc81OCrjy/Render', 08:46:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:38 INFO - 'HOME': '/Users/cltbld', 08:46:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:38 INFO - 'LOGNAME': 'cltbld', 08:46:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:38 INFO - 'MOZ_NO_REMOTE': '1', 08:46:38 INFO - 'NO_EM_RESTART': '1', 08:46:38 INFO - 'PAGER': '/bin/cat', 08:46:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:38 INFO - 'PWD': '/builds/slave/test', 08:46:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:38 INFO - 'SHELL': '/bin/bash', 08:46:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7vD782sYJo/Listeners', 08:46:38 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 08:46:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:38 INFO - 'USER': 'cltbld', 08:46:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:38 INFO - 'XPC_FLAGS': '0x0', 08:46:38 INFO - 'XPC_SERVICE_NAME': '0', 08:46:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:39 INFO - Downloading/unpacking jsonschema==2.5.1 08:46:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:46:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:46:40 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 08:46:40 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 08:46:40 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 08:46:40 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 08:46:40 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 08:46:40 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 08:46:40 INFO - Downloading functools32-3.2.3-2.tar.gz 08:46:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:46:40 INFO - warning: no files found matching '*.txt' 08:46:40 INFO - no previously-included directories found matching 'build' 08:46:40 INFO - no previously-included directories found matching 'dist' 08:46:40 INFO - no previously-included directories found matching '.git*' 08:46:40 INFO - Installing collected packages: jsonschema, functools32 08:46:40 INFO - Running setup.py install for functools32 08:46:40 INFO - warning: no files found matching '*.txt' 08:46:40 INFO - no previously-included directories found matching 'build' 08:46:40 INFO - no previously-included directories found matching 'dist' 08:46:40 INFO - no previously-included directories found matching '.git*' 08:46:40 INFO - Successfully installed jsonschema functools32 08:46:40 INFO - Cleaning up... 08:46:40 INFO - Return code: 0 08:46:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:46: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')]} 08:46:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46: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')]} 08:46:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46: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', '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 0x10956db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109570768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcc9ceaea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10934bd30>, '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 0x109271db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109340be8>, '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.Cnc81OCrjy/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.7vD782sYJo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 08:46: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:46: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 functools32==3.2.3-2 08:46:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cnc81OCrjy/Render', 08:46:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:40 INFO - 'HOME': '/Users/cltbld', 08:46:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:40 INFO - 'LOGNAME': 'cltbld', 08:46:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:40 INFO - 'MOZ_NO_REMOTE': '1', 08:46:40 INFO - 'NO_EM_RESTART': '1', 08:46:40 INFO - 'PAGER': '/bin/cat', 08:46:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:40 INFO - 'PWD': '/builds/slave/test', 08:46:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:40 INFO - 'SHELL': '/bin/bash', 08:46:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7vD782sYJo/Listeners', 08:46:40 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 08:46:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:40 INFO - 'USER': 'cltbld', 08:46:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:40 INFO - 'XPC_FLAGS': '0x0', 08:46:40 INFO - 'XPC_SERVICE_NAME': '0', 08:46:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:46:41 INFO - Cleaning up... 08:46:41 INFO - Return code: 0 08:46:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:46:41 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')]} 08:46:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:41 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')]} 08:46:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:41 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 0x10956db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109570768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcc9ceaea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10934bd30>, '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 0x109271db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109340be8>, '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.Cnc81OCrjy/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.7vD782sYJo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 08:46:41 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 08:46:41 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 08:46:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cnc81OCrjy/Render', 08:46:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:41 INFO - 'HOME': '/Users/cltbld', 08:46:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:41 INFO - 'LOGNAME': 'cltbld', 08:46:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:41 INFO - 'MOZ_NO_REMOTE': '1', 08:46:41 INFO - 'NO_EM_RESTART': '1', 08:46:41 INFO - 'PAGER': '/bin/cat', 08:46:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:41 INFO - 'PWD': '/builds/slave/test', 08:46:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:41 INFO - 'SHELL': '/bin/bash', 08:46:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7vD782sYJo/Listeners', 08:46:41 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 08:46:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:41 INFO - 'USER': 'cltbld', 08:46:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:41 INFO - 'XPC_FLAGS': '0x0', 08:46:41 INFO - 'XPC_SERVICE_NAME': '0', 08:46:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:41 INFO - Downloading/unpacking blobuploader==1.2.4 08:46: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 08:46: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 08:46: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 08:46: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 08:46: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 08:46: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 08:46:42 INFO - Downloading blobuploader-1.2.4.tar.gz 08:46:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:46:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:46:42 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 08:46:42 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 08:46:42 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 08:46:42 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 08:46:42 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 08:46:42 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 08:46:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:46:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:46:43 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 08:46:43 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 08:46:43 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 08:46:43 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 08:46:43 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 08:46:43 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 08:46:43 INFO - Downloading docopt-0.6.1.tar.gz 08:46:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:46:43 INFO - Installing collected packages: blobuploader, requests, docopt 08:46:43 INFO - Running setup.py install for blobuploader 08:46:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:46:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:46:43 INFO - Running setup.py install for requests 08:46:44 INFO - Running setup.py install for docopt 08:46:44 INFO - Successfully installed blobuploader requests docopt 08:46:44 INFO - Cleaning up... 08:46:44 INFO - Return code: 0 08:46:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:46:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:44 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 0x10956db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109570768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcc9ceaea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10934bd30>, '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 0x109271db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109340be8>, '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.Cnc81OCrjy/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.7vD782sYJo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 08:46:44 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 08:46:44 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 08:46:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cnc81OCrjy/Render', 08:46:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:44 INFO - 'HOME': '/Users/cltbld', 08:46:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:44 INFO - 'LOGNAME': 'cltbld', 08:46:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:44 INFO - 'MOZ_NO_REMOTE': '1', 08:46:44 INFO - 'NO_EM_RESTART': '1', 08:46:44 INFO - 'PAGER': '/bin/cat', 08:46:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:44 INFO - 'PWD': '/builds/slave/test', 08:46:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:44 INFO - 'SHELL': '/bin/bash', 08:46:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7vD782sYJo/Listeners', 08:46:44 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 08:46:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:44 INFO - 'USER': 'cltbld', 08:46:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:44 INFO - 'XPC_FLAGS': '0x0', 08:46:44 INFO - 'XPC_SERVICE_NAME': '0', 08:46:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:46:44 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-Qgsa7i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:46:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:46:45 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-b9nL9W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:46:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:46:45 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-6W6Lfn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:46:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:46:45 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-MjKI8k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:46:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:46:45 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-eGpKpf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:46:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:46:45 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-b5jW0G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:46:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:46:45 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-MUqVjt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:46:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:46:45 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-Ak5KQi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:46:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:46:45 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-FP7Px_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:46:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:46:45 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-YcFSjS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:46:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:46:45 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-BRUN1z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:46:46 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-BYSjSz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:46:46 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-byXPSv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:46:46 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-QN2oQm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:46:46 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-fm_9Ei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:46:46 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-cZSy7S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:46:46 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-aODDYe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:46:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:46:46 INFO - Running setup.py install for manifestparser 08:46:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:46:46 INFO - Running setup.py install for mozcrash 08:46:46 INFO - Running setup.py install for mozdebug 08:46:47 INFO - Running setup.py install for mozdevice 08:46:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:46:47 INFO - Running setup.py install for mozfile 08:46:47 INFO - Running setup.py install for mozhttpd 08:46:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:46:47 INFO - Running setup.py install for mozinfo 08:46:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:46:47 INFO - Running setup.py install for mozInstall 08:46:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:46:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:46:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:46:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:46:47 INFO - Running setup.py install for mozleak 08:46:47 INFO - Running setup.py install for mozlog 08:46:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:46:48 INFO - Running setup.py install for moznetwork 08:46:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:46:48 INFO - Running setup.py install for mozprocess 08:46:48 INFO - Running setup.py install for mozprofile 08:46:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:46:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:46:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:46:48 INFO - Running setup.py install for mozrunner 08:46:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:46:48 INFO - Running setup.py install for mozscreenshot 08:46:48 INFO - Running setup.py install for moztest 08:46:49 INFO - Running setup.py install for mozversion 08:46:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:46:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:46:49 INFO - Cleaning up... 08:46:49 INFO - Return code: 0 08:46:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:46:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:49 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 0x10956db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109570768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcc9ceaea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10934bd30>, '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 0x109271db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109340be8>, '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.Cnc81OCrjy/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.7vD782sYJo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 08:46:49 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 08:46:49 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 08:46:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cnc81OCrjy/Render', 08:46:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:49 INFO - 'HOME': '/Users/cltbld', 08:46:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:49 INFO - 'LOGNAME': 'cltbld', 08:46:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:49 INFO - 'MOZ_NO_REMOTE': '1', 08:46:49 INFO - 'NO_EM_RESTART': '1', 08:46:49 INFO - 'PAGER': '/bin/cat', 08:46:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:49 INFO - 'PWD': '/builds/slave/test', 08:46:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:49 INFO - 'SHELL': '/bin/bash', 08:46:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7vD782sYJo/Listeners', 08:46:49 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 08:46:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:49 INFO - 'USER': 'cltbld', 08:46:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:49 INFO - 'XPC_FLAGS': '0x0', 08:46:49 INFO - 'XPC_SERVICE_NAME': '0', 08:46:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:46:49 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-y2rSBy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:46:49 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)) 08:46:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:46:49 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-h_ZsFg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:46:49 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)) 08:46:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:46:49 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-mL5D_u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:46:49 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)) 08:46:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:46:49 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-fzczHY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:46:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:46:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:46:49 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-zwzK1X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:46:50 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)) 08:46:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:46:50 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-2_cLMu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:46:50 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)) 08:46:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:46:50 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-NIFoO3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:46:50 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)) 08:46:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:46:50 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-Mxzy6o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:46:50 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)) 08:46:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:46:50 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-hddSxv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:46:50 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)) 08:46:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:46:50 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-Ebpj5B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:46:50 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)) 08:46:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:46:50 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-_W_t1g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:46:50 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)) 08:46:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:46:50 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-fGrDiK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:46:50 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)) 08:46:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:46:50 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-qI2858-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:46:50 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)) 08:46:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:46:50 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-yn6N5v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:46:51 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)) 08:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:46:51 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-Csl3D7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:46:51 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)) 08:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:46:51 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-1wJ9IG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:46:51 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)) 08:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:46:51 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-RubGjC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:46:51 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)) 08:46:51 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)) 08:46:51 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)) 08:46:51 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)) 08:46:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:46:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:46:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:46:51 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 08:46:51 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 08:46:51 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 08:46:51 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 08:46:51 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 08:46:51 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 08:46:52 INFO - Downloading blessings-1.6.tar.gz 08:46:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:46:52 INFO - Installing collected packages: blessings 08:46:52 INFO - Running setup.py install for blessings 08:46:52 INFO - Successfully installed blessings 08:46:52 INFO - Cleaning up... 08:46:53 INFO - Return code: 0 08:46:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:46:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:46:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:46:53 INFO - Reading from file tmpfile_stdout 08:46:53 INFO - Current package versions: 08:46:53 INFO - blessings == 1.6 08:46:53 INFO - blobuploader == 1.2.4 08:46:53 INFO - docopt == 0.6.1 08:46:53 INFO - functools32 == 3.2.3-2 08:46:53 INFO - jsonschema == 2.5.1 08:46:53 INFO - manifestparser == 1.1 08:46:53 INFO - mozInstall == 1.12 08:46:53 INFO - mozcrash == 1.0 08:46:53 INFO - mozdebug == 0.1 08:46:53 INFO - mozdevice == 0.50 08:46:53 INFO - mozfile == 1.2 08:46:53 INFO - mozhttpd == 0.7 08:46:53 INFO - mozinfo == 0.9 08:46:53 INFO - mozleak == 0.1 08:46:53 INFO - mozlog == 3.4 08:46:53 INFO - moznetwork == 0.27 08:46:53 INFO - mozprocess == 0.25 08:46:53 INFO - mozprofile == 0.28 08:46:53 INFO - mozrunner == 6.13 08:46:53 INFO - mozscreenshot == 0.1 08:46:53 INFO - mozsystemmonitor == 0.3 08:46:53 INFO - moztest == 0.8 08:46:53 INFO - mozversion == 1.4 08:46:53 INFO - psutil == 3.1.1 08:46:53 INFO - requests == 1.2.3 08:46:53 INFO - wsgiref == 0.1.2 08:46:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:46:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:53 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 0x10956db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109570768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcc9ceaea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10934bd30>, '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 0x109271db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109340be8>, '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.Cnc81OCrjy/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.7vD782sYJo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 08:46:53 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 08:46:53 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 08:46:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cnc81OCrjy/Render', 08:46:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:53 INFO - 'HOME': '/Users/cltbld', 08:46:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:53 INFO - 'LOGNAME': 'cltbld', 08:46:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:53 INFO - 'MOZ_NO_REMOTE': '1', 08:46:53 INFO - 'NO_EM_RESTART': '1', 08:46:53 INFO - 'PAGER': '/bin/cat', 08:46:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:53 INFO - 'PWD': '/builds/slave/test', 08:46:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:53 INFO - 'SHELL': '/bin/bash', 08:46:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7vD782sYJo/Listeners', 08:46:53 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 08:46:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:53 INFO - 'USER': 'cltbld', 08:46:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:53 INFO - 'XPC_FLAGS': '0x0', 08:46:53 INFO - 'XPC_SERVICE_NAME': '0', 08:46:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:53 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)) 08:46:53 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)) 08:46:53 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)) 08:46:53 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)) 08:46:53 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)) 08:46:53 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)) 08:46:53 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)) 08:46:53 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)) 08:46:53 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)) 08:46:53 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)) 08:46:53 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)) 08:46:53 INFO - Cleaning up... 08:46:53 INFO - Return code: 0 08:46:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:46:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:53 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 0x10956db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109570768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcc9ceaea0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10934bd30>, '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 0x109271db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109340be8>, '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.Cnc81OCrjy/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.7vD782sYJo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 08:46:53 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 08:46:53 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 08:46:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cnc81OCrjy/Render', 08:46:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:53 INFO - 'HOME': '/Users/cltbld', 08:46:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:53 INFO - 'LOGNAME': 'cltbld', 08:46:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:53 INFO - 'MOZ_NO_REMOTE': '1', 08:46:53 INFO - 'NO_EM_RESTART': '1', 08:46:53 INFO - 'PAGER': '/bin/cat', 08:46:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:53 INFO - 'PWD': '/builds/slave/test', 08:46:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:53 INFO - 'SHELL': '/bin/bash', 08:46:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7vD782sYJo/Listeners', 08:46:53 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 08:46:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:53 INFO - 'USER': 'cltbld', 08:46:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:53 INFO - 'XPC_FLAGS': '0x0', 08:46:53 INFO - 'XPC_SERVICE_NAME': '0', 08:46:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:46:53 INFO - Cleaning up... 08:46:53 INFO - Return code: 0 08:46:53 INFO - Running post-action listener: _resource_record_post_action 08:46:53 INFO - Running post-action listener: _start_resource_monitoring 08:46:53 INFO - Starting resource monitoring. 08:46:53 INFO - [mozharness: 2017-05-07 15:46:53.916213Z] Finished create-virtualenv step (success) 08:46:53 INFO - [mozharness: 2017-05-07 15:46:53.917236Z] Running install step. 08:46:53 INFO - Running pre-action listener: _resource_record_pre_action 08:46:53 INFO - Running main action method: install 08:46:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:46:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:46:54 INFO - Reading from file tmpfile_stdout 08:46:54 INFO - Detecting whether we're running mozinstall >=1.0... 08:46:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:46:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:46:54 INFO - Reading from file tmpfile_stdout 08:46:54 INFO - Output received: 08:46:54 INFO - Usage: mozinstall [options] installer 08:46:54 INFO - Options: 08:46:54 INFO - -h, --help show this help message and exit 08:46:54 INFO - -d DEST, --destination=DEST 08:46:54 INFO - Directory to install application into. [default: 08:46:54 INFO - "/builds/slave/test"] 08:46:54 INFO - --app=APP Application being installed. [default: firefox] 08:46:54 INFO - mkdir: /builds/slave/test/build/application 08:46:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:46:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:47:14 INFO - Reading from file tmpfile_stdout 08:47:14 INFO - Output received: 08:47:14 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:47:14 INFO - Running post-action listener: _resource_record_post_action 08:47:14 INFO - [mozharness: 2017-05-07 15:47:14.116601Z] Finished install step (success) 08:47:14 INFO - [mozharness: 2017-05-07 15:47:14.116735Z] Running run-tests step. 08:47:14 INFO - Running pre-action listener: _resource_record_pre_action 08:47:14 INFO - Running main action method: run_tests 08:47:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:47:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:47:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:47:14 INFO - 2017-05-07 08:47:14.131 screenresolution[2036:76950] starting screenresolution argv=screenresolution get 08:47:14 INFO - 2017-05-07 08:47:14.133 screenresolution[2036:76950] Display 0: 1600x1200x32@60 08:47:14 INFO - 2017-05-07 08:47:14.139 screenresolution[2037:76953] starting screenresolution argv=screenresolution list 08:47:14 INFO - Available Modes on Display 0 08:47:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:47:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:47:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:47:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:47:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:47:14 INFO - Intel Iris: 08:47:14 INFO - Chipset Model: Intel Iris 08:47:14 INFO - Type: GPU 08:47:14 INFO - Bus: Built-In 08:47:14 INFO - VRAM (Dynamic, Max): 1536 MB 08:47:14 INFO - Vendor: Intel (0x8086) 08:47:14 INFO - Device ID: 0x0a2e 08:47:14 INFO - Revision ID: 0x0009 08:47:14 INFO - Displays: 08:47:14 INFO - Display: 08:47:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:47:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:47:14 INFO - Display Serial Number: 1600x1200 60 08:47:14 INFO - Main Display: Yes 08:47:14 INFO - Mirror: Off 08:47:14 INFO - Online: Yes 08:47:14 INFO - Rotation: Supported 08:47:14 INFO - Return code: 0 08:47:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:47:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:47:14 INFO - Python 2.7.3 08:47:14 INFO - Return code: 0 08:47:14 INFO - grabbing minidump binary from tooltool 08:47:14 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')]} 08:47:14 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 0x10934bd30>, '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 0x109271db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109340be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:47:14 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 08:47:14 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 08:47:14 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 08:47:14 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:47:14 INFO - Return code: 0 08:47:14 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:47:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:47:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:47:14 INFO - ENV: RUST_BACKTRACE is now 1 08:47:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:47:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:47:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HymmffKfRrS1UZ2ObfgxVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0246', '--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 08:47:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HymmffKfRrS1UZ2ObfgxVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0246 --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 08:47:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cnc81OCrjy/Render', 08:47:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:47:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:47:14 INFO - 'HOME': '/Users/cltbld', 08:47:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:47:14 INFO - 'LOGNAME': 'cltbld', 08:47:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:47:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:47:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:14 INFO - 'MOZ_NO_REMOTE': '1', 08:47:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:47:14 INFO - 'NO_EM_RESTART': '1', 08:47:14 INFO - 'PAGER': '/bin/cat', 08:47:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:47:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:47:14 INFO - 'PWD': '/builds/slave/test', 08:47:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:47:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:47:14 INFO - 'RUST_BACKTRACE': '1', 08:47:14 INFO - 'SHELL': '/bin/bash', 08:47:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7vD782sYJo/Listeners', 08:47:14 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 08:47:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:47:14 INFO - 'USER': 'cltbld', 08:47:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:47:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:47:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:14 INFO - 'XPC_FLAGS': '0x0', 08:47:14 INFO - 'XPC_SERVICE_NAME': '0', 08:47:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:47:14 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HymmffKfRrS1UZ2ObfgxVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0246', '--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 08:47:14 INFO - mozversion application_buildid: 20170507080444 08:47:14 INFO - mozversion application_changeset: 17d8a1e278a9c54a6fdda9d390abce4077e55b20 08:47:14 INFO - mozversion application_display_name: Nightly 08:47:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:47:14 INFO - mozversion application_name: Firefox 08:47:14 INFO - mozversion application_remotingname: firefox 08:47:14 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:47:14 INFO - mozversion application_vendor: Mozilla 08:47:14 INFO - mozversion application_version: 55.0a1 08:47:14 INFO - mozversion platform_buildid: 20170507080444 08:47:14 INFO - mozversion platform_changeset: 17d8a1e278a9c54a6fdda9d390abce4077e55b20 08:47:14 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:47:14 INFO - mozversion platform_version: 55.0a1 08:47:14 INFO - using testdate: 1494172034 08:47:14 INFO - actual date: 1494172034 08:47:14 INFO - starting webserver on 'localhost:49278' 08:47:14 INFO - SUITE-START | Running 2 tests 08:47:14 INFO - TEST-START | dromaeo_css 08:47:14 INFO - operating with platform_type : mac_ 08:47:14 INFO - Initialising browser for dromaeo_css test... 08:47:14 INFO - TEST-INFO | started process 2047 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/tmpCAAORf/profile http://localhost:49278/getInfo.html) 08:47:17 INFO - PID 2047 | 2017-05-07 08:47:17.544 plugin-container[2049:77114] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b47, name = 'com.apple.tsm.portname' 08:47:17 INFO - PID 2047 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:47:17 INFO - PID 2047 | 2017-05-07 08:47:17.546 plugin-container[2049:77114] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f0b, name = 'com.apple.CFPasteboardClient' 08:47:17 INFO - PID 2047 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:47:17 INFO - PID 2047 | 2017-05-07 08:47:17.546 plugin-container[2049:77114] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:47:17 INFO - PID 2047 | __metrics Screen width/height:1600/1200 08:47:17 INFO - PID 2047 | colorDepth:24 08:47:17 INFO - PID 2047 | Browser inner width/height: 1024/691 08:47:17 INFO - PID 2047 | __metrics 08:47:18 INFO - PID 2047 | 2017-05-07 08:47:18.225 plugin-container[2050:77167] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 08:47:18 INFO - PID 2047 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:47:18 INFO - PID 2047 | 2017-05-07 08:47:18.225 plugin-container[2050:77167] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980b, name = 'com.apple.CFPasteboardClient' 08:47:18 INFO - PID 2047 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:47:18 INFO - PID 2047 | 2017-05-07 08:47:18.225 plugin-container[2050:77167] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:47:18 INFO - TEST-INFO | 2047: exit 0 08:47:18 INFO - Browser initialized. 08:47:18 INFO - Running cycle 1/1 for dromaeo_css test... 08:47:18 INFO - TEST-INFO | started process 2051 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/tmpCAAORf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:47:21 INFO - PID 2051 | 2017-05-07 08:47:21.469 plugin-container[2052:77279] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x473b, name = 'com.apple.tsm.portname' 08:47:21 INFO - PID 2051 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:47:21 INFO - PID 2051 | 2017-05-07 08:47:21.470 plugin-container[2052:77279] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 08:47:21 INFO - PID 2051 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:47:21 INFO - PID 2051 | 2017-05-07 08:47:21.470 plugin-container[2052:77279] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:47:21 INFO - PID 2051 | 2017-05-07 08:47:21.850 plugin-container[2053:77322] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x902b, name = 'com.apple.tsm.portname' 08:47:21 INFO - PID 2051 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:47:21 INFO - PID 2051 | 2017-05-07 08:47:21.851 plugin-container[2053:77322] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x990b, name = 'com.apple.CFPasteboardClient' 08:47:21 INFO - PID 2051 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:47:21 INFO - PID 2051 | 2017-05-07 08:47:21.851 plugin-container[2053:77322] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:47:31 INFO - PID 2051 | RSS: Main: 233496576 08:47:31 INFO - PID 2051 | 08:50:35 INFO - PID 2051 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 08:50:37 INFO - PID 2051 | RSS: Main: 249700352 08:50:37 INFO - PID 2051 | 08:53:38 INFO - PID 2051 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 08:53:39 INFO - PID 2051 | RSS: Main: 254337024 08:53:39 INFO - PID 2051 | 08:56:41 INFO - PID 2051 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 08:56:41 INFO - PID 2051 | RSS: Main: 264118272 08:56:41 INFO - PID 2051 | 08:59:43 INFO - PID 2051 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 08:59:44 INFO - PID 2051 | RSS: Main: 256737280 08:59:44 INFO - PID 2051 | 09:02:47 INFO - PID 2051 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 09:02:47 INFO - PID 2051 | RSS: Main: 261648384 09:02:47 INFO - PID 2051 | 09:05:49 INFO - PID 2051 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 09:05:49 INFO - PID 2051 | RSS: Main: 260435968 09:05:49 INFO - PID 2051 | 09:05:49 INFO - PID 2051 | __start_tp_report 09:05:49 INFO - PID 2051 | _x_x_mozilla_page_load 09:05:49 INFO - PID 2051 | _x_x_mozilla_page_load_details 09:05:49 INFO - PID 2051 | |i|pagename|runs| 09:05:49 INFO - PID 2051 | |0;dojo.html;3606.080449485381;3612;3631.5279013728214;3651.452282157676;3657.140571965589;6091.573589848701;6259.906101408476;6277.780277690287;6320.557560970734;6356.364363563642;5723.198752174689;6228.781992630259;6232.937670623292;6261.655608941506;6326.841828954279;4985.950140498603;5375.408705042442;5482.972585137069;5485.149801780721;5626.2967129108865;4501.347304640825;5434.83695489134;5483.013057649621;5618.859528511779;5643.181738647893;4923.433805112422;5564.33228012637;5598.328200615912;5617.915731264035;5662.716864156776;4523.638108951309;5511.22842802008;5547.5007249347755;5590.329160500725;5614.29821272341;5480.177973304037;5505.311836020497;5510.614257002011;5524.723763811833;5534.667919924817;6013.278406591194;6195.628262304276;6324.968375158141;6328.873422531524;6352.841178970562;6556.639384833832;6669;6743.527953043289;6760.526763126583;6792.388685018372;5952.285725712899;6892.965535172342;6899.931000689979;6902.758403455905;6922.03091567181;6175.938240617581;6375.107484952064;6379.393957574023;6387.041943708481;6390.840228994312;6761.526693131532;6771.72913083481;6946.235914049451;6976.058232138596;7023.157221133497;6362.427381535684;6599.60402375859;6602.3397660233595;6627.204735431779;6649.035889795952;6594.109795177686;6607.80176594703;6675.532712710161;6688.498362622823;6695.330466953363;5157.896842063138;6686.632235227012;6701.229358623821;6745.12313399255;6747.257801641816;6394.680265986682;6480.935190648128;6674.199096108498;6739.52823302374;6803.183617965876;5360.571154307646;5402.621816472809;5422;5438.129899216186;5446.2647542582035;4773.284007398918;4792.760361981887;4803.543663351976;4812.494688057774;4839.128956787742;6324.051392291193;6431.903521447182;6459.935400646027;6461.160049193575;6474.902876456857;6766.93233067663;6819.352161544645;6819.795406137725;6836.863262734817;6876.518643694893;6338.429541341301;6437.549371544041;6441.001644745072;6464.159659244268;6475.125858009108;5809.244798176294;6067.696615169224;6084.787032453843;6086.878262434727;6109.20580324555;6929.445644348541;6938.548994315353;7013.649317534205;7050.894236586353;7059.082319298458;6896.793096207121;6909;6915.446764258747;6916.896246556406;6924;6914.481413893877;6985.266547012694;7060;7120.821979450555;7161.462890283354;6436.485081193587;6512.837179070561;6615.834604134935;6666.000099985041;6723.865522689616;6667.366600172976;6851.280615535297;6857.691403886937;6869.175698916162;6869.484788640772;6265.091561723616;6491.188601424822;6587.110740050032;6588.868222635616;6603.306652801579;6393.680315984275;6490.350964903472;6505.902411463737;6526.151600292122;6546.443552298108;4819.421669399694;5621.550275977994;6458.67706614658;6474.902876456763;6493.805185844432;6631.801045968628;6631.801045968628;6642.933570664231;6688;6695.765648202289;6204.317525072329;6304.873902522016;6318.968405158129;6354.1104245406605;6507.381798729302 09:05:49 INFO - PID 2051 | |1;ext.html;730.8976743255942;769.2691942654478;810.1938571121731;1183.6153250193688;1228.3428365824286;36410.635893641054;37022.25955480889;37027.4445883312;37115.25769484612;37160.628393716055;37282.8135859321;37660.62339376605;37754.43368349477;37774.43338349927;37904.24191516168;7704.3451306639;7840.7255746048895;8085.110637829864;8228.794280142984;8287.502749835;8187.140350263226;8304.584770761468;8308.667653293862;8327.583620818965;8329.50022998622;8271.54506502142;8340.332773378146;8344.249017588416;8344.457610255344;8363.790905227388;8300.2114799094;8320.209580089942;8329.83340333196;8350.162964147878;8362.790930226733;8107;8115.79710507242;8124.6343914523395;8180.713675021405;8182.427230093896;36890.26219475595;37071.07322316937;37094.70168544115;37108.25783484315;37158.8142059288;2984.7612191024664;3109.5957525521762;3617.3850445424077;3642.417213245868;3651.7261205409436;29928.251793705113;30070.097897063122;30282.18306901593;30390.392192156032;30446.695533044607;3099.922501937447;3129.702678245563;3142.214446388403;3142.937141257185;3165.2561648012697;247667;250391;265158;301250.49374752986;301850;40320;45183;46967.29549056767;47076.293855592194;47144.7642761784;49074.75462622664;49398.50601494011;49424.50575494271;49571.05147382337;49605;14069.437222510986;14148.929255353863;14212;14224.715505689828;14229.573112806633;11495.367754773568;11605.941970290094;11612.535498580131;11615.36115513638;11647.76704465923;13365.599032029055;13381.464741410235;13382.665433364244;13393.531226407224;13491.920646348268;5315.3090098286975;5550.835174282033;5907.793227237026;5950;5964.373740757244;972.0765272990687;995.3778888194878;998.3660375661507;999.6101520406902;1038.1798379280283;19268.710969335472;23378.298651040495;23873.761262387503;23887.402814929766;24453.144139955373;1036.3367444835312;1064.2124827627654;1101.7961677089604;1135.982960255597;1137.624583887315;10241.334313269614;12300.569480068334;12305.876941230652;12316.50733970649;12543.372831358578;257832;267841;272435;305042;307869;53551.19673204905;53819.730901346025;53956.73021634945;54048.72975635175;54272.99999999921;1384.6884450998446;1452.3246690288854;1472.4404726203975;1521.9239038048054;1536.0169491525432;23600.17399391013;23617.173398930954;23688.88155559211;23712;23762.287131386085;37916;38019.42970855384;38020.429693555176;38150.61849381582;38212.04469888275;277613;279549;279950;280489.755921968;280723;33498.83250583829;34390.140246493036;35161.824190878884;35180.29639407249;35270.29459410746;43321.78339108411;43677.68966930997;45525;45629;45758.771206143756;22179.445513862283;30138;30330;31110.53334200034;31165;30916.845415772776;31046.68953310438;31236.219094522818;31313.06060818179;31314.686853131177;32379.676203237665;35529.82235088912;37132.62867371292;37497.437538437494;37604.87185384443;30001.249968750955;31143.844280778452;31169.8441507791;31656.525152122274;32016.35967280595;21884.12463501442;24104;24377.756222438256;24492;24789 09:05:49 INFO - PID 2051 | |2;jquery.html;427.87805475439507;436.7314101827377;436.9082492676538;452.81887245101956;453.91375638628637;18998.90500547499;20605.96970151492;21234.15063397466;21391.358259252247;21456;21571.24500642478;21618.783812161873;21921;21950.001498157235;23465.882670586667;6062.151298818169;6319.684015799215;6341.365863413668;6343.492520598341;6352.269489008754;4960.627952903528;5949.6727679977585;6232.127502149703;6276.68616569172;6281.748730050793;5477.808276710316;6190.3190649028575;6206.441420272175;6211.503079753631;6239.032949892774;4659.673822832403;6128.142060111588;6138.17134686816;6197.69011549423;6202;4649.814007439699;5350.35795704514;5488.7530061147345;5500.58745594082;5533.363663178746;26687.466250675072;27548.862255688593;27751.583726244124;27891.442171156465;28230.717692823015;2183.9563208735894;2263.8981245843975;2265.161890100657;2270.2735124760084;2272.7727227277305;17459;17648.029358385284;17778.46664600064;18145.7278140828;18319.54201144969;2158.6222411077997;2175.0973346061364;2200.9119635214574;2204.1624182810588;2206.845520813528;131428;160322.1983890073;163188.5521717175;163502;164956;10506.789864202674;10828.566857325619;11786.469608867621;12874.420651070724;13020.02349823767;14935.103893766409;15617.453389131553;16572;16653.91673041627;16677.16614169311;7393.519421237603;8875.955620221857;8945.792318037113;9060.323840089604;9307.72076837696;7101.431885449152;7212.242714515005;8118.660421030582;8645.178708022728;8863.64545418176;7956.2043795619975;7974.481658692166;8123;8126.268635822805;8143.75568732939;2161.5784921940385;2168.392850002002;2180.9236676716237;2192.5066859956382;2390.5338459000327;944.5182956692137;961.711486554031;966.5501747378931;984.7685793838442;1008.5108722269694;17553.64892702139;19897.204111835654;20774.272900448414;20889.582208355747;21065;884.4958373727043;910.5720311453606;923.4320892650971;992.667456402099;1013.3261381181431;10336.328138671113;10581.470926453647;10646.627368042082;10648.041676249175;10708.892911070945;532186;536377;541384;541491;549629;110964.33553496705;112281;126321.36839315745;127156;127762.88948442144;1202.4168278384977;1218.0803493362455;1254.8368712067556;1263.298869127643;1266.8796464335874;18614.81385186185;21421.678674820203;22244.221452249094;22315.218967336066;22445.77554224502;47851.80370490766;50833.745831272085;51135;55083.941551440024;56569;71317.64341178261;75772;75985.6200719015;76432;76678;9052.456852588866;9092.909070909207;9127.452352858849;9220.44677319363;9240.353175277665;12963.22220666763;13330.800037999632;14115.505957291445;14394.568162955127;14497.492587759378;9430.915444723945;11871.643850684493;11904.642860714192;12093.81859272094;12198.451069701707;11000.724981875197;11344.319340839576;11745.765084698429;11766.235194712317;12040.397980101136;12144.210626309261;12234.204776689487;12408.503659853504;12426.192297500633;12545.93727031359;6416;8066.193380661887;9030.819383612423;9117.589708463003;9146.405483643543;11977.461014254553;11993.700157496132;12095.697607559881;12137.575184868487;12242.387880605756 09:05:49 INFO - PID 2051 | |3;mootools.html;1196.5393323570424;1424.5726282115363;1456.6067161866297;1459.7445447046764;1463.6414078550754;11385.146114041449;11652.592159274416;11728.296302221852;11812.822807657882;11935.283882967027;11697.298162110279;11720.413979301044;11901.940490297558;11903.940480297608;11926.344051077187;2148.860324078934;2523.8611876346795;2557.1177943609423;2572.1383336582267;2602.10227471522;2387.77316154965;2486.266551367351;2511.573032584465;2523.8107141964333;2526.4694414173046;2495.70051593809;2524.5203411351786;2540.9110681126162;2542.478791847676;2544.847309161447;2469.345623409798;2480.6527086208034;2510.0587279770084;2517.0435234610914;2530.493901219754;2430.878456077189;2513.74862513749;2517.0812653381386;2517.546841568517;2552.6426300317785;11609.593664221797;11673.708157296136;11762.294262344287;11775.411229438494;11794.587189448328;6580.341965803429;6826.795196144124;6882.002109694116;6888.104546408985;6900.551464154814;4085.775282359469;4405.449318835145;4464.7321160730135;4489.304157855538;4520;1187.1689817128045;1251.7809383357967;1256.1709271880607;1284.2551320234336;1293.3856418201278;16177;16437.753433698505;16474.341026359052;16613.252403641865;16817.915910420368;5498.285222920995;5541.750621221974;5696.686682232434;5712.11462223356;5724.370319264877;11865.644030679094;11880.821787673192;11889.048876089892;11901.88098119025;11961;6605.438537724347;6626.7017984190825;6627.337266273431;6635.402813746689;6636.871731805604;5194.0910340690225;6083.148359229712;6175.722092505848;6325.209348831396;6332.398422149889;3705.27747089315;4360.10083525523;4434.290513517822;4461;4463.977680111579;1258.8048852427705;1278.6675464379327;1286.7555164518712;1296.6304603188046;1297.1438850358907;730.0910366593519;775.5889378629336;808.4138999225632;812.4191203289677;815.8001289684065;6678.131842860397;6999.8600027999155;7010.859782804316;7055.89416158758;7092.787216383516;899.2086963472234;922.8108237811233;927.221134247222;934.9111834375724;938.1181689212119;4090.2024105299774;4322.15717935006;4423.8451654192595;4539.863804085882;4552.362669226311;13164.341782910817;13980.44078236859;15107.093574385572;15423.691526169638;15467;11276.210665253517;12095.939520302341;14528.491502797351;14838.480653177086;15312.846871531365;8370.414071015091;8555.957220213859;8707.216350528357;8792.34057445694;8807.735767926972;6824.829379265558;6992.860142797013;7015.438764898694;7045.11936007999;7187.065681461377;12586.559470418491;13051.347432628521;13903.374348154155;13917.443302267797;13948.16311021342;13792;13920.72158556803;13935.651608709863;14103.647408814451;14290.142591444546;8176.264136227768;8943;9562.665306714232;9600.903990960001;9617.278704097082;8867.64529418816;10539.629848119695;10758.354498730101;10879.891201087888;10881.292715973437;7192.892106618509;9211.815763684823;9247.907520924973;9273.3045021625;9287;9007.819843603222;9021.233195178484;9029.548522573976;9102.499362535124;9269.953650231706;8040.959795200986;8185.8772118419465;8189.344852411674;8190.262876341158;8209.95895020545;5776.306843178845;6069.544784141119;6141.9385806143155;6263.279722831932;6324.430801227912;5396.730163491889;6114.2357205349335;6236.781712640036;6320.4311611955145;6431.29255781873 09:05:49 INFO - PID 2051 | |4;prototype.html;351.0416562783602;403.1171733902753;405.18153330272844;406.932856078747;418.9015581338387;5935.792247271346;6198.78304259351;6449.645269510181;6459.773907913224;6483.8703225935515;5703.230063941367;6235.563510554263;6313.873722525546;6322.6838658067145;6364.459020983222;3983.7012224083164;4813.326134341195;4859.538343857328;4860.756962151896;4884.169691152512;4047.251258517169;4799.496052914446;4857.514248575132;4868.367112275399;4900.338454308659;3910.432987216852;4806.110869489138;4827.927581086286;4850.393700787402;4850.951490485085;4809.807607695688;4814.277858321245;4816.060868130716;4834.975825120887;4857.2714092886;3188.6651901550235;4244.363345498169;4321.58944900234;4333.479982402132;4342.891427714301;6082.148499210114;6104.511639068908;6122.846928826771;6173.753049878;6218.22272215973;4798.496157903405;5770.711464426762;5791.638964843259;5791.885961624019;5807.125472890903;5352.74210560518;5878.441548052928;5930.55520835939;6371.585846919982;6840.9819103364625;3744.9514136041953;3833.616638336172;4362.541933097043;4441.800118994653;4469.82120715171;6790.32096790322;6966.303369663044;7218.783436496913;7234.6020968847215;7241.167265764399;5011.624128190328;5134.563562097189;5143.8456846294675;5159.265589237635;5168.19892916591;4048;4354.782260886943;5120.206368012964;5236.24074509194;5237.3453318335205;4932.383452068491;5614.494695477427;5793.7392817323325;5797.304323481125;5811.360750317462;5933.910991335047;6792.08306878575;6832.145981752281;6836.871916133881;6858;1529.5029115537495;1730.1435789284385;1771.04363643631;1795.353672677835;1795.6857549928711;1181.2262967756133;1224.8162775583735;1245.819356193347;1246.426643743888;1251.474380760083;5472.616916815785;6450.226187798393;6473.579217350857;6539.542232043711;6557.901631475532;6806.76176333826;6910.48171387148;7017.649117544102;7144.3212894774915;7189;6403.807885763435;6432.71052802625;6472.546921715529;6541.345865413516;6548.466545768818;3147.669494703069;3199.344134452478;3664.15724383388;3668.6331366863633;3683.226522430266;5549.5837812164245;5723.942760572341;7269.236730143259;7284.410821182727;7303.8539229216185;5887.852803679943;7173.96413017942;7201.3158749918675;7242.493025488166;7253.419726421874;6010.879782404328;6969.1985421677155;7182.317679820409;7216.494845360774;7218.458615603956;6150.892839288971;6759.898601521079;7044.802383594699;7075.365635508544;7085.964570177116;5924.733386997682;6787.93212067873;6846.691898864663;6986.54587451814;7034.522750222511;7388.815279618053;7400.296971787671;7412.147603025721;7413.406927445899;7433.182349941394;4392.868213953587;4557.202489564379;4583.449986001701;4589.242774942097;4609.9166695827125;6374.394432528902;6578.76974305897;7056.188538318159;7090.432765378654;7145.892811607934;5765.106408506688;6090.421409966046;6820.31796820314;6830.59016459014;6888;6583.473322134122;6767.052612634334;6826.044353790374;6861.980728479453;6870.896936546056;6724.49566282521;6729.966350168414;6738;6749.156355455568;6753.189617245962;6579.276279609144;6773.491988101011;6829.521933464808;6865.656717164222;6894.965525172342;5269;6625.768098116543;6650.900236496554;6713;6748.122744043243 09:05:49 INFO - PID 2051 | |5;yui.html;823.5717426937989;838.325148255654;848.3849209323239;862.8446879561678;863.0506442912792;16527.338906443743;16550.751738723913;16918.661626767473;18263.26946922125;19366.70949935752;17949.820501794977;18113.275468981225;18405.26378944844;18467.168977396017;18709.158087886044;981.2934687025366;991.1674193677292;992.2211064314479;995.6017592962801;995.9800803983916;914.8399030169727;986.2849434160212;987.239825334492;1003.5183112106193;1008.5007921079076;1054.398992574233;1063.3354153653966;1064.138048180972;1066.1204506282309;1066.2909165404997;955.4935883981432;1012.4937531234419;1049.2078480747025;1063.6755789484205;1064.5103252503857;768.7155752371602;778.6924164954811;782.1748055801128;784.070876011922;784.9843003139962;16676;16946;17079.231434585472;17245.051522166403;17435.65128697419;17355.913220433817;17398.478045658652;17616;17697.20362583674;17749.11254437286;13910.696884135808;14228.359723812453;14242.359093840698;14355.856441435557;14362.281885905662;3391.4404123319623;3404.063882432326;3441.0193094968063;3443.2252743132594;3461.117415059169;109410;127960.36019819841;128125.99999999907;128979;134292;13238.139520931109;13332.333383330795;13383.130096543693;13386.19682819034;13553.915686745037;24511.38721531976;25430;25500.617490737655;26002.21993340203;26324.868375658;6443.5167362448;6644.302348253364;7643.738783100837;7802.648880800378;7882;3812.5806161322234;3857.0550215197454;3889.4749208857006;3893.552241492265;3893.8831835044425;3985.601439856049;4075.7758323292505;4085.97957010219;4108.8356465741035;4118.629323360912;894.8568229083315;930.0792215706537;930.9767255818658;932.4545141092536;936.1247233836385;635.1520719838975;645.554567348528;666.7233098264167;671.358852296056;675.3044364304774;11158.330500170016;11536.59621913229;12069.275843449246;12184.756304873852;12195;644.9193850768654;648.6270394523074;651.6318280171688;661.0843981086134;681.5127184063418;4707.905841883143;5359.552920711386;5477.15104158848;5578.860528486739;5607.719614019365;100394.99605004002;102936.45595316077;105232;105264.47367763112;109244;75396.62301688457;84789;88304.5584772072;88509.55745221232;89693.65459518113;84402.15597843943;85176;85359;85398.57300713456;86582;12230.593481749713;12516.998640108952;12754.043446741345;12762.298073605669;12777.744445111231;28437.289067773472;29280.41439171247;36979.260414792094;37121;40856.79571602242;55192.44807551873;55495;55720;56090.719546402004;56262;16364.181790910645;16790.58023549421;17388.47834564965;17559.473215803548;17578.91210543939;14977.475788347356;16862.494125176265;18004.099795010457;18041.955944758654;18818.905905470387;13937.233452159822;16897.408590699266;17338.826611734225;17375.652486949937;17567.912160439115;16551.420700275434;17028.744568831724;17431.47705568835;17498.125093745006;17721.848079874766;16851.578710532336;16869.650427965487;16871.66256674835;17204.13979301005;17340.47978560645;11596.478158483058;11814.409279536161;12146.39268036577;12248.510059597516;12344.506219751109;12620.684482888002;13347.06570540053;13386.062975591614;13481.494811349316;13727.176369417866 09:05:49 INFO - PID 2051 | __end_tp_report 09:05:49 INFO - PID 2051 | __start_cc_report 09:05:49 INFO - PID 2051 | _x_x_mozilla_cycle_collect,406 09:05:49 INFO - PID 2051 | __end_cc_report 09:05:49 INFO - PID 2051 | __startTimestamp1494173149819__endTimestamp 09:05:49 INFO - PID 2051 | 09:05:49 INFO - PID 2051 | ------- Summary: start ------- 09:05:49 INFO - PID 2051 | Number of tests: 6 09:05:49 INFO - PID 2051 | 09:05:49 INFO - PID 2051 | [#0] dojo.html Cycles:165 Average:6198.18 Median:6441.00 stddev:759.61 (11.8%) stddev-sans-first:734.22 09:05:49 INFO - PID 2051 | Values: 3606.1 3612.0 3631.5 3651.5 3657.1 6091.6 6259.9 6277.8 6320.6 6356.4 5723.2 6228.8 6232.9 6261.7 6326.8 4986.0 5375.4 5483.0 5485.1 5626.3 4501.3 5434.8 5483.0 5618.9 5643.2 4923.4 5564.3 5598.3 5617.9 5662.7 4523.6 5511.2 5547.5 5590.3 5614.3 5480.2 5505.3 5510.6 5524.7 5534.7 6013.3 6195.6 6325.0 6328.9 6352.8 6556.6 6669.0 6743.5 6760.5 6792.4 5952.3 6893.0 6899.9 6902.8 6922.0 6175.9 6375.1 6379.4 6387.0 6390.8 6761.5 6771.7 6946.2 6976.1 7023.2 6362.4 6599.6 6602.3 6627.2 6649.0 6594.1 6607.8 6675.5 6688.5 6695.3 5157.9 6686.6 6701.2 6745.1 6747.3 6394.7 6480.9 6674.2 6739.5 6803.2 5360.6 5402.6 5422.0 5438.1 5446.3 4773.3 4792.8 4803.5 4812.5 4839.1 6324.1 6431.9 6459.9 6461.2 6474.9 6766.9 6819.4 6819.8 6836.9 6876.5 6338.4 6437.5 6441.0 6464.2 6475.1 5809.2 6067.7 6084.8 6086.9 6109.2 6929.4 6938.5 7013.6 7050.9 7059.1 6896.8 6909.0 6915.4 6916.9 6924.0 6914.5 6985.3 7060.0 7120.8 7161.5 6436.5 6512.8 6615.8 6666.0 6723.9 6667.4 6851.3 6857.7 6869.2 6869.5 6265.1 6491.2 6587.1 6588.9 6603.3 6393.7 6490.4 6505.9 6526.2 6546.4 4819.4 5621.6 6458.7 6474.9 6493.8 6631.8 6631.8 6642.9 6688.0 6695.8 6204.3 6304.9 6319.0 6354.1 6507.4 09:05:49 INFO - PID 2051 | 09:05:49 INFO - PID 2051 | [#1] ext.html Cycles:180 Average:43062.79 Median:32939.25 stddev:72988.26 (221.6%) stddev-sans-first:73123.81 09:05:49 INFO - PID 2051 | Values: 730.9 769.3 810.2 1183.6 1228.3 36410.6 37022.3 37027.4 37115.3 37160.6 37282.8 37660.6 37754.4 37774.4 37904.2 7704.3 7840.7 8085.1 8228.8 8287.5 8187.1 8304.6 8308.7 8327.6 8329.5 8271.5 8340.3 8344.2 8344.5 8363.8 8300.2 8320.2 8329.8 8350.2 8362.8 8107.0 8115.8 8124.6 8180.7 8182.4 36890.3 37071.1 37094.7 37108.3 37158.8 2984.8 3109.6 3617.4 3642.4 3651.7 29928.3 30070.1 30282.2 30390.4 30446.7 3099.9 3129.7 3142.2 3142.9 3165.3 247667.0 250391.0 265158.0 301250.5 301850.0 40320.0 45183.0 46967.3 47076.3 47144.8 49074.8 49398.5 49424.5 49571.1 49605.0 14069.4 14148.9 14212.0 14224.7 14229.6 11495.4 11605.9 11612.5 11615.4 11647.8 13365.6 13381.5 13382.7 13393.5 13491.9 5315.3 5550.8 5907.8 5950.0 5964.4 972.1 995.4 998.4 999.6 1038.2 19268.7 23378.3 23873.8 23887.4 24453.1 1036.3 1064.2 1101.8 1136.0 1137.6 10241.3 12300.6 12305.9 12316.5 12543.4 257832.0 267841.0 272435.0 305042.0 307869.0 53551.2 53819.7 53956.7 54048.7 54273.0 1384.7 1452.3 1472.4 1521.9 1536.0 23600.2 23617.2 23688.9 23712.0 23762.3 37916.0 38019.4 38020.4 38150.6 38212.0 277613.0 279549.0 279950.0 280489.8 280723.0 33498.8 34390.1 35161.8 35180.3 35270.3 43321.8 43677.7 45525.0 45629.0 45758.8 22179.4 30138.0 30330.0 31110.5 31165.0 30916.8 31046.7 31236.2 31313.1 31314.7 32379.7 35529.8 37132.6 37497.4 37604.9 30001.2 31143.8 31169.8 31656.5 32016.4 21884.1 24104.0 24377.8 24492.0 24789.0 09:05:49 INFO - PID 2051 | 09:05:49 INFO - PID 2051 | [#2] jquery.html Cycles:180 Average:34915.30 Median:2264.53 stddev:91612.11 (4045.5%) stddev-sans-first:91832.51 09:05:49 INFO - PID 2051 | Values: 427.9 436.7 436.9 452.8 453.9 18998.9 20606.0 21234.2 21391.4 21456.0 21571.2 21618.8 21921.0 21950.0 23465.9 6062.2 6319.7 6341.4 6343.5 6352.3 4960.6 5949.7 6232.1 6276.7 6281.7 5477.8 6190.3 6206.4 6211.5 6239.0 4659.7 6128.1 6138.2 6197.7 6202.0 4649.8 5350.4 5488.8 5500.6 5533.4 26687.5 27548.9 27751.6 27891.4 28230.7 2184.0 2263.9 2265.2 2270.3 2272.8 17459.0 17648.0 17778.5 18145.7 18319.5 2158.6 2175.1 2200.9 2204.2 2206.8 131428.0 160322.2 163188.6 163502.0 164956.0 10506.8 10828.6 11786.5 12874.4 13020.0 14935.1 15617.5 16572.0 16653.9 16677.2 7393.5 8876.0 8945.8 9060.3 9307.7 7101.4 7212.2 8118.7 8645.2 8863.6 7956.2 7974.5 8123.0 8126.3 8143.8 2161.6 2168.4 2180.9 2192.5 2390.5 944.5 961.7 966.6 984.8 1008.5 17553.6 19897.2 20774.3 20889.6 21065.0 884.5 910.6 923.4 992.7 1013.3 10336.3 10581.5 10646.6 10648.0 10708.9 532186.0 536377.0 541384.0 541491.0 549629.0 110964.3 112281.0 126321.4 127156.0 127762.9 1202.4 1218.1 1254.8 1263.3 1266.9 18614.8 21421.7 22244.2 22315.2 22445.8 47851.8 50833.7 51135.0 55083.9 56569.0 71317.6 75772.0 75985.6 76432.0 76678.0 9052.5 9092.9 9127.5 9220.4 9240.4 12963.2 13330.8 14115.5 14394.6 14497.5 9430.9 11871.6 11904.6 12093.8 12198.5 11000.7 11344.3 11745.8 11766.2 12040.4 12144.2 12234.2 12408.5 12426.2 12545.9 6416.0 8066.2 9030.8 9117.6 9146.4 11977.5 11993.7 12095.7 12137.6 12242.4 09:05:49 INFO - PID 2051 | 09:05:49 INFO - PID 2051 | [#3] mootools.html Cycles:180 Average:7116.87 Median:4087.99 stddev:4447.27 (108.8%) stddev-sans-first:4437.49 09:05:49 INFO - PID 2051 | Values: 1196.5 1424.6 1456.6 1459.7 1463.6 11385.1 11652.6 11728.3 11812.8 11935.3 11697.3 11720.4 11901.9 11903.9 11926.3 2148.9 2523.9 2557.1 2572.1 2602.1 2387.8 2486.3 2511.6 2523.8 2526.5 2495.7 2524.5 2540.9 2542.5 2544.8 2469.3 2480.7 2510.1 2517.0 2530.5 2430.9 2513.7 2517.1 2517.5 2552.6 11609.6 11673.7 11762.3 11775.4 11794.6 6580.3 6826.8 6882.0 6888.1 6900.6 4085.8 4405.4 4464.7 4489.3 4520.0 1187.2 1251.8 1256.2 1284.3 1293.4 16177.0 16437.8 16474.3 16613.3 16817.9 5498.3 5541.8 5696.7 5712.1 5724.4 11865.6 11880.8 11889.0 11901.9 11961.0 6605.4 6626.7 6627.3 6635.4 6636.9 5194.1 6083.1 6175.7 6325.2 6332.4 3705.3 4360.1 4434.3 4461.0 4464.0 1258.8 1278.7 1286.8 1296.6 1297.1 730.1 775.6 808.4 812.4 815.8 6678.1 6999.9 7010.9 7055.9 7092.8 899.2 922.8 927.2 934.9 938.1 4090.2 4322.2 4423.8 4539.9 4552.4 13164.3 13980.4 15107.1 15423.7 15467.0 11276.2 12095.9 14528.5 14838.5 15312.8 8370.4 8556.0 8707.2 8792.3 8807.7 6824.8 6992.9 7015.4 7045.1 7187.1 12586.6 13051.3 13903.4 13917.4 13948.2 13792.0 13920.7 13935.7 14103.6 14290.1 8176.3 8943.0 9562.7 9600.9 9617.3 8867.6 10539.6 10758.4 10879.9 10881.3 7192.9 9211.8 9247.9 9273.3 9287.0 9007.8 9021.2 9029.5 9102.5 9270.0 8041.0 8185.9 8189.3 8190.3 8210.0 5776.3 6069.5 6141.9 6263.3 6324.4 5396.7 6114.2 6236.8 6320.4 6431.3 09:05:49 INFO - PID 2051 | 09:05:49 INFO - PID 2051 | [#4] prototype.html Cycles:180 Average:5536.37 Median:6097.47 stddev:1711.00 (28.1%) stddev-sans-first:1670.94 09:05:49 INFO - PID 2051 | Values: 351.0 403.1 405.2 406.9 418.9 5935.8 6198.8 6449.6 6459.8 6483.9 5703.2 6235.6 6313.9 6322.7 6364.5 3983.7 4813.3 4859.5 4860.8 4884.2 4047.3 4799.5 4857.5 4868.4 4900.3 3910.4 4806.1 4827.9 4850.4 4851.0 4809.8 4814.3 4816.1 4835.0 4857.3 3188.7 4244.4 4321.6 4333.5 4342.9 6082.1 6104.5 6122.8 6173.8 6218.2 4798.5 5770.7 5791.6 5791.9 5807.1 5352.7 5878.4 5930.6 6371.6 6841.0 3745.0 3833.6 4362.5 4441.8 4469.8 6790.3 6966.3 7218.8 7234.6 7241.2 5011.6 5134.6 5143.8 5159.3 5168.2 4048.0 4354.8 5120.2 5236.2 5237.3 4932.4 5614.5 5793.7 5797.3 5811.4 5933.9 6792.1 6832.1 6836.9 6858.0 1529.5 1730.1 1771.0 1795.4 1795.7 1181.2 1224.8 1245.8 1246.4 1251.5 5472.6 6450.2 6473.6 6539.5 6557.9 6806.8 6910.5 7017.6 7144.3 7189.0 6403.8 6432.7 6472.5 6541.3 6548.5 3147.7 3199.3 3664.2 3668.6 3683.2 5549.6 5723.9 7269.2 7284.4 7303.9 5887.9 7174.0 7201.3 7242.5 7253.4 6010.9 6969.2 7182.3 7216.5 7218.5 6150.9 6759.9 7044.8 7075.4 7086.0 5924.7 6787.9 6846.7 6986.5 7034.5 7388.8 7400.3 7412.1 7413.4 7433.2 4392.9 4557.2 4583.4 4589.2 4609.9 6374.4 6578.8 7056.2 7090.4 7145.9 5765.1 6090.4 6820.3 6830.6 6888.0 6583.5 6767.1 6826.0 6862.0 6870.9 6724.5 6730.0 6738.0 6749.2 6753.2 6579.3 6773.5 6829.5 6865.7 6895.0 5269.0 6625.8 6650.9 6713.0 6748.1 09:05:49 INFO - PID 2051 | 09:05:49 INFO - PID 2051 | [#5] yui.html Cycles:180 Average:21725.31 Median:18334.27 stddev:30483.99 (166.3%) stddev-sans-first:30529.10 09:05:49 INFO - PID 2051 | Values: 823.6 838.3 848.4 862.8 863.1 16527.3 16550.8 16918.7 18263.3 19366.7 17949.8 18113.3 18405.3 18467.2 18709.2 981.3 991.2 992.2 995.6 996.0 914.8 986.3 987.2 1003.5 1008.5 1054.4 1063.3 1064.1 1066.1 1066.3 955.5 1012.5 1049.2 1063.7 1064.5 768.7 778.7 782.2 784.1 785.0 16676.0 16946.0 17079.2 17245.1 17435.7 17355.9 17398.5 17616.0 17697.2 17749.1 13910.7 14228.4 14242.4 14355.9 14362.3 3391.4 3404.1 3441.0 3443.2 3461.1 109410.0 127960.4 128126.0 128979.0 134292.0 13238.1 13332.3 13383.1 13386.2 13553.9 24511.4 25430.0 25500.6 26002.2 26324.9 6443.5 6644.3 7643.7 7802.6 7882.0 3812.6 3857.1 3889.5 3893.6 3893.9 3985.6 4075.8 4086.0 4108.8 4118.6 894.9 930.1 931.0 932.5 936.1 635.2 645.6 666.7 671.4 675.3 11158.3 11536.6 12069.3 12184.8 12195.0 644.9 648.6 651.6 661.1 681.5 4707.9 5359.6 5477.2 5578.9 5607.7 100395.0 102936.5 105232.0 105264.5 109244.0 75396.6 84789.0 88304.6 88509.6 89693.7 84402.2 85176.0 85359.0 85398.6 86582.0 12230.6 12517.0 12754.0 12762.3 12777.7 28437.3 29280.4 36979.3 37121.0 40856.8 55192.4 55495.0 55720.0 56090.7 56262.0 16364.2 16790.6 17388.5 17559.5 17578.9 14977.5 16862.5 18004.1 18042.0 18818.9 13937.2 16897.4 17338.8 17375.7 17567.9 16551.4 17028.7 17431.5 17498.1 17721.8 16851.6 16869.7 16871.7 17204.1 17340.5 11596.5 11814.4 12146.4 12248.5 12344.5 12620.7 13347.1 13386.1 13481.5 13727.2 09:05:49 INFO - PID 2051 | -------- Summary: end -------- 09:05:49 INFO - PID 2051 | 09:05:50 INFO - TEST-INFO | 2051: exit 0 09:05:50 INFO - TEST-OK | dromaeo_css | took 1115786ms 09:05:50 INFO - TEST-START | kraken 09:05:50 INFO - operating with platform_type : mac_ 09:05:50 INFO - Initialising browser for kraken test... 09:05:50 INFO - TEST-INFO | started process 2057 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/tmpg7r71n/profile http://localhost:49278/getInfo.html) 09:05:53 INFO - PID 2057 | 2017-05-07 09:05:53.178 plugin-container[2058:79503] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x933b, name = 'com.apple.tsm.portname' 09:05:53 INFO - PID 2057 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:05:53 INFO - PID 2057 | 2017-05-07 09:05:53.181 plugin-container[2058:79503] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980f, name = 'com.apple.CFPasteboardClient' 09:05:53 INFO - PID 2057 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:05:53 INFO - PID 2057 | 2017-05-07 09:05:53.181 plugin-container[2058:79503] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:05:53 INFO - PID 2057 | __metrics Screen width/height:1600/1200 09:05:53 INFO - PID 2057 | colorDepth:24 09:05:53 INFO - PID 2057 | Browser inner width/height: 1024/691 09:05:53 INFO - PID 2057 | __metrics 09:05:54 INFO - TEST-INFO | 2057: exit 0 09:05:54 INFO - Browser initialized. 09:05:54 INFO - Running cycle 1/1 for kraken test... 09:05:54 INFO - TEST-INFO | started process 2060 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/tmpg7r71n/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:05:56 INFO - PID 2060 | 2017-05-07 09:05:56.957 plugin-container[2061:79662] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x433f, name = 'com.apple.tsm.portname' 09:05:56 INFO - PID 2060 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:05:56 INFO - PID 2060 | 2017-05-07 09:05:56.958 plugin-container[2061:79662] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4343, name = 'com.apple.CFPasteboardClient' 09:05:56 INFO - PID 2060 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:05:56 INFO - PID 2060 | 2017-05-07 09:05:56.958 plugin-container[2061:79662] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:05:57 INFO - PID 2060 | 2017-05-07 09:05:57.264 plugin-container[2062:79704] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 09:05:57 INFO - PID 2060 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:05:57 INFO - PID 2060 | 2017-05-07 09:05:57.265 plugin-container[2062:79704] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x990b, name = 'com.apple.CFPasteboardClient' 09:05:57 INFO - PID 2060 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:05:57 INFO - PID 2060 | 2017-05-07 09:05:57.265 plugin-container[2062:79704] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:06:06 INFO - PID 2060 | RSS: Main: 234057728 09:06:06 INFO - PID 2060 | 09:06:09 INFO - PID 2060 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 09:06:10 INFO - PID 2060 | RSS: Main: 246923264 09:06:10 INFO - PID 2060 | 09:06:12 INFO - PID 2060 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 09:06:13 INFO - PID 2060 | RSS: Main: 254771200 09:06:13 INFO - PID 2060 | 09:06:16 INFO - PID 2060 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 09:06:17 INFO - PID 2060 | RSS: Main: 257167360 09:06:17 INFO - PID 2060 | 09:06:19 INFO - PID 2060 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 09:06:20 INFO - PID 2060 | RSS: Main: 259534848 09:06:20 INFO - PID 2060 | 09:06:22 INFO - PID 2060 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:06:22 INFO - PID 2060 | RSS: Main: 259829760 09:06:22 INFO - PID 2060 | 09:06:25 INFO - PID 2060 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 09:06:26 INFO - PID 2060 | RSS: Main: 265490432 09:06:26 INFO - PID 2060 | 09:06:29 INFO - PID 2060 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 09:06:30 INFO - PID 2060 | RSS: Main: 267571200 09:06:30 INFO - PID 2060 | 09:06:32 INFO - PID 2060 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 09:06:33 INFO - PID 2060 | RSS: Main: 267071488 09:06:33 INFO - PID 2060 | 09:06:34 INFO - PID 2060 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:06:35 INFO - PID 2060 | RSS: Main: 266792960 09:06:35 INFO - PID 2060 | 09:06:37 INFO - PID 2060 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:06:38 INFO - PID 2060 | RSS: Main: 266833920 09:06:38 INFO - PID 2060 | 09:06:40 INFO - PID 2060 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:06:40 INFO - PID 2060 | RSS: Main: 266506240 09:06:40 INFO - PID 2060 | 09:06:43 INFO - PID 2060 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:06:44 INFO - PID 2060 | RSS: Main: 266539008 09:06:44 INFO - PID 2060 | 09:06:47 INFO - PID 2060 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:06:47 INFO - PID 2060 | RSS: Main: 266805248 09:06:47 INFO - PID 2060 | 09:06:49 INFO - PID 2060 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 09:06:49 INFO - PID 2060 | RSS: Main: 268869632 09:06:49 INFO - PID 2060 | 09:06:49 INFO - PID 2060 | __start_tp_report 09:06:49 INFO - PID 2060 | _x_x_mozilla_page_load 09:06:49 INFO - PID 2060 | _x_x_mozilla_page_load_details 09:06:49 INFO - PID 2060 | |i|pagename|runs| 09:06:49 INFO - PID 2060 | |0;ai-astar;104;116;115;114;115;113;112;113;115;113 09:06:49 INFO - PID 2060 | |1;audio-beat-detection;147;112;117;151;109;113;109;111;141;108 09:06:49 INFO - PID 2060 | |2;audio-dft;218;289;211;212;218;217;202;202;198;198 09:06:49 INFO - PID 2060 | |3;audio-fft;85;58;59;88;73;88;76;62;93;72 09:06:49 INFO - PID 2060 | |4;audio-oscillator;91;70;84;69;71;79;107;102;98;89 09:06:49 INFO - PID 2060 | |5;imaging-gaussian-blur;90;89;91;90;91;90;91;113;131;136 09:06:49 INFO - PID 2060 | |6;imaging-darkroom;97;98;97;98;97;97;97;94;120;155 09:06:49 INFO - PID 2060 | |7;imaging-desaturate;99;92;90;94;94;91;91;91;91;91 09:06:49 INFO - PID 2060 | |8;json-parse-financial;51;50;48;50;51;50;50;52;51;50 09:06:49 INFO - PID 2060 | |9;json-stringify-tinderbox;57;59;63;63;63;61;58;60;59;57 09:06:49 INFO - PID 2060 | |10;stanford-crypto-aes;84;81;84;82;82;83;80;82;84;84 09:06:49 INFO - PID 2060 | |11;stanford-crypto-ccm;147;135;135;136;136;137;136;141;189;138 09:06:49 INFO - PID 2060 | |12;stanford-crypto-pbkdf2;183;185;185;186;185;184;184;186;173;184 09:06:49 INFO - PID 2060 | |13;stanford-crypto-sha256-iterative;70;68;69;64;69;67;67;69;65;68 09:06:49 INFO - PID 2060 | __end_tp_report 09:06:49 INFO - PID 2060 | __start_cc_report 09:06:49 INFO - PID 2060 | _x_x_mozilla_cycle_collect,964 09:06:49 INFO - PID 2060 | __end_cc_report 09:06:49 INFO - PID 2060 | __startTimestamp1494173209874__endTimestamp 09:06:49 INFO - PID 2060 | 09:06:49 INFO - PID 2060 | ------- Summary: start ------- 09:06:49 INFO - PID 2060 | Number of tests: 14 09:06:49 INFO - PID 2060 | 09:06:49 INFO - PID 2060 | [#0] ai-astar Cycles:10 Average:113.00 Median:114.50 stddev:3.40 (3.0%) stddev-sans-first:1.32 09:06:49 INFO - PID 2060 | Values: 104.0 116.0 115.0 114.0 115.0 113.0 112.0 113.0 115.0 113.0 09:06:49 INFO - PID 2060 | 09:06:49 INFO - PID 2060 | [#1] audio-beat-detection Cycles:10 Average:121.80 Median:115.00 stddev:17.28 (15.0%) stddev-sans-first:15.74 09:06:49 INFO - PID 2060 | Values: 147.0 112.0 117.0 151.0 109.0 113.0 109.0 111.0 141.0 108.0 09:06:49 INFO - PID 2060 | 09:06:49 INFO - PID 2060 | [#2] audio-dft Cycles:10 Average:216.50 Median:214.50 stddev:26.70 (12.4%) stddev-sans-first:28.32 09:06:49 INFO - PID 2060 | Values: 218.0 289.0 211.0 212.0 218.0 217.0 202.0 202.0 198.0 198.0 09:06:49 INFO - PID 2060 | 09:06:49 INFO - PID 2060 | [#3] audio-fft Cycles:10 Average:75.40 Median:80.50 stddev:12.86 (16.0%) stddev-sans-first:13.16 09:06:49 INFO - PID 2060 | Values: 85.0 58.0 59.0 88.0 73.0 88.0 76.0 62.0 93.0 72.0 09:06:49 INFO - PID 2060 | 09:06:49 INFO - PID 2060 | [#4] audio-oscillator Cycles:10 Average:86.00 Median:81.50 stddev:13.74 (16.9%) stddev-sans-first:14.45 09:06:49 INFO - PID 2060 | Values: 91.0 70.0 84.0 69.0 71.0 79.0 107.0 102.0 98.0 89.0 09:06:49 INFO - PID 2060 | 09:06:49 INFO - PID 2060 | [#5] imaging-gaussian-blur Cycles:10 Average:101.20 Median:90.00 stddev:18.49 (20.5%) stddev-sans-first:19.16 09:06:49 INFO - PID 2060 | Values: 90.0 89.0 91.0 90.0 91.0 90.0 91.0 113.0 131.0 136.0 09:06:49 INFO - PID 2060 | 09:06:49 INFO - PID 2060 | [#6] imaging-darkroom Cycles:10 Average:105.00 Median:97.00 stddev:19.04 (19.6%) stddev-sans-first:19.98 09:06:49 INFO - PID 2060 | Values: 97.0 98.0 97.0 98.0 97.0 97.0 97.0 94.0 120.0 155.0 09:06:49 INFO - PID 2060 | 09:06:49 INFO - PID 2060 | [#7] imaging-desaturate Cycles:10 Average:92.40 Median:91.50 stddev:2.67 (2.9%) stddev-sans-first:1.41 09:06:49 INFO - PID 2060 | Values: 99.0 92.0 90.0 94.0 94.0 91.0 91.0 91.0 91.0 91.0 09:06:49 INFO - PID 2060 | 09:06:49 INFO - PID 2060 | [#8] json-parse-financial Cycles:10 Average:50.30 Median:50.50 stddev:1.06 (2.1%) stddev-sans-first:1.09 09:06:49 INFO - PID 2060 | Values: 51.0 50.0 48.0 50.0 51.0 50.0 50.0 52.0 51.0 50.0 09:06:49 INFO - PID 2060 | 09:06:49 INFO - PID 2060 | [#9] json-stringify-tinderbox Cycles:10 Average:60.00 Median:60.50 stddev:2.40 (4.0%) stddev-sans-first:2.29 09:06:49 INFO - PID 2060 | Values: 57.0 59.0 63.0 63.0 63.0 61.0 58.0 60.0 59.0 57.0 09:06:49 INFO - PID 2060 | 09:06:49 INFO - PID 2060 | [#10] stanford-crypto-aes Cycles:10 Average:82.60 Median:83.50 stddev:1.43 (1.7%) stddev-sans-first:1.42 09:06:49 INFO - PID 2060 | Values: 84.0 81.0 84.0 82.0 82.0 83.0 80.0 82.0 84.0 84.0 09:06:49 INFO - PID 2060 | 09:06:49 INFO - PID 2060 | [#11] stanford-crypto-ccm Cycles:10 Average:143.00 Median:137.50 stddev:16.57 (12.1%) stddev-sans-first:17.52 09:06:49 INFO - PID 2060 | Values: 147.0 135.0 135.0 136.0 136.0 137.0 136.0 141.0 189.0 138.0 09:06:49 INFO - PID 2060 | 09:06:49 INFO - PID 2060 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.50 Median:185.00 stddev:3.81 (2.1%) stddev-sans-first:4.03 09:06:49 INFO - PID 2060 | Values: 183.0 185.0 185.0 186.0 185.0 184.0 184.0 186.0 173.0 184.0 09:06:49 INFO - PID 2060 | 09:06:49 INFO - PID 2060 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.60 Median:68.50 stddev:1.90 (2.8%) stddev-sans-first:1.80 09:06:49 INFO - PID 2060 | Values: 70.0 68.0 69.0 64.0 69.0 67.0 67.0 69.0 65.0 68.0 09:06:49 INFO - PID 2060 | -------- Summary: end -------- 09:06:49 INFO - PID 2060 | 09:06:50 INFO - TEST-INFO | 2060: exit 0 09:06:50 INFO - TEST-OK | kraken | took 60439ms 09:06:50 INFO - SUITE-END | took 1176s 09:06:51 INFO - Completed test suite (00:19:36) 09:06:51 INFO - javascript benchmark 09:06:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7932.141792069667, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3606.080449485381, 3612.0, 3631.5279013728214, 3651.452282157676, 3657.140571965589, 6091.573589848701, 6259.906101408476, 6277.780277690287, 6320.557560970734, 6356.364363563642, 5723.198752174689, 6228.781992630259, 6232.937670623292, 6261.655608941506, 6326.841828954279, 4985.950140498603, 5375.408705042442, 5482.972585137069, 5485.149801780721, 5626.2967129108865, 4501.347304640825, 5434.83695489134, 5483.013057649621, 5618.859528511779, 5643.181738647893, 4923.433805112422, 5564.33228012637, 5598.328200615912, 5617.915731264035, 5662.716864156776, 4523.638108951309, 5511.22842802008, 5547.5007249347755, 5590.329160500725, 5614.29821272341, 5480.177973304037, 5505.311836020497, 5510.614257002011, 5524.723763811833, 5534.667919924817, 6013.278406591194, 6195.628262304276, 6324.968375158141, 6328.873422531524, 6352.841178970562, 6556.639384833832, 6669.0, 6743.527953043289, 6760.526763126583, 6792.388685018372, 5952.285725712899, 6892.965535172342, 6899.931000689979, 6902.758403455905, 6922.03091567181, 6175.938240617581, 6375.107484952064, 6379.393957574023, 6387.041943708481, 6390.840228994312, 6761.526693131532, 6771.72913083481, 6946.235914049451, 6976.058232138596, 7023.157221133497, 6362.427381535684, 6599.60402375859, 6602.3397660233595, 6627.204735431779, 6649.035889795952, 6594.109795177686, 6607.80176594703, 6675.532712710161, 6688.498362622823, 6695.330466953363, 5157.896842063138, 6686.632235227012, 6701.229358623821, 6745.12313399255, 6747.257801641816, 6394.680265986682, 6480.935190648128, 6674.199096108498, 6739.52823302374, 6803.183617965876, 5360.571154307646, 5402.621816472809, 5422.0, 5438.129899216186, 5446.2647542582035, 4773.284007398918, 4792.760361981887, 4803.543663351976, 4812.494688057774, 4839.128956787742, 6324.051392291193, 6431.903521447182, 6459.935400646027, 6461.160049193575, 6474.902876456857, 6766.93233067663, 6819.352161544645, 6819.795406137725, 6836.863262734817, 6876.518643694893, 6338.429541341301, 6437.549371544041, 6441.001644745072, 6464.159659244268, 6475.125858009108, 5809.244798176294, 6067.696615169224, 6084.787032453843, 6086.878262434727, 6109.20580324555, 6929.445644348541, 6938.548994315353, 7013.649317534205, 7050.894236586353, 7059.082319298458, 6896.793096207121, 6909.0, 6915.446764258747, 6916.896246556406, 6924.0, 6914.481413893877, 6985.266547012694, 7060.0, 7120.821979450555, 7161.462890283354, 6436.485081193587, 6512.837179070561, 6615.834604134935, 6666.000099985041, 6723.865522689616, 6667.366600172976, 6851.280615535297, 6857.691403886937, 6869.175698916162, 6869.484788640772, 6265.091561723616, 6491.188601424822, 6587.110740050032, 6588.868222635616, 6603.306652801579, 6393.680315984275, 6490.350964903472, 6505.902411463737, 6526.151600292122, 6546.443552298108, 4819.421669399694, 5621.550275977994, 6458.67706614658, 6474.902876456763, 6493.805185844432, 6631.801045968628, 6631.801045968628, 6642.933570664231, 6688.0, 6695.765648202289, 6204.317525072329, 6304.873902522016, 6318.968405158129, 6354.1104245406605, 6507.381798729302], "value": 6148.704870372443, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [730.8976743255942, 769.2691942654478, 810.1938571121731, 1183.6153250193688, 1228.3428365824286, 36410.635893641054, 37022.25955480889, 37027.4445883312, 37115.25769484612, 37160.628393716055, 37282.8135859321, 37660.62339376605, 37754.43368349477, 37774.43338349927, 37904.24191516168, 7704.3451306639, 7840.7255746048895, 8085.110637829864, 8228.794280142984, 8287.502749835, 8187.140350263226, 8304.584770761468, 8308.667653293862, 8327.583620818965, 8329.50022998622, 8271.54506502142, 8340.332773378146, 8344.249017588416, 8344.457610255344, 8363.790905227388, 8300.2114799094, 8320.209580089942, 8329.83340333196, 8350.162964147878, 8362.790930226733, 8107.0, 8115.79710507242, 8124.6343914523395, 8180.713675021405, 8182.427230093896, 36890.26219475595, 37071.07322316937, 37094.70168544115, 37108.25783484315, 37158.8142059288, 2984.7612191024664, 3109.5957525521762, 3617.3850445424077, 3642.417213245868, 3651.7261205409436, 29928.251793705113, 30070.097897063122, 30282.18306901593, 30390.392192156032, 30446.695533044607, 3099.922501937447, 3129.702678245563, 3142.214446388403, 3142.937141257185, 3165.2561648012697, 247667.0, 250391.0, 265158.0, 301250.49374752986, 301850.0, 40320.0, 45183.0, 46967.29549056767, 47076.293855592194, 47144.7642761784, 49074.75462622664, 49398.50601494011, 49424.50575494271, 49571.05147382337, 49605.0, 14069.437222510986, 14148.929255353863, 14212.0, 14224.715505689828, 14229.573112806633, 11495.367754773568, 11605.941970290094, 11612.535498580131, 11615.36115513638, 11647.76704465923, 13365.599032029055, 13381.464741410235, 13382.665433364244, 13393.531226407224, 13491.920646348268, 5315.3090098286975, 5550.835174282033, 5907.793227237026, 5950.0, 5964.373740757244, 972.0765272990687, 995.3778888194878, 998.3660375661507, 999.6101520406902, 1038.1798379280283, 19268.710969335472, 23378.298651040495, 23873.761262387503, 23887.402814929766, 24453.144139955373, 1036.3367444835312, 1064.2124827627654, 1101.7961677089604, 1135.982960255597, 1137.624583887315, 10241.334313269614, 12300.569480068334, 12305.876941230652, 12316.50733970649, 12543.372831358578, 257832.0, 267841.0, 272435.0, 305042.0, 307869.0, 53551.19673204905, 53819.730901346025, 53956.73021634945, 54048.72975635175, 54272.99999999921, 1384.6884450998446, 1452.3246690288854, 1472.4404726203975, 1521.9239038048054, 1536.0169491525432, 23600.17399391013, 23617.173398930954, 23688.88155559211, 23712.0, 23762.287131386085, 37916.0, 38019.42970855384, 38020.429693555176, 38150.61849381582, 38212.04469888275, 277613.0, 279549.0, 279950.0, 280489.755921968, 280723.0, 33498.83250583829, 34390.140246493036, 35161.824190878884, 35180.29639407249, 35270.29459410746, 43321.78339108411, 43677.68966930997, 45525.0, 45629.0, 45758.771206143756, 22179.445513862283, 30138.0, 30330.0, 31110.53334200034, 31165.0, 30916.845415772776, 31046.68953310438, 31236.219094522818, 31313.06060818179, 31314.686853131177, 32379.676203237665, 35529.82235088912, 37132.62867371292, 37497.437538437494, 37604.87185384443, 30001.249968750955, 31143.844280778452, 31169.8441507791, 31656.525152122274, 32016.35967280595, 21884.12463501442, 24104.0, 24377.756222438256, 24492.0, 24789.0], "value": 17189.37007411623, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [427.87805475439507, 436.7314101827377, 436.9082492676538, 452.81887245101956, 453.91375638628637, 18998.90500547499, 20605.96970151492, 21234.15063397466, 21391.358259252247, 21456.0, 21571.24500642478, 21618.783812161873, 21921.0, 21950.001498157235, 23465.882670586667, 6062.151298818169, 6319.684015799215, 6341.365863413668, 6343.492520598341, 6352.269489008754, 4960.627952903528, 5949.6727679977585, 6232.127502149703, 6276.68616569172, 6281.748730050793, 5477.808276710316, 6190.3190649028575, 6206.441420272175, 6211.503079753631, 6239.032949892774, 4659.673822832403, 6128.142060111588, 6138.17134686816, 6197.69011549423, 6202.0, 4649.814007439699, 5350.35795704514, 5488.7530061147345, 5500.58745594082, 5533.363663178746, 26687.466250675072, 27548.862255688593, 27751.583726244124, 27891.442171156465, 28230.717692823015, 2183.9563208735894, 2263.8981245843975, 2265.161890100657, 2270.2735124760084, 2272.7727227277305, 17459.0, 17648.029358385284, 17778.46664600064, 18145.7278140828, 18319.54201144969, 2158.6222411077997, 2175.0973346061364, 2200.9119635214574, 2204.1624182810588, 2206.845520813528, 131428.0, 160322.1983890073, 163188.5521717175, 163502.0, 164956.0, 10506.789864202674, 10828.566857325619, 11786.469608867621, 12874.420651070724, 13020.02349823767, 14935.103893766409, 15617.453389131553, 16572.0, 16653.91673041627, 16677.16614169311, 7393.519421237603, 8875.955620221857, 8945.792318037113, 9060.323840089604, 9307.72076837696, 7101.431885449152, 7212.242714515005, 8118.660421030582, 8645.178708022728, 8863.64545418176, 7956.2043795619975, 7974.481658692166, 8123.0, 8126.268635822805, 8143.75568732939, 2161.5784921940385, 2168.392850002002, 2180.9236676716237, 2192.5066859956382, 2390.5338459000327, 944.5182956692137, 961.711486554031, 966.5501747378931, 984.7685793838442, 1008.5108722269694, 17553.64892702139, 19897.204111835654, 20774.272900448414, 20889.582208355747, 21065.0, 884.4958373727043, 910.5720311453606, 923.4320892650971, 992.667456402099, 1013.3261381181431, 10336.328138671113, 10581.470926453647, 10646.627368042082, 10648.041676249175, 10708.892911070945, 532186.0, 536377.0, 541384.0, 541491.0, 549629.0, 110964.33553496705, 112281.0, 126321.36839315745, 127156.0, 127762.88948442144, 1202.4168278384977, 1218.0803493362455, 1254.8368712067556, 1263.298869127643, 1266.8796464335874, 18614.81385186185, 21421.678674820203, 22244.221452249094, 22315.218967336066, 22445.77554224502, 47851.80370490766, 50833.745831272085, 51135.0, 55083.941551440024, 56569.0, 71317.64341178261, 75772.0, 75985.6200719015, 76432.0, 76678.0, 9052.456852588866, 9092.909070909207, 9127.452352858849, 9220.44677319363, 9240.353175277665, 12963.22220666763, 13330.800037999632, 14115.505957291445, 14394.568162955127, 14497.492587759378, 9430.915444723945, 11871.643850684493, 11904.642860714192, 12093.81859272094, 12198.451069701707, 11000.724981875197, 11344.319340839576, 11745.765084698429, 11766.235194712317, 12040.397980101136, 12144.210626309261, 12234.204776689487, 12408.503659853504, 12426.192297500633, 12545.93727031359, 6416.0, 8066.193380661887, 9030.819383612423, 9117.589708463003, 9146.405483643543, 11977.461014254553, 11993.700157496132, 12095.697607559881, 12137.575184868487, 12242.387880605756], "value": 10364.344214080313, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1196.5393323570424, 1424.5726282115363, 1456.6067161866297, 1459.7445447046764, 1463.6414078550754, 11385.146114041449, 11652.592159274416, 11728.296302221852, 11812.822807657882, 11935.283882967027, 11697.298162110279, 11720.413979301044, 11901.940490297558, 11903.940480297608, 11926.344051077187, 2148.860324078934, 2523.8611876346795, 2557.1177943609423, 2572.1383336582267, 2602.10227471522, 2387.77316154965, 2486.266551367351, 2511.573032584465, 2523.8107141964333, 2526.4694414173046, 2495.70051593809, 2524.5203411351786, 2540.9110681126162, 2542.478791847676, 2544.847309161447, 2469.345623409798, 2480.6527086208034, 2510.0587279770084, 2517.0435234610914, 2530.493901219754, 2430.878456077189, 2513.74862513749, 2517.0812653381386, 2517.546841568517, 2552.6426300317785, 11609.593664221797, 11673.708157296136, 11762.294262344287, 11775.411229438494, 11794.587189448328, 6580.341965803429, 6826.795196144124, 6882.002109694116, 6888.104546408985, 6900.551464154814, 4085.775282359469, 4405.449318835145, 4464.7321160730135, 4489.304157855538, 4520.0, 1187.1689817128045, 1251.7809383357967, 1256.1709271880607, 1284.2551320234336, 1293.3856418201278, 16177.0, 16437.753433698505, 16474.341026359052, 16613.252403641865, 16817.915910420368, 5498.285222920995, 5541.750621221974, 5696.686682232434, 5712.11462223356, 5724.370319264877, 11865.644030679094, 11880.821787673192, 11889.048876089892, 11901.88098119025, 11961.0, 6605.438537724347, 6626.7017984190825, 6627.337266273431, 6635.402813746689, 6636.871731805604, 5194.0910340690225, 6083.148359229712, 6175.722092505848, 6325.209348831396, 6332.398422149889, 3705.27747089315, 4360.10083525523, 4434.290513517822, 4461.0, 4463.977680111579, 1258.8048852427705, 1278.6675464379327, 1286.7555164518712, 1296.6304603188046, 1297.1438850358907, 730.0910366593519, 775.5889378629336, 808.4138999225632, 812.4191203289677, 815.8001289684065, 6678.131842860397, 6999.8600027999155, 7010.859782804316, 7055.89416158758, 7092.787216383516, 899.2086963472234, 922.8108237811233, 927.221134247222, 934.9111834375724, 938.1181689212119, 4090.2024105299774, 4322.15717935006, 4423.8451654192595, 4539.863804085882, 4552.362669226311, 13164.341782910817, 13980.44078236859, 15107.093574385572, 15423.691526169638, 15467.0, 11276.210665253517, 12095.939520302341, 14528.491502797351, 14838.480653177086, 15312.846871531365, 8370.414071015091, 8555.957220213859, 8707.216350528357, 8792.34057445694, 8807.735767926972, 6824.829379265558, 6992.860142797013, 7015.438764898694, 7045.11936007999, 7187.065681461377, 12586.559470418491, 13051.347432628521, 13903.374348154155, 13917.443302267797, 13948.16311021342, 13792.0, 13920.72158556803, 13935.651608709863, 14103.647408814451, 14290.142591444546, 8176.264136227768, 8943.0, 9562.665306714232, 9600.903990960001, 9617.278704097082, 8867.64529418816, 10539.629848119695, 10758.354498730101, 10879.891201087888, 10881.292715973437, 7192.892106618509, 9211.815763684823, 9247.907520924973, 9273.3045021625, 9287.0, 9007.819843603222, 9021.233195178484, 9029.548522573976, 9102.499362535124, 9269.953650231706, 8040.959795200986, 8185.8772118419465, 8189.344852411674, 8190.262876341158, 8209.95895020545, 5776.306843178845, 6069.544784141119, 6141.9385806143155, 6263.279722831932, 6324.430801227912, 5396.730163491889, 6114.2357205349335, 6236.781712640036, 6320.4311611955145, 6431.29255781873], "value": 5405.5570588517085, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.0416562783602, 403.1171733902753, 405.18153330272844, 406.932856078747, 418.9015581338387, 5935.792247271346, 6198.78304259351, 6449.645269510181, 6459.773907913224, 6483.8703225935515, 5703.230063941367, 6235.563510554263, 6313.873722525546, 6322.6838658067145, 6364.459020983222, 3983.7012224083164, 4813.326134341195, 4859.538343857328, 4860.756962151896, 4884.169691152512, 4047.251258517169, 4799.496052914446, 4857.514248575132, 4868.367112275399, 4900.338454308659, 3910.432987216852, 4806.110869489138, 4827.927581086286, 4850.393700787402, 4850.951490485085, 4809.807607695688, 4814.277858321245, 4816.060868130716, 4834.975825120887, 4857.2714092886, 3188.6651901550235, 4244.363345498169, 4321.58944900234, 4333.479982402132, 4342.891427714301, 6082.148499210114, 6104.511639068908, 6122.846928826771, 6173.753049878, 6218.22272215973, 4798.496157903405, 5770.711464426762, 5791.638964843259, 5791.885961624019, 5807.125472890903, 5352.74210560518, 5878.441548052928, 5930.55520835939, 6371.585846919982, 6840.9819103364625, 3744.9514136041953, 3833.616638336172, 4362.541933097043, 4441.800118994653, 4469.82120715171, 6790.32096790322, 6966.303369663044, 7218.783436496913, 7234.6020968847215, 7241.167265764399, 5011.624128190328, 5134.563562097189, 5143.8456846294675, 5159.265589237635, 5168.19892916591, 4048.0, 4354.782260886943, 5120.206368012964, 5236.24074509194, 5237.3453318335205, 4932.383452068491, 5614.494695477427, 5793.7392817323325, 5797.304323481125, 5811.360750317462, 5933.910991335047, 6792.08306878575, 6832.145981752281, 6836.871916133881, 6858.0, 1529.5029115537495, 1730.1435789284385, 1771.04363643631, 1795.353672677835, 1795.6857549928711, 1181.2262967756133, 1224.8162775583735, 1245.819356193347, 1246.426643743888, 1251.474380760083, 5472.616916815785, 6450.226187798393, 6473.579217350857, 6539.542232043711, 6557.901631475532, 6806.76176333826, 6910.48171387148, 7017.649117544102, 7144.3212894774915, 7189.0, 6403.807885763435, 6432.71052802625, 6472.546921715529, 6541.345865413516, 6548.466545768818, 3147.669494703069, 3199.344134452478, 3664.15724383388, 3668.6331366863633, 3683.226522430266, 5549.5837812164245, 5723.942760572341, 7269.236730143259, 7284.410821182727, 7303.8539229216185, 5887.852803679943, 7173.96413017942, 7201.3158749918675, 7242.493025488166, 7253.419726421874, 6010.879782404328, 6969.1985421677155, 7182.317679820409, 7216.494845360774, 7218.458615603956, 6150.892839288971, 6759.898601521079, 7044.802383594699, 7075.365635508544, 7085.964570177116, 5924.733386997682, 6787.93212067873, 6846.691898864663, 6986.54587451814, 7034.522750222511, 7388.815279618053, 7400.296971787671, 7412.147603025721, 7413.406927445899, 7433.182349941394, 4392.868213953587, 4557.202489564379, 4583.449986001701, 4589.242774942097, 4609.9166695827125, 6374.394432528902, 6578.76974305897, 7056.188538318159, 7090.432765378654, 7145.892811607934, 5765.106408506688, 6090.421409966046, 6820.31796820314, 6830.59016459014, 6888.0, 6583.473322134122, 6767.052612634334, 6826.044353790374, 6861.980728479453, 6870.896936546056, 6724.49566282521, 6729.966350168414, 6738.0, 6749.156355455568, 6753.189617245962, 6579.276279609144, 6773.491988101011, 6829.521933464808, 6865.656717164222, 6894.965525172342, 5269.0, 6625.768098116543, 6650.900236496554, 6713.0, 6748.122744043243], "value": 5014.314647077226, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [823.5717426937989, 838.325148255654, 848.3849209323239, 862.8446879561678, 863.0506442912792, 16527.338906443743, 16550.751738723913, 16918.661626767473, 18263.26946922125, 19366.70949935752, 17949.820501794977, 18113.275468981225, 18405.26378944844, 18467.168977396017, 18709.158087886044, 981.2934687025366, 991.1674193677292, 992.2211064314479, 995.6017592962801, 995.9800803983916, 914.8399030169727, 986.2849434160212, 987.239825334492, 1003.5183112106193, 1008.5007921079076, 1054.398992574233, 1063.3354153653966, 1064.138048180972, 1066.1204506282309, 1066.2909165404997, 955.4935883981432, 1012.4937531234419, 1049.2078480747025, 1063.6755789484205, 1064.5103252503857, 768.7155752371602, 778.6924164954811, 782.1748055801128, 784.070876011922, 784.9843003139962, 16676.0, 16946.0, 17079.231434585472, 17245.051522166403, 17435.65128697419, 17355.913220433817, 17398.478045658652, 17616.0, 17697.20362583674, 17749.11254437286, 13910.696884135808, 14228.359723812453, 14242.359093840698, 14355.856441435557, 14362.281885905662, 3391.4404123319623, 3404.063882432326, 3441.0193094968063, 3443.2252743132594, 3461.117415059169, 109410.0, 127960.36019819841, 128125.99999999907, 128979.0, 134292.0, 13238.139520931109, 13332.333383330795, 13383.130096543693, 13386.19682819034, 13553.915686745037, 24511.38721531976, 25430.0, 25500.617490737655, 26002.21993340203, 26324.868375658, 6443.5167362448, 6644.302348253364, 7643.738783100837, 7802.648880800378, 7882.0, 3812.5806161322234, 3857.0550215197454, 3889.4749208857006, 3893.552241492265, 3893.8831835044425, 3985.601439856049, 4075.7758323292505, 4085.97957010219, 4108.8356465741035, 4118.629323360912, 894.8568229083315, 930.0792215706537, 930.9767255818658, 932.4545141092536, 936.1247233836385, 635.1520719838975, 645.554567348528, 666.7233098264167, 671.358852296056, 675.3044364304774, 11158.330500170016, 11536.59621913229, 12069.275843449246, 12184.756304873852, 12195.0, 644.9193850768654, 648.6270394523074, 651.6318280171688, 661.0843981086134, 681.5127184063418, 4707.905841883143, 5359.552920711386, 5477.15104158848, 5578.860528486739, 5607.719614019365, 100394.99605004002, 102936.45595316077, 105232.0, 105264.47367763112, 109244.0, 75396.62301688457, 84789.0, 88304.5584772072, 88509.55745221232, 89693.65459518113, 84402.15597843943, 85176.0, 85359.0, 85398.57300713456, 86582.0, 12230.593481749713, 12516.998640108952, 12754.043446741345, 12762.298073605669, 12777.744445111231, 28437.289067773472, 29280.41439171247, 36979.260414792094, 37121.0, 40856.79571602242, 55192.44807551873, 55495.0, 55720.0, 56090.719546402004, 56262.0, 16364.181790910645, 16790.58023549421, 17388.47834564965, 17559.473215803548, 17578.91210543939, 14977.475788347356, 16862.494125176265, 18004.099795010457, 18041.955944758654, 18818.905905470387, 13937.233452159822, 16897.408590699266, 17338.826611734225, 17375.652486949937, 17567.912160439115, 16551.420700275434, 17028.744568831724, 17431.47705568835, 17498.125093745006, 17721.848079874766, 16851.578710532336, 16869.650427965487, 16871.66256674835, 17204.13979301005, 17340.47978560645, 11596.478158483058, 11814.409279536161, 12146.39268036577, 12248.510059597516, 12344.506219751109, 12620.684482888002, 13347.06570540053, 13386.062975591614, 13481.494811349316, 13727.176369417866], "value": 8388.365107579944, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1498.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 116.0, 115.0, 114.0, 115.0, 113.0, 112.0, 113.0, 115.0, 113.0], "value": 113.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 112.0, 117.0, 151.0, 109.0, 113.0, 109.0, 111.0, 141.0, 108.0], "value": 121.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [218.0, 289.0, 211.0, 212.0, 218.0, 217.0, 202.0, 202.0, 198.0, 198.0], "value": 216.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 58.0, 59.0, 88.0, 73.0, 88.0, 76.0, 62.0, 93.0, 72.0], "value": 75.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 70.0, 84.0, 69.0, 71.0, 79.0, 107.0, 102.0, 98.0, 89.0], "value": 86.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 91.0, 90.0, 91.0, 90.0, 91.0, 113.0, 131.0, 136.0], "value": 101.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 97.0, 98.0, 97.0, 97.0, 97.0, 94.0, 120.0, 155.0], "value": 105.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 92.0, 90.0, 94.0, 94.0, 91.0, 91.0, 91.0, 91.0, 91.0], "value": 92.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 48.0, 50.0, 51.0, 50.0, 50.0, 52.0, 51.0, 50.0], "value": 50.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 59.0, 63.0, 63.0, 63.0, 61.0, 58.0, 60.0, 59.0, 57.0], "value": 60.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 81.0, 84.0, 82.0, 82.0, 83.0, 80.0, 82.0, 84.0, 84.0], "value": 82.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 135.0, 135.0, 136.0, 136.0, 137.0, 136.0, 141.0, 189.0, 138.0], "value": 143.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 185.0, 186.0, 185.0, 184.0, 184.0, 186.0, 173.0, 184.0], "value": 183.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 69.0, 64.0, 69.0, 67.0, 67.0, 69.0, 65.0, 68.0], "value": 67.6, "unit": "score"}]}]} 09:06:51 INFO - Return code: 0 09:06:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:06:51 INFO - # TBPL SUCCESS # 09:06:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:06:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:06:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:06:51 INFO - 2017-05-07 09:06:51.243 screenresolution[2067:80082] starting screenresolution argv=screenresolution get 09:06:51 INFO - 2017-05-07 09:06:51.246 screenresolution[2067:80082] Display 0: 1600x1200x32@60 09:06:51 INFO - 2017-05-07 09:06:51.252 screenresolution[2068:80085] starting screenresolution argv=screenresolution list 09:06:51 INFO - Available Modes on Display 0 09:06:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:06:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:06:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:06:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:06:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:06:51 INFO - Intel Iris: 09:06:51 INFO - Chipset Model: Intel Iris 09:06:51 INFO - Type: GPU 09:06:51 INFO - Bus: Built-In 09:06:51 INFO - VRAM (Dynamic, Max): 1536 MB 09:06:51 INFO - Vendor: Intel (0x8086) 09:06:51 INFO - Device ID: 0x0a2e 09:06:51 INFO - Revision ID: 0x0009 09:06:51 INFO - Displays: 09:06:51 INFO - Display: 09:06:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:06:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:06:51 INFO - Display Serial Number: 1600x1200 60 09:06:51 INFO - Main Display: Yes 09:06:51 INFO - Mirror: Off 09:06:51 INFO - Online: Yes 09:06:51 INFO - Rotation: Supported 09:06:51 INFO - Return code: 0 09:06:51 INFO - Running post-action listener: _resource_record_post_action 09:06:51 INFO - [mozharness: 2017-05-07 16:06:51.489316Z] Finished run-tests step (success) 09:06:51 INFO - Running post-run listener: _resource_record_post_run 09:06:51 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 415232; Write bytes: 516535296; Read time: 6; Write time: 1621 09:06:51 INFO - TinderboxPrint: CPU usage
54.2% 09:06:51 INFO - TinderboxPrint: I/O read bytes / time
415,232 / 6 09:06:51 INFO - TinderboxPrint: I/O write bytes / time
516,535,296 / 1,621 09:06:51 INFO - TinderboxPrint: CPU idle
2,194.0 (45.8%) 09:06:51 INFO - TinderboxPrint: CPU system
967.5 (20.2%) 09:06:51 INFO - TinderboxPrint: CPU user
1,628.3 (34.0%) 09:06:51 INFO - TinderboxPrint: Swap in / out
208,297,984 / 0 09:06:51 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 207636992; Write bytes: 208365568; Read time: 16175; Write time: 450 09:06:51 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 415232; Write bytes: 301001728; Read time: 6; Write time: 1138 09:06:51 INFO - Running post-run listener: _upload_blobber_files 09:06:51 INFO - Blob upload gear active. 09:06:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:06:51 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 09:06:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:06:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:06:52 INFO - (blobuploader) - INFO - Open directory for files ... 09:06:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:06:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:06:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:06:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:06:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:06:53 INFO - (blobuploader) - INFO - Done attempting. 09:06:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:06:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:06:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:06:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:06:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:06:54 INFO - (blobuploader) - INFO - Done attempting. 09:06:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:06:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:06:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:06:55 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:06:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:06:55 INFO - (blobuploader) - INFO - Done attempting. 09:06:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:06:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:06:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:06:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:06:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:06:57 INFO - (blobuploader) - INFO - Done attempting. 09:06:57 INFO - (blobuploader) - INFO - Iteration through files over. 09:06:57 INFO - Return code: 0 09:06:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:06:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:06:57 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0c4397032c2812be280733912de3a7c84e54e6f162e92f393f9549e5b51d8ebdb2792021a29792dad79f4a45ebb23b216b74b96d52768100e07247b90b693906", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/271f6a596fff4b2b29b7bb4cdf02d61b75454c41cb63ee1434215ea5b1ec077f3fc35c9c5f67b2769e9a02daad7cb1b5fda6ad771e312071e70b005784ad992b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9372f34e8c3b0fb27367e6d8724d66749176cff12c7eae44e40f5f563cfe9b47004269c38bd6bfe8370a1382bc7502786dcc8e5548b6308ec1bf640114ab77f9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f5f1b425c9d2c95a06bdcab6c3100471adcdaaa17b700e35109a38e142213b7389135ca4c13440d09d80e97985d1e453f7ae7c5af14e66f91440acd4b0128acd"} 09:06:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:06:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:06:57 INFO - Contents: 09:06:57 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0c4397032c2812be280733912de3a7c84e54e6f162e92f393f9549e5b51d8ebdb2792021a29792dad79f4a45ebb23b216b74b96d52768100e07247b90b693906", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/271f6a596fff4b2b29b7bb4cdf02d61b75454c41cb63ee1434215ea5b1ec077f3fc35c9c5f67b2769e9a02daad7cb1b5fda6ad771e312071e70b005784ad992b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9372f34e8c3b0fb27367e6d8724d66749176cff12c7eae44e40f5f563cfe9b47004269c38bd6bfe8370a1382bc7502786dcc8e5548b6308ec1bf640114ab77f9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f5f1b425c9d2c95a06bdcab6c3100471adcdaaa17b700e35109a38e142213b7389135ca4c13440d09d80e97985d1e453f7ae7c5af14e66f91440acd4b0128acd"} 09:06:57 INFO - Running post-run listener: copy_logs_to_upload_dir 09:06:57 INFO - Copying logs to upload dir... 09:06:57 INFO - mkdir: /builds/slave/test/build/upload/logs 09:06:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.865305 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-05-07 09:06:58.025051) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-07 09:06:58.028546) ========= 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.Cnc81OCrjy/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.7vD782sYJo/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0c4397032c2812be280733912de3a7c84e54e6f162e92f393f9549e5b51d8ebdb2792021a29792dad79f4a45ebb23b216b74b96d52768100e07247b90b693906", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/271f6a596fff4b2b29b7bb4cdf02d61b75454c41cb63ee1434215ea5b1ec077f3fc35c9c5f67b2769e9a02daad7cb1b5fda6ad771e312071e70b005784ad992b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9372f34e8c3b0fb27367e6d8724d66749176cff12c7eae44e40f5f563cfe9b47004269c38bd6bfe8370a1382bc7502786dcc8e5548b6308ec1bf640114ab77f9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f5f1b425c9d2c95a06bdcab6c3100471adcdaaa17b700e35109a38e142213b7389135ca4c13440d09d80e97985d1e453f7ae7c5af14e66f91440acd4b0128acd"} build_url:https://queue.taskcluster.net/v1/task/HymmffKfRrS1UZ2ObfgxVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011292 build_url: 'https://queue.taskcluster.net/v1/task/HymmffKfRrS1UZ2ObfgxVQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0c4397032c2812be280733912de3a7c84e54e6f162e92f393f9549e5b51d8ebdb2792021a29792dad79f4a45ebb23b216b74b96d52768100e07247b90b693906", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/271f6a596fff4b2b29b7bb4cdf02d61b75454c41cb63ee1434215ea5b1ec077f3fc35c9c5f67b2769e9a02daad7cb1b5fda6ad771e312071e70b005784ad992b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9372f34e8c3b0fb27367e6d8724d66749176cff12c7eae44e40f5f563cfe9b47004269c38bd6bfe8370a1382bc7502786dcc8e5548b6308ec1bf640114ab77f9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f5f1b425c9d2c95a06bdcab6c3100471adcdaaa17b700e35109a38e142213b7389135ca4c13440d09d80e97985d1e453f7ae7c5af14e66f91440acd4b0128acd"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-07 09:06:58.065107) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-07 09:06:58.065455) ========= 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.Cnc81OCrjy/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.7vD782sYJo/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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.005316 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-07 09:06:58.117711) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-07 09:06:58.118443) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-07 09:06:58.119210) ========= ========= Total master_lag: 0.21 =========