builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0279 starttime: 1485188628.79 results: success (0) buildid: 20170123064543 builduid: 352ae97b189e40a68f6ba076c6eb7e77 revision: f80dc9fc34680105b714a49b4704bb843f5f7004 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-23 08:23:48.789789) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-23 08:23:48.790268) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-23 08:23:48.790574) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-23 08:23:48.824900) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-23 08:23:48.825302) ========= 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.NLf7dJsGmw/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.UYq8iZ1qJO/Listeners TMPDIR=/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/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-01-23 08:23:48-- 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% 25.7M=0s 2017-01-23 08:23:48 (25.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.151289 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-23 08:23:49.000880) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-23 08:23:49.001237) ========= 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.NLf7dJsGmw/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.UYq8iZ1qJO/Listeners TMPDIR=/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/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.024450 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-23 08:23:49.050099) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-23 08:23:49.050455) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f80dc9fc34680105b714a49b4704bb843f5f7004 --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 f80dc9fc34680105b714a49b4704bb843f5f7004 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NLf7dJsGmw/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.UYq8iZ1qJO/Listeners TMPDIR=/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/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-01-23 08:23:49,136 truncating revision to first 12 chars 2017-01-23 08:23:49,136 Setting DEBUG logging. 2017-01-23 08:23:49,136 attempt 1/10 2017-01-23 08:23:49,136 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f80dc9fc3468?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-23 08:23:50,300 unpacking tar archive at: mozilla-central-f80dc9fc3468/testing/mozharness/ program finished with exit code 0 elapsedTime=1.425465 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-23 08:23:50.500800) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-23 08:23:50.501383) ========= script_repo_revision: f80dc9fc34680105b714a49b4704bb843f5f7004 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-23 08:23:50.501990) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-23 08:23:50.505513) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-23 08:23:50.528050) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-01-23 08:23:50.528428) ========= /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.NLf7dJsGmw/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.UYq8iZ1qJO/Listeners TMPDIR=/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/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:23:50 INFO - MultiFileLogger online at 20170123 08:23:50 in /builds/slave/test 08:23:50 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:23:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:23:50 INFO - {'append_to_log': False, 08:23:50 INFO - 'base_work_dir': '/builds/slave/test', 08:23:50 INFO - 'blob_upload_branch': 'Firefox', 08:23:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:23:50 INFO - 'branch': 'Firefox', 08:23:50 INFO - 'buildbot_json_path': 'buildprops.json', 08:23:50 INFO - 'config_files': ('talos/mac_config.py',), 08:23:50 INFO - 'default_actions': ('clobber', 08:23:50 INFO - 'read-buildbot-config', 08:23:50 INFO - 'download-and-extract', 08:23:50 INFO - 'populate-webroot', 08:23:50 INFO - 'create-virtualenv', 08:23:50 INFO - 'install', 08:23:50 INFO - 'run-tests'), 08:23:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:23:50 INFO - 'download_minidump_stackwalk': True, 08:23:50 INFO - 'download_symbols': 'ondemand', 08:23:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:23:50 INFO - 'tooltool.py': '/tools/tooltool.py', 08:23:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:23:50 INFO - '/tools/misc-python/virtualenv.py')}, 08:23:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:23:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:23:50 INFO - 'installer_path': 'installer.exe', 08:23:50 INFO - 'log_level': 'info', 08:23:50 INFO - 'log_name': 'talos', 08:23:50 INFO - 'log_to_console': True, 08:23:50 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:23:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:23:50 INFO - 'opt_config_files': (), 08:23:50 INFO - 'pip_index': False, 08:23:50 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:23:50 INFO - 'cmd': ('bash', 08:23:50 INFO - '-c', 08:23:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:23:50 INFO - 'enabled': True, 08:23:50 INFO - 'halt_on_failure': False, 08:23:50 INFO - 'name': 'check_screen_resolution'},), 08:23:50 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:23:50 INFO - 'cmd': ('bash', 08:23:50 INFO - '-c', 08:23:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:23:50 INFO - 'enabled': True, 08:23:50 INFO - 'halt_on_failure': False, 08:23:50 INFO - 'name': 'check_screen_resolution'},), 08:23:50 INFO - 'run_cmd_checks_enabled': True, 08:23:50 INFO - 'sps_profile': False, 08:23:50 INFO - 'sps_profile_interval': 0, 08:23:50 INFO - 'suite': 'dromaeojs-e10s', 08:23:50 INFO - 'system_bits': '32', 08:23:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:23:50 INFO - 'title': 't-yosemite-r7-0279', 08:23:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:23:50 INFO - 'use_talos_json': True, 08:23:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:23:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:23:50 INFO - 'work_dir': 'build'} 08:23:50 INFO - [mozharness: 2017-01-23 16:23:50.721336Z] Running clobber step. 08:23:50 INFO - Running pre-action listener: _resource_record_pre_action 08:23:50 INFO - Running main action method: clobber 08:23:50 INFO - rmtree: /builds/slave/test/build 08:23:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:23:53 INFO - Running post-action listener: _resource_record_post_action 08:23:53 INFO - [mozharness: 2017-01-23 16:23:53.134455Z] Finished clobber step (success) 08:23:53 INFO - [mozharness: 2017-01-23 16:23:53.134569Z] Running read-buildbot-config step. 08:23:53 INFO - Running pre-action listener: _resource_record_pre_action 08:23:53 INFO - Running main action method: read_buildbot_config 08:23:53 INFO - Using buildbot properties: 08:23:53 INFO - { 08:23:53 INFO - "project": "", 08:23:53 INFO - "product": "firefox", 08:23:53 INFO - "script_repo_revision": "production", 08:23:53 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 08:23:53 INFO - "repository": "", 08:23:53 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 08:23:53 INFO - "buildid": "20170123064543", 08:23:53 INFO - "pgo_build": "False", 08:23:53 INFO - "basedir": "/builds/slave/test", 08:23:53 INFO - "buildnumber": 71, 08:23:53 INFO - "slavename": "t-yosemite-r7-0279", 08:23:53 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 08:23:53 INFO - "platform": "yosemite_r7", 08:23:53 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:23:53 INFO - "branch": "mozilla-central", 08:23:53 INFO - "repo_path": "mozilla-central", 08:23:53 INFO - "revision": "f80dc9fc34680105b714a49b4704bb843f5f7004", 08:23:53 INFO - "stage_platform": "macosx64", 08:23:53 INFO - "builduid": "352ae97b189e40a68f6ba076c6eb7e77", 08:23:53 INFO - "slavebuilddir": "test" 08:23:53 INFO - } 08:23:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/b9w9lKPuRzqqo-Qco89CWA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 08:23:53 INFO - Running post-action listener: _resource_record_post_action 08:23:53 INFO - [mozharness: 2017-01-23 16:23:53.137865Z] Finished read-buildbot-config step (success) 08:23:53 INFO - [mozharness: 2017-01-23 16:23:53.137971Z] Running download-and-extract step. 08:23:53 INFO - Running pre-action listener: _resource_record_pre_action 08:23:53 INFO - Running main action method: download_and_extract 08:23:53 INFO - mkdir: /builds/slave/test/build/tests 08:23: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:23:53 INFO - trying https://queue.taskcluster.net/v1/task/b9w9lKPuRzqqo-Qco89CWA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 08:23:53 INFO - Downloading https://queue.taskcluster.net/v1/task/b9w9lKPuRzqqo-Qco89CWA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 08:23:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b9w9lKPuRzqqo-Qco89CWA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 08:23:54 INFO - Downloaded 1199 bytes. 08:23:54 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 08:23:54 INFO - Using the following test package requirements: 08:23:54 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 08:23:54 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:23:54 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 08:23:54 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:23:54 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 08:23:54 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:23:54 INFO - u'jsshell-mac.zip'], 08:23:54 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:23:54 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 08:23:54 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 08:23:54 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:23:54 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 08:23:54 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:23:54 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 08:23:54 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:23:54 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 08:23:54 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:23:54 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 08:23:54 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:23:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b9w9lKPuRzqqo-Qco89CWA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 08:23:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b9w9lKPuRzqqo-Qco89CWA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:23:54 INFO - Fetch https://queue.taskcluster.net/v1/task/b9w9lKPuRzqqo-Qco89CWA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 08:23:55 INFO - Expected file size: 16350951 08:23:55 INFO - Obtained file size: 16350951 08:24:01 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:24:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b9w9lKPuRzqqo-Qco89CWA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 08:24:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b9w9lKPuRzqqo-Qco89CWA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:24:01 INFO - Fetch https://queue.taskcluster.net/v1/task/b9w9lKPuRzqqo-Qco89CWA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 08:24:02 INFO - Expected file size: 16350951 08:24:02 INFO - Obtained file size: 16350951 08:24:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b9w9lKPuRzqqo-Qco89CWA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 08:24:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b9w9lKPuRzqqo-Qco89CWA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:24:09 INFO - Fetch https://queue.taskcluster.net/v1/task/b9w9lKPuRzqqo-Qco89CWA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 08:24:09 INFO - Expected file size: 14403485 08:24:09 INFO - Obtained file size: 14403485 08:24:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:24:10 INFO - trying https://queue.taskcluster.net/v1/task/b9w9lKPuRzqqo-Qco89CWA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:24:10 INFO - Downloading https://queue.taskcluster.net/v1/task/b9w9lKPuRzqqo-Qco89CWA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 08:24:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b9w9lKPuRzqqo-Qco89CWA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 08:24:12 INFO - Downloaded 69970431 bytes. 08:24:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/b9w9lKPuRzqqo-Qco89CWA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:24:12 INFO - mkdir: /builds/slave/test/properties 08:24:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:24:12 INFO - Writing to file /builds/slave/test/properties/build_url 08:24:12 INFO - Contents: 08:24:12 INFO - build_url:https://queue.taskcluster.net/v1/task/b9w9lKPuRzqqo-Qco89CWA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:24:13 INFO - Running post-action listener: _resource_record_post_action 08:24:13 INFO - Running post-action listener: set_extra_try_arguments 08:24:13 INFO - [mozharness: 2017-01-23 16:24:13.344964Z] Finished download-and-extract step (success) 08:24:13 INFO - [mozharness: 2017-01-23 16:24:13.345114Z] Running populate-webroot step. 08:24:13 INFO - Running pre-action listener: _resource_record_pre_action 08:24:13 INFO - Running main action method: populate_webroot 08:24:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:24:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:24:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:24:13 INFO - '--tppagecycles', 08:24:13 INFO - '7'], 08:24:13 INFO - 'tests': ['tsvgm']}}, 08:24:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:24:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:24:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:24:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:24:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:24:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:24:13 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:24:13 INFO - 'talos_options': ['--disable-e10s'], 08:24:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:24:13 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:24:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:24:13 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:24:13 INFO - 'talos_options': ['--disable-e10s'], 08:24:13 INFO - 'tests': ['damp', 'tps']}, 08:24:13 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:24:13 INFO - 'tests': ['damp', 'tps']}, 08:24:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:24:13 INFO - 'tests': ['dromaeo_dom']}, 08:24:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:24:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:24:13 INFO - 'tests': ['basic_compositor_video']}, 08:24:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:24:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:24:13 INFO - 'tests': ['a11yr', 08:24:13 INFO - 'ts_paint', 08:24:13 INFO - 'tpaint', 08:24:13 INFO - 'sessionrestore', 08:24:13 INFO - 'sessionrestore_no_auto_restore', 08:24:13 INFO - 'tabpaint']}, 08:24:13 INFO - 'other-e10s': {'tests': ['a11yr', 08:24:13 INFO - 'ts_paint', 08:24:13 INFO - 'tpaint', 08:24:13 INFO - 'sessionrestore', 08:24:13 INFO - 'sessionrestore_no_auto_restore', 08:24:13 INFO - 'tabpaint']}, 08:24:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:24:13 INFO - 'ts_paint', 08:24:13 INFO - 'tpaint', 08:24:13 INFO - 'sessionrestore', 08:24:13 INFO - 'sessionrestore_no_auto_restore', 08:24:13 INFO - 'tabpaint']}, 08:24:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:24:13 INFO - 'ts_paint', 08:24:13 INFO - 'tpaint', 08:24:13 INFO - 'sessionrestore', 08:24:13 INFO - 'sessionrestore_no_auto_restore', 08:24:13 INFO - 'tabpaint']}, 08:24:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:24:13 INFO - 'tests': ['a11yr', 08:24:13 INFO - 'ts_paint', 08:24:13 INFO - 'tpaint', 08:24:13 INFO - 'sessionrestore', 08:24:13 INFO - 'sessionrestore_no_auto_restore', 08:24:13 INFO - 'tabpaint']}, 08:24:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:24:13 INFO - 'tests': ['a11yr', 08:24:13 INFO - 'ts_paint', 08:24:13 INFO - 'tpaint', 08:24:13 INFO - 'sessionrestore', 08:24:13 INFO - 'sessionrestore_no_auto_restore', 08:24:13 INFO - 'tabpaint']}, 08:24:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:24:13 INFO - 'tests': ['tsvgx', 08:24:13 INFO - 'tsvgr_opacity', 08:24:13 INFO - 'tart', 08:24:13 INFO - 'tscrollx', 08:24:13 INFO - 'cart', 08:24:13 INFO - 'tsvg_static']}, 08:24:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:24:13 INFO - 'tsvgr_opacity', 08:24:13 INFO - 'tart', 08:24:13 INFO - 'tscrollx', 08:24:13 INFO - 'cart', 08:24:13 INFO - 'tsvg_static']}, 08:24:13 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:24:13 INFO - 'talos_options': ['--disable-e10s'], 08:24:13 INFO - 'tests': ['tp5o']}, 08:24:13 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:24:13 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:24:13 INFO - 'talos_options': ['--disable-e10s', 08:24:13 INFO - '--xperf_path', 08:24:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:24:13 INFO - 'tests': ['tp5n']}, 08:24:13 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:24:13 INFO - 'talos_options': ['--xperf_path', 08:24:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:24:13 INFO - 'tests': ['tp5n']}}, 08:24:13 INFO - 'talos.zip': {'path': '', 08:24:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:24:13 INFO - Running post-action listener: _resource_record_post_action 08:24:13 INFO - [mozharness: 2017-01-23 16:24:13.363165Z] Finished populate-webroot step (success) 08:24:13 INFO - [mozharness: 2017-01-23 16:24:13.363287Z] Running create-virtualenv step. 08:24:13 INFO - Running pre-action listener: _resource_record_pre_action 08:24:13 INFO - Running main action method: create_virtualenv 08:24:13 INFO - Creating virtualenv /builds/slave/test/build/venv 08:24:13 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:24:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:24:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:24:13 INFO - Using real prefix '/tools/python27' 08:24:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:24:14 INFO - Installing distribute.............................................................................................................................................................................................done. 08:24:17 INFO - Installing pip.................done. 08:24:17 INFO - Return code: 0 08:24:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:24:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:24:17 INFO - Reading from file tmpfile_stdout 08:24:17 INFO - Output received: 08:24:17 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:24:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:24:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:24:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:24:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:24:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:24:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f5ffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f602768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fade3d86330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3ddd30>, '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 0x10f304db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3d2be8>, '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.NLf7dJsGmw/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.UYq8iZ1qJO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/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:24:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:24:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:24:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NLf7dJsGmw/Render', 08:24:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:24:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:24:17 INFO - 'HOME': '/Users/cltbld', 08:24:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:24:17 INFO - 'LOGNAME': 'cltbld', 08:24:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:24:17 INFO - 'MOZ_NO_REMOTE': '1', 08:24:17 INFO - 'NO_EM_RESTART': '1', 08:24:17 INFO - 'PAGER': '/bin/cat', 08:24:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:24:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:24:17 INFO - 'PWD': '/builds/slave/test', 08:24:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:24:17 INFO - 'SHELL': '/bin/bash', 08:24:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UYq8iZ1qJO/Listeners', 08:24:17 INFO - 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', 08:24:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:24:17 INFO - 'USER': 'cltbld', 08:24:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:24:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:24:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:24:17 INFO - 'XPC_FLAGS': '0x0', 08:24:17 INFO - 'XPC_SERVICE_NAME': '0', 08:24:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:24:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:24:17 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:24:17 INFO - Cleaning up... 08:24:17 INFO - Return code: 0 08:24:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:24:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:24:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:24:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:24:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:24:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f5ffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f602768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fade3d86330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3ddd30>, '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 0x10f304db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3d2be8>, '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.NLf7dJsGmw/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.UYq8iZ1qJO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/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:24:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:24:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:24:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NLf7dJsGmw/Render', 08:24:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:24:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:24:17 INFO - 'HOME': '/Users/cltbld', 08:24:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:24:17 INFO - 'LOGNAME': 'cltbld', 08:24:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:24:17 INFO - 'MOZ_NO_REMOTE': '1', 08:24:17 INFO - 'NO_EM_RESTART': '1', 08:24:17 INFO - 'PAGER': '/bin/cat', 08:24:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:24:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:24:17 INFO - 'PWD': '/builds/slave/test', 08:24:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:24:17 INFO - 'SHELL': '/bin/bash', 08:24:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UYq8iZ1qJO/Listeners', 08:24:17 INFO - 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', 08:24:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:24:17 INFO - 'USER': 'cltbld', 08:24:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:24:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:24:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:24:17 INFO - 'XPC_FLAGS': '0x0', 08:24:17 INFO - 'XPC_SERVICE_NAME': '0', 08:24:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:24:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:24:17 INFO - Downloading/unpacking psutil>=3.1.1 08:24:17 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:24:17 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:24:17 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:24:17 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:24:17 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:24:17 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:24:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:24:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:24:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:24:21 INFO - Installing collected packages: psutil 08:24:21 INFO - Running setup.py install for psutil 08:24:21 INFO - building 'psutil._psutil_osx' extension 08:24:21 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 08:24:22 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 08:24:22 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 08:24:22 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 08:24:22 INFO - building 'psutil._psutil_posix' extension 08:24:22 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 08:24:22 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:24:22 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:24:22 INFO - ^ 08:24:22 INFO - 1 warning generated. 08:24:22 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 08:24:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:24:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:24:22 INFO - Successfully installed psutil 08:24:22 INFO - Cleaning up... 08:24:22 INFO - Return code: 0 08:24:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:24:22 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:24:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:24:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:22 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:24:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:24:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:22 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 0x10f5ffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f602768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fade3d86330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3ddd30>, '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 0x10f304db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3d2be8>, '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.NLf7dJsGmw/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.UYq8iZ1qJO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/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:24:22 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:24:22 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:24:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NLf7dJsGmw/Render', 08:24:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:24:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:24:22 INFO - 'HOME': '/Users/cltbld', 08:24:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:24:22 INFO - 'LOGNAME': 'cltbld', 08:24:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:24:22 INFO - 'MOZ_NO_REMOTE': '1', 08:24:22 INFO - 'NO_EM_RESTART': '1', 08:24:22 INFO - 'PAGER': '/bin/cat', 08:24:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:24:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:24:22 INFO - 'PWD': '/builds/slave/test', 08:24:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:24:22 INFO - 'SHELL': '/bin/bash', 08:24:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UYq8iZ1qJO/Listeners', 08:24:22 INFO - 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', 08:24:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:24:22 INFO - 'USER': 'cltbld', 08:24:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:24:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:24:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:24:22 INFO - 'XPC_FLAGS': '0x0', 08:24:22 INFO - 'XPC_SERVICE_NAME': '0', 08:24:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:24:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:24:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:24:22 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:24:22 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:24:22 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:24:22 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:24:22 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:24:22 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:24:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:24:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:24:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:24:25 INFO - Installing collected packages: mozsystemmonitor 08:24:25 INFO - Running setup.py install for mozsystemmonitor 08:24:25 INFO - Successfully installed mozsystemmonitor 08:24:25 INFO - Cleaning up... 08:24:25 INFO - Return code: 0 08:24:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:24:25 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:24:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:24:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:25 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:24:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:24:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:25 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 0x10f5ffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f602768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fade3d86330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3ddd30>, '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 0x10f304db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3d2be8>, '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.NLf7dJsGmw/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.UYq8iZ1qJO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/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:24:25 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:24:25 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:24:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NLf7dJsGmw/Render', 08:24:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:24:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:24:25 INFO - 'HOME': '/Users/cltbld', 08:24:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:24:25 INFO - 'LOGNAME': 'cltbld', 08:24:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:24:25 INFO - 'MOZ_NO_REMOTE': '1', 08:24:25 INFO - 'NO_EM_RESTART': '1', 08:24:25 INFO - 'PAGER': '/bin/cat', 08:24:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:24:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:24:25 INFO - 'PWD': '/builds/slave/test', 08:24:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:24:25 INFO - 'SHELL': '/bin/bash', 08:24:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UYq8iZ1qJO/Listeners', 08:24:25 INFO - 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', 08:24:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:24:25 INFO - 'USER': 'cltbld', 08:24:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:24:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:24:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:24:25 INFO - 'XPC_FLAGS': '0x0', 08:24:25 INFO - 'XPC_SERVICE_NAME': '0', 08:24:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:24:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:24:25 INFO - Downloading/unpacking jsonschema==2.5.1 08:24:25 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:24:25 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:24:25 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:24:25 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:24:25 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:24:25 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:24:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:24:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:24:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:24:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:24:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:24:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:24:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:24:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:24:28 INFO - Downloading functools32-3.2.3-2.tar.gz 08:24:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:24:29 INFO - warning: no files found matching '*.txt' 08:24:29 INFO - no previously-included directories found matching 'build' 08:24:29 INFO - no previously-included directories found matching 'dist' 08:24:29 INFO - no previously-included directories found matching '.git*' 08:24:29 INFO - Installing collected packages: jsonschema, functools32 08:24:29 INFO - Running setup.py install for functools32 08:24:29 INFO - warning: no files found matching '*.txt' 08:24:29 INFO - no previously-included directories found matching 'build' 08:24:29 INFO - no previously-included directories found matching 'dist' 08:24:29 INFO - no previously-included directories found matching '.git*' 08:24:29 INFO - Successfully installed jsonschema functools32 08:24:29 INFO - Cleaning up... 08:24:29 INFO - Return code: 0 08:24:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:24:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:24:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:24:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:24:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:24:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f5ffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f602768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fade3d86330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3ddd30>, '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 0x10f304db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3d2be8>, '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.NLf7dJsGmw/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.UYq8iZ1qJO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/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:24:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:24:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:24:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NLf7dJsGmw/Render', 08:24:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:24:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:24:29 INFO - 'HOME': '/Users/cltbld', 08:24:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:24:29 INFO - 'LOGNAME': 'cltbld', 08:24:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:24:29 INFO - 'MOZ_NO_REMOTE': '1', 08:24:29 INFO - 'NO_EM_RESTART': '1', 08:24:29 INFO - 'PAGER': '/bin/cat', 08:24:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:24:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:24:29 INFO - 'PWD': '/builds/slave/test', 08:24:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:24:29 INFO - 'SHELL': '/bin/bash', 08:24:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UYq8iZ1qJO/Listeners', 08:24:29 INFO - 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', 08:24:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:24:29 INFO - 'USER': 'cltbld', 08:24:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:24:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:24:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:24:29 INFO - 'XPC_FLAGS': '0x0', 08:24:29 INFO - 'XPC_SERVICE_NAME': '0', 08:24:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:24:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:24:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:24:29 INFO - Cleaning up... 08:24:29 INFO - Return code: 0 08:24:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:24:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:24:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:24:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:24:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:24:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f5ffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f602768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fade3d86330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3ddd30>, '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 0x10f304db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3d2be8>, '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.NLf7dJsGmw/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.UYq8iZ1qJO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/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:24:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:24:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:24:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NLf7dJsGmw/Render', 08:24:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:24:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:24:29 INFO - 'HOME': '/Users/cltbld', 08:24:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:24:29 INFO - 'LOGNAME': 'cltbld', 08:24:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:24:29 INFO - 'MOZ_NO_REMOTE': '1', 08:24:29 INFO - 'NO_EM_RESTART': '1', 08:24:29 INFO - 'PAGER': '/bin/cat', 08:24:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:24:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:24:29 INFO - 'PWD': '/builds/slave/test', 08:24:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:24:29 INFO - 'SHELL': '/bin/bash', 08:24:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UYq8iZ1qJO/Listeners', 08:24:29 INFO - 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', 08:24:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:24:29 INFO - 'USER': 'cltbld', 08:24:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:24:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:24:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:24:29 INFO - 'XPC_FLAGS': '0x0', 08:24:29 INFO - 'XPC_SERVICE_NAME': '0', 08:24:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:24:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:24:29 INFO - Downloading/unpacking blobuploader==1.2.4 08:24:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:24:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:24:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:24:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:24:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:24:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:24:32 INFO - Downloading blobuploader-1.2.4.tar.gz 08:24:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:24:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:24:32 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:24:32 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:24:32 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:24:32 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:24:32 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:24:32 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:24:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:24:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:24: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:24: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:24: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:24: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:24: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:24: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:24:33 INFO - Downloading docopt-0.6.1.tar.gz 08:24:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:24:33 INFO - Installing collected packages: blobuploader, requests, docopt 08:24:33 INFO - Running setup.py install for blobuploader 08:24:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:24:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:24:33 INFO - Running setup.py install for requests 08:24:34 INFO - Running setup.py install for docopt 08:24:34 INFO - Successfully installed blobuploader requests docopt 08:24:34 INFO - Cleaning up... 08:24:34 INFO - Return code: 0 08:24:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:24:34 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:24:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:24:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:34 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:24:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:24:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:34 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 0x10f5ffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f602768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fade3d86330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3ddd30>, '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 0x10f304db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3d2be8>, '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.NLf7dJsGmw/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.UYq8iZ1qJO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/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:24:34 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:24:34 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:24:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NLf7dJsGmw/Render', 08:24:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:24:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:24:34 INFO - 'HOME': '/Users/cltbld', 08:24:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:24:34 INFO - 'LOGNAME': 'cltbld', 08:24:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:24:34 INFO - 'MOZ_NO_REMOTE': '1', 08:24:34 INFO - 'NO_EM_RESTART': '1', 08:24:34 INFO - 'PAGER': '/bin/cat', 08:24:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:24:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:24:34 INFO - 'PWD': '/builds/slave/test', 08:24:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:24:34 INFO - 'SHELL': '/bin/bash', 08:24:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UYq8iZ1qJO/Listeners', 08:24:34 INFO - 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', 08:24:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:24:34 INFO - 'USER': 'cltbld', 08:24:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:24:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:24:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:24:34 INFO - 'XPC_FLAGS': '0x0', 08:24:34 INFO - 'XPC_SERVICE_NAME': '0', 08:24:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:24:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:24:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:24:34 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-zIcdDr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:24:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:24:35 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-bOpZRW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:24:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:24:35 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-dWr9Va-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:24:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:24:35 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-M4f1dv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:24:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:24:35 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-CDxi5N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:24:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:24:35 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-EhzC8c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:24:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:24:35 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-Zym9hA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:24:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:24:35 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-8vpbUS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:24:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:24:35 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-FOyqPt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:24:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:24:35 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-UUozBj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:24:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:24:36 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-Ie4JhN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:24:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:24:36 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-QCcBpw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:24:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:24:36 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-4tqxkG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:24:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:24:36 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-mz_JIu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:24:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:24:36 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-S450vJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:24:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:24:36 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-heQZMM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:24:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:24:36 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-YgIWPr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:24:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:24:36 INFO - Running setup.py install for manifestparser 08:24:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:24:36 INFO - Running setup.py install for mozcrash 08:24:37 INFO - Running setup.py install for mozdebug 08:24:37 INFO - Running setup.py install for mozdevice 08:24:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:24:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:24:37 INFO - Running setup.py install for mozfile 08:24:37 INFO - Running setup.py install for mozhttpd 08:24:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:24:37 INFO - Running setup.py install for mozinfo 08:24:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:24:37 INFO - Running setup.py install for mozInstall 08:24:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:24:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:24:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:24:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:24:37 INFO - Running setup.py install for mozleak 08:24:38 INFO - Running setup.py install for mozlog 08:24:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:24:38 INFO - Running setup.py install for moznetwork 08:24:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:24:38 INFO - Running setup.py install for mozprocess 08:24:38 INFO - Running setup.py install for mozprofile 08:24:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:24:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:24:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:24:38 INFO - Running setup.py install for mozrunner 08:24:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:24:38 INFO - Running setup.py install for mozscreenshot 08:24:39 INFO - Running setup.py install for moztest 08:24:39 INFO - Running setup.py install for mozversion 08:24:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:24:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:24:39 INFO - Cleaning up... 08:24:39 INFO - Return code: 0 08:24:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:24:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:24:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:24:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:24:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:24:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f5ffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f602768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fade3d86330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3ddd30>, '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 0x10f304db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3d2be8>, '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.NLf7dJsGmw/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.UYq8iZ1qJO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/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:24:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:24:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:24:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NLf7dJsGmw/Render', 08:24:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:24:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:24:39 INFO - 'HOME': '/Users/cltbld', 08:24:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:24:39 INFO - 'LOGNAME': 'cltbld', 08:24:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:24:39 INFO - 'MOZ_NO_REMOTE': '1', 08:24:39 INFO - 'NO_EM_RESTART': '1', 08:24:39 INFO - 'PAGER': '/bin/cat', 08:24:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:24:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:24:39 INFO - 'PWD': '/builds/slave/test', 08:24:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:24:39 INFO - 'SHELL': '/bin/bash', 08:24:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UYq8iZ1qJO/Listeners', 08:24:39 INFO - 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', 08:24:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:24:39 INFO - 'USER': 'cltbld', 08:24:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:24:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:24:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:24:39 INFO - 'XPC_FLAGS': '0x0', 08:24:39 INFO - 'XPC_SERVICE_NAME': '0', 08:24:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:24:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:24:39 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-gIsJPi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:24:39 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:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:24:39 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-lh1BHS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:24:39 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:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:24:39 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-elxpTU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:24:39 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:24:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:24:39 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-lQbctE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:24:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:24:40 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-TPMRDs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:24:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:24:40 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-1PKDAs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:24:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:24:40 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-fKgn4n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:24:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:24:40 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-pREsMx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:24:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:24:40 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-uyL8Kl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:24:40 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:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:24:40 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-VnttOS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:24:40 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:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:24:40 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-IVN4Kx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:24:40 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:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:24:40 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-lXKNhF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:24:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:24:40 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-Bq_9kr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:24:40 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:24:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:24:40 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-NBiQPR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:24:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:24:41 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-D5oJVK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:24:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:24:41 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-61kIx0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:24:41 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:24:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:24:41 INFO - Running setup.py (path:/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/pip-LXhZIX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:24:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:24:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:24:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:24:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:24:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:24:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:24:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:24: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:24: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:24: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:24: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:24: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:24: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:24:44 INFO - Downloading blessings-1.6.tar.gz 08:24:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:24:44 INFO - Installing collected packages: blessings 08:24:44 INFO - Running setup.py install for blessings 08:24:44 INFO - Successfully installed blessings 08:24:44 INFO - Cleaning up... 08:24:44 INFO - Return code: 0 08:24:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:24:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:24:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:24:44 INFO - Reading from file tmpfile_stdout 08:24:44 INFO - Current package versions: 08:24:44 INFO - blessings == 1.6 08:24:44 INFO - blobuploader == 1.2.4 08:24:44 INFO - docopt == 0.6.1 08:24:44 INFO - functools32 == 3.2.3-2 08:24:44 INFO - jsonschema == 2.5.1 08:24:44 INFO - manifestparser == 1.1 08:24:44 INFO - mozInstall == 1.12 08:24:44 INFO - mozcrash == 1.0 08:24:44 INFO - mozdebug == 0.1 08:24:44 INFO - mozdevice == 0.49 08:24:44 INFO - mozfile == 1.2 08:24:44 INFO - mozhttpd == 0.7 08:24:44 INFO - mozinfo == 0.9 08:24:44 INFO - mozleak == 0.1 08:24:44 INFO - mozlog == 3.4 08:24:44 INFO - moznetwork == 0.27 08:24:44 INFO - mozprocess == 0.24 08:24:44 INFO - mozprofile == 0.28 08:24:44 INFO - mozrunner == 6.13 08:24:44 INFO - mozscreenshot == 0.1 08:24:44 INFO - mozsystemmonitor == 0.3 08:24:44 INFO - moztest == 0.8 08:24:44 INFO - mozversion == 1.4 08:24:44 INFO - psutil == 3.1.1 08:24:44 INFO - requests == 1.2.3 08:24:44 INFO - wsgiref == 0.1.2 08:24:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:24: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:24:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:24:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:24:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:24:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f5ffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f602768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fade3d86330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3ddd30>, '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 0x10f304db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3d2be8>, '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.NLf7dJsGmw/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.UYq8iZ1qJO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/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:24:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:24:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:24:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NLf7dJsGmw/Render', 08:24:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:24:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:24:45 INFO - 'HOME': '/Users/cltbld', 08:24:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:24:45 INFO - 'LOGNAME': 'cltbld', 08:24:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:24:45 INFO - 'MOZ_NO_REMOTE': '1', 08:24:45 INFO - 'NO_EM_RESTART': '1', 08:24:45 INFO - 'PAGER': '/bin/cat', 08:24:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:24:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:24:45 INFO - 'PWD': '/builds/slave/test', 08:24:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:24:45 INFO - 'SHELL': '/bin/bash', 08:24:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UYq8iZ1qJO/Listeners', 08:24:45 INFO - 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', 08:24:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:24:45 INFO - 'USER': 'cltbld', 08:24:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:24:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:24:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:24:45 INFO - 'XPC_FLAGS': '0x0', 08:24:45 INFO - 'XPC_SERVICE_NAME': '0', 08:24:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:24:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:24:45 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:24:45 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:24:45 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:24:45 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:24:45 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:24:45 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:24:45 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:24:45 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:24:45 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:24:45 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:24:45 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:24:45 INFO - Cleaning up... 08:24:45 INFO - Return code: 0 08:24:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:24:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:24:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:24:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:24:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:24:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:24:45 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 0x10f5ffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f602768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fade3d86330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3ddd30>, '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 0x10f304db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3d2be8>, '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.NLf7dJsGmw/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.UYq8iZ1qJO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/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:24:45 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:24:45 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:24:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NLf7dJsGmw/Render', 08:24:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:24:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:24:45 INFO - 'HOME': '/Users/cltbld', 08:24:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:24:45 INFO - 'LOGNAME': 'cltbld', 08:24:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:24:45 INFO - 'MOZ_NO_REMOTE': '1', 08:24:45 INFO - 'NO_EM_RESTART': '1', 08:24:45 INFO - 'PAGER': '/bin/cat', 08:24:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:24:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:24:45 INFO - 'PWD': '/builds/slave/test', 08:24:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:24:45 INFO - 'SHELL': '/bin/bash', 08:24:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UYq8iZ1qJO/Listeners', 08:24:45 INFO - 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', 08:24:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:24:45 INFO - 'USER': 'cltbld', 08:24:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:24:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:24:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:24:45 INFO - 'XPC_FLAGS': '0x0', 08:24:45 INFO - 'XPC_SERVICE_NAME': '0', 08:24:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:24:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:24:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:24:45 INFO - Cleaning up... 08:24:45 INFO - Return code: 0 08:24:45 INFO - Running post-action listener: _resource_record_post_action 08:24:45 INFO - Running post-action listener: _start_resource_monitoring 08:24:45 INFO - Starting resource monitoring. 08:24:45 INFO - [mozharness: 2017-01-23 16:24:45.908341Z] Finished create-virtualenv step (success) 08:24:45 INFO - [mozharness: 2017-01-23 16:24:45.909304Z] Running install step. 08:24:45 INFO - Running pre-action listener: _resource_record_pre_action 08:24:45 INFO - Running main action method: install 08:24:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:24:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:24:46 INFO - Reading from file tmpfile_stdout 08:24:46 INFO - Detecting whether we're running mozinstall >=1.0... 08:24:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:24:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:24:46 INFO - Reading from file tmpfile_stdout 08:24:46 INFO - Output received: 08:24:46 INFO - Usage: mozinstall [options] installer 08:24:46 INFO - Options: 08:24:46 INFO - -h, --help show this help message and exit 08:24:46 INFO - -d DEST, --destination=DEST 08:24:46 INFO - Directory to install application into. [default: 08:24:46 INFO - "/builds/slave/test"] 08:24:46 INFO - --app=APP Application being installed. [default: firefox] 08:24:46 INFO - mkdir: /builds/slave/test/build/application 08:24:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:24:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:25:08 INFO - Reading from file tmpfile_stdout 08:25:08 INFO - Output received: 08:25:08 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:25:08 INFO - Running post-action listener: _resource_record_post_action 08:25:08 INFO - [mozharness: 2017-01-23 16:25:08.060429Z] Finished install step (success) 08:25:08 INFO - [mozharness: 2017-01-23 16:25:08.060557Z] Running run-tests step. 08:25:08 INFO - Running pre-action listener: _resource_record_pre_action 08:25:08 INFO - Running main action method: run_tests 08:25:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:25:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:25:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:25:08 INFO - 2017-01-23 08:25:08.073 screenresolution[1621:7480] starting screenresolution argv=screenresolution get 08:25:08 INFO - 2017-01-23 08:25:08.075 screenresolution[1621:7480] Display 0: 1600x1200x32@60 08:25:08 INFO - 2017-01-23 08:25:08.080 screenresolution[1622:7482] starting screenresolution argv=screenresolution list 08:25:08 INFO - Available Modes on Display 0 08:25:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:25:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:25:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:25:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:25:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:25:08 INFO - Intel Iris: 08:25:08 INFO - Chipset Model: Intel Iris 08:25:08 INFO - Type: GPU 08:25:08 INFO - Bus: Built-In 08:25:08 INFO - VRAM (Dynamic, Max): 1536 MB 08:25:08 INFO - Vendor: Intel (0x8086) 08:25:08 INFO - Device ID: 0x0a2e 08:25:08 INFO - Revision ID: 0x0009 08:25:08 INFO - Displays: 08:25:08 INFO - Display: 08:25:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:25:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:25:08 INFO - Display Serial Number: 1600x1200 60 08:25:08 INFO - Main Display: Yes 08:25:08 INFO - Mirror: Off 08:25:08 INFO - Online: Yes 08:25:08 INFO - Rotation: Supported 08:25:08 INFO - Return code: 0 08:25:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:25:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:25:08 INFO - Python 2.7.3 08:25:08 INFO - Return code: 0 08:25:08 INFO - grabbing minidump binary from tooltool 08:25:08 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:25:08 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 0x10f3ddd30>, '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 0x10f304db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f3d2be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:25:08 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:25:08 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:25:08 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:25:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:25:08 INFO - Return code: 0 08:25:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:25:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:25:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:25:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:25:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:25:08 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/b9w9lKPuRzqqo-Qco89CWA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0279', '--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:25:08 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/b9w9lKPuRzqqo-Qco89CWA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0279 --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:25:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NLf7dJsGmw/Render', 08:25:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:25:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:25:08 INFO - 'HOME': '/Users/cltbld', 08:25:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:25:08 INFO - 'LOGNAME': 'cltbld', 08:25:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:25:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:25:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:25:08 INFO - 'MOZ_NO_REMOTE': '1', 08:25:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:25:08 INFO - 'NO_EM_RESTART': '1', 08:25:08 INFO - 'PAGER': '/bin/cat', 08:25:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:25:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:25:08 INFO - 'PWD': '/builds/slave/test', 08:25:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:25:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:25:08 INFO - 'SHELL': '/bin/bash', 08:25:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UYq8iZ1qJO/Listeners', 08:25:08 INFO - 'TMPDIR': '/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/', 08:25:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:25:08 INFO - 'USER': 'cltbld', 08:25:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:25:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:25:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:25:08 INFO - 'XPC_FLAGS': '0x0', 08:25:08 INFO - 'XPC_SERVICE_NAME': '0', 08:25:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:25:08 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/b9w9lKPuRzqqo-Qco89CWA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0279', '--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:25:08 INFO - mozversion application_buildid: 20170123064543 08:25:08 INFO - mozversion application_changeset: f80dc9fc34680105b714a49b4704bb843f5f7004 08:25:08 INFO - mozversion application_display_name: Nightly 08:25:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:25:08 INFO - mozversion application_name: Firefox 08:25:08 INFO - mozversion application_remotingname: firefox 08:25:08 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:25:08 INFO - mozversion application_vendor: Mozilla 08:25:08 INFO - mozversion application_version: 53.0a1 08:25:08 INFO - mozversion platform_buildid: 20170123064543 08:25:08 INFO - mozversion platform_changeset: f80dc9fc34680105b714a49b4704bb843f5f7004 08:25:08 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:25:08 INFO - mozversion platform_version: 53.0a1 08:25:08 INFO - using testdate: 1485188708 08:25:08 INFO - actual date: 1485188708 08:25:08 INFO - starting webserver on 'localhost:49239' 08:25:08 INFO - SUITE-START | Running 2 tests 08:25:08 INFO - TEST-START | dromaeo_css 08:25:08 INFO - operating with platform_type : mac_ 08:25:08 INFO - Initialising browser for dromaeo_css test... 08:25:08 INFO - TEST-INFO | started process 1632 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/tmpetaOjN/profile http://localhost:49239/getInfo.html) 08:25:11 INFO - PROCESS | 1632 | 2017-01-23 08:25:11.495 plugin-container[1634:7649] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 08:25:11 INFO - PROCESS | 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:25:11 INFO - PROCESS | 1632 | 2017-01-23 08:25:11.498 plugin-container[1634:7649] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900f, name = 'com.apple.CFPasteboardClient' 08:25:11 INFO - PROCESS | 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:25:11 INFO - PROCESS | 1632 | 2017-01-23 08:25:11.498 plugin-container[1634:7649] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:25:11 INFO - PROCESS | 1632 | __metrics Screen width/height:1600/1200 08:25:11 INFO - PROCESS | 1632 | colorDepth:24 08:25:11 INFO - PROCESS | 1632 | Browser inner width/height: 1024/689 08:25:11 INFO - PROCESS | 1632 | __metrics 08:25:11 ERROR - PROCESS | 1632 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 08:25:11 INFO - PROCESS | 1632 | JavaScript error: resource://app/modules/webrtcUI.jsm, line 66: NS_ERROR_XPC_NOT_ENOUGH_ARGS: Not enough arguments [nsIMessageListenerManager.removeMessageListener] 08:25:12 INFO - TEST-INFO | 1632: exit 0 08:25:12 INFO - Browser initialized. 08:25:12 INFO - Running cycle 1/1 for dromaeo_css test... 08:25:12 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/tmpetaOjN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:25:14 INFO - PROCESS | 1635 | 2017-01-23 08:25:14.906 plugin-container[1636:7766] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 08:25:14 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:25:14 INFO - PROCESS | 1635 | 2017-01-23 08:25:14.909 plugin-container[1636:7766] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 08:25:14 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:25:14 INFO - PROCESS | 1635 | 2017-01-23 08:25:14.909 plugin-container[1636:7766] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:25:24 INFO - PROCESS | 1635 | RSS: Main: 233115648 08:25:24 INFO - PROCESS | 1635 | 08:28:26 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49239/tests/dromaeo/cssquery-ext.html) 08:28:28 INFO - PROCESS | 1635 | RSS: Main: 251531264 08:28:28 INFO - PROCESS | 1635 | 08:30:12 INFO - PROCESS | 1635 | ************************* 08:30:12 INFO - PROCESS | 1635 | A coding exception was thrown and uncaught in a Task. 08:30:12 INFO - PROCESS | 1635 | 08:30:12 ERROR - PROCESS | 1635 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:30:12 INFO - PROCESS | 1635 | Full stack: 08:30:12 INFO - PROCESS | 1635 | ************************* 08:31:30 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-ext.html (next: http://localhost:49239/tests/dromaeo/cssquery-jquery.html) 08:31:31 INFO - PROCESS | 1635 | RSS: Main: 264790016 08:31:31 INFO - PROCESS | 1635 | 08:34:33 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49239/tests/dromaeo/cssquery-mootools.html) 08:34:33 INFO - PROCESS | 1635 | RSS: Main: 271855616 08:34:33 INFO - PROCESS | 1635 | 08:37:35 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49239/tests/dromaeo/cssquery-prototype.html) 08:37:36 INFO - PROCESS | 1635 | RSS: Main: 267976704 08:37:36 INFO - PROCESS | 1635 | 08:40:39 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49239/tests/dromaeo/cssquery-yui.html) 08:40:39 INFO - PROCESS | 1635 | RSS: Main: 276156416 08:40:39 INFO - PROCESS | 1635 | 08:43:42 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-yui.html (next: http://localhost:49239/tests/dromaeo/cssquery-dojo.html) 08:43:42 INFO - PROCESS | 1635 | RSS: Main: 278220800 08:43:42 INFO - PROCESS | 1635 | 08:43:42 INFO - PROCESS | 1635 | __start_tp_report 08:43:42 INFO - PROCESS | 1635 | _x_x_mozilla_page_load 08:43:42 INFO - PROCESS | 1635 | _x_x_mozilla_page_load_details 08:43:42 INFO - PROCESS | 1635 | |i|pagename|runs| 08:43:42 INFO - PROCESS | 1635 | |0;dojo.html;3313.088900552348;3331.8000919944798;3334.8499317530723;3366.5455163552942;3391.32173565287;5532.225488431613;5569.52659023983;5594.049011668011;5671.886562268752;5698.914516282259;4634.8146074157075;5066.771995260213;5267.420583735796;5510.9448905510935;5577.749001294941;5066.138756411419;5084.983003399317;5168.896622067575;5227.876006658567;5247.475252474741;4674.976625116887;5071.670341427795;5260.211528080452;5280.1551751719735;5313.760880760375;4977.651564390494;5007.399112106534;5201.297824793643;5226.111561034633;5241.973790131044;5052.69683818972;5100.795968161269;5158.664686795384;5212.296339994128;5259.710715910624;5084.110280700899;5087.135187018179;5107.978404319114;5112.565431938253;5147.253648220987;4747.311639812242;5576.9163462548095;5577.498025177753;5682.403347648478;5705.657660540381;6086.7565297388055;6116.366205733259;6127.953177891112;6142.437386315926;6146.200993870812;6208.906866396963;6216.844578885519;6222.62664240147;6228.314885362606;6279.309275979638;5784.219130417382;5798.246227990418;5803.419658034247;5844.006518891799;5873.911891321633;6132.959035350176;6159.692015399301;6180.165677633462;6213.96893015532;6236.844078898064;5103.132467480537;6035.7283922223605;6141.232345956755;6142.140100385949;6148.846278842975;6207.037909124093;6212.72042758077;6225.128482012522;6229.283632382243;6244.344343843922;6045.425684559959;6107.006761825686;6115.879294474695;6129.664444256096;6130.141780150782;6196.194494715658;6199.56603037783;6239.625622462667;6244.094606282064;6247.562670613104;4885.145099607555;4937;4942.9258561121615;4965.627577931669;4967.205247160437;4332.241857674894;4340.218760623118;4348.326009468537;4372.803223854934;4504.842330518416;5684.062129748545;5689.544836413077;5720.170575266563;5736.397678243808;5786.276715410574;6046.27444706638;6174.629522228681;6195.690215489298;6198.907016394758;6210.192674952227;5667.971660141756;5676.659400435987;5707.1153971134545;5725.112607545837;5760.567957403212;5428.4028756836715;5431;5467.781288748405;5472.91790623141;5513.42109078549;6233.501319894398;6236.501079913505;6259.655718935503;6270.0908368287255;6301.306856245717;6091.1472393864005;6174.783882564088;6212.875742485035;6242.406971337898;6257.624542527463;5103.617228707936;5239.109351410193;5272.209168624737;6251.71867265965;6258.280297765815;5529.612927095226;5568.888622227451;6057;6113.419225173601;6157.568970172045;5239.47605239473;6022.1870047543025;6106.755729770759;6191.25704915413;6225.937740622536;4978.900421991468;5894.911576326444;6021.488173505301;6071.180390647206;6151.507879369729;6047.274327080779;6083.908741368971;6089.360617135314;6099.902017636868;6109.938900610937;4769.523047695203;4857.465678775403;5558.305211848519;5851.034579294539;5992.550558708027;6060.090986352083;6102.428659371999;6105.694715264308;6122.142899994094;6134.079888016655;4975.925361119514;5858.882822343615;5859.121131830259;5868.149118377726;5985.042393217064 08:43:42 INFO - PROCESS | 1635 | |1;ext.html;418.60023677388097;438.1040771622033;475.7502311286574;506.2861365474681;526.2159382520044;38110;40720;40722.99999999996;40784.592154078484;40925.18149637005;41484.79257603715;42145;42159.57840421602;42174.73475795723;42233.36649950253;9228.8615670765;9435.952820235923;9496.905030949705;10737.78524429511;11149.442527873574;10928.562857485691;10996.28524145932;11011.229213955026;11022.834657480144;11024.944875275653;10910.890891091067;10965.945170274137;10966.835497467544;10978.066864316543;11020.338779673246;10941.343519388862;10944.781104377907;10988.230823842334;11000.724981875437;11022.338659680405;10954.178436617285;11036.337819730761;11046.502907369188;11047.889521104766;11062.39156846373;37031;37178.07054823624;37221.25557488835;37366.25267494662;37395.81302093472;3961.960380396188;3980.24375368679;3987.1029018470904;4000;4007.859724909614;28768.280792980135;28773.56839647407;29247;29355;29378.70621293781;3190.6968837960358;3297.851596678155;3299.736021118305;3343.9665603343897;3353.2622822978906;471309.4345282756;476093;478178.21821781684;480700;481763.59118204535;56887.43112568904;56977.430225698045;57026.144607830916;57063.714681426325;57076;59270.407295927354;59398;59488;59672.70163649154;59756.402435975084;14877.256137193097;15429.997050191701;15447.995880267972;15514.767278490832;15542.300596473186;10785.29895556802;11050.392228427352;11666.76666466683;11953.40232988347;11968.486617810184;14430;15568.610784730472;16951.321947122222;17715.025673588076;18095.72856407155;7775.688972441152;7813.218678132255;7839.624143971974;7862.764117076383;7942.761717148494;787.4409169489727;863.7797361672733;869.3175856952389;890.4301247201796;898.1602201941206;23509.88245058798;28898.566521502195;29990.40019199604;30010.84994575013;30519.389612207633;934.9953250233841;966.7239244197732;1056.9735756605992;1084.9132069434427;1179.979317890019;15545.766813497807;15646.295916683777;15658.68682626341;15772.211389430482;15959.521214363587;595712.2572645568;601343;601510;601707;602603.9869800623;61003.38996610066;61494.38505614976;61625.38374616196;61700;61858.69070654708;1405.444849284527;1413.236852099875;1417.64558860287;1420.5596265157628;1428.5000249912448;28359.29101772472;28957.276068098465;29279.85360073186;29814.254643633216;29894.85052574723;43704.12591748211;51657.7417112912;51978;52060.47939520556;52151.73924130355;515893;516595;516891;518193;522478;23219.3034208974;23823.761762382153;26115;26524.469510610066;26849.194524164308;46647.533524664315;47482.52517474781;47652;48438.75780621074;48493.75753121212;34521;35694;37096.814515927246;38152.809235953646;39853.2029359417;35493.467597986564;36073.81963090168;36230.09424764402;37074.814625926694;37154.628453715115;35318.82340588281;35690;35958.64041359553;36387.90836274916;36496.90509284726;28982.42035159327;31397.52903706492;31567.84216078905;31586.842065789522;31688.841555792074;20499.28252511155;20933.476663083544;23919.52160956806;24001.719406804623;24185 08:43:42 INFO - PROCESS | 1635 | |2;jquery.html;393.56511055283926;394.8144372145091;398.71292669278125;402.45266437644796;425.05001322045223;14202.36089375978;15787.92106039471;19915.60168796625;21196.04617792199;21368.252111176113;16631.584210394747;20589.27937522187;20872.373828785123;20978.89510552449;21234.25680101193;5602.495775380215;5642.238297829792;5763.452472015152;5833.854153646172;5842.218123472339;5778.451047150514;5790.739416726236;5830.737616807232;5831.970840145794;5837.9124313135335;5201.271821944931;6426.550141490075;6486.448651864597;6551.181102362205;6553;5934.7922822701;5976.731047102848;6336.081268216106;6450.129232553592;6503.642299673516;5834.095715164156;5837.270341207349;5837.3287071986415;5842.298924129132;5846.50304724099;12932.418041188075;13280.800787988188;13446.932765336209;13470.326483675875;19445.638805283637;2552.8978840846344;2610.099515667092;2635.5651317532584;2642.9867850660626;2670.626112344273;9871.309008369346;9930.255230857714;10054.547545360476;10335.94832025835;10472.633457829015;1816.6275913437714;1868.616933528637;1893.4508992392327;1893.640208360421;1899.9715004274947;130623;145855.99999999895;146506.80239796411;146923;148056.51943480535;11677.766444671059;11907.821382679267;11927.343996080299;11932.761344773056;12101;15415.152166630718;15433;15444.691106178037;15462.613434664;15464.690706186038;8310.459820111673;8483.83032339362;8553.187447192508;8604.5697715114;8640.524771137521;8237.711680091288;8265;8283.668653253802;8397.412181147383;8408.537530435884;4709.0817290628;4711.858644240679;4714.269288260325;4760.309755085563;4811.663183577186;1746.589551455407;2018.0716870239853;2081.105124796352;2084.937451876446;2086.926957556478;941.3786900645541;959.4531117263233;962.629387685732;963.9180669643018;993.7565296502258;12642;12677.619671409873;13101.737965240833;13137.934310328386;13269.535566255136;947.5025611553989;951.9952400238093;981.504340308154;983.6409710455632;992.335135459244;6631.10480085192;6640.269570347258;6771.492138089663;6887.793366199022;6899.827504312332;654373;660051;677357;689455;715997;137406.6259337414;137811;138769;141421;148320.25839870732;989.5992123190119;1008.8738907636546;1052.1950707708609;1056.7305337139135;1056.7780766039218;15854.9207253963;19912.103955321756;19936.102875370354;20268.493287667927;20336.69494957547;50201.4939551814;52643;59110.704446477495;60273.69863150656;61781;69115.65442172757;85464.29071418662;86855;88897.11102889146;90451.54774226087;6914.550554214161;7135.393491553276;8369.037560680355;8482.812406263252;8544.957275213585;12140.968017718593;12770.23378597287;12882.098253122567;13157.60527184186;13179.934100329821;10800.891991079989;10844.566217351217;11009.06422954058;11147.219694621299;11291.604793832177;9759.9024009759;10482.742070951535;10772.515236814206;10982;11341.206115571831;8532.445391049561;8921.776955576162;9213.078692131001;11679.708007299885;11801.35092569917;8180.263776260165;8223.588820559067;8418.579071046546;8434.24091831738;8552.144785521397;8053.355731541345;10474.738131546772;10827.837582436427;11108.555887734543;11209.047230985132 08:43:42 INFO - PROCESS | 1635 | |3;mootools.html;1116.2576886370564;1139.652406016165;1237.2143688757635;1249.900087922628;1255.2405794494327;6404.807855764323;7382.963085184567;7488.288612581803;7616.314531692146;8402.495850248983;6675.7663481778145;6995.510314278003;8084.029916410039;8169.468984516017;8210.507369557816;1902.7145928110756;1936.7966363531766;2271.5684020036183;2317.918872839451;2321.3964369263945;2111.746590409153;2129.7337832770904;2284.097781376355;2323.8954247058923;2332.0438620165737;1888.8677792554527;2018.4247489465506;2160.0063970573556;2277.817774578038;2298.7586303438065;2242.865428074321;2261.8416710830247;2263.7509873913855;2266.3427605994325;2267.548768787977;2038.8776673399643;2107.083418712865;2211.237123192512;2299.9425014374606;2302.3553405046614;8115.310198633123;8329.250367466897;8335.166483351677;8445.197706217961;8560.48637081777;5022.397312322545;5177.352830896138;5188.610854185951;5200.97399513;5204.401493828221;3409.3863104641155;3465.8440370183403;3469.6356882527225;3486.5467489226494;3518.437050071976;1213.6480420677992;1214.542548941274;1248.6254123762835;1254.5609036837125;1269.2701696524443;10579.100776434012;10649.414282214475;10691.465426728633;10731.946340268327;10784.78430431387;4358.891027724332;4616.376789133424;4661.324108004388;4690.671652984327;4715.269133284346;9399.812003760024;9403.200727938065;9440.527973601293;9445.905540944503;9474.336796424184;5136.743162841843;5247.658902171346;5247.685138891602;5251.159814429673;5256.474352564713;4542.295944128666;4762.071396077731;4773.284007398848;4784.904301913943;4800.591949684246;3787.867424640124;3820;3840.807959602065;3878.806059697004;3907.3552863777727;1276.6042526816716;1279.993600031994;1281.0200196849419;1297.9935100324626;1301.4078594239597;775.3332134364442;857.7684025313255;902.4269588811021;910.717677519971;941.6045260990401;4977.253411988231;5090.363704536933;5114.590832733373;5218.765155568009;5555.14222652078;862.9266512346521;952.514217748953;986.5067466266867;989.4805227255748;1023.3961962442195;3249.756268279841;3337.632860385356;3554.662307080823;3772.6981841452616;3791.3933770596573;9577.281703872237;9860.506974651238;9899.109080182818;9911.702648920544;9974.052465015811;9227.584758685874;9744;9846.261530385249;9891.505424728735;9900.811902571593;7412.740554080581;7731.381489480828;7736.071671399468;7780.999999999887;7781.844363112706;5549.278593782782;5572.832815015556;5678.4605462480995;5713.257276554022;5788.871170121786;7818.726344577913;8798.780030499289;8817.867731983897;9189.448633082036;9190.218831399407;8878.911210888067;9192.816143677222;9195.632174712937;9235.538223088954;9346.672866449642;7076.929230707627;7085;7132;7139.107611548557;7204.063471748639;7556.886646700414;7722.575258360844;7741.187175346733;7759.495632783851;7841.294283514511;6873.965630171817;6941.167059952838;6985.126859142607;7017.7192912282935;7164.283571642794;6817.795466136024;6884.51808373409;6897.89653155193;6916.3429474199875;7024.719011239493;6272.5922815016875;6276.309605943251;6288.025356069817;6348.301686814354;6379.649119298483;4245.702800803914;4940.209566469348;4953.603711702983;4977.278294647183;5016.648834581544;4933.605311575137;5004.1492946198505;5058.2918391425965;5119.232115182753;5128.8461346159675 08:43:42 INFO - PROCESS | 1635 | |4;prototype.html;349.38333840771037;358.1529682301358;363.1900861079792;375.2738451097129;380.23382883489757;4563.566461186186;4900.313956046148;5154.896902061966;5461.399246082932;5533.612647114704;4785.1147537705465;5015.623828212881;5150.50635315758;5432.782688692448;5435.124881915733;3733.682636975847;4057.756534607918;4252.681048921343;4258.041940563379;4264.786760661955;4131.6281534661875;4257.829686812539;4285.849995250167;4302.763348015857;4304.397384366192;4035.656969157625;4168.270552653289;4169;4210.852620158295;4216.304309788897;3757.6284656100324;4145.709800313979;4168.541460439349;4170.833166673345;4190.036291652922;3144.3868445653106;3610.627961374661;3710.4805327254207;3725.6833169180654;3731.5149030625844;4976.959446626865;5196.76614552346;5254.216963786068;5270.525652691276;5298.761555729963;4870.561649451531;4882.609391248691;4886.975565122188;4905.068037072981;4935.383077115361;5058.696478211319;5263.842084737464;5417.291354322839;5759.712014399305;5812.418758124196;3672.5042119313816;3970.5235371755284;4078.9802549362657;4120.031792528754;4155.459790227282;5980.252468441445;6008.008678568031;6023.759049638009;6059.484943779784;6068.453839154453;4180;4202.3696445533415;4210.663146948298;4214.683898707548;4231.597998190167;3275.81982990933;4115.814788334532;4189.314860032949;4213.6207741302815;4214.915701685949;3982.5818289079807;4777.593904518155;4845.418549773979;4870.634702397334;4875.585575226075;5225.817096401608;5381.892362152735;5735.039191099669;5788;5863.237779088777;1168.5734706832116;1183.2959389163434;1193.797054500737;1235.832138629005;1353.35715535122;813.6013353456764;817.3461231015164;893.9955300223457;993.5181437003048;993.6436763817337;4474.8917727302;4947.275352038794;5083.745812709423;5106.463821298785;5146.176611742103;4659.301104834234;5114.309568208244;5209.609279304067;5345.1982202669915;5558.916616250759;4275.551067137968;4774.904501909942;4791.784369703371;5060.266261392151;5108.66793658411;2727.290904364879;2739.931501712433;2766.271080574641;2784.651918510186;2922.1946930946106;4875.634327425457;5259;5312.76092575835;5814.389489103583;5837.293318742629;5216.869578260574;5468.945310546923;5622.341409284271;5766.682832444256;5834.745529711047;5072.71727722519;5342.492463215911;5774.566907481872;5794.82615521535;5826.7755876989695;4793.472718001087;5307.416184219655;5621.971890140523;5640.0823719935415;5710.314762228559;4419.80110895017;5322.175062865262;5329.7068661224;5437.700926449083;5489.968711576583;4651.488266313394;5056.662602682359;5241.816536421207;5575.386707462095;5779.335376431764;3493.8338230296363;3951.9604803952743;3989.960100399075;3999.3401088821183;4003.799810009546;4925.926111108264;5546.75039623226;5660.603757736919;5663;5860.120981852756;5159.942211846637;5236.790528378974;5531.225628411945;5747.683877386784;5764.884702306014;5427.8330159016;5718.340971448297;5720.685362305113;5758.452946970031;5778.624389414675;5249.47505249472;5338.2526446298325;5523.530499907552;5575.7490912909825;5625.493705566519;5478.123500239943;5660.235868157746;5683.914741278802;5712.514436272963;5730.510067382427;4750.619950404029;5336.332958380202;5542.501174894279;5586;5624.521915637053 08:43:42 INFO - PROCESS | 1635 | |5;yui.html;596.3678500789166;693.393473594667;706.9116360454943;768.070634532216;859.6518410043931;12084.879151208495;13509.459621615137;14214.933879959006;14439.350229239682;14480.91393145513;10610.204234682391;12516.81224781629;12918.354082295895;13121;13198.274094924775;856.39624065034;869.0614136732314;872.0276891266225;877.6270085213758;879.248242752447;610.267678785457;700.4711442860633;800.0759123212698;841.4446465332882;862.6894318045498;748.7005197920848;908.4776253654143;933.2114363362947;934.7055677461611;935.3826474526813;916.1571354354011;924.5793164110381;926.5182105305204;926.5413620257982;928.7585227840742;683.4634811672863;698.7868700046483;698.9440844732409;700.310194458458;701.3232230897208;14084.577462676136;14416.053832732656;14442.133471991714;14579.781303280353;14686.412543498247;11682.824757628643;11895.405229738479;12831.294278814661;13105.86894131056;13527.526536571173;11481.73700892901;12132.696682582919;12174.756504869853;12213.389330533439;12244.02047836171;3795.9240815183543;4203.747775133502;4291.4850217973735;4311.267084595626;4372.169287835333;82859.5857020711;100522.96339256255;101884;102512;103054.48472757664;9981.351212171186;10054.29619926598;10071.043250891154;10281.280310378203;10534.315269507457;18735.437936861916;18983.9558824266;18984.715229271573;19004.904975475034;19023.904880475508;7176.7846964591145;7238.493305468672;7307.451941104438;7405.44459165554;7428.405727541784;4068.4304197412384;4094.5700701426517;4116.91766164681;4146.336586146263;4150.771707556113;3204.471262241704;3227.322262324891;3239.8704051837663;3269.9346013079603;3274.246923207628;582.7115577788937;604.3956043956044;627.5669787846463;630.2027934662656;637.7831537277348;509.1573445946964;537.7176982084437;545.8901346296535;546.4670902614537;547.3431881741925;8469.872951905727;8572.742817715478;8624.008239052464;9353.204977576983;9467.621295148254;612.2224774536314;639.9872002559923;642.996785016072;656.5830697507117;681.4786688183543;4518.909621807546;4613.677042606985;4640.907181856344;4670.036590594167;4679.742614156254;97265;98153;99722;100825;102204;83670;84249;85724;86857;89790.20419591572;77003.84494232592;78559;78699.60650196827;78827.60586197033;80042.5997870007;8033.758987230392;8091.959540202497;8611.13888611134;8979.820403592023;8995.82008359816;50421.495785043146;50561.74719126381;50870.49129508658;51783.22325165201;52824.73587632191;50107;66083;66430.66784666046;68634.9704754439;68752.31247687459;10301.639442619473;10307.587696492057;10466.895331046591;10468.738281543023;10836.078933290759;11807.291562506278;11833.881661183277;11841;11868.643940681794;12264.632061038183;11056.778864422506;11250.831237531607;11342.546298147981;11658.70853228676;11667.824982625098;11340.659780206606;11463.828042579535;11616.244944078608;11774.646760597196;11791.410429478321;10993;10998.890011099787;11112.555497779998;11341.716457088638;11509.251898626562;9218.631254750004;9323.440593564408;9403.153716165578;9581.185599224144;9593.196146068785;8238.217369349903;9011.188992990663;9090.409123406956;9100.862392200976;9139.817203656023 08:43:42 INFO - PROCESS | 1635 | __end_tp_report 08:43:42 INFO - PROCESS | 1635 | __start_cc_report 08:43:42 INFO - PROCESS | 1635 | _x_x_mozilla_cycle_collect,379 08:43:42 INFO - PROCESS | 1635 | __end_cc_report 08:43:42 INFO - PROCESS | 1635 | __startTimestamp1485189822496__endTimestamp 08:43:42 INFO - PROCESS | 1635 | 08:43:42 INFO - PROCESS | 1635 | ------- Summary: start ------- 08:43:42 INFO - PROCESS | 1635 | Number of tests: 6 08:43:42 INFO - PROCESS | 1635 | 08:43:42 INFO - PROCESS | 1635 | [#0] dojo.html Cycles:165 Average:5623.20 Median:5786.28 stddev:652.49 (11.3%) stddev-sans-first:628.82 08:43:42 INFO - PROCESS | 1635 | Values: 3313.1 3331.8 3334.8 3366.5 3391.3 5532.2 5569.5 5594.0 5671.9 5698.9 4634.8 5066.8 5267.4 5510.9 5577.7 5066.1 5085.0 5168.9 5227.9 5247.5 4675.0 5071.7 5260.2 5280.2 5313.8 4977.7 5007.4 5201.3 5226.1 5242.0 5052.7 5100.8 5158.7 5212.3 5259.7 5084.1 5087.1 5108.0 5112.6 5147.3 4747.3 5576.9 5577.5 5682.4 5705.7 6086.8 6116.4 6128.0 6142.4 6146.2 6208.9 6216.8 6222.6 6228.3 6279.3 5784.2 5798.2 5803.4 5844.0 5873.9 6133.0 6159.7 6180.2 6214.0 6236.8 5103.1 6035.7 6141.2 6142.1 6148.8 6207.0 6212.7 6225.1 6229.3 6244.3 6045.4 6107.0 6115.9 6129.7 6130.1 6196.2 6199.6 6239.6 6244.1 6247.6 4885.1 4937.0 4942.9 4965.6 4967.2 4332.2 4340.2 4348.3 4372.8 4504.8 5684.1 5689.5 5720.2 5736.4 5786.3 6046.3 6174.6 6195.7 6198.9 6210.2 5668.0 5676.7 5707.1 5725.1 5760.6 5428.4 5431.0 5467.8 5472.9 5513.4 6233.5 6236.5 6259.7 6270.1 6301.3 6091.1 6174.8 6212.9 6242.4 6257.6 5103.6 5239.1 5272.2 6251.7 6258.3 5529.6 5568.9 6057.0 6113.4 6157.6 5239.5 6022.2 6106.8 6191.3 6225.9 4978.9 5894.9 6021.5 6071.2 6151.5 6047.3 6083.9 6089.4 6099.9 6109.9 4769.5 4857.5 5558.3 5851.0 5992.6 6060.1 6102.4 6105.7 6122.1 6134.1 4975.9 5858.9 5859.1 5868.1 5985.0 08:43:42 INFO - PROCESS | 1635 | 08:43:42 INFO - PROCESS | 1635 | [#1] ext.html Cycles:180 Average:66647.64 Median:36016.23 stddev:142566.89 (395.8%) stddev-sans-first:142880.10 08:43:42 INFO - PROCESS | 1635 | Values: 418.6 438.1 475.8 506.3 526.2 38110.0 40720.0 40723.0 40784.6 40925.2 41484.8 42145.0 42159.6 42174.7 42233.4 9228.9 9436.0 9496.9 10737.8 11149.4 10928.6 10996.3 11011.2 11022.8 11024.9 10910.9 10965.9 10966.8 10978.1 11020.3 10941.3 10944.8 10988.2 11000.7 11022.3 10954.2 11036.3 11046.5 11047.9 11062.4 37031.0 37178.1 37221.3 37366.3 37395.8 3962.0 3980.2 3987.1 4000.0 4007.9 28768.3 28773.6 29247.0 29355.0 29378.7 3190.7 3297.9 3299.7 3344.0 3353.3 471309.4 476093.0 478178.2 480700.0 481763.6 56887.4 56977.4 57026.1 57063.7 57076.0 59270.4 59398.0 59488.0 59672.7 59756.4 14877.3 15430.0 15448.0 15514.8 15542.3 10785.3 11050.4 11666.8 11953.4 11968.5 14430.0 15568.6 16951.3 17715.0 18095.7 7775.7 7813.2 7839.6 7862.8 7942.8 787.4 863.8 869.3 890.4 898.2 23509.9 28898.6 29990.4 30010.8 30519.4 935.0 966.7 1057.0 1084.9 1180.0 15545.8 15646.3 15658.7 15772.2 15959.5 595712.3 601343.0 601510.0 601707.0 602604.0 61003.4 61494.4 61625.4 61700.0 61858.7 1405.4 1413.2 1417.6 1420.6 1428.5 28359.3 28957.3 29279.9 29814.3 29894.9 43704.1 51657.7 51978.0 52060.5 52151.7 515893.0 516595.0 516891.0 518193.0 522478.0 23219.3 23823.8 26115.0 26524.5 26849.2 46647.5 47482.5 47652.0 48438.8 48493.8 34521.0 35694.0 37096.8 38152.8 39853.2 35493.5 36073.8 36230.1 37074.8 37154.6 35318.8 35690.0 35958.6 36387.9 36496.9 28982.4 31397.5 31567.8 31586.8 31688.8 20499.3 20933.5 23919.5 24001.7 24185.0 08:43:42 INFO - PROCESS | 1635 | 08:43:42 INFO - PROCESS | 1635 | [#2] jquery.html Cycles:180 Average:37965.19 Median:413.75 stddev:113879.34 (27523.6%) stddev-sans-first:114163.86 08:43:42 INFO - PROCESS | 1635 | Values: 393.6 394.8 398.7 402.5 425.1 14202.4 15787.9 19915.6 21196.0 21368.3 16631.6 20589.3 20872.4 20978.9 21234.3 5602.5 5642.2 5763.5 5833.9 5842.2 5778.5 5790.7 5830.7 5832.0 5837.9 5201.3 6426.6 6486.4 6551.2 6553.0 5934.8 5976.7 6336.1 6450.1 6503.6 5834.1 5837.3 5837.3 5842.3 5846.5 12932.4 13280.8 13446.9 13470.3 19445.6 2552.9 2610.1 2635.6 2643.0 2670.6 9871.3 9930.3 10054.5 10335.9 10472.6 1816.6 1868.6 1893.5 1893.6 1900.0 130623.0 145856.0 146506.8 146923.0 148056.5 11677.8 11907.8 11927.3 11932.8 12101.0 15415.2 15433.0 15444.7 15462.6 15464.7 8310.5 8483.8 8553.2 8604.6 8640.5 8237.7 8265.0 8283.7 8397.4 8408.5 4709.1 4711.9 4714.3 4760.3 4811.7 1746.6 2018.1 2081.1 2084.9 2086.9 941.4 959.5 962.6 963.9 993.8 12642.0 12677.6 13101.7 13137.9 13269.5 947.5 952.0 981.5 983.6 992.3 6631.1 6640.3 6771.5 6887.8 6899.8 654373.0 660051.0 677357.0 689455.0 715997.0 137406.6 137811.0 138769.0 141421.0 148320.3 989.6 1008.9 1052.2 1056.7 1056.8 15854.9 19912.1 19936.1 20268.5 20336.7 50201.5 52643.0 59110.7 60273.7 61781.0 69115.7 85464.3 86855.0 88897.1 90451.5 6914.6 7135.4 8369.0 8482.8 8545.0 12141.0 12770.2 12882.1 13157.6 13179.9 10800.9 10844.6 11009.1 11147.2 11291.6 9759.9 10482.7 10772.5 10982.0 11341.2 8532.4 8921.8 9213.1 11679.7 11801.4 8180.3 8223.6 8418.6 8434.2 8552.1 8053.4 10474.7 10827.8 11108.6 11209.0 08:43:42 INFO - PROCESS | 1635 | 08:43:42 INFO - PROCESS | 1635 | [#3] mootools.html Cycles:180 Average:5324.14 Median:5233.21 stddev:2902.08 (55.5%) stddev-sans-first:2892.98 08:43:42 INFO - PROCESS | 1635 | Values: 1116.3 1139.7 1237.2 1249.9 1255.2 6404.8 7383.0 7488.3 7616.3 8402.5 6675.8 6995.5 8084.0 8169.5 8210.5 1902.7 1936.8 2271.6 2317.9 2321.4 2111.7 2129.7 2284.1 2323.9 2332.0 1888.9 2018.4 2160.0 2277.8 2298.8 2242.9 2261.8 2263.8 2266.3 2267.5 2038.9 2107.1 2211.2 2299.9 2302.4 8115.3 8329.3 8335.2 8445.2 8560.5 5022.4 5177.4 5188.6 5201.0 5204.4 3409.4 3465.8 3469.6 3486.5 3518.4 1213.6 1214.5 1248.6 1254.6 1269.3 10579.1 10649.4 10691.5 10731.9 10784.8 4358.9 4616.4 4661.3 4690.7 4715.3 9399.8 9403.2 9440.5 9445.9 9474.3 5136.7 5247.7 5247.7 5251.2 5256.5 4542.3 4762.1 4773.3 4784.9 4800.6 3787.9 3820.0 3840.8 3878.8 3907.4 1276.6 1280.0 1281.0 1298.0 1301.4 775.3 857.8 902.4 910.7 941.6 4977.3 5090.4 5114.6 5218.8 5555.1 862.9 952.5 986.5 989.5 1023.4 3249.8 3337.6 3554.7 3772.7 3791.4 9577.3 9860.5 9899.1 9911.7 9974.1 9227.6 9744.0 9846.3 9891.5 9900.8 7412.7 7731.4 7736.1 7781.0 7781.8 5549.3 5572.8 5678.5 5713.3 5788.9 7818.7 8798.8 8817.9 9189.4 9190.2 8878.9 9192.8 9195.6 9235.5 9346.7 7076.9 7085.0 7132.0 7139.1 7204.1 7556.9 7722.6 7741.2 7759.5 7841.3 6874.0 6941.2 6985.1 7017.7 7164.3 6817.8 6884.5 6897.9 6916.3 7024.7 6272.6 6276.3 6288.0 6348.3 6379.6 4245.7 4940.2 4953.6 4977.3 5016.6 4933.6 5004.1 5058.3 5119.2 5128.8 08:43:42 INFO - PROCESS | 1635 | 08:43:42 INFO - PROCESS | 1635 | [#4] prototype.html Cycles:180 Average:4563.25 Median:5078.23 stddev:1359.07 (26.8%) stddev-sans-first:1325.57 08:43:42 INFO - PROCESS | 1635 | Values: 349.4 358.2 363.2 375.3 380.2 4563.6 4900.3 5154.9 5461.4 5533.6 4785.1 5015.6 5150.5 5432.8 5435.1 3733.7 4057.8 4252.7 4258.0 4264.8 4131.6 4257.8 4285.8 4302.8 4304.4 4035.7 4168.3 4169.0 4210.9 4216.3 3757.6 4145.7 4168.5 4170.8 4190.0 3144.4 3610.6 3710.5 3725.7 3731.5 4977.0 5196.8 5254.2 5270.5 5298.8 4870.6 4882.6 4887.0 4905.1 4935.4 5058.7 5263.8 5417.3 5759.7 5812.4 3672.5 3970.5 4079.0 4120.0 4155.5 5980.3 6008.0 6023.8 6059.5 6068.5 4180.0 4202.4 4210.7 4214.7 4231.6 3275.8 4115.8 4189.3 4213.6 4214.9 3982.6 4777.6 4845.4 4870.6 4875.6 5225.8 5381.9 5735.0 5788.0 5863.2 1168.6 1183.3 1193.8 1235.8 1353.4 813.6 817.3 894.0 993.5 993.6 4474.9 4947.3 5083.7 5106.5 5146.2 4659.3 5114.3 5209.6 5345.2 5558.9 4275.6 4774.9 4791.8 5060.3 5108.7 2727.3 2739.9 2766.3 2784.7 2922.2 4875.6 5259.0 5312.8 5814.4 5837.3 5216.9 5468.9 5622.3 5766.7 5834.7 5072.7 5342.5 5774.6 5794.8 5826.8 4793.5 5307.4 5622.0 5640.1 5710.3 4419.8 5322.2 5329.7 5437.7 5490.0 4651.5 5056.7 5241.8 5575.4 5779.3 3493.8 3952.0 3990.0 3999.3 4003.8 4925.9 5546.8 5660.6 5663.0 5860.1 5159.9 5236.8 5531.2 5747.7 5764.9 5427.8 5718.3 5720.7 5758.5 5778.6 5249.5 5338.3 5523.5 5575.7 5625.5 5478.1 5660.2 5683.9 5712.5 5730.5 4750.6 5336.3 5542.5 5586.0 5624.5 08:43:42 INFO - PROCESS | 1635 | 08:43:42 INFO - PROCESS | 1635 | [#5] yui.html Cycles:180 Average:19298.71 Median:52303.98 stddev:28461.20 (54.4%) stddev-sans-first:28506.39 08:43:42 INFO - PROCESS | 1635 | Values: 596.4 693.4 706.9 768.1 859.7 12084.9 13509.5 14214.9 14439.4 14480.9 10610.2 12516.8 12918.4 13121.0 13198.3 856.4 869.1 872.0 877.6 879.2 610.3 700.5 800.1 841.4 862.7 748.7 908.5 933.2 934.7 935.4 916.2 924.6 926.5 926.5 928.8 683.5 698.8 698.9 700.3 701.3 14084.6 14416.1 14442.1 14579.8 14686.4 11682.8 11895.4 12831.3 13105.9 13527.5 11481.7 12132.7 12174.8 12213.4 12244.0 3795.9 4203.7 4291.5 4311.3 4372.2 82859.6 100523.0 101884.0 102512.0 103054.5 9981.4 10054.3 10071.0 10281.3 10534.3 18735.4 18984.0 18984.7 19004.9 19023.9 7176.8 7238.5 7307.5 7405.4 7428.4 4068.4 4094.6 4116.9 4146.3 4150.8 3204.5 3227.3 3239.9 3269.9 3274.2 582.7 604.4 627.6 630.2 637.8 509.2 537.7 545.9 546.5 547.3 8469.9 8572.7 8624.0 9353.2 9467.6 612.2 640.0 643.0 656.6 681.5 4518.9 4613.7 4640.9 4670.0 4679.7 97265.0 98153.0 99722.0 100825.0 102204.0 83670.0 84249.0 85724.0 86857.0 89790.2 77003.8 78559.0 78699.6 78827.6 80042.6 8033.8 8092.0 8611.1 8979.8 8995.8 50421.5 50561.7 50870.5 51783.2 52824.7 50107.0 66083.0 66430.7 68635.0 68752.3 10301.6 10307.6 10466.9 10468.7 10836.1 11807.3 11833.9 11841.0 11868.6 12264.6 11056.8 11250.8 11342.5 11658.7 11667.8 11340.7 11463.8 11616.2 11774.6 11791.4 10993.0 10998.9 11112.6 11341.7 11509.3 9218.6 9323.4 9403.2 9581.2 9593.2 8238.2 9011.2 9090.4 9100.9 9139.8 08:43:42 INFO - PROCESS | 1635 | -------- Summary: end -------- 08:43:42 INFO - PROCESS | 1635 | 08:43:43 INFO - TEST-INFO | 1635: exit 0 08:43:43 INFO - TEST-OK | dromaeo_css | took 1114500ms 08:43:43 INFO - TEST-START | kraken 08:43:43 INFO - operating with platform_type : mac_ 08:43:43 INFO - Initialising browser for kraken test... 08:43:43 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/tmpBS8rJQ/profile http://localhost:49239/getInfo.html) 08:43:45 INFO - PROCESS | 1648 | 2017-01-23 08:43:45.906 plugin-container[1649:10553] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893b, name = 'com.apple.tsm.portname' 08:43:45 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:43:45 INFO - PROCESS | 1648 | 2017-01-23 08:43:45.909 plugin-container[1649:10553] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4607, name = 'com.apple.CFPasteboardClient' 08:43:45 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:43:45 INFO - PROCESS | 1648 | 2017-01-23 08:43:45.909 plugin-container[1649:10553] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:43:45 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 08:43:45 INFO - PROCESS | 1648 | colorDepth:24 08:43:45 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 08:43:45 INFO - PROCESS | 1648 | __metrics 08:43:46 ERROR - PROCESS | 1648 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 08:43:46 INFO - PROCESS | 1648 | JavaScript error: resource://app/modules/webrtcUI.jsm, line 66: NS_ERROR_XPC_NOT_ENOUGH_ARGS: Not enough arguments [nsIMessageListenerManager.removeMessageListener] 08:43:46 INFO - TEST-INFO | 1648: exit 0 08:43:46 INFO - Browser initialized. 08:43:46 INFO - Running cycle 1/1 for kraken test... 08:43:46 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/T/tmpBS8rJQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:43:49 INFO - PROCESS | 1650 | 2017-01-23 08:43:49.134 plugin-container[1651:10670] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 08:43:49 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:43:49 INFO - PROCESS | 1650 | 2017-01-23 08:43:49.134 plugin-container[1651:10670] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4717, name = 'com.apple.CFPasteboardClient' 08:43:49 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:43:49 INFO - PROCESS | 1650 | 2017-01-23 08:43:49.134 plugin-container[1651:10670] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:43:58 INFO - PROCESS | 1650 | RSS: Main: 232968192 08:43:58 INFO - PROCESS | 1650 | 08:44:02 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-beat-detection) 08:44:02 INFO - PROCESS | 1650 | RSS: Main: 246652928 08:44:02 INFO - PROCESS | 1650 | 08:44:05 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-dft) 08:44:06 INFO - PROCESS | 1650 | RSS: Main: 254275584 08:44:06 INFO - PROCESS | 1650 | 08:44:09 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-fft) 08:44:10 INFO - PROCESS | 1650 | RSS: Main: 257097728 08:44:10 INFO - PROCESS | 1650 | 08:44:12 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-oscillator) 08:44:13 INFO - PROCESS | 1650 | RSS: Main: 259186688 08:44:13 INFO - PROCESS | 1650 | 08:44:15 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49239/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:44:15 INFO - PROCESS | 1650 | RSS: Main: 259911680 08:44:15 INFO - PROCESS | 1650 | 08:44:18 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49239/tests/kraken/driver.html?testName=imaging-darkroom) 08:44:19 INFO - PROCESS | 1650 | RSS: Main: 265674752 08:44:19 INFO - PROCESS | 1650 | 08:44:22 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49239/tests/kraken/driver.html?testName=imaging-desaturate) 08:44:23 INFO - PROCESS | 1650 | RSS: Main: 264622080 08:44:23 INFO - PROCESS | 1650 | 08:44:25 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49239/tests/kraken/driver.html?testName=json-parse-financial) 08:44:26 INFO - PROCESS | 1650 | RSS: Main: 264441856 08:44:26 INFO - PROCESS | 1650 | 08:44:28 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49239/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:44:28 INFO - PROCESS | 1650 | RSS: Main: 264175616 08:44:28 INFO - PROCESS | 1650 | 08:44:30 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:44:31 INFO - PROCESS | 1650 | RSS: Main: 264110080 08:44:31 INFO - PROCESS | 1650 | 08:44:33 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:44:34 INFO - PROCESS | 1650 | RSS: Main: 264151040 08:44:34 INFO - PROCESS | 1650 | 08:44:37 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:44:38 INFO - PROCESS | 1650 | RSS: Main: 264372224 08:44:38 INFO - PROCESS | 1650 | 08:44:41 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:44:41 INFO - PROCESS | 1650 | RSS: Main: 264224768 08:44:41 INFO - PROCESS | 1650 | 08:44:43 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49239/tests/kraken/driver.html?testName=ai-astar) 08:44:43 INFO - PROCESS | 1650 | RSS: Main: 266272768 08:44:43 INFO - PROCESS | 1650 | 08:44:43 INFO - PROCESS | 1650 | __start_tp_report 08:44:43 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 08:44:43 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 08:44:43 INFO - PROCESS | 1650 | |i|pagename|runs| 08:44:43 INFO - PROCESS | 1650 | |0;ai-astar;106;115;121;119;119;119;140;172;117;119 08:44:43 INFO - PROCESS | 1650 | |1;audio-beat-detection;147;116;116;151;136;112;124;144;163;137 08:44:43 INFO - PROCESS | 1650 | |2;audio-dft;201;263;227;276;249;237;265;196;198;199 08:44:43 INFO - PROCESS | 1650 | |3;audio-fft;79;56;57;85;61;61;61;62;64;91 08:44:43 INFO - PROCESS | 1650 | |4;audio-oscillator;90;72;84;71;72;94;114;106;85;83 08:44:43 INFO - PROCESS | 1650 | |5;imaging-gaussian-blur;88;80;137;130;88;86;92;107;105;86 08:44:43 INFO - PROCESS | 1650 | |6;imaging-darkroom;137;96;116;156;154;97;95;97;96;96 08:44:43 INFO - PROCESS | 1650 | |7;imaging-desaturate;93;110;92;91;92;92;90;96;90;91 08:44:43 INFO - PROCESS | 1650 | |8;json-parse-financial;63;60;60;60;63;63;61;59;71;62 08:44:43 INFO - PROCESS | 1650 | |9;json-stringify-tinderbox;70;76;69;82;113;110;78;76;75;71 08:44:43 INFO - PROCESS | 1650 | |10;stanford-crypto-aes;91;91;107;114;114;85;91;90;94;90 08:44:43 INFO - PROCESS | 1650 | |11;stanford-crypto-ccm;146;152;160;140;141;146;136;143;228;161 08:44:43 INFO - PROCESS | 1650 | |12;stanford-crypto-pbkdf2;182;174;174;179;175;172;173;173;177;236 08:44:43 INFO - PROCESS | 1650 | |13;stanford-crypto-sha256-iterative;64;67;93;70;68;68;68;67;69;67 08:44:43 INFO - PROCESS | 1650 | __end_tp_report 08:44:43 INFO - PROCESS | 1650 | __start_cc_report 08:44:43 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,919 08:44:43 INFO - PROCESS | 1650 | __end_cc_report 08:44:43 INFO - PROCESS | 1650 | __startTimestamp1485189883857__endTimestamp 08:44:43 INFO - PROCESS | 1650 | 08:44:43 INFO - PROCESS | 1650 | ------- Summary: start ------- 08:44:43 INFO - PROCESS | 1650 | Number of tests: 14 08:44:43 INFO - PROCESS | 1650 | 08:44:43 INFO - PROCESS | 1650 | [#0] ai-astar Cycles:10 Average:124.70 Median:119.00 stddev:18.61 (15.6%) stddev-sans-first:18.47 08:44:43 INFO - PROCESS | 1650 | Values: 106.0 115.0 121.0 119.0 119.0 119.0 140.0 172.0 117.0 119.0 08:44:43 INFO - PROCESS | 1650 | 08:44:43 INFO - PROCESS | 1650 | [#1] audio-beat-detection Cycles:10 Average:134.60 Median:140.50 stddev:17.13 (12.2%) stddev-sans-first:17.57 08:44:43 INFO - PROCESS | 1650 | Values: 147.0 116.0 116.0 151.0 136.0 112.0 124.0 144.0 163.0 137.0 08:44:43 INFO - PROCESS | 1650 | 08:44:43 INFO - PROCESS | 1650 | [#2] audio-dft Cycles:10 Average:231.10 Median:243.00 stddev:31.30 (12.9%) stddev-sans-first:31.25 08:44:43 INFO - PROCESS | 1650 | Values: 201.0 263.0 227.0 276.0 249.0 237.0 265.0 196.0 198.0 199.0 08:44:43 INFO - PROCESS | 1650 | 08:44:43 INFO - PROCESS | 1650 | [#3] audio-fft Cycles:10 Average:67.70 Median:63.00 stddev:12.48 (19.8%) stddev-sans-first:12.55 08:44:43 INFO - PROCESS | 1650 | Values: 79.0 56.0 57.0 85.0 61.0 61.0 61.0 62.0 64.0 91.0 08:44:43 INFO - PROCESS | 1650 | 08:44:43 INFO - PROCESS | 1650 | [#4] audio-oscillator Cycles:10 Average:87.10 Median:83.50 stddev:14.46 (17.3%) stddev-sans-first:15.30 08:44:43 INFO - PROCESS | 1650 | Values: 90.0 72.0 84.0 71.0 72.0 94.0 114.0 106.0 85.0 83.0 08:44:43 INFO - PROCESS | 1650 | 08:44:43 INFO - PROCESS | 1650 | [#5] imaging-gaussian-blur Cycles:10 Average:99.90 Median:86.00 stddev:19.68 (22.9%) stddev-sans-first:20.40 08:44:43 INFO - PROCESS | 1650 | Values: 88.0 80.0 137.0 130.0 88.0 86.0 92.0 107.0 105.0 86.0 08:44:43 INFO - PROCESS | 1650 | 08:44:43 INFO - PROCESS | 1650 | [#6] imaging-darkroom Cycles:10 Average:114.00 Median:96.00 stddev:25.40 (26.5%) stddev-sans-first:25.54 08:44:43 INFO - PROCESS | 1650 | Values: 137.0 96.0 116.0 156.0 154.0 97.0 95.0 97.0 96.0 96.0 08:44:43 INFO - PROCESS | 1650 | 08:44:43 INFO - PROCESS | 1650 | [#7] imaging-desaturate Cycles:10 Average:93.70 Median:92.00 stddev:5.98 (6.5%) stddev-sans-first:6.34 08:44:43 INFO - PROCESS | 1650 | Values: 93.0 110.0 92.0 91.0 92.0 92.0 90.0 96.0 90.0 91.0 08:44:43 INFO - PROCESS | 1650 | 08:44:43 INFO - PROCESS | 1650 | [#8] json-parse-financial Cycles:10 Average:62.20 Median:62.50 stddev:3.43 (5.5%) stddev-sans-first:3.62 08:44:43 INFO - PROCESS | 1650 | Values: 63.0 60.0 60.0 60.0 63.0 63.0 61.0 59.0 71.0 62.0 08:44:43 INFO - PROCESS | 1650 | 08:44:43 INFO - PROCESS | 1650 | [#9] json-stringify-tinderbox Cycles:10 Average:82.00 Median:75.50 stddev:16.04 (21.2%) stddev-sans-first:16.42 08:44:43 INFO - PROCESS | 1650 | Values: 70.0 76.0 69.0 82.0 113.0 110.0 78.0 76.0 75.0 71.0 08:44:43 INFO - PROCESS | 1650 | 08:44:43 INFO - PROCESS | 1650 | [#10] stanford-crypto-aes Cycles:10 Average:96.70 Median:90.50 stddev:10.73 (11.9%) stddev-sans-first:11.18 08:44:43 INFO - PROCESS | 1650 | Values: 91.0 91.0 107.0 114.0 114.0 85.0 91.0 90.0 94.0 90.0 08:44:43 INFO - PROCESS | 1650 | 08:44:43 INFO - PROCESS | 1650 | [#11] stanford-crypto-ccm Cycles:10 Average:155.30 Median:149.00 stddev:26.85 (18.0%) stddev-sans-first:28.26 08:44:43 INFO - PROCESS | 1650 | Values: 146.0 152.0 160.0 140.0 141.0 146.0 136.0 143.0 228.0 161.0 08:44:43 INFO - PROCESS | 1650 | 08:44:43 INFO - PROCESS | 1650 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.50 Median:176.00 stddev:19.40 (11.0%) stddev-sans-first:20.57 08:44:43 INFO - PROCESS | 1650 | Values: 182.0 174.0 174.0 179.0 175.0 172.0 173.0 173.0 177.0 236.0 08:44:43 INFO - PROCESS | 1650 | 08:44:43 INFO - PROCESS | 1650 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.10 Median:68.00 stddev:8.20 (12.1%) stddev-sans-first:8.39 08:44:43 INFO - PROCESS | 1650 | Values: 64.0 67.0 93.0 70.0 68.0 68.0 68.0 67.0 69.0 67.0 08:44:43 INFO - PROCESS | 1650 | -------- Summary: end -------- 08:44:43 INFO - PROCESS | 1650 | 08:44:44 INFO - TEST-INFO | 1650: exit 0 08:44:44 INFO - TEST-OK | kraken | took 61600ms 08:44:44 INFO - SUITE-END | took 1176s 08:44:45 INFO - Completed test suite (00:19:36) 08:44:45 INFO - javascript benchmark 08:44:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7108.627020673659, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3313.088900552348, 3331.8000919944798, 3334.8499317530723, 3366.5455163552942, 3391.32173565287, 5532.225488431613, 5569.52659023983, 5594.049011668011, 5671.886562268752, 5698.914516282259, 4634.8146074157075, 5066.771995260213, 5267.420583735796, 5510.9448905510935, 5577.749001294941, 5066.138756411419, 5084.983003399317, 5168.896622067575, 5227.876006658567, 5247.475252474741, 4674.976625116887, 5071.670341427795, 5260.211528080452, 5280.1551751719735, 5313.760880760375, 4977.651564390494, 5007.399112106534, 5201.297824793643, 5226.111561034633, 5241.973790131044, 5052.69683818972, 5100.795968161269, 5158.664686795384, 5212.296339994128, 5259.710715910624, 5084.110280700899, 5087.135187018179, 5107.978404319114, 5112.565431938253, 5147.253648220987, 4747.311639812242, 5576.9163462548095, 5577.498025177753, 5682.403347648478, 5705.657660540381, 6086.7565297388055, 6116.366205733259, 6127.953177891112, 6142.437386315926, 6146.200993870812, 6208.906866396963, 6216.844578885519, 6222.62664240147, 6228.314885362606, 6279.309275979638, 5784.219130417382, 5798.246227990418, 5803.419658034247, 5844.006518891799, 5873.911891321633, 6132.959035350176, 6159.692015399301, 6180.165677633462, 6213.96893015532, 6236.844078898064, 5103.132467480537, 6035.7283922223605, 6141.232345956755, 6142.140100385949, 6148.846278842975, 6207.037909124093, 6212.72042758077, 6225.128482012522, 6229.283632382243, 6244.344343843922, 6045.425684559959, 6107.006761825686, 6115.879294474695, 6129.664444256096, 6130.141780150782, 6196.194494715658, 6199.56603037783, 6239.625622462667, 6244.094606282064, 6247.562670613104, 4885.145099607555, 4937.0, 4942.9258561121615, 4965.627577931669, 4967.205247160437, 4332.241857674894, 4340.218760623118, 4348.326009468537, 4372.803223854934, 4504.842330518416, 5684.062129748545, 5689.544836413077, 5720.170575266563, 5736.397678243808, 5786.276715410574, 6046.27444706638, 6174.629522228681, 6195.690215489298, 6198.907016394758, 6210.192674952227, 5667.971660141756, 5676.659400435987, 5707.1153971134545, 5725.112607545837, 5760.567957403212, 5428.4028756836715, 5431.0, 5467.781288748405, 5472.91790623141, 5513.42109078549, 6233.501319894398, 6236.501079913505, 6259.655718935503, 6270.0908368287255, 6301.306856245717, 6091.1472393864005, 6174.783882564088, 6212.875742485035, 6242.406971337898, 6257.624542527463, 5103.617228707936, 5239.109351410193, 5272.209168624737, 6251.71867265965, 6258.280297765815, 5529.612927095226, 5568.888622227451, 6057.0, 6113.419225173601, 6157.568970172045, 5239.47605239473, 6022.1870047543025, 6106.755729770759, 6191.25704915413, 6225.937740622536, 4978.900421991468, 5894.911576326444, 6021.488173505301, 6071.180390647206, 6151.507879369729, 6047.274327080779, 6083.908741368971, 6089.360617135314, 6099.902017636868, 6109.938900610937, 4769.523047695203, 4857.465678775403, 5558.305211848519, 5851.034579294539, 5992.550558708027, 6060.090986352083, 6102.428659371999, 6105.694715264308, 6122.142899994094, 6134.079888016655, 4975.925361119514, 5858.882822343615, 5859.121131830259, 5868.149118377726, 5985.042393217064], "value": 5584.006336330549, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [418.60023677388097, 438.1040771622033, 475.7502311286574, 506.2861365474681, 526.2159382520044, 38110.0, 40720.0, 40722.99999999996, 40784.592154078484, 40925.18149637005, 41484.79257603715, 42145.0, 42159.57840421602, 42174.73475795723, 42233.36649950253, 9228.8615670765, 9435.952820235923, 9496.905030949705, 10737.78524429511, 11149.442527873574, 10928.562857485691, 10996.28524145932, 11011.229213955026, 11022.834657480144, 11024.944875275653, 10910.890891091067, 10965.945170274137, 10966.835497467544, 10978.066864316543, 11020.338779673246, 10941.343519388862, 10944.781104377907, 10988.230823842334, 11000.724981875437, 11022.338659680405, 10954.178436617285, 11036.337819730761, 11046.502907369188, 11047.889521104766, 11062.39156846373, 37031.0, 37178.07054823624, 37221.25557488835, 37366.25267494662, 37395.81302093472, 3961.960380396188, 3980.24375368679, 3987.1029018470904, 4000.0, 4007.859724909614, 28768.280792980135, 28773.56839647407, 29247.0, 29355.0, 29378.70621293781, 3190.6968837960358, 3297.851596678155, 3299.736021118305, 3343.9665603343897, 3353.2622822978906, 471309.4345282756, 476093.0, 478178.21821781684, 480700.0, 481763.59118204535, 56887.43112568904, 56977.430225698045, 57026.144607830916, 57063.714681426325, 57076.0, 59270.407295927354, 59398.0, 59488.0, 59672.70163649154, 59756.402435975084, 14877.256137193097, 15429.997050191701, 15447.995880267972, 15514.767278490832, 15542.300596473186, 10785.29895556802, 11050.392228427352, 11666.76666466683, 11953.40232988347, 11968.486617810184, 14430.0, 15568.610784730472, 16951.321947122222, 17715.025673588076, 18095.72856407155, 7775.688972441152, 7813.218678132255, 7839.624143971974, 7862.764117076383, 7942.761717148494, 787.4409169489727, 863.7797361672733, 869.3175856952389, 890.4301247201796, 898.1602201941206, 23509.88245058798, 28898.566521502195, 29990.40019199604, 30010.84994575013, 30519.389612207633, 934.9953250233841, 966.7239244197732, 1056.9735756605992, 1084.9132069434427, 1179.979317890019, 15545.766813497807, 15646.295916683777, 15658.68682626341, 15772.211389430482, 15959.521214363587, 595712.2572645568, 601343.0, 601510.0, 601707.0, 602603.9869800623, 61003.38996610066, 61494.38505614976, 61625.38374616196, 61700.0, 61858.69070654708, 1405.444849284527, 1413.236852099875, 1417.64558860287, 1420.5596265157628, 1428.5000249912448, 28359.29101772472, 28957.276068098465, 29279.85360073186, 29814.254643633216, 29894.85052574723, 43704.12591748211, 51657.7417112912, 51978.0, 52060.47939520556, 52151.73924130355, 515893.0, 516595.0, 516891.0, 518193.0, 522478.0, 23219.3034208974, 23823.761762382153, 26115.0, 26524.469510610066, 26849.194524164308, 46647.533524664315, 47482.52517474781, 47652.0, 48438.75780621074, 48493.75753121212, 34521.0, 35694.0, 37096.814515927246, 38152.809235953646, 39853.2029359417, 35493.467597986564, 36073.81963090168, 36230.09424764402, 37074.814625926694, 37154.628453715115, 35318.82340588281, 35690.0, 35958.64041359553, 36387.90836274916, 36496.90509284726, 28982.42035159327, 31397.52903706492, 31567.84216078905, 31586.842065789522, 31688.841555792074, 20499.28252511155, 20933.476663083544, 23919.52160956806, 24001.719406804623, 24185.0], "value": 19722.535919920156, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [393.56511055283926, 394.8144372145091, 398.71292669278125, 402.45266437644796, 425.05001322045223, 14202.36089375978, 15787.92106039471, 19915.60168796625, 21196.04617792199, 21368.252111176113, 16631.584210394747, 20589.27937522187, 20872.373828785123, 20978.89510552449, 21234.25680101193, 5602.495775380215, 5642.238297829792, 5763.452472015152, 5833.854153646172, 5842.218123472339, 5778.451047150514, 5790.739416726236, 5830.737616807232, 5831.970840145794, 5837.9124313135335, 5201.271821944931, 6426.550141490075, 6486.448651864597, 6551.181102362205, 6553.0, 5934.7922822701, 5976.731047102848, 6336.081268216106, 6450.129232553592, 6503.642299673516, 5834.095715164156, 5837.270341207349, 5837.3287071986415, 5842.298924129132, 5846.50304724099, 12932.418041188075, 13280.800787988188, 13446.932765336209, 13470.326483675875, 19445.638805283637, 2552.8978840846344, 2610.099515667092, 2635.5651317532584, 2642.9867850660626, 2670.626112344273, 9871.309008369346, 9930.255230857714, 10054.547545360476, 10335.94832025835, 10472.633457829015, 1816.6275913437714, 1868.616933528637, 1893.4508992392327, 1893.640208360421, 1899.9715004274947, 130623.0, 145855.99999999895, 146506.80239796411, 146923.0, 148056.51943480535, 11677.766444671059, 11907.821382679267, 11927.343996080299, 11932.761344773056, 12101.0, 15415.152166630718, 15433.0, 15444.691106178037, 15462.613434664, 15464.690706186038, 8310.459820111673, 8483.83032339362, 8553.187447192508, 8604.5697715114, 8640.524771137521, 8237.711680091288, 8265.0, 8283.668653253802, 8397.412181147383, 8408.537530435884, 4709.0817290628, 4711.858644240679, 4714.269288260325, 4760.309755085563, 4811.663183577186, 1746.589551455407, 2018.0716870239853, 2081.105124796352, 2084.937451876446, 2086.926957556478, 941.3786900645541, 959.4531117263233, 962.629387685732, 963.9180669643018, 993.7565296502258, 12642.0, 12677.619671409873, 13101.737965240833, 13137.934310328386, 13269.535566255136, 947.5025611553989, 951.9952400238093, 981.504340308154, 983.6409710455632, 992.335135459244, 6631.10480085192, 6640.269570347258, 6771.492138089663, 6887.793366199022, 6899.827504312332, 654373.0, 660051.0, 677357.0, 689455.0, 715997.0, 137406.6259337414, 137811.0, 138769.0, 141421.0, 148320.25839870732, 989.5992123190119, 1008.8738907636546, 1052.1950707708609, 1056.7305337139135, 1056.7780766039218, 15854.9207253963, 19912.103955321756, 19936.102875370354, 20268.493287667927, 20336.69494957547, 50201.4939551814, 52643.0, 59110.704446477495, 60273.69863150656, 61781.0, 69115.65442172757, 85464.29071418662, 86855.0, 88897.11102889146, 90451.54774226087, 6914.550554214161, 7135.393491553276, 8369.037560680355, 8482.812406263252, 8544.957275213585, 12140.968017718593, 12770.23378597287, 12882.098253122567, 13157.60527184186, 13179.934100329821, 10800.891991079989, 10844.566217351217, 11009.06422954058, 11147.219694621299, 11291.604793832177, 9759.9024009759, 10482.742070951535, 10772.515236814206, 10982.0, 11341.206115571831, 8532.445391049561, 8921.776955576162, 9213.078692131001, 11679.708007299885, 11801.35092569917, 8180.263776260165, 8223.588820559067, 8418.579071046546, 8434.24091831738, 8552.144785521397, 8053.355731541345, 10474.738131546772, 10827.837582436427, 11108.555887734543, 11209.047230985132], "value": 9412.29660394216, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1116.2576886370564, 1139.652406016165, 1237.2143688757635, 1249.900087922628, 1255.2405794494327, 6404.807855764323, 7382.963085184567, 7488.288612581803, 7616.314531692146, 8402.495850248983, 6675.7663481778145, 6995.510314278003, 8084.029916410039, 8169.468984516017, 8210.507369557816, 1902.7145928110756, 1936.7966363531766, 2271.5684020036183, 2317.918872839451, 2321.3964369263945, 2111.746590409153, 2129.7337832770904, 2284.097781376355, 2323.8954247058923, 2332.0438620165737, 1888.8677792554527, 2018.4247489465506, 2160.0063970573556, 2277.817774578038, 2298.7586303438065, 2242.865428074321, 2261.8416710830247, 2263.7509873913855, 2266.3427605994325, 2267.548768787977, 2038.8776673399643, 2107.083418712865, 2211.237123192512, 2299.9425014374606, 2302.3553405046614, 8115.310198633123, 8329.250367466897, 8335.166483351677, 8445.197706217961, 8560.48637081777, 5022.397312322545, 5177.352830896138, 5188.610854185951, 5200.97399513, 5204.401493828221, 3409.3863104641155, 3465.8440370183403, 3469.6356882527225, 3486.5467489226494, 3518.437050071976, 1213.6480420677992, 1214.542548941274, 1248.6254123762835, 1254.5609036837125, 1269.2701696524443, 10579.100776434012, 10649.414282214475, 10691.465426728633, 10731.946340268327, 10784.78430431387, 4358.891027724332, 4616.376789133424, 4661.324108004388, 4690.671652984327, 4715.269133284346, 9399.812003760024, 9403.200727938065, 9440.527973601293, 9445.905540944503, 9474.336796424184, 5136.743162841843, 5247.658902171346, 5247.685138891602, 5251.159814429673, 5256.474352564713, 4542.295944128666, 4762.071396077731, 4773.284007398848, 4784.904301913943, 4800.591949684246, 3787.867424640124, 3820.0, 3840.807959602065, 3878.806059697004, 3907.3552863777727, 1276.6042526816716, 1279.993600031994, 1281.0200196849419, 1297.9935100324626, 1301.4078594239597, 775.3332134364442, 857.7684025313255, 902.4269588811021, 910.717677519971, 941.6045260990401, 4977.253411988231, 5090.363704536933, 5114.590832733373, 5218.765155568009, 5555.14222652078, 862.9266512346521, 952.514217748953, 986.5067466266867, 989.4805227255748, 1023.3961962442195, 3249.756268279841, 3337.632860385356, 3554.662307080823, 3772.6981841452616, 3791.3933770596573, 9577.281703872237, 9860.506974651238, 9899.109080182818, 9911.702648920544, 9974.052465015811, 9227.584758685874, 9744.0, 9846.261530385249, 9891.505424728735, 9900.811902571593, 7412.740554080581, 7731.381489480828, 7736.071671399468, 7780.999999999887, 7781.844363112706, 5549.278593782782, 5572.832815015556, 5678.4605462480995, 5713.257276554022, 5788.871170121786, 7818.726344577913, 8798.780030499289, 8817.867731983897, 9189.448633082036, 9190.218831399407, 8878.911210888067, 9192.816143677222, 9195.632174712937, 9235.538223088954, 9346.672866449642, 7076.929230707627, 7085.0, 7132.0, 7139.107611548557, 7204.063471748639, 7556.886646700414, 7722.575258360844, 7741.187175346733, 7759.495632783851, 7841.294283514511, 6873.965630171817, 6941.167059952838, 6985.126859142607, 7017.7192912282935, 7164.283571642794, 6817.795466136024, 6884.51808373409, 6897.89653155193, 6916.3429474199875, 7024.719011239493, 6272.5922815016875, 6276.309605943251, 6288.025356069817, 6348.301686814354, 6379.649119298483, 4245.702800803914, 4940.209566469348, 4953.603711702983, 4977.278294647183, 5016.648834581544, 4933.605311575137, 5004.1492946198505, 5058.2918391425965, 5119.232115182753, 5128.8461346159675], "value": 4324.109965050445, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.38333840771037, 358.1529682301358, 363.1900861079792, 375.2738451097129, 380.23382883489757, 4563.566461186186, 4900.313956046148, 5154.896902061966, 5461.399246082932, 5533.612647114704, 4785.1147537705465, 5015.623828212881, 5150.50635315758, 5432.782688692448, 5435.124881915733, 3733.682636975847, 4057.756534607918, 4252.681048921343, 4258.041940563379, 4264.786760661955, 4131.6281534661875, 4257.829686812539, 4285.849995250167, 4302.763348015857, 4304.397384366192, 4035.656969157625, 4168.270552653289, 4169.0, 4210.852620158295, 4216.304309788897, 3757.6284656100324, 4145.709800313979, 4168.541460439349, 4170.833166673345, 4190.036291652922, 3144.3868445653106, 3610.627961374661, 3710.4805327254207, 3725.6833169180654, 3731.5149030625844, 4976.959446626865, 5196.76614552346, 5254.216963786068, 5270.525652691276, 5298.761555729963, 4870.561649451531, 4882.609391248691, 4886.975565122188, 4905.068037072981, 4935.383077115361, 5058.696478211319, 5263.842084737464, 5417.291354322839, 5759.712014399305, 5812.418758124196, 3672.5042119313816, 3970.5235371755284, 4078.9802549362657, 4120.031792528754, 4155.459790227282, 5980.252468441445, 6008.008678568031, 6023.759049638009, 6059.484943779784, 6068.453839154453, 4180.0, 4202.3696445533415, 4210.663146948298, 4214.683898707548, 4231.597998190167, 3275.81982990933, 4115.814788334532, 4189.314860032949, 4213.6207741302815, 4214.915701685949, 3982.5818289079807, 4777.593904518155, 4845.418549773979, 4870.634702397334, 4875.585575226075, 5225.817096401608, 5381.892362152735, 5735.039191099669, 5788.0, 5863.237779088777, 1168.5734706832116, 1183.2959389163434, 1193.797054500737, 1235.832138629005, 1353.35715535122, 813.6013353456764, 817.3461231015164, 893.9955300223457, 993.5181437003048, 993.6436763817337, 4474.8917727302, 4947.275352038794, 5083.745812709423, 5106.463821298785, 5146.176611742103, 4659.301104834234, 5114.309568208244, 5209.609279304067, 5345.1982202669915, 5558.916616250759, 4275.551067137968, 4774.904501909942, 4791.784369703371, 5060.266261392151, 5108.66793658411, 2727.290904364879, 2739.931501712433, 2766.271080574641, 2784.651918510186, 2922.1946930946106, 4875.634327425457, 5259.0, 5312.76092575835, 5814.389489103583, 5837.293318742629, 5216.869578260574, 5468.945310546923, 5622.341409284271, 5766.682832444256, 5834.745529711047, 5072.71727722519, 5342.492463215911, 5774.566907481872, 5794.82615521535, 5826.7755876989695, 4793.472718001087, 5307.416184219655, 5621.971890140523, 5640.0823719935415, 5710.314762228559, 4419.80110895017, 5322.175062865262, 5329.7068661224, 5437.700926449083, 5489.968711576583, 4651.488266313394, 5056.662602682359, 5241.816536421207, 5575.386707462095, 5779.335376431764, 3493.8338230296363, 3951.9604803952743, 3989.960100399075, 3999.3401088821183, 4003.799810009546, 4925.926111108264, 5546.75039623226, 5660.603757736919, 5663.0, 5860.120981852756, 5159.942211846637, 5236.790528378974, 5531.225628411945, 5747.683877386784, 5764.884702306014, 5427.8330159016, 5718.340971448297, 5720.685362305113, 5758.452946970031, 5778.624389414675, 5249.47505249472, 5338.2526446298325, 5523.530499907552, 5575.7490912909825, 5625.493705566519, 5478.123500239943, 5660.235868157746, 5683.914741278802, 5712.514436272963, 5730.510067382427, 4750.619950404029, 5336.332958380202, 5542.501174894279, 5586.0, 5624.521915637053], "value": 4138.394458181475, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [596.3678500789166, 693.393473594667, 706.9116360454943, 768.070634532216, 859.6518410043931, 12084.879151208495, 13509.459621615137, 14214.933879959006, 14439.350229239682, 14480.91393145513, 10610.204234682391, 12516.81224781629, 12918.354082295895, 13121.0, 13198.274094924775, 856.39624065034, 869.0614136732314, 872.0276891266225, 877.6270085213758, 879.248242752447, 610.267678785457, 700.4711442860633, 800.0759123212698, 841.4446465332882, 862.6894318045498, 748.7005197920848, 908.4776253654143, 933.2114363362947, 934.7055677461611, 935.3826474526813, 916.1571354354011, 924.5793164110381, 926.5182105305204, 926.5413620257982, 928.7585227840742, 683.4634811672863, 698.7868700046483, 698.9440844732409, 700.310194458458, 701.3232230897208, 14084.577462676136, 14416.053832732656, 14442.133471991714, 14579.781303280353, 14686.412543498247, 11682.824757628643, 11895.405229738479, 12831.294278814661, 13105.86894131056, 13527.526536571173, 11481.73700892901, 12132.696682582919, 12174.756504869853, 12213.389330533439, 12244.02047836171, 3795.9240815183543, 4203.747775133502, 4291.4850217973735, 4311.267084595626, 4372.169287835333, 82859.5857020711, 100522.96339256255, 101884.0, 102512.0, 103054.48472757664, 9981.351212171186, 10054.29619926598, 10071.043250891154, 10281.280310378203, 10534.315269507457, 18735.437936861916, 18983.9558824266, 18984.715229271573, 19004.904975475034, 19023.904880475508, 7176.7846964591145, 7238.493305468672, 7307.451941104438, 7405.44459165554, 7428.405727541784, 4068.4304197412384, 4094.5700701426517, 4116.91766164681, 4146.336586146263, 4150.771707556113, 3204.471262241704, 3227.322262324891, 3239.8704051837663, 3269.9346013079603, 3274.246923207628, 582.7115577788937, 604.3956043956044, 627.5669787846463, 630.2027934662656, 637.7831537277348, 509.1573445946964, 537.7176982084437, 545.8901346296535, 546.4670902614537, 547.3431881741925, 8469.872951905727, 8572.742817715478, 8624.008239052464, 9353.204977576983, 9467.621295148254, 612.2224774536314, 639.9872002559923, 642.996785016072, 656.5830697507117, 681.4786688183543, 4518.909621807546, 4613.677042606985, 4640.907181856344, 4670.036590594167, 4679.742614156254, 97265.0, 98153.0, 99722.0, 100825.0, 102204.0, 83670.0, 84249.0, 85724.0, 86857.0, 89790.20419591572, 77003.84494232592, 78559.0, 78699.60650196827, 78827.60586197033, 80042.5997870007, 8033.758987230392, 8091.959540202497, 8611.13888611134, 8979.820403592023, 8995.82008359816, 50421.495785043146, 50561.74719126381, 50870.49129508658, 51783.22325165201, 52824.73587632191, 50107.0, 66083.0, 66430.66784666046, 68634.9704754439, 68752.31247687459, 10301.639442619473, 10307.587696492057, 10466.895331046591, 10468.738281543023, 10836.078933290759, 11807.291562506278, 11833.881661183277, 11841.0, 11868.643940681794, 12264.632061038183, 11056.778864422506, 11250.831237531607, 11342.546298147981, 11658.70853228676, 11667.824982625098, 11340.659780206606, 11463.828042579535, 11616.244944078608, 11774.646760597196, 11791.410429478321, 10993.0, 10998.890011099787, 11112.555497779998, 11341.716457088638, 11509.251898626562, 9218.631254750004, 9323.440593564408, 9403.153716165578, 9581.185599224144, 9593.196146068785, 8238.217369349903, 9011.188992990663, 9090.409123406956, 9100.862392200976, 9139.817203656023], "value": 6955.595477292974, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1600.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 115.0, 121.0, 119.0, 119.0, 119.0, 140.0, 172.0, 117.0, 119.0], "value": 124.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 116.0, 116.0, 151.0, 136.0, 112.0, 124.0, 144.0, 163.0, 137.0], "value": 134.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 263.0, 227.0, 276.0, 249.0, 237.0, 265.0, 196.0, 198.0, 199.0], "value": 231.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 56.0, 57.0, 85.0, 61.0, 61.0, 61.0, 62.0, 64.0, 91.0], "value": 67.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 72.0, 84.0, 71.0, 72.0, 94.0, 114.0, 106.0, 85.0, 83.0], "value": 87.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 80.0, 137.0, 130.0, 88.0, 86.0, 92.0, 107.0, 105.0, 86.0], "value": 99.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 96.0, 116.0, 156.0, 154.0, 97.0, 95.0, 97.0, 96.0, 96.0], "value": 114.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 110.0, 92.0, 91.0, 92.0, 92.0, 90.0, 96.0, 90.0, 91.0], "value": 93.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 60.0, 60.0, 60.0, 63.0, 63.0, 61.0, 59.0, 71.0, 62.0], "value": 62.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 76.0, 69.0, 82.0, 113.0, 110.0, 78.0, 76.0, 75.0, 71.0], "value": 82.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 107.0, 114.0, 114.0, 85.0, 91.0, 90.0, 94.0, 90.0], "value": 96.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 152.0, 160.0, 140.0, 141.0, 146.0, 136.0, 143.0, 228.0, 161.0], "value": 155.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 174.0, 174.0, 179.0, 175.0, 172.0, 173.0, 173.0, 177.0, 236.0], "value": 181.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 67.0, 93.0, 70.0, 68.0, 68.0, 68.0, 67.0, 69.0, 67.0], "value": 70.1, "unit": "score"}]}]} 08:44:45 INFO - Return code: 0 08:44:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:44:45 ERROR - # TBPL SUCCESS # 08:44:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:44:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:44:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:44:45 INFO - 2017-01-23 08:44:45.209 screenresolution[1655:11048] starting screenresolution argv=screenresolution get 08:44:45 INFO - 2017-01-23 08:44:45.212 screenresolution[1655:11048] Display 0: 1600x1200x32@60 08:44:45 INFO - 2017-01-23 08:44:45.218 screenresolution[1656:11051] starting screenresolution argv=screenresolution list 08:44:45 INFO - Available Modes on Display 0 08:44:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:44:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:44:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:44:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:44:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:44:45 INFO - Intel Iris: 08:44:45 INFO - Chipset Model: Intel Iris 08:44:45 INFO - Type: GPU 08:44:45 INFO - Bus: Built-In 08:44:45 INFO - VRAM (Dynamic, Max): 1536 MB 08:44:45 INFO - Vendor: Intel (0x8086) 08:44:45 INFO - Device ID: 0x0a2e 08:44:45 INFO - Revision ID: 0x0009 08:44:45 INFO - Displays: 08:44:45 INFO - Display: 08:44:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:44:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:44:45 INFO - Display Serial Number: 1600x1200 60 08:44:45 INFO - Main Display: Yes 08:44:45 INFO - Mirror: Off 08:44:45 INFO - Online: Yes 08:44:45 INFO - Rotation: Supported 08:44:45 INFO - Return code: 0 08:44:45 INFO - Running post-action listener: _resource_record_post_action 08:44:45 INFO - [mozharness: 2017-01-23 16:44:45.454458Z] Finished run-tests step (success) 08:44:45 INFO - Running post-run listener: _resource_record_post_run 08:44:45 INFO - Total resource usage - Wall time: 1199s; CPU: 54.0%; Read bytes: 38648320; Write bytes: 555617280; Read time: 247; Write time: 1732 08:44:45 INFO - TinderboxPrint: CPU usage
54.4% 08:44:45 INFO - TinderboxPrint: I/O read bytes / time
38,648,320 / 247 08:44:45 INFO - TinderboxPrint: I/O write bytes / time
555,617,280 / 1,732 08:44:45 INFO - TinderboxPrint: CPU idle
2,185.4 (45.6%) 08:44:45 INFO - TinderboxPrint: CPU system
966.1 (20.1%) 08:44:45 INFO - TinderboxPrint: CPU user
1,646.2 (34.3%) 08:44:45 INFO - TinderboxPrint: Swap in / out
289,697,792 / 0 08:44:45 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 252236288; Write bytes: 252051456; Read time: 18177; Write time: 554 08:44:45 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 35256832; Write bytes: 301122560; Read time: 225; Write time: 1166 08:44:45 INFO - Running post-run listener: _upload_blobber_files 08:44:45 INFO - Blob upload gear active. 08:44:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:44:45 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 08:44:45 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'] 08:44:45 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 08:44:46 INFO - (blobuploader) - INFO - Open directory for files ... 08:44:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:44:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:44:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:44:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:44:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:44:47 INFO - (blobuploader) - INFO - Done attempting. 08:44:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:44:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:44:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:44:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:44:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:44:48 INFO - (blobuploader) - INFO - Done attempting. 08:44:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:44:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:44:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:44:49 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:44:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:44:49 INFO - (blobuploader) - INFO - Done attempting. 08:44:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:44:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:44:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:44:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:44:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:44:51 INFO - (blobuploader) - INFO - Done attempting. 08:44:51 INFO - (blobuploader) - INFO - Iteration through files over. 08:44:51 INFO - Return code: 0 08:44:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:44:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:44:51 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/57df2e0440f0d50c5e5448ec7cd437895fddac0c43916d8c6dc14e04499a1e5ceefe347f54fb1bb43fcd3f245620c3a036bc7335f04f14525afeea74cc9c84c4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c079444369d35841bb6b34011a25cc2c27fe2027c2f5ce6d52455832991472a04630796b8ab3b3783343d4cd4169f5bcabea81175e17256ae48349e7f3e2abe", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4334f2df49a2f94699e083d7ad4c4c9026a5b3a4ca275ed70e8dfca26c9d20a268402aff46cc550a790bd7fda38924fc11bb2329343dbb0e853a2f936f46a325", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f13d4b471eb530b2ea56d35292e206328de31754ca875c3f4bcf1e9ad4db0560c06d24e86447f140edda3933a16f84025cf3e026f5373d9f7ca45dfc04d8e1c6"} 08:44:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:44:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:44:51 INFO - Contents: 08:44:51 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/57df2e0440f0d50c5e5448ec7cd437895fddac0c43916d8c6dc14e04499a1e5ceefe347f54fb1bb43fcd3f245620c3a036bc7335f04f14525afeea74cc9c84c4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c079444369d35841bb6b34011a25cc2c27fe2027c2f5ce6d52455832991472a04630796b8ab3b3783343d4cd4169f5bcabea81175e17256ae48349e7f3e2abe", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4334f2df49a2f94699e083d7ad4c4c9026a5b3a4ca275ed70e8dfca26c9d20a268402aff46cc550a790bd7fda38924fc11bb2329343dbb0e853a2f936f46a325", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f13d4b471eb530b2ea56d35292e206328de31754ca875c3f4bcf1e9ad4db0560c06d24e86447f140edda3933a16f84025cf3e026f5373d9f7ca45dfc04d8e1c6"} 08:44:51 INFO - Running post-run listener: copy_logs_to_upload_dir 08:44:51 INFO - Copying logs to upload dir... 08:44:51 INFO - mkdir: /builds/slave/test/build/upload/logs 08:44:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.236549 ========= master_lag: 1.77 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-01-23 08:44:53.532860) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-23 08:44:53.536458) ========= 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.NLf7dJsGmw/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.UYq8iZ1qJO/Listeners TMPDIR=/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/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/57df2e0440f0d50c5e5448ec7cd437895fddac0c43916d8c6dc14e04499a1e5ceefe347f54fb1bb43fcd3f245620c3a036bc7335f04f14525afeea74cc9c84c4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c079444369d35841bb6b34011a25cc2c27fe2027c2f5ce6d52455832991472a04630796b8ab3b3783343d4cd4169f5bcabea81175e17256ae48349e7f3e2abe", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4334f2df49a2f94699e083d7ad4c4c9026a5b3a4ca275ed70e8dfca26c9d20a268402aff46cc550a790bd7fda38924fc11bb2329343dbb0e853a2f936f46a325", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f13d4b471eb530b2ea56d35292e206328de31754ca875c3f4bcf1e9ad4db0560c06d24e86447f140edda3933a16f84025cf3e026f5373d9f7ca45dfc04d8e1c6"} build_url:https://queue.taskcluster.net/v1/task/b9w9lKPuRzqqo-Qco89CWA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011452 build_url: 'https://queue.taskcluster.net/v1/task/b9w9lKPuRzqqo-Qco89CWA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/57df2e0440f0d50c5e5448ec7cd437895fddac0c43916d8c6dc14e04499a1e5ceefe347f54fb1bb43fcd3f245620c3a036bc7335f04f14525afeea74cc9c84c4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c079444369d35841bb6b34011a25cc2c27fe2027c2f5ce6d52455832991472a04630796b8ab3b3783343d4cd4169f5bcabea81175e17256ae48349e7f3e2abe", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4334f2df49a2f94699e083d7ad4c4c9026a5b3a4ca275ed70e8dfca26c9d20a268402aff46cc550a790bd7fda38924fc11bb2329343dbb0e853a2f936f46a325", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f13d4b471eb530b2ea56d35292e206328de31754ca875c3f4bcf1e9ad4db0560c06d24e86447f140edda3933a16f84025cf3e026f5373d9f7ca45dfc04d8e1c6"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-23 08:44:53.576857) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-23 08:44:53.577205) ========= 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.NLf7dJsGmw/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.UYq8iZ1qJO/Listeners TMPDIR=/var/folders/d3/5t0h_zln4kq7v984ly1b_brh00000w/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.005226 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-23 08:44:53.628622) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-23 08:44:53.629407) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-23 08:44:53.630124) ========= ========= Total master_lag: 1.92 =========