builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0347 starttime: 1490287101.8 results: success (0) buildid: 20170323080623 builduid: 9f7371c17d854ace891154e9da208965 revision: 439ee2e4fc4ed4a82102608639d9751e376d5536 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-23 09:38:21.799113) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-23 09:38:21.799592) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-23 09:38:21.799879) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-23 09:38:21.826229) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 09:38:21.826696) ========= 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.R5ZMznFSp7/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.AZeUWB7za3/Listeners TMPDIR=/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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-03-23 09:38:21-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.19M=0.001s 2017-03-23 09:38:21 (8.19 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.123685 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 09:38:21.974017) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 09:38:21.974362) ========= 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.R5ZMznFSp7/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.AZeUWB7za3/Listeners TMPDIR=/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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.021617 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 09:38:22.023399) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-23 09:38:22.023784) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 439ee2e4fc4ed4a82102608639d9751e376d5536 --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 439ee2e4fc4ed4a82102608639d9751e376d5536 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.R5ZMznFSp7/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.AZeUWB7za3/Listeners TMPDIR=/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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-03-23 09:38:22,114 truncating revision to first 12 chars 2017-03-23 09:38:22,114 Setting DEBUG logging. 2017-03-23 09:38:22,114 attempt 1/10 2017-03-23 09:38:22,114 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/439ee2e4fc4e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-23 09:38:23,122 unpacking tar archive at: mozilla-central-439ee2e4fc4e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.276634 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-23 09:38:23.324152) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-23 09:38:23.324480) ========= script_repo_revision: 439ee2e4fc4ed4a82102608639d9751e376d5536 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-23 09:38:23.324912) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-23 09:38:23.325203) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-23 09:38:23.345771) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-03-23 09:38:23.346151) ========= /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.R5ZMznFSp7/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.AZeUWB7za3/Listeners TMPDIR=/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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 09:38:23 INFO - MultiFileLogger online at 20170323 09:38:23 in /builds/slave/test 09:38:23 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 09:38:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:38:23 INFO - {'append_to_log': False, 09:38:23 INFO - 'base_work_dir': '/builds/slave/test', 09:38:23 INFO - 'blob_upload_branch': 'Firefox', 09:38:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:38:23 INFO - 'branch': 'Firefox', 09:38:23 INFO - 'buildbot_json_path': 'buildprops.json', 09:38:23 INFO - 'config_files': ('talos/mac_config.py',), 09:38:23 INFO - 'default_actions': ('clobber', 09:38:23 INFO - 'read-buildbot-config', 09:38:23 INFO - 'download-and-extract', 09:38:23 INFO - 'populate-webroot', 09:38:23 INFO - 'create-virtualenv', 09:38:23 INFO - 'install', 09:38:23 INFO - 'run-tests'), 09:38:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:38:23 INFO - 'download_minidump_stackwalk': True, 09:38:23 INFO - 'download_symbols': 'ondemand', 09:38:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:38:23 INFO - 'tooltool.py': '/tools/tooltool.py', 09:38:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:38:23 INFO - '/tools/misc-python/virtualenv.py')}, 09:38:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:38:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:38:23 INFO - 'gecko_profile': False, 09:38:23 INFO - 'gecko_profile_interval': 0, 09:38:23 INFO - 'installer_path': 'installer.exe', 09:38:23 INFO - 'log_level': 'info', 09:38:23 INFO - 'log_name': 'talos', 09:38:23 INFO - 'log_to_console': True, 09:38:23 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:38:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:38:23 INFO - 'opt_config_files': (), 09:38:23 INFO - 'pip_index': False, 09:38:23 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:38:23 INFO - 'cmd': ('bash', 09:38:23 INFO - '-c', 09:38:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:38:23 INFO - 'enabled': True, 09:38:23 INFO - 'halt_on_failure': False, 09:38:23 INFO - 'name': 'check_screen_resolution'},), 09:38:23 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:38:23 INFO - 'cmd': ('bash', 09:38:23 INFO - '-c', 09:38:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:38:23 INFO - 'enabled': True, 09:38:23 INFO - 'halt_on_failure': False, 09:38:23 INFO - 'name': 'check_screen_resolution'},), 09:38:23 INFO - 'run_cmd_checks_enabled': True, 09:38:23 INFO - 'suite': 'dromaeojs-e10s', 09:38:23 INFO - 'system_bits': '32', 09:38:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:38:23 INFO - 'title': 't-yosemite-r7-0347', 09:38:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:38:23 INFO - 'use_talos_json': True, 09:38:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:38:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:38:23 INFO - 'work_dir': 'build'} 09:38:23 INFO - [mozharness: 2017-03-23 16:38:23.557765Z] Running clobber step. 09:38:23 INFO - Running pre-action listener: _resource_record_pre_action 09:38:23 INFO - Running main action method: clobber 09:38:23 INFO - rmtree: /builds/slave/test/build 09:38:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:38:25 INFO - Running post-action listener: _resource_record_post_action 09:38:25 INFO - [mozharness: 2017-03-23 16:38:25.394661Z] Finished clobber step (success) 09:38:25 INFO - [mozharness: 2017-03-23 16:38:25.394906Z] Running read-buildbot-config step. 09:38:25 INFO - Running pre-action listener: _resource_record_pre_action 09:38:25 INFO - Running main action method: read_buildbot_config 09:38:25 INFO - Using buildbot properties: 09:38:25 INFO - { 09:38:25 INFO - "project": "", 09:38:25 INFO - "product": "firefox", 09:38:25 INFO - "script_repo_revision": "production", 09:38:25 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 09:38:25 INFO - "repository": "", 09:38:25 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 09:38:25 INFO - "buildid": "20170323080623", 09:38:25 INFO - "pgo_build": "False", 09:38:25 INFO - "basedir": "/builds/slave/test", 09:38:25 INFO - "buildnumber": 19, 09:38:25 INFO - "slavename": "t-yosemite-r7-0347", 09:38:25 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 09:38:25 INFO - "platform": "yosemite_r7", 09:38:25 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:38:25 INFO - "branch": "mozilla-central", 09:38:25 INFO - "repo_path": "mozilla-central", 09:38:25 INFO - "revision": "439ee2e4fc4ed4a82102608639d9751e376d5536", 09:38:25 INFO - "stage_platform": "macosx64", 09:38:25 INFO - "builduid": "9f7371c17d854ace891154e9da208965", 09:38:25 INFO - "slavebuilddir": "test" 09:38:25 INFO - } 09:38:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/GXppFqLrTvioF211EqXANg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 09:38:25 INFO - Running post-action listener: _resource_record_post_action 09:38:25 INFO - [mozharness: 2017-03-23 16:38:25.400694Z] Finished read-buildbot-config step (success) 09:38:25 INFO - [mozharness: 2017-03-23 16:38:25.400894Z] Running download-and-extract step. 09:38:25 INFO - Running pre-action listener: _resource_record_pre_action 09:38:25 INFO - Running main action method: download_and_extract 09:38:25 INFO - mkdir: /builds/slave/test/build/tests 09:38: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')]} 09:38:25 INFO - trying https://queue.taskcluster.net/v1/task/GXppFqLrTvioF211EqXANg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 09:38:25 INFO - Downloading https://queue.taskcluster.net/v1/task/GXppFqLrTvioF211EqXANg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:38:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GXppFqLrTvioF211EqXANg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 09:38:26 INFO - Downloaded 1325 bytes. 09:38:26 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:38:26 INFO - Using the following test package requirements: 09:38:26 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:38:26 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 09:38:26 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:38:26 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:38:26 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 09:38:26 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:38:26 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 09:38:26 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:38:26 INFO - u'jsshell-mac.zip'], 09:38:26 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:38:26 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 09:38:26 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:38:26 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:38:26 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 09:38:26 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:38:26 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 09:38:26 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:38:26 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 09:38:26 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:38:26 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 09:38:26 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:38:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GXppFqLrTvioF211EqXANg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:38:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GXppFqLrTvioF211EqXANg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:38:26 INFO - Fetch https://queue.taskcluster.net/v1/task/GXppFqLrTvioF211EqXANg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:38:28 INFO - Expected file size: 52406564 09:38:28 INFO - Obtained file size: 52406564 09:38:43 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:38:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GXppFqLrTvioF211EqXANg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:38:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GXppFqLrTvioF211EqXANg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:38:43 INFO - Fetch https://queue.taskcluster.net/v1/task/GXppFqLrTvioF211EqXANg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:38:46 INFO - Expected file size: 52406564 09:38:46 INFO - Obtained file size: 52406564 09:39:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GXppFqLrTvioF211EqXANg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 09:39:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GXppFqLrTvioF211EqXANg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:39:01 INFO - Fetch https://queue.taskcluster.net/v1/task/GXppFqLrTvioF211EqXANg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 09:39:02 INFO - Expected file size: 14404171 09:39:02 INFO - Obtained file size: 14404171 09:39:02 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')]} 09:39:02 INFO - trying https://queue.taskcluster.net/v1/task/GXppFqLrTvioF211EqXANg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:39:02 INFO - Downloading https://queue.taskcluster.net/v1/task/GXppFqLrTvioF211EqXANg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 09:39:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GXppFqLrTvioF211EqXANg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 09:39:04 INFO - Downloaded 75444039 bytes. 09:39:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GXppFqLrTvioF211EqXANg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:39:04 INFO - mkdir: /builds/slave/test/properties 09:39:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:39:04 INFO - Writing to file /builds/slave/test/properties/build_url 09:39:04 INFO - Contents: 09:39:04 INFO - build_url:https://queue.taskcluster.net/v1/task/GXppFqLrTvioF211EqXANg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:39:05 INFO - Running post-action listener: _resource_record_post_action 09:39:05 INFO - Running post-action listener: set_extra_try_arguments 09:39:05 INFO - [mozharness: 2017-03-23 16:39:05.414278Z] Finished download-and-extract step (success) 09:39:05 INFO - [mozharness: 2017-03-23 16:39:05.414434Z] Running populate-webroot step. 09:39:05 INFO - Running pre-action listener: _resource_record_pre_action 09:39:05 INFO - Running main action method: populate_webroot 09:39:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:39:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:39:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:39:05 INFO - '--tppagecycles', 09:39:05 INFO - '7'], 09:39:05 INFO - 'tests': ['tsvgm']}}, 09:39:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:39:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:39:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:39:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:39:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:39:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:39:05 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:39:05 INFO - 'talos_options': ['--disable-e10s'], 09:39:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:39:05 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:39:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:39:05 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:39:05 INFO - 'talos_options': ['--disable-e10s'], 09:39:05 INFO - 'tests': ['damp', 'tps']}, 09:39:05 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:39:05 INFO - 'tests': ['damp', 'tps']}, 09:39:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:39:05 INFO - 'tests': ['dromaeo_dom']}, 09:39:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:39:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:39:05 INFO - 'tests': ['basic_compositor_video']}, 09:39:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:39:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:39:05 INFO - 'tests': ['a11yr', 09:39:05 INFO - 'ts_paint', 09:39:05 INFO - 'tpaint', 09:39:05 INFO - 'sessionrestore', 09:39:05 INFO - 'sessionrestore_no_auto_restore', 09:39:05 INFO - 'tabpaint']}, 09:39:05 INFO - 'other-e10s': {'tests': ['a11yr', 09:39:05 INFO - 'ts_paint', 09:39:05 INFO - 'tpaint', 09:39:05 INFO - 'sessionrestore', 09:39:05 INFO - 'sessionrestore_no_auto_restore', 09:39:05 INFO - 'tabpaint']}, 09:39:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:39:05 INFO - 'ts_paint', 09:39:05 INFO - 'tpaint', 09:39:05 INFO - 'sessionrestore', 09:39:05 INFO - 'sessionrestore_no_auto_restore', 09:39:05 INFO - 'tabpaint']}, 09:39:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:39:05 INFO - 'ts_paint', 09:39:05 INFO - 'tpaint', 09:39:05 INFO - 'sessionrestore', 09:39:05 INFO - 'sessionrestore_no_auto_restore', 09:39:05 INFO - 'tabpaint']}, 09:39:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:39:05 INFO - 'tests': ['a11yr', 09:39:05 INFO - 'ts_paint', 09:39:05 INFO - 'tpaint', 09:39:05 INFO - 'sessionrestore', 09:39:05 INFO - 'sessionrestore_no_auto_restore', 09:39:05 INFO - 'tabpaint']}, 09:39:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:39:05 INFO - 'tests': ['a11yr', 09:39:05 INFO - 'ts_paint', 09:39:05 INFO - 'tpaint', 09:39:05 INFO - 'sessionrestore', 09:39:05 INFO - 'sessionrestore_no_auto_restore', 09:39:05 INFO - 'tabpaint']}, 09:39:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:39:05 INFO - 'tests': ['tsvgx', 09:39:05 INFO - 'tsvgr_opacity', 09:39:05 INFO - 'tart', 09:39:05 INFO - 'tscrollx', 09:39:05 INFO - 'cart', 09:39:05 INFO - 'tsvg_static']}, 09:39:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:39:05 INFO - 'tsvgr_opacity', 09:39:05 INFO - 'tart', 09:39:05 INFO - 'tscrollx', 09:39:05 INFO - 'cart', 09:39:05 INFO - 'tsvg_static']}, 09:39:05 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:39:05 INFO - 'talos_options': ['--disable-e10s'], 09:39:05 INFO - 'tests': ['tp5o']}, 09:39:05 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:39:05 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:39:05 INFO - 'talos_options': ['--disable-e10s', 09:39:05 INFO - '--xperf_path', 09:39:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:39:05 INFO - 'tests': ['tp5n']}, 09:39:05 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:39:05 INFO - 'talos_options': ['--xperf_path', 09:39:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:39:05 INFO - 'tests': ['tp5n']}}, 09:39:05 INFO - 'talos.zip': {'path': '', 09:39:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:39:05 INFO - Running post-action listener: _resource_record_post_action 09:39:05 INFO - [mozharness: 2017-03-23 16:39:05.432491Z] Finished populate-webroot step (success) 09:39:05 INFO - [mozharness: 2017-03-23 16:39:05.432607Z] Running create-virtualenv step. 09:39:05 INFO - Running pre-action listener: _resource_record_pre_action 09:39:05 INFO - Running main action method: create_virtualenv 09:39:05 INFO - Creating virtualenv /builds/slave/test/build/venv 09:39:05 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 09:39:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:39:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:39:05 INFO - Using real prefix '/tools/python27' 09:39:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:39:06 INFO - Installing distribute.............................................................................................................................................................................................done. 09:39:09 INFO - Installing pip.................done. 09:39:09 INFO - Return code: 0 09:39:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:39:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:39:09 INFO - Reading from file tmpfile_stdout 09:39:09 INFO - Output received: 09:39:09 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) 09:39:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:39:09 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')]} 09:39:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:09 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')]} 09:39:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:09 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 0x1040b3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd569c7ef20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e91d30>, '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 0x103db8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e86be8>, '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.R5ZMznFSp7/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.AZeUWB7za3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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 09:39:09 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 09:39:09 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 09:39:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R5ZMznFSp7/Render', 09:39:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:09 INFO - 'HOME': '/Users/cltbld', 09:39:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:09 INFO - 'LOGNAME': 'cltbld', 09:39:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:09 INFO - 'MOZ_NO_REMOTE': '1', 09:39:09 INFO - 'NO_EM_RESTART': '1', 09:39:09 INFO - 'PAGER': '/bin/cat', 09:39:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:09 INFO - 'PWD': '/builds/slave/test', 09:39:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:09 INFO - 'SHELL': '/bin/bash', 09:39:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AZeUWB7za3/Listeners', 09:39:09 INFO - 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/', 09:39:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:09 INFO - 'USER': 'cltbld', 09:39:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:09 INFO - 'XPC_FLAGS': '0x0', 09:39:09 INFO - 'XPC_SERVICE_NAME': '0', 09:39:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:09 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 09:39:09 INFO - Cleaning up... 09:39:09 INFO - Return code: 0 09:39:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:39:09 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')]} 09:39:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:09 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')]} 09:39:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:09 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 0x1040b3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd569c7ef20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e91d30>, '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 0x103db8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e86be8>, '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.R5ZMznFSp7/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.AZeUWB7za3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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 09:39:09 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 09:39:09 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 09:39:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R5ZMznFSp7/Render', 09:39:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:09 INFO - 'HOME': '/Users/cltbld', 09:39:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:09 INFO - 'LOGNAME': 'cltbld', 09:39:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:09 INFO - 'MOZ_NO_REMOTE': '1', 09:39:09 INFO - 'NO_EM_RESTART': '1', 09:39:09 INFO - 'PAGER': '/bin/cat', 09:39:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:09 INFO - 'PWD': '/builds/slave/test', 09:39:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:09 INFO - 'SHELL': '/bin/bash', 09:39:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AZeUWB7za3/Listeners', 09:39:09 INFO - 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/', 09:39:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:09 INFO - 'USER': 'cltbld', 09:39:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:09 INFO - 'XPC_FLAGS': '0x0', 09:39:09 INFO - 'XPC_SERVICE_NAME': '0', 09:39:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:09 INFO - Downloading/unpacking psutil>=3.1.1 09:39:09 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 09:39:09 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 09:39:09 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 09:39:09 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 09:39:09 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 09:39:09 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 09:39:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:39:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:39:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:39:13 INFO - Installing collected packages: psutil 09:39:13 INFO - Running setup.py install for psutil 09:39:13 INFO - building 'psutil._psutil_osx' extension 09:39:13 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 09:39:13 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 09:39:14 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 09:39:14 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 09:39:14 INFO - building 'psutil._psutil_posix' extension 09:39:14 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 09:39:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:39:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:39:14 INFO - ^ 09:39:14 INFO - 1 warning generated. 09:39:14 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 09:39:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:39:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:39:14 INFO - Successfully installed psutil 09:39:14 INFO - Cleaning up... 09:39:14 INFO - Return code: 0 09:39:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:39:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:14 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 0x1040b3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd569c7ef20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e91d30>, '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 0x103db8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e86be8>, '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.R5ZMznFSp7/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.AZeUWB7za3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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 09:39:14 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 09:39:14 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 09:39:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R5ZMznFSp7/Render', 09:39:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:14 INFO - 'HOME': '/Users/cltbld', 09:39:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:14 INFO - 'LOGNAME': 'cltbld', 09:39:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:14 INFO - 'MOZ_NO_REMOTE': '1', 09:39:14 INFO - 'NO_EM_RESTART': '1', 09:39:14 INFO - 'PAGER': '/bin/cat', 09:39:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:14 INFO - 'PWD': '/builds/slave/test', 09:39:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:14 INFO - 'SHELL': '/bin/bash', 09:39:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AZeUWB7za3/Listeners', 09:39:14 INFO - 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/', 09:39:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:14 INFO - 'USER': 'cltbld', 09:39:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:14 INFO - 'XPC_FLAGS': '0x0', 09:39:14 INFO - 'XPC_SERVICE_NAME': '0', 09:39:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:39:14 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 09:39:14 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 09:39:14 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 09:39:14 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 09:39:14 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 09:39:14 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 09:39:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:39:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:39:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:39:17 INFO - Installing collected packages: mozsystemmonitor 09:39:17 INFO - Running setup.py install for mozsystemmonitor 09:39:17 INFO - Successfully installed mozsystemmonitor 09:39:17 INFO - Cleaning up... 09:39:17 INFO - Return code: 0 09:39:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:39: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')]} 09:39:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:18 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 0x1040b3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd569c7ef20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e91d30>, '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 0x103db8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e86be8>, '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.R5ZMznFSp7/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.AZeUWB7za3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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 09:39:18 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 09:39:18 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 09:39:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R5ZMznFSp7/Render', 09:39:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:18 INFO - 'HOME': '/Users/cltbld', 09:39:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:18 INFO - 'LOGNAME': 'cltbld', 09:39:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:18 INFO - 'MOZ_NO_REMOTE': '1', 09:39:18 INFO - 'NO_EM_RESTART': '1', 09:39:18 INFO - 'PAGER': '/bin/cat', 09:39:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:18 INFO - 'PWD': '/builds/slave/test', 09:39:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:18 INFO - 'SHELL': '/bin/bash', 09:39:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AZeUWB7za3/Listeners', 09:39:18 INFO - 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/', 09:39:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:18 INFO - 'USER': 'cltbld', 09:39:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:18 INFO - 'XPC_FLAGS': '0x0', 09:39:18 INFO - 'XPC_SERVICE_NAME': '0', 09:39:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:18 INFO - Downloading/unpacking jsonschema==2.5.1 09:39:18 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 09:39:18 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 09:39:18 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 09:39:18 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 09:39:18 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 09:39:18 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 09:39:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:39:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:39:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:39:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:39:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:39:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:39:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:39:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:39:22 INFO - Downloading functools32-3.2.3-2.tar.gz 09:39:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:39:22 INFO - warning: no files found matching '*.txt' 09:39:22 INFO - no previously-included directories found matching 'build' 09:39:22 INFO - no previously-included directories found matching 'dist' 09:39:22 INFO - no previously-included directories found matching '.git*' 09:39:22 INFO - Installing collected packages: jsonschema, functools32 09:39:22 INFO - Running setup.py install for functools32 09:39:22 INFO - warning: no files found matching '*.txt' 09:39:22 INFO - no previously-included directories found matching 'build' 09:39:22 INFO - no previously-included directories found matching 'dist' 09:39:22 INFO - no previously-included directories found matching '.git*' 09:39:22 INFO - Successfully installed jsonschema functools32 09:39:22 INFO - Cleaning up... 09:39:22 INFO - Return code: 0 09:39:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:39: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')]} 09:39:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39: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')]} 09:39:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39: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', '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 0x1040b3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd569c7ef20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e91d30>, '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 0x103db8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e86be8>, '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.R5ZMznFSp7/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.AZeUWB7za3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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 09:39: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:39: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 functools32==3.2.3-2 09:39:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R5ZMznFSp7/Render', 09:39:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:22 INFO - 'HOME': '/Users/cltbld', 09:39:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:22 INFO - 'LOGNAME': 'cltbld', 09:39:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:22 INFO - 'MOZ_NO_REMOTE': '1', 09:39:22 INFO - 'NO_EM_RESTART': '1', 09:39:22 INFO - 'PAGER': '/bin/cat', 09:39:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:22 INFO - 'PWD': '/builds/slave/test', 09:39:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:22 INFO - 'SHELL': '/bin/bash', 09:39:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AZeUWB7za3/Listeners', 09:39:22 INFO - 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/', 09:39:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:22 INFO - 'USER': 'cltbld', 09:39:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:22 INFO - 'XPC_FLAGS': '0x0', 09:39:22 INFO - 'XPC_SERVICE_NAME': '0', 09:39:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:39:23 INFO - Cleaning up... 09:39:23 INFO - Return code: 0 09:39:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:39:23 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')]} 09:39:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:23 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')]} 09:39:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:23 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 0x1040b3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd569c7ef20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e91d30>, '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 0x103db8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e86be8>, '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.R5ZMznFSp7/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.AZeUWB7za3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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 09:39:23 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 09:39:23 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 09:39:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R5ZMznFSp7/Render', 09:39:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:23 INFO - 'HOME': '/Users/cltbld', 09:39:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:23 INFO - 'LOGNAME': 'cltbld', 09:39:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:23 INFO - 'MOZ_NO_REMOTE': '1', 09:39:23 INFO - 'NO_EM_RESTART': '1', 09:39:23 INFO - 'PAGER': '/bin/cat', 09:39:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:23 INFO - 'PWD': '/builds/slave/test', 09:39:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:23 INFO - 'SHELL': '/bin/bash', 09:39:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AZeUWB7za3/Listeners', 09:39:23 INFO - 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/', 09:39:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:23 INFO - 'USER': 'cltbld', 09:39:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:23 INFO - 'XPC_FLAGS': '0x0', 09:39:23 INFO - 'XPC_SERVICE_NAME': '0', 09:39:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:23 INFO - Downloading/unpacking blobuploader==1.2.4 09:39:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:39:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:39:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:39:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:39:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:39:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:39:25 INFO - Downloading blobuploader-1.2.4.tar.gz 09:39:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:39:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:39: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 09:39: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 09:39: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 09:39: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 09:39: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 09:39: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 09:39:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:39:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:39:26 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 09:39:26 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 09:39:26 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 09:39:26 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 09:39:26 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 09:39:26 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 09:39:27 INFO - Downloading docopt-0.6.1.tar.gz 09:39:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:39:27 INFO - Installing collected packages: blobuploader, requests, docopt 09:39:27 INFO - Running setup.py install for blobuploader 09:39:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:39:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:39:27 INFO - Running setup.py install for requests 09:39:27 INFO - Running setup.py install for docopt 09:39:28 INFO - Successfully installed blobuploader requests docopt 09:39:28 INFO - Cleaning up... 09:39:28 INFO - Return code: 0 09:39:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:39:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:28 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 0x1040b3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd569c7ef20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e91d30>, '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 0x103db8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e86be8>, '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.R5ZMznFSp7/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.AZeUWB7za3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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 09:39:28 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 09:39:28 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 09:39:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R5ZMznFSp7/Render', 09:39:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:28 INFO - 'HOME': '/Users/cltbld', 09:39:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:28 INFO - 'LOGNAME': 'cltbld', 09:39:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:28 INFO - 'MOZ_NO_REMOTE': '1', 09:39:28 INFO - 'NO_EM_RESTART': '1', 09:39:28 INFO - 'PAGER': '/bin/cat', 09:39:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:28 INFO - 'PWD': '/builds/slave/test', 09:39:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:28 INFO - 'SHELL': '/bin/bash', 09:39:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AZeUWB7za3/Listeners', 09:39:28 INFO - 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/', 09:39:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:28 INFO - 'USER': 'cltbld', 09:39:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:28 INFO - 'XPC_FLAGS': '0x0', 09:39:28 INFO - 'XPC_SERVICE_NAME': '0', 09:39:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:39:28 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-sLrykz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:39:28 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-U2rAKU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:39:28 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-QeuTUJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:39:28 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-vL3yXn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:39:28 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-GsXFOt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:39:28 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-1fmrEJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:39:28 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-LjiK2I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:39:29 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-6AMdmP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:39:29 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-EFSyyx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:39:29 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-TCurIm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:39:29 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-3ypzp6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:39:29 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-zpsf84-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:39:29 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-x8A4S8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:39:29 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-ZcJo1z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:39:29 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-P3kaIT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:39:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:39:29 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-4IuexO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:39:30 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-5nZDPx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:39:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:39:30 INFO - Running setup.py install for manifestparser 09:39:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:39:30 INFO - Running setup.py install for mozcrash 09:39:30 INFO - Running setup.py install for mozdebug 09:39:30 INFO - Running setup.py install for mozdevice 09:39:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:39:30 INFO - Running setup.py install for mozfile 09:39:30 INFO - Running setup.py install for mozhttpd 09:39:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:39:30 INFO - Running setup.py install for mozinfo 09:39:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:39:31 INFO - Running setup.py install for mozInstall 09:39:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:39:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:39:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:39:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:39:31 INFO - Running setup.py install for mozleak 09:39:31 INFO - Running setup.py install for mozlog 09:39:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:39:31 INFO - Running setup.py install for moznetwork 09:39:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:39:31 INFO - Running setup.py install for mozprocess 09:39:31 INFO - Running setup.py install for mozprofile 09:39:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:39:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:39:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:39:32 INFO - Running setup.py install for mozrunner 09:39:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:39:32 INFO - Running setup.py install for mozscreenshot 09:39:32 INFO - Running setup.py install for moztest 09:39:32 INFO - Running setup.py install for mozversion 09:39:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:39:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:39:32 INFO - Cleaning up... 09:39:32 INFO - Return code: 0 09:39:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:39:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:32 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 0x1040b3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd569c7ef20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e91d30>, '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 0x103db8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e86be8>, '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.R5ZMznFSp7/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.AZeUWB7za3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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 09:39:32 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 09:39:32 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 09:39:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R5ZMznFSp7/Render', 09:39:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:32 INFO - 'HOME': '/Users/cltbld', 09:39:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:32 INFO - 'LOGNAME': 'cltbld', 09:39:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:32 INFO - 'MOZ_NO_REMOTE': '1', 09:39:32 INFO - 'NO_EM_RESTART': '1', 09:39:32 INFO - 'PAGER': '/bin/cat', 09:39:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:32 INFO - 'PWD': '/builds/slave/test', 09:39:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:32 INFO - 'SHELL': '/bin/bash', 09:39:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AZeUWB7za3/Listeners', 09:39:32 INFO - 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/', 09:39:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:32 INFO - 'USER': 'cltbld', 09:39:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:32 INFO - 'XPC_FLAGS': '0x0', 09:39:32 INFO - 'XPC_SERVICE_NAME': '0', 09:39:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:39:32 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-dKzoAt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:39:33 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)) 09:39:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:39:33 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-AI_SX4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:39:33 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)) 09:39:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:39:33 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-Hjnrfw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:39:33 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)) 09:39:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:39:33 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-s0dBm3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:39:33 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)) 09:39:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:39:33 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-x_J8Yf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:39:33 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)) 09:39:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:39:33 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-zgvniz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:39:33 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)) 09:39:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:39:33 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-268C41-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:39:33 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)) 09:39:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:39:33 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-1e3TL8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:39:33 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)) 09:39:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:39:33 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-Nci4Zc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:39:33 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)) 09:39:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:39:33 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-933QOX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:39:34 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)) 09:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:39:34 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-N21K0c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:39:34 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)) 09:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:39:34 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-s5r22T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:39:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:39:34 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-yr0vZa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:39:34 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)) 09:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:39:34 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-c2zaH8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:39:34 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)) 09:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:39:34 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-FSdRom-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:39:34 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)) 09:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:39:34 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-MtbV77-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:39:34 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)) 09:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:39:34 INFO - Running setup.py (path:/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/pip-wIyydN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:39:34 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)) 09:39:34 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)) 09:39:34 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)) 09:39:34 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)) 09:39:34 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)) 09:39:34 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)) 09:39:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:39:34 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 09:39:34 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 09:39:34 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 09:39:34 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 09:39:34 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 09:39:34 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 09:39:37 INFO - Downloading blessings-1.6.tar.gz 09:39:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:39:37 INFO - Installing collected packages: blessings 09:39:37 INFO - Running setup.py install for blessings 09:39:37 INFO - Successfully installed blessings 09:39:37 INFO - Cleaning up... 09:39:37 INFO - Return code: 0 09:39:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:39:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:39:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:39:37 INFO - Reading from file tmpfile_stdout 09:39:37 INFO - Current package versions: 09:39:37 INFO - blessings == 1.6 09:39:37 INFO - blobuploader == 1.2.4 09:39:37 INFO - docopt == 0.6.1 09:39:37 INFO - functools32 == 3.2.3-2 09:39:37 INFO - jsonschema == 2.5.1 09:39:37 INFO - manifestparser == 1.1 09:39:37 INFO - mozInstall == 1.12 09:39:37 INFO - mozcrash == 1.0 09:39:37 INFO - mozdebug == 0.1 09:39:37 INFO - mozdevice == 0.49 09:39:37 INFO - mozfile == 1.2 09:39:37 INFO - mozhttpd == 0.7 09:39:37 INFO - mozinfo == 0.9 09:39:37 INFO - mozleak == 0.1 09:39:37 INFO - mozlog == 3.4 09:39:37 INFO - moznetwork == 0.27 09:39:37 INFO - mozprocess == 0.25 09:39:37 INFO - mozprofile == 0.28 09:39:37 INFO - mozrunner == 6.13 09:39:37 INFO - mozscreenshot == 0.1 09:39:37 INFO - mozsystemmonitor == 0.3 09:39:37 INFO - moztest == 0.8 09:39:37 INFO - mozversion == 1.4 09:39:37 INFO - psutil == 3.1.1 09:39:37 INFO - requests == 1.2.3 09:39:37 INFO - wsgiref == 0.1.2 09:39:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:39:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1040b3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd569c7ef20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e91d30>, '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 0x103db8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e86be8>, '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.R5ZMznFSp7/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.AZeUWB7za3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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 09:39:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:39:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:39:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R5ZMznFSp7/Render', 09:39:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:37 INFO - 'HOME': '/Users/cltbld', 09:39:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:38 INFO - 'LOGNAME': 'cltbld', 09:39:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:38 INFO - 'MOZ_NO_REMOTE': '1', 09:39:38 INFO - 'NO_EM_RESTART': '1', 09:39:38 INFO - 'PAGER': '/bin/cat', 09:39:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:38 INFO - 'PWD': '/builds/slave/test', 09:39:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:38 INFO - 'SHELL': '/bin/bash', 09:39:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AZeUWB7za3/Listeners', 09:39:38 INFO - 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/', 09:39:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:38 INFO - 'USER': 'cltbld', 09:39:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:38 INFO - 'XPC_FLAGS': '0x0', 09:39:38 INFO - 'XPC_SERVICE_NAME': '0', 09:39:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:38 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)) 09:39:38 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)) 09:39:38 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)) 09:39:38 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)) 09:39:38 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)) 09:39:38 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)) 09:39:38 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)) 09:39:38 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)) 09:39:38 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)) 09:39:38 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)) 09:39:38 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)) 09:39:38 INFO - Cleaning up... 09:39:38 INFO - Return code: 0 09:39:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:39:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1040b3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd569c7ef20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e91d30>, '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 0x103db8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e86be8>, '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.R5ZMznFSp7/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.AZeUWB7za3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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 09:39:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:39:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:39:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R5ZMznFSp7/Render', 09:39:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:38 INFO - 'HOME': '/Users/cltbld', 09:39:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:38 INFO - 'LOGNAME': 'cltbld', 09:39:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:38 INFO - 'MOZ_NO_REMOTE': '1', 09:39:38 INFO - 'NO_EM_RESTART': '1', 09:39:38 INFO - 'PAGER': '/bin/cat', 09:39:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:38 INFO - 'PWD': '/builds/slave/test', 09:39:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:38 INFO - 'SHELL': '/bin/bash', 09:39:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AZeUWB7za3/Listeners', 09:39:38 INFO - 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/', 09:39:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:38 INFO - 'USER': 'cltbld', 09:39:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:38 INFO - 'XPC_FLAGS': '0x0', 09:39:38 INFO - 'XPC_SERVICE_NAME': '0', 09:39:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:38 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:39:38 INFO - Cleaning up... 09:39:38 INFO - Return code: 0 09:39:38 INFO - Running post-action listener: _resource_record_post_action 09:39:38 INFO - Running post-action listener: _start_resource_monitoring 09:39:38 INFO - Starting resource monitoring. 09:39:38 INFO - [mozharness: 2017-03-23 16:39:38.560165Z] Finished create-virtualenv step (success) 09:39:38 INFO - [mozharness: 2017-03-23 16:39:38.561171Z] Running install step. 09:39:38 INFO - Running pre-action listener: _resource_record_pre_action 09:39:38 INFO - Running main action method: install 09:39:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:39:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:39:38 INFO - Reading from file tmpfile_stdout 09:39:38 INFO - Detecting whether we're running mozinstall >=1.0... 09:39:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:39:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:39:38 INFO - Reading from file tmpfile_stdout 09:39:38 INFO - Output received: 09:39:38 INFO - Usage: mozinstall [options] installer 09:39:38 INFO - Options: 09:39:38 INFO - -h, --help show this help message and exit 09:39:38 INFO - -d DEST, --destination=DEST 09:39:38 INFO - Directory to install application into. [default: 09:39:38 INFO - "/builds/slave/test"] 09:39:38 INFO - --app=APP Application being installed. [default: firefox] 09:39:38 INFO - mkdir: /builds/slave/test/build/application 09:39:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:39:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:39:59 INFO - Reading from file tmpfile_stdout 09:39:59 INFO - Output received: 09:39:59 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:39:59 INFO - Running post-action listener: _resource_record_post_action 09:39:59 INFO - [mozharness: 2017-03-23 16:39:59.235182Z] Finished install step (success) 09:39:59 INFO - [mozharness: 2017-03-23 16:39:59.235312Z] Running run-tests step. 09:39:59 INFO - Running pre-action listener: _resource_record_pre_action 09:39:59 INFO - Running main action method: run_tests 09:39:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:39:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:39:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:39:59 INFO - 2017-03-23 09:39:59.249 screenresolution[2179:21513] starting screenresolution argv=screenresolution get 09:39:59 INFO - 2017-03-23 09:39:59.252 screenresolution[2179:21513] Display 0: 1600x1200x32@60 09:39:59 INFO - 2017-03-23 09:39:59.258 screenresolution[2180:21515] starting screenresolution argv=screenresolution list 09:39:59 INFO - Available Modes on Display 0 09:39:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:39:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:39:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:39:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:39:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:39:59 INFO - Intel Iris: 09:39:59 INFO - Chipset Model: Intel Iris 09:39:59 INFO - Type: GPU 09:39:59 INFO - Bus: Built-In 09:39:59 INFO - VRAM (Dynamic, Max): 1536 MB 09:39:59 INFO - Vendor: Intel (0x8086) 09:39:59 INFO - Device ID: 0x0a2e 09:39:59 INFO - Revision ID: 0x0009 09:39:59 INFO - Displays: 09:39:59 INFO - Display: 09:39:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:39:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:39:59 INFO - Display Serial Number: 1600x1200 60 09:39:59 INFO - Main Display: Yes 09:39:59 INFO - Mirror: Off 09:39:59 INFO - Online: Yes 09:39:59 INFO - Rotation: Supported 09:39:59 INFO - Return code: 0 09:39:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:39:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:39:59 INFO - Python 2.7.3 09:39:59 INFO - Return code: 0 09:39:59 INFO - grabbing minidump binary from tooltool 09:39:59 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')]} 09:39:59 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 0x103e91d30>, '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 0x103db8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e86be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:39:59 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 09:39:59 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 09:39:59 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 09:39:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:39:59 INFO - Return code: 0 09:39:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:39:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:39:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:39:59 INFO - ENV: RUST_BACKTRACE is now 1 09:39:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:39:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:39:59 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/GXppFqLrTvioF211EqXANg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0347', '--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 09:39:59 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/GXppFqLrTvioF211EqXANg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0347 --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 09:39:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R5ZMznFSp7/Render', 09:39:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:59 INFO - 'HOME': '/Users/cltbld', 09:39:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:59 INFO - 'LOGNAME': 'cltbld', 09:39:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:39:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:39:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:59 INFO - 'MOZ_NO_REMOTE': '1', 09:39:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:39:59 INFO - 'NO_EM_RESTART': '1', 09:39:59 INFO - 'PAGER': '/bin/cat', 09:39:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:59 INFO - 'PWD': '/builds/slave/test', 09:39:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:39:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:59 INFO - 'RUST_BACKTRACE': '1', 09:39:59 INFO - 'SHELL': '/bin/bash', 09:39:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AZeUWB7za3/Listeners', 09:39:59 INFO - 'TMPDIR': '/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/', 09:39:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:59 INFO - 'USER': 'cltbld', 09:39:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:59 INFO - 'XPC_FLAGS': '0x0', 09:39:59 INFO - 'XPC_SERVICE_NAME': '0', 09:39:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:59 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/GXppFqLrTvioF211EqXANg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0347', '--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 09:39:59 INFO - mozversion application_buildid: 20170323080623 09:39:59 INFO - mozversion application_changeset: 439ee2e4fc4ed4a82102608639d9751e376d5536 09:39:59 INFO - mozversion application_display_name: Nightly 09:39:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:39:59 INFO - mozversion application_name: Firefox 09:39:59 INFO - mozversion application_remotingname: firefox 09:39:59 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:39:59 INFO - mozversion application_vendor: Mozilla 09:39:59 INFO - mozversion application_version: 55.0a1 09:39:59 INFO - mozversion platform_buildid: 20170323080623 09:39:59 INFO - mozversion platform_changeset: 439ee2e4fc4ed4a82102608639d9751e376d5536 09:39:59 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:39:59 INFO - mozversion platform_version: 55.0a1 09:39:59 INFO - using testdate: 1490287199 09:39:59 INFO - actual date: 1490287199 09:39:59 INFO - starting webserver on 'localhost:55248' 09:39:59 INFO - SUITE-START | Running 2 tests 09:39:59 INFO - TEST-START | dromaeo_css 09:39:59 INFO - operating with platform_type : mac_ 09:39:59 INFO - Initialising browser for dromaeo_css test... 09:39:59 INFO - TEST-INFO | started process 2190 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/tmp4siU4T/profile http://localhost:55248/getInfo.html) 09:40:02 INFO - PID 2190 | 2017-03-23 09:40:02.614 plugin-container[2192:21695] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b47, name = 'com.apple.tsm.portname' 09:40:02 INFO - PID 2190 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:40:02 INFO - PID 2190 | 2017-03-23 09:40:02.617 plugin-container[2192:21695] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b4b, name = 'com.apple.CFPasteboardClient' 09:40:02 INFO - PID 2190 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:40:02 INFO - PID 2190 | 2017-03-23 09:40:02.617 plugin-container[2192:21695] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:40:02 INFO - PID 2190 | __metrics Screen width/height:1600/1200 09:40:02 INFO - PID 2190 | colorDepth:24 09:40:02 INFO - PID 2190 | Browser inner width/height: 1024/689 09:40:02 INFO - PID 2190 | __metrics 09:40:02 ERROR - PID 2190 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3388: TypeError: this.tabs is undefined 09:40:03 INFO - TEST-INFO | 2190: exit 0 09:40:03 INFO - Browser initialized. 09:40:03 INFO - Running cycle 1/1 for dromaeo_css test... 09:40:03 INFO - TEST-INFO | started process 2193 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/tmp4siU4T/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:40:05 INFO - PID 2193 | 2017-03-23 09:40:05.984 plugin-container[2194:21821] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b4f, name = 'com.apple.tsm.portname' 09:40:05 INFO - PID 2193 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:40:05 INFO - PID 2193 | 2017-03-23 09:40:05.985 plugin-container[2194:21821] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 09:40:05 INFO - PID 2193 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:40:05 INFO - PID 2193 | 2017-03-23 09:40:05.985 plugin-container[2194:21821] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:40:15 INFO - PID 2193 | RSS: Main: 227815424 09:40:15 INFO - PID 2193 | 09:43:19 INFO - PID 2193 | Cycle 1(1): loaded http://localhost:55248/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55248/tests/dromaeo/cssquery-ext.html) 09:43:21 INFO - PID 2193 | RSS: Main: 254291968 09:43:21 INFO - PID 2193 | 09:45:04 INFO - PID 2193 | ************************* 09:45:04 INFO - PID 2193 | A coding exception was thrown and uncaught in a Task. 09:45:04 INFO - PID 2193 | 09:45:04 ERROR - PID 2193 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:45:04 INFO - PID 2193 | Full stack: 09:45:04 INFO - PID 2193 | ************************* 09:46:24 INFO - PID 2193 | Cycle 1(1): loaded http://localhost:55248/tests/dromaeo/cssquery-ext.html (next: http://localhost:55248/tests/dromaeo/cssquery-jquery.html) 09:46:24 INFO - PID 2193 | RSS: Main: 262983680 09:46:24 INFO - PID 2193 | 09:49:26 INFO - PID 2193 | Cycle 1(1): loaded http://localhost:55248/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55248/tests/dromaeo/cssquery-mootools.html) 09:49:27 INFO - PID 2193 | RSS: Main: 274944000 09:49:27 INFO - PID 2193 | 09:52:29 INFO - PID 2193 | Cycle 1(1): loaded http://localhost:55248/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55248/tests/dromaeo/cssquery-prototype.html) 09:52:30 INFO - PID 2193 | RSS: Main: 267423744 09:52:30 INFO - PID 2193 | 09:55:32 INFO - PID 2193 | Cycle 1(1): loaded http://localhost:55248/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55248/tests/dromaeo/cssquery-yui.html) 09:55:33 INFO - PID 2193 | RSS: Main: 277536768 09:55:33 INFO - PID 2193 | 09:58:36 INFO - PID 2193 | Cycle 1(1): loaded http://localhost:55248/tests/dromaeo/cssquery-yui.html (next: http://localhost:55248/tests/dromaeo/cssquery-dojo.html) 09:58:36 INFO - PID 2193 | RSS: Main: 271253504 09:58:36 INFO - PID 2193 | 09:58:36 INFO - PID 2193 | __start_tp_report 09:58:36 INFO - PID 2193 | _x_x_mozilla_page_load 09:58:36 INFO - PID 2193 | _x_x_mozilla_page_load_details 09:58:36 INFO - PID 2193 | |i|pagename|runs| 09:58:36 INFO - PID 2193 | |0;dojo.html;3253.4794432890744;3480.1125712943212;3521.753477256593;3577.856885724571;3586.4441011643203;5011.398632164145;6083.0875368694715;6149.69251537423;6152.538559608036;6157.692115394229;6036.607620504664;6101.725422355994;6123.265208175025;6130.632162070279;6181.876362472748;5528.889422211553;5566.888662226743;5601.383847776741;5717.684932465535;5722.713864306768;4918.36061312029;5554.792369887752;5606.439356064402;5672.262605861232;5725.683092888638;3546.627604101571;5593.636413633141;5644.858878528049;5662.575306851982;5675.489205971462;4751.0497900419705;5147.150720131174;5446.91829622556;5471.343438787331;5541.778328866841;5424.810131645413;5489.643173193769;5514.944850551524;5526.364468086182;5529.281193444866;5945.791897283574;6054.878902421927;6187.443130118266;6224.844378890519;6231.345708700612;6287.6856157192415;6383.2978372379;6462.935370646281;6549.476041916635;6564.179477565304;6639.800805975829;6660.400563949268;6666.100076489709;6682.799516014527;6694.866102677968;6264.780732674426;6264.906026409653;6268.811935641938;6277.7175027123885;6293.433590976788;6484.805455836333;6495.382938620824;6738.663066846638;6755.93244067553;6767;5906.40935906406;6237.126802247689;6442.581232219891;6458.515611329169;6468.288488266287;6427.967860160669;6440.871182576322;6547.574407663486;6630;6635.767748128889;6381.1704478417505;6517.250516190604;6527.249366322839;6534.869302614016;6536.379043990813;6316.178896743394;6342.682865856688;6474.12599299098;6556.737730490727;6564.146660934143;5241.31862857826;5280.049591073644;5286.497782710637;5294.92057619136;5309.495597918269;4664.743439110882;4669.95330046702;4673.415823022122;4682.81268749253;4684.4378674559275;6131.172291740647;6172.166757487682;6277.52918531112;6283.497320214463;6301.590396624205;6416.03759436079;6589.9341006590275;6606.8017959461295;6649.767258145942;6674.8998765017595;6202.534809889276;6228.128062071313;6241.21984751906;6266.216722909637;6274;4808.783604737795;5749.568782341341;5832.416758324133;5846.492104737209;5848.239728835224;6448.290688024314;6562.081308616702;6682.064510968371;6714.597124172566;6776.457883369417;6628.900566491603;6635.966820166062;6640.169978752656;6645.767398141042;6655.367740064686;6683.598984060972;6735.898961515483;6754.8649027020165;6768.661566921536;6807.965960170168;6260;6361.7137228823885;6490.805275841543;6491.513136514686;6553.344665533408;6589;6640.867182656223;6649.700763465559;6652.002199670088;6668.966655166693;4929.704217746947;6145.324014358328;6347.952587822958;6365.61806291624;6391.808245752635;6298.811035668754;6350.174477317919;6372.171617689671;6389.87220255583;6400.167978162809;5910.793122240873;6159.076138579249;6240.906386404116;6244.2819075806865;6301.243850737941;5149.253358262956;6313;6371.585846919936;6380.1067850501895;6406.135171751754;6032.728527216374;6191;6194.101855230876;6213.627182369072;6219.844503887439 09:58:36 INFO - PID 2193 | |1;ext.html;669.5848573884192;709.5317090720124;800.4516905919446;1274.1272228523455;1281.9230846149233;32130;36423.4536481953;37394.626053739456;37450.812745936266;37899.24201515972;37058.44412333817;37101.25797484049;37269.81365093178;37385.81307093461;37386.6914657987;6597.307282735315;8030.959845200766;8230.135835737252;8475.83048339033;8499.532525711084;8355.331573474108;8427.83144337113;8484.745457636282;8486.490810551355;8490.10853860345;8384.161583841598;8418.452800567975;8498.575071246412;8500.617472213766;8511.404201705884;8451.408401411903;8481.194286542768;8493.108223636491;8493.915060849375;8497.787555311104;8347.207015333533;8397.832043359098;8404.327653787683;8420.073791882887;8424.115467875907;37104.62895371039;37345.06637334089;37391.62608373909;37491.4376284356;37534.8123259382;2648.0599387217644;2978.3447641518833;2997.5503674448787;3019.773516986235;3070.6929307069336;27177.320566985785;27209;27270.5909411359;28048.579271310948;28435.715642843512;2754.5454999924987;2775.111964171466;2781.735735105162;2853.857307134656;2872.4255148970287;159679;195433;271608.64195679093;294803;297253;47394;47458.05083898372;47556.28665570019;47600.285995710095;47613.76193119012;49200.0159996798;49470.752646237255;49516.50483495191;49586;49658.75170624196;14318.069325494018;14400.20798856052;14435;14441.85558144426;14532.927335363467;11786.29282243051;11819.936205741524;11830.349330786717;11892.643220703392;11935.582254621047;11243.775124497464;13456;13493.325333733274;13499.055066145473;13583.86416135826;5061.620378471629;6008.279006519246;6059.575829691968;6068.241469816273;6069.147500674764;948.4878165790396;955.861400096982;963.5760265483165;993.9900600994042;1006.1498034161123;28169.43661126766;28475.00337488178;28509.71490285112;28879.278018049295;29220.415591688474;1032.9070383665505;1037.1754455208127;1056.0917610854658;1076.1444651502075;1079.06121674144;11641.941790290994;12130.696732581757;12222.449989750305;12242.44908979098;12329.198602090835;471481;472028;474936;475654;475718;42270.94322641897;45361.31958020632;52627.210591841154;53313;53422;1453.2007395932194;1492.7462331403688;1503.7443634582148;1506.284514855435;1516.4692357674726;17697.82302177013;20559.588808223452;22371.44071398228;23169.768302316763;23513.17703880356;37707.43438848365;45853.77073114613;46836;46846;46934.765326173154;391087;393817;394064;394600;398460.02309964795;26431.603525946743;26915.92336306526;31993.520097199027;32122.87569935041;33132.1716957078;31977.200569985936;37635;38622.806885966515;40490.1901961965;42068;25577.104801331865;25651.871740641178;29450.852745736993;29506;29527.86609874662;28370.57444138298;29649.25876853096;30205.942792002174;30381.544276836306;30590.235244119074;35731.642683572834;35876.28247434984;36019;36497.452538211415;36790;21904;23462;29410.705892941653;29676.55485167681;29866.70133298639;20755.584888302452;23533.29400117999;23641.763582364645;23869.761302386753;23918.52162956766 09:58:36 INFO - PID 2193 | |2;jquery.html;422.07565431704586;426.56532508640373;427.3333599584648;441.4217375238438;451.8418553506273;20768.688469672947;21445.356639300808;21476.241657709877;21618.243361482353;21961.121555137815;22225.666615000788;22399.216027439;22829.31512054637;23261.767382326172;23390.64914026291;4366.585174408426;6047.848803779919;6210.068489726532;6249.250089989185;6263.123162757218;6087.178230938833;6172.166757487749;6234.189555357797;6238.344973777734;6244.56288059836;6013.128096426016;6077.3314935357075;6132.816015519519;6195.504359651217;6196.628202307854;6103.664298463583;6156.56904016719;6174.66039367839;6175.104609831594;6190.473809726179;5222.138347172712;5402.081646120129;5402.972985135049;5427.267318911936;5435.565154787608;26787.79454924533;26941.057063002896;27022.86488567564;27304.180874573594;27524;1620.335662378419;1983.4446355020618;1993.3820515640198;2018.5256464730776;2024.4230394337544;12831.807522887164;14145.434182632682;15731.82011349142;15950.840491595052;16473.337561107226;1354.9661258468518;1611.3796188467525;1625.089949628212;1628.145223757525;1639.098495827302;141689.29155354158;150230;153973;155238;155676;11840.940795295968;12597.370131493388;12659.556915507914;12694.238345699288;12732.172408793398;12971.767682070189;16137.112458814878;16187.433439829549;16209.83790162107;16269.918650406673;8713.390062695551;8922.776930576658;8925.955370223108;8927.33045021626;8939.061398553189;8541.572921353907;8556;8575;8686.391952563386;8732.257758090633;7774.611269436506;7842.254985776342;7902.683892644345;7907.920920790833;7936.6031698414845;1803.92784288627;2139.8074173324476;2151.8386121040985;2156.590247852903;2347.1223021582664;807.834393949239;904.7602385367883;914.9432405571575;944.15498129174;951.9428834269966;17178.656426871392;20097.095630696655;20400.693989590167;20480;20510;904.7740311877443;928.3501548915785;936.8735220745249;958.7842735384622;964.358701463533;8906.643734250558;10144.28989970695;10170.186385089175;10377.533011014522;10775.892241077645;659363;659421.8115637661;672015;682616;687027;123590.38204808919;123942.38028809978;142680;144413;151039.48960510475;1046.890076541952;1163.959261425846;1176.4294317256124;1230.2741382584318;1234.4198226833373;20797.48006299794;20902.37292881216;21044.071720064647;21114.788852111284;21346.75859034779;48552.271715924995;50517.74741126271;58000.4199957995;62964.37035629768;63597.456101755415;90645.54677226572;91963.62054569053;92809;92992.53503732439;93288.3659346968;8301.335893128657;8416.28461580748;8485.575721214038;8498.53258070812;8640.611172497127;11222.82160373149;11876.346800925687;12394.75210495803;12966.675833104247;13232.073754837069;9958.751031224278;10029.44838033886;10215.540300686338;10219.437930913871;10612.681619551426;9663.710088697351;9697.32118751709;10341.689749307532;10704.625338113128;10705.625303114353;10522.421266830397;10588.047075763217;10828.620998265023;11503.311979563736;11771.823422648496;7459.962700186465;7984.640691169027;8339.708110216114;8370.958145209479;8384.958075209584;9891.010898910052;10519.684409467729;11629.476673549541;11947.701307467383;11970.880291197325 09:58:36 INFO - PID 2193 | |3;mootools.html;1359.1369480379958;1414.8231471066117;1419.41094445805;1432.91402515849;1445.869871711546;10530;10917.072048875854;11008.834867476984;11009.339439633628;11273.774524509505;9883.91276959534;10375.273730838844;10882.347059176454;10953.17851161164;11050.944745276262;2460.692413448319;2492.5887228607257;2501.562226610336;2505.6742623458927;2513;2098.412444515538;2371.691680081587;2424.7454017328105;2438.0979037756056;2481.4292712676097;2004.0180311647334;2434.3914021494625;2442.8656423896678;2443.1082654831034;2496.1762618335997;2353.352827972304;2415.492746523232;2426.4783071639595;2433.7809597136256;2450.5711500487523;2396.0894859953105;2453.374389530676;2468.790152837011;2471.159805666064;2492.2274095030416;11020.614278500214;11085.50115244816;11252.718682032933;11285.605003824827;11333.206675532716;5409.648372855791;6256.436920677115;6385.074164246204;6489.967550162266;6534.183227096613;3682.797446140461;3791.848326066954;3993.101552150772;4066.1054567994993;4126.628603425706;1178.723000094977;1185.9762804743857;1204.3556697167062;1240.640214337841;1245.806899930503;13591;13790.103643263255;14670.413183472541;15286.923565382102;15440.45958391451;5266.368035835648;5282.128448805981;5446.564274858081;5513.58648101394;5540.36285827127;11515.25150865191;11522.827157592643;11576.36330001841;11651.242669226473;11669.241499302518;6247.562670613013;6359.650219237894;6359.904601430982;6371.713272902731;6375.043743438521;5746.482816546531;5974.103884417285;5977.880442391128;6045.697715114226;6083.9391606084255;4196.643285320721;4319.719218250803;4331.306990881507;4358.847440339573;4398.714083584557;1061.856649352343;1284.794432890733;1287.620152055146;1304.6347022833622;1307.2091384712112;736.8578703010313;752.5221484357363;755.8110472381904;780.9180036096242;793.686493834932;6014.218151640259;6083.878322433527;6144.723487443076;6658.4340331071435;6682.866342673119;558.7651290647633;571.7027145884117;574.635106707244;579.7593998490579;587.9470847623735;3793.203427280302;3870.651641352292;4215.304474761691;4299.462567179103;4326.545712700184;9979.950100249598;11124.88751124881;11381.577302837146;14160.78758818598;14177.645558860904;13432.932835335761;13434.731305373838;13643.59069227905;14077.507287244896;14124.717505649829;8733.563321833883;10149.64476243328;10323.63867264642;10359;10363.999999999849;5674.57440691958;6245.937540624535;6430.453411460078;6583.80248592543;6733.293004234485;10104.595816167564;12086.395680215777;12291.078169137172;12299.93850030744;12363.75272494563;11730.70673233176;12904.677383065124;12956.8056479151;12987.740245195233;13084.542041028899;9018.323625727966;9122.179003889682;9122.224610908148;9132.863007054766;9159.313051521296;9614.95192524033;9992.800143997225;10084.54619542137;10215.233857460571;10225.48872556384;7418.406527477897;8226.177382261725;8507.361947853811;8543.829123417621;8639.95680021596;8474.788130296793;8478.23695867375;8479.448835825651;8509.957450212709;8635.309175265838;7605.277498637621;7715.267049630276;7768.378529717722;7776.377889768918;7787.377009839312;5411.2153737708595;5626.690532020778;5738.770449181986;5817.2146760188525;5929.288485381781;5645.520130788929;5782.739776709974;5830.53355731549;5938.495227905676;5988.850278743066 09:58:36 INFO - PID 2193 | |4;prototype.html;386.70803543324786;400.1436924980541;402.49084907591885;409.4308910614246;410.9301418758811;5136.3579552555975;6022.904424991005;6230.781922632709;6291.15069465622;6413.198350206224;4904.534069263424;5934.821955341346;6155.26136863577;6170;6205.503559715224;4826;4831.68594041387;4843.152448321547;4869.147899117658;4878.512148785111;4044.6562042226446;4233.941514621345;4831.855044348657;4843.297721830332;4891.975540122295;3859.9614003859883;4376.277914144162;4801.087793319258;4801.951980480186;4824.324594556747;4506.59440650343;4842.297866809293;4842.9031419371595;4843.733594652311;4850.253908592904;3436.4020730360253;3565.1265439967374;3801.851953646181;4000.6999475039197;4279.358096285582;5901.406620212519;5996.550258730569;6000.879982400371;6017.789377371771;6024.397560243984;5322.04203243416;5379.946200538023;5402.838389746224;5410.269613602152;5425.321834770653;6398.936010639881;6511.251206111263;6526.477881769447;6547.345265473462;6566.737330506774;3675;4106.2198182345255;4431.578999994996;4432.468103827529;4436.511983681792;6360;6367.5861069030825;6483.902741458882;6960.477964152658;6981;4041.939370909439;4397.934030989538;4470.709403888802;4505.954940450553;4518.43519560055;4583.472900616405;4604.562566556172;4613.5386461354265;4618.630509559227;4644.606618014582;5138.075146473596;5374.973125134349;5410.1614249790555;5430.755615997289;5440.721430463838;6050.909236361458;6545.738170473128;6631.551593171524;6637.070810086591;6653.835578773792;1367.4803574641574;1385.8198434203691;1552.3480138341924;1554.3471741868443;1573.732465480871;970.7670159161751;1028.1928685981468;1035.409816404642;1039.4854546999284;1070.8072546941469;6157.322694503601;6176.657191529295;6188.721507532172;6198.535109866688;6274.309825919146;6861.622610756356;6866.313368663094;6878.209005964378;6893.1398416886705;6914.965425172943;5291.629585929025;5897.64614123154;6024.97575412181;6109.747501762128;6233.189685340877;3377.6115746689447;3523;3594.993401847487;3596.8021758803516;3666.4133738602227;5445.019896418604;5681.358168451689;6089.421504957022;6815.386615204656;7044.753433629799;5256.922330922152;6385.69093335865;6564.67176641166;6672.799816005528;7017.824554386181;5907.232059832194;6372.426481616584;6404.477537354436;6705.932940670531;7044.471664625071;5903.439173278532;6856.5200435969;6860.279670634512;6876.587404755731;6877.828054298682;4373.387725718466;5618.550515958632;6021.819345419644;6786.93213067863;6858.3141685831015;5812.651240925558;7155.248698886542;7171.42628589722;7184.209736928829;7194.70495310849;3161.4151381994407;4265.808038638331;4313.641203021039;4525.456945166601;4556.316552517149;6003.369646187088;6371.808845734636;6930.826729331807;6965.33829286217;6981.930180698128;5730.169125476699;5882.000119959234;6656.334366563498;6674.199096108498;6715.026321183499;4810.759462026955;5822.155787410887;6595.208574971034;6645.800625981228;6647.867042659216;5990.400959903975;6439.195100612424;6459.644719540471;6475.287718351072;6482.513811464253;6195.597286176384;6417.2299324081405;6515;6520.902186467112;6527.281998980204;5685.516731077904;6418.972964325685;6423.839404014937;6441.806745797634;6455.709493072917 09:58:36 INFO - PID 2193 | |5;yui.html;577.3533642320605;700.8107810891063;714.2000958926004;726.4696771356909;750.5159172333845;15093;15157.545273641781;15613.921930390361;15629.452969146083;15674.216289185551;14749.631259218524;14858;14964.625884352896;14971.47599834006;15289.388424463035;914.387360468486;948.7485816258662;950.6625148072438;951.1819834941945;954.1555723184962;923.9630414783401;949.0936155971066;951.8619800128977;952.6379975609259;952.8427809411439;1000.67978246961;1005.5826831864767;1011.0698157694939;1011.7774089700254;1013.2096964367805;998.2762497189516;998.5656239535753;1008.8184126857162;1011.1152741351318;1012.4026824173699;740.7185269597572;742.6175519607407;745.4856149257049;747.9813004674927;748.3115533709001;15543.689126217525;15795.921020394939;15892.841071589251;16207.756883646754;16517.504474865775;11872.853693771158;14360.497382591664;14368;14721.55835324942;14947.775783363259;11361.375124368156;11473.713157171054;11505;11601.477933493014;12103.878961210452;2957.9112626621236;2961.9851900740573;3003.354278830062;3059.0669845697053;3145.4495463293833;101015.98984010139;109207;110830;116181;117077;9394.95302523483;9533.52332383378;10546;11215.719607009727;11775.23460975034;25238.116665916605;25246.36884077876;25255.62116568253;25280;25303;7233.529820561646;7470.140933792574;7497.812554686177;7662.310392064741;7722.810701083202;3325.9501107483406;3496.4405695088662;3824.541055073409;3959.544652364957;3963.7621742695533;3496.7727097738566;3990;4163.3130533462245;4173.728707633994;4176.874693759192;793.9245771651683;810.8297257575975;817.435969181263;819.2135549872071;819.8893149424871;537.7872896618101;542.4494138449477;555.9583031272671;556.8162506372887;560.9186667933127;8884.939139465105;9486.430814151174;9633.819352206883;9809.705708828747;9858.507074646239;516.19215927074;557.7044166591713;565.5193085876972;580.8809194115196;588.4733163818358;4175.665946724254;4188.023999682092;4386;4583.977080114578;4586.4266966629175;97149;100411;100855;101942;105343.94656053341;83752.88545729456;87751.56124219466;89560.55219723859;89696;92642;67984.6600767003;77157;78292;78669.81995270075;78813.21186788059;8509.787255318666;8511.228030231974;8754.956225218833;8817.691380801642;10620.946895265783;26710.465790684466;29467;34348;35549.289014220085;36530.8173459131;60236.69881650564;61159.694201528706;61285.774284514955;61776;63794.40513987187;12427.75144497123;13527;13735.519256825963;14232.573022809333;14735.410583576966;11987.501562304711;13743.862561374659;13858.263030249196;14723;14858.597256951227;12476.563320283747;12809.615711528297;13248.602541923758;13362.465501379836;13381.598552043455;11343.943280283545;11348.148888833206;11431;11872.643820685393;13042.934785326013;11521.05294503017;13029.937964578126;13034.4134513949;13234.205947643171;13516.93241533786;9330.440173589606;9397.953010235178;9482.762930926781;9527.38072025316;9603.327767056238;7087;7275.308845659654;8615;9115.035875578407;10228.48857557134 09:58:36 INFO - PID 2193 | __end_tp_report 09:58:36 INFO - PID 2193 | __start_cc_report 09:58:36 INFO - PID 2193 | _x_x_mozilla_cycle_collect,429 09:58:36 INFO - PID 2193 | __end_cc_report 09:58:36 INFO - PID 2193 | __startTimestamp1490288316873__endTimestamp 09:58:36 INFO - PID 2193 | 09:58:36 INFO - PID 2193 | ------- Summary: start ------- 09:58:36 INFO - PID 2193 | Number of tests: 6 09:58:36 INFO - PID 2193 | 09:58:36 INFO - PID 2193 | [#0] dojo.html Cycles:165 Average:6035.94 Median:6266.22 stddev:722.78 (11.5%) stddev-sans-first:691.25 09:58:36 INFO - PID 2193 | Values: 3253.5 3480.1 3521.8 3577.9 3586.4 5011.4 6083.1 6149.7 6152.5 6157.7 6036.6 6101.7 6123.3 6130.6 6181.9 5528.9 5566.9 5601.4 5717.7 5722.7 4918.4 5554.8 5606.4 5672.3 5725.7 3546.6 5593.6 5644.9 5662.6 5675.5 4751.0 5147.2 5446.9 5471.3 5541.8 5424.8 5489.6 5514.9 5526.4 5529.3 5945.8 6054.9 6187.4 6224.8 6231.3 6287.7 6383.3 6462.9 6549.5 6564.2 6639.8 6660.4 6666.1 6682.8 6694.9 6264.8 6264.9 6268.8 6277.7 6293.4 6484.8 6495.4 6738.7 6755.9 6767.0 5906.4 6237.1 6442.6 6458.5 6468.3 6428.0 6440.9 6547.6 6630.0 6635.8 6381.2 6517.3 6527.2 6534.9 6536.4 6316.2 6342.7 6474.1 6556.7 6564.1 5241.3 5280.0 5286.5 5294.9 5309.5 4664.7 4670.0 4673.4 4682.8 4684.4 6131.2 6172.2 6277.5 6283.5 6301.6 6416.0 6589.9 6606.8 6649.8 6674.9 6202.5 6228.1 6241.2 6266.2 6274.0 4808.8 5749.6 5832.4 5846.5 5848.2 6448.3 6562.1 6682.1 6714.6 6776.5 6628.9 6636.0 6640.2 6645.8 6655.4 6683.6 6735.9 6754.9 6768.7 6808.0 6260.0 6361.7 6490.8 6491.5 6553.3 6589.0 6640.9 6649.7 6652.0 6669.0 4929.7 6145.3 6348.0 6365.6 6391.8 6298.8 6350.2 6372.2 6389.9 6400.2 5910.8 6159.1 6240.9 6244.3 6301.2 5149.3 6313.0 6371.6 6380.1 6406.1 6032.7 6191.0 6194.1 6213.6 6219.8 09:58:36 INFO - PID 2193 | 09:58:36 INFO - PID 2193 | [#1] ext.html Cycles:180 Average:50375.51 Median:35803.96 stddev:102271.93 (285.6%) stddev-sans-first:102490.73 09:58:36 INFO - PID 2193 | Values: 669.6 709.5 800.5 1274.1 1281.9 32130.0 36423.5 37394.6 37450.8 37899.2 37058.4 37101.3 37269.8 37385.8 37386.7 6597.3 8031.0 8230.1 8475.8 8499.5 8355.3 8427.8 8484.7 8486.5 8490.1 8384.2 8418.5 8498.6 8500.6 8511.4 8451.4 8481.2 8493.1 8493.9 8497.8 8347.2 8397.8 8404.3 8420.1 8424.1 37104.6 37345.1 37391.6 37491.4 37534.8 2648.1 2978.3 2997.6 3019.8 3070.7 27177.3 27209.0 27270.6 28048.6 28435.7 2754.5 2775.1 2781.7 2853.9 2872.4 159679.0 195433.0 271608.6 294803.0 297253.0 47394.0 47458.1 47556.3 47600.3 47613.8 49200.0 49470.8 49516.5 49586.0 49658.8 14318.1 14400.2 14435.0 14441.9 14532.9 11786.3 11819.9 11830.3 11892.6 11935.6 11243.8 13456.0 13493.3 13499.1 13583.9 5061.6 6008.3 6059.6 6068.2 6069.1 948.5 955.9 963.6 994.0 1006.1 28169.4 28475.0 28509.7 28879.3 29220.4 1032.9 1037.2 1056.1 1076.1 1079.1 11641.9 12130.7 12222.4 12242.4 12329.2 471481.0 472028.0 474936.0 475654.0 475718.0 42270.9 45361.3 52627.2 53313.0 53422.0 1453.2 1492.7 1503.7 1506.3 1516.5 17697.8 20559.6 22371.4 23169.8 23513.2 37707.4 45853.8 46836.0 46846.0 46934.8 391087.0 393817.0 394064.0 394600.0 398460.0 26431.6 26915.9 31993.5 32122.9 33132.2 31977.2 37635.0 38622.8 40490.2 42068.0 25577.1 25651.9 29450.9 29506.0 29527.9 28370.6 29649.3 30205.9 30381.5 30590.2 35731.6 35876.3 36019.0 36497.5 36790.0 21904.0 23462.0 29410.7 29676.6 29866.7 20755.6 23533.3 23641.8 23869.8 23918.5 09:58:36 INFO - PID 2193 | 09:58:36 INFO - PID 2193 | [#2] jquery.html Cycles:180 Average:39185.66 Median:26864.43 stddev:112692.26 (419.5%) stddev-sans-first:112970.80 09:58:36 INFO - PID 2193 | Values: 422.1 426.6 427.3 441.4 451.8 20768.7 21445.4 21476.2 21618.2 21961.1 22225.7 22399.2 22829.3 23261.8 23390.6 4366.6 6047.8 6210.1 6249.3 6263.1 6087.2 6172.2 6234.2 6238.3 6244.6 6013.1 6077.3 6132.8 6195.5 6196.6 6103.7 6156.6 6174.7 6175.1 6190.5 5222.1 5402.1 5403.0 5427.3 5435.6 26787.8 26941.1 27022.9 27304.2 27524.0 1620.3 1983.4 1993.4 2018.5 2024.4 12831.8 14145.4 15731.8 15950.8 16473.3 1355.0 1611.4 1625.1 1628.1 1639.1 141689.3 150230.0 153973.0 155238.0 155676.0 11840.9 12597.4 12659.6 12694.2 12732.2 12971.8 16137.1 16187.4 16209.8 16269.9 8713.4 8922.8 8926.0 8927.3 8939.1 8541.6 8556.0 8575.0 8686.4 8732.3 7774.6 7842.3 7902.7 7907.9 7936.6 1803.9 2139.8 2151.8 2156.6 2347.1 807.8 904.8 914.9 944.2 951.9 17178.7 20097.1 20400.7 20480.0 20510.0 904.8 928.4 936.9 958.8 964.4 8906.6 10144.3 10170.2 10377.5 10775.9 659363.0 659421.8 672015.0 682616.0 687027.0 123590.4 123942.4 142680.0 144413.0 151039.5 1046.9 1164.0 1176.4 1230.3 1234.4 20797.5 20902.4 21044.1 21114.8 21346.8 48552.3 50517.7 58000.4 62964.4 63597.5 90645.5 91963.6 92809.0 92992.5 93288.4 8301.3 8416.3 8485.6 8498.5 8640.6 11222.8 11876.3 12394.8 12966.7 13232.1 9958.8 10029.4 10215.5 10219.4 10612.7 9663.7 9697.3 10341.7 10704.6 10705.6 10522.4 10588.0 10828.6 11503.3 11771.8 7460.0 7984.6 8339.7 8371.0 8385.0 9891.0 10519.7 11629.5 11947.7 11970.9 09:58:36 INFO - PID 2193 | 09:58:36 INFO - PID 2193 | [#3] mootools.html Cycles:180 Average:6689.13 Median:3737.32 stddev:4090.13 (109.4%) stddev-sans-first:4081.99 09:58:36 INFO - PID 2193 | Values: 1359.1 1414.8 1419.4 1432.9 1445.9 10530.0 10917.1 11008.8 11009.3 11273.8 9883.9 10375.3 10882.3 10953.2 11050.9 2460.7 2492.6 2501.6 2505.7 2513.0 2098.4 2371.7 2424.7 2438.1 2481.4 2004.0 2434.4 2442.9 2443.1 2496.2 2353.4 2415.5 2426.5 2433.8 2450.6 2396.1 2453.4 2468.8 2471.2 2492.2 11020.6 11085.5 11252.7 11285.6 11333.2 5409.6 6256.4 6385.1 6490.0 6534.2 3682.8 3791.8 3993.1 4066.1 4126.6 1178.7 1186.0 1204.4 1240.6 1245.8 13591.0 13790.1 14670.4 15286.9 15440.5 5266.4 5282.1 5446.6 5513.6 5540.4 11515.3 11522.8 11576.4 11651.2 11669.2 6247.6 6359.7 6359.9 6371.7 6375.0 5746.5 5974.1 5977.9 6045.7 6083.9 4196.6 4319.7 4331.3 4358.8 4398.7 1061.9 1284.8 1287.6 1304.6 1307.2 736.9 752.5 755.8 780.9 793.7 6014.2 6083.9 6144.7 6658.4 6682.9 558.8 571.7 574.6 579.8 587.9 3793.2 3870.7 4215.3 4299.5 4326.5 9980.0 11124.9 11381.6 14160.8 14177.6 13432.9 13434.7 13643.6 14077.5 14124.7 8733.6 10149.6 10323.6 10359.0 10364.0 5674.6 6245.9 6430.5 6583.8 6733.3 10104.6 12086.4 12291.1 12299.9 12363.8 11730.7 12904.7 12956.8 12987.7 13084.5 9018.3 9122.2 9122.2 9132.9 9159.3 9615.0 9992.8 10084.5 10215.2 10225.5 7418.4 8226.2 8507.4 8543.8 8640.0 8474.8 8478.2 8479.4 8510.0 8635.3 7605.3 7715.3 7768.4 7776.4 7787.4 5411.2 5626.7 5738.8 5817.2 5929.3 5645.5 5782.7 5830.5 5938.5 5988.9 09:58:36 INFO - PID 2193 | 09:58:36 INFO - PID 2193 | [#4] prototype.html Cycles:180 Average:5289.73 Median:5905.34 stddev:1645.81 (27.9%) stddev-sans-first:1608.76 09:58:36 INFO - PID 2193 | Values: 386.7 400.1 402.5 409.4 410.9 5136.4 6022.9 6230.8 6291.2 6413.2 4904.5 5934.8 6155.3 6170.0 6205.5 4826.0 4831.7 4843.2 4869.1 4878.5 4044.7 4233.9 4831.9 4843.3 4892.0 3860.0 4376.3 4801.1 4802.0 4824.3 4506.6 4842.3 4842.9 4843.7 4850.3 3436.4 3565.1 3801.9 4000.7 4279.4 5901.4 5996.6 6000.9 6017.8 6024.4 5322.0 5379.9 5402.8 5410.3 5425.3 6398.9 6511.3 6526.5 6547.3 6566.7 3675.0 4106.2 4431.6 4432.5 4436.5 6360.0 6367.6 6483.9 6960.5 6981.0 4041.9 4397.9 4470.7 4506.0 4518.4 4583.5 4604.6 4613.5 4618.6 4644.6 5138.1 5375.0 5410.2 5430.8 5440.7 6050.9 6545.7 6631.6 6637.1 6653.8 1367.5 1385.8 1552.3 1554.3 1573.7 970.8 1028.2 1035.4 1039.5 1070.8 6157.3 6176.7 6188.7 6198.5 6274.3 6861.6 6866.3 6878.2 6893.1 6915.0 5291.6 5897.6 6025.0 6109.7 6233.2 3377.6 3523.0 3595.0 3596.8 3666.4 5445.0 5681.4 6089.4 6815.4 7044.8 5256.9 6385.7 6564.7 6672.8 7017.8 5907.2 6372.4 6404.5 6705.9 7044.5 5903.4 6856.5 6860.3 6876.6 6877.8 4373.4 5618.6 6021.8 6786.9 6858.3 5812.7 7155.2 7171.4 7184.2 7194.7 3161.4 4265.8 4313.6 4525.5 4556.3 6003.4 6371.8 6930.8 6965.3 6981.9 5730.2 5882.0 6656.3 6674.2 6715.0 4810.8 5822.2 6595.2 6645.8 6647.9 5990.4 6439.2 6459.6 6475.3 6482.5 6195.6 6417.2 6515.0 6520.9 6527.3 5685.5 6419.0 6423.8 6441.8 6455.7 09:58:36 INFO - PID 2193 | 09:58:36 INFO - PID 2193 | [#5] yui.html Cycles:180 Average:19737.96 Median:3892.04 stddev:29053.55 (746.5%) stddev-sans-first:29099.43 09:58:36 INFO - PID 2193 | Values: 577.4 700.8 714.2 726.5 750.5 15093.0 15157.5 15613.9 15629.5 15674.2 14749.6 14858.0 14964.6 14971.5 15289.4 914.4 948.7 950.7 951.2 954.2 924.0 949.1 951.9 952.6 952.8 1000.7 1005.6 1011.1 1011.8 1013.2 998.3 998.6 1008.8 1011.1 1012.4 740.7 742.6 745.5 748.0 748.3 15543.7 15795.9 15892.8 16207.8 16517.5 11872.9 14360.5 14368.0 14721.6 14947.8 11361.4 11473.7 11505.0 11601.5 12103.9 2957.9 2962.0 3003.4 3059.1 3145.4 101016.0 109207.0 110830.0 116181.0 117077.0 9395.0 9533.5 10546.0 11215.7 11775.2 25238.1 25246.4 25255.6 25280.0 25303.0 7233.5 7470.1 7497.8 7662.3 7722.8 3326.0 3496.4 3824.5 3959.5 3963.8 3496.8 3990.0 4163.3 4173.7 4176.9 793.9 810.8 817.4 819.2 819.9 537.8 542.4 556.0 556.8 560.9 8884.9 9486.4 9633.8 9809.7 9858.5 516.2 557.7 565.5 580.9 588.5 4175.7 4188.0 4386.0 4584.0 4586.4 97149.0 100411.0 100855.0 101942.0 105343.9 83752.9 87751.6 89560.6 89696.0 92642.0 67984.7 77157.0 78292.0 78669.8 78813.2 8509.8 8511.2 8755.0 8817.7 10620.9 26710.5 29467.0 34348.0 35549.3 36530.8 60236.7 61159.7 61285.8 61776.0 63794.4 12427.8 13527.0 13735.5 14232.6 14735.4 11987.5 13743.9 13858.3 14723.0 14858.6 12476.6 12809.6 13248.6 13362.5 13381.6 11343.9 11348.1 11431.0 11872.6 13042.9 11521.1 13029.9 13034.4 13234.2 13516.9 9330.4 9398.0 9482.8 9527.4 9603.3 7087.0 7275.3 8615.0 9115.0 10228.5 09:58:36 INFO - PID 2193 | -------- Summary: end -------- 09:58:36 INFO - PID 2193 | 09:58:37 INFO - TEST-INFO | 2193: exit 0 09:58:37 INFO - TEST-OK | dromaeo_css | took 1117693ms 09:58:37 INFO - TEST-START | kraken 09:58:37 INFO - operating with platform_type : mac_ 09:58:37 INFO - Initialising browser for kraken test... 09:58:37 INFO - TEST-INFO | started process 2199 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/tmpjeBwoP/profile http://localhost:55248/getInfo.html) 09:58:40 INFO - PID 2199 | 2017-03-23 09:58:40.281 plugin-container[2200:24041] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 09:58:40 INFO - PID 2199 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:58:40 INFO - PID 2199 | 2017-03-23 09:58:40.284 plugin-container[2200:24041] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9103, name = 'com.apple.CFPasteboardClient' 09:58:40 INFO - PID 2199 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:58:40 INFO - PID 2199 | 2017-03-23 09:58:40.284 plugin-container[2200:24041] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:58:40 INFO - PID 2199 | __metrics Screen width/height:1600/1200 09:58:40 INFO - PID 2199 | colorDepth:24 09:58:40 INFO - PID 2199 | Browser inner width/height: 1024/689 09:58:40 INFO - PID 2199 | __metrics 09:58:40 ERROR - PID 2199 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3388: TypeError: this.tabs is undefined 09:58:41 INFO - TEST-INFO | 2199: exit 0 09:58:41 INFO - Browser initialized. 09:58:41 INFO - Running cycle 1/1 for kraken test... 09:58:41 INFO - TEST-INFO | started process 2201 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/T/tmpjeBwoP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:58:43 INFO - PID 2201 | 2017-03-23 09:58:43.809 plugin-container[2202:24165] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x443f, name = 'com.apple.tsm.portname' 09:58:43 INFO - PID 2201 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:58:43 INFO - PID 2201 | 2017-03-23 09:58:43.810 plugin-container[2202:24165] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4723, name = 'com.apple.CFPasteboardClient' 09:58:43 INFO - PID 2201 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:58:43 INFO - PID 2201 | 2017-03-23 09:58:43.811 plugin-container[2202:24165] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:58:53 INFO - PID 2201 | RSS: Main: 227344384 09:58:53 INFO - PID 2201 | 09:58:56 INFO - PID 2201 | Cycle 1(1): loaded http://localhost:55248/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55248/tests/kraken/driver.html?testName=audio-beat-detection) 09:58:57 INFO - PID 2201 | RSS: Main: 241532928 09:58:57 INFO - PID 2201 | 09:58:59 INFO - PID 2201 | Cycle 1(1): loaded http://localhost:55248/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55248/tests/kraken/driver.html?testName=audio-dft) 09:59:00 INFO - PID 2201 | RSS: Main: 249942016 09:59:00 INFO - PID 2201 | 09:59:03 INFO - PID 2201 | Cycle 1(1): loaded http://localhost:55248/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55248/tests/kraken/driver.html?testName=audio-fft) 09:59:04 INFO - PID 2201 | RSS: Main: 252338176 09:59:04 INFO - PID 2201 | 09:59:06 INFO - PID 2201 | Cycle 1(1): loaded http://localhost:55248/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55248/tests/kraken/driver.html?testName=audio-oscillator) 09:59:07 INFO - PID 2201 | RSS: Main: 254627840 09:59:07 INFO - PID 2201 | 09:59:09 INFO - PID 2201 | Cycle 1(1): loaded http://localhost:55248/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55248/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:59:09 INFO - PID 2201 | RSS: Main: 254980096 09:59:09 INFO - PID 2201 | 09:59:13 INFO - PID 2201 | Cycle 1(1): loaded http://localhost:55248/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55248/tests/kraken/driver.html?testName=imaging-darkroom) 09:59:13 INFO - PID 2201 | RSS: Main: 260616192 09:59:13 INFO - PID 2201 | 09:59:16 INFO - PID 2201 | Cycle 1(1): loaded http://localhost:55248/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55248/tests/kraken/driver.html?testName=imaging-desaturate) 09:59:17 INFO - PID 2201 | RSS: Main: 261853184 09:59:17 INFO - PID 2201 | 09:59:20 INFO - PID 2201 | Cycle 1(1): loaded http://localhost:55248/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55248/tests/kraken/driver.html?testName=json-parse-financial) 09:59:21 INFO - PID 2201 | RSS: Main: 261623808 09:59:21 INFO - PID 2201 | 09:59:22 INFO - PID 2201 | Cycle 1(1): loaded http://localhost:55248/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55248/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:59:23 INFO - PID 2201 | RSS: Main: 261566464 09:59:23 INFO - PID 2201 | 09:59:25 INFO - PID 2201 | Cycle 1(1): loaded http://localhost:55248/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55248/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:59:26 INFO - PID 2201 | RSS: Main: 261578752 09:59:26 INFO - PID 2201 | 09:59:28 INFO - PID 2201 | Cycle 1(1): loaded http://localhost:55248/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55248/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:59:28 INFO - PID 2201 | RSS: Main: 261554176 09:59:28 INFO - PID 2201 | 09:59:31 INFO - PID 2201 | Cycle 1(1): loaded http://localhost:55248/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:59:32 INFO - PID 2201 | RSS: Main: 261873664 09:59:32 INFO - PID 2201 | 09:59:36 INFO - PID 2201 | Cycle 1(1): loaded http://localhost:55248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:59:36 INFO - PID 2201 | RSS: Main: 259579904 09:59:36 INFO - PID 2201 | 09:59:38 INFO - PID 2201 | Cycle 1(1): loaded http://localhost:55248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55248/tests/kraken/driver.html?testName=ai-astar) 09:59:39 INFO - PID 2201 | RSS: Main: 262664192 09:59:39 INFO - PID 2201 | 09:59:39 INFO - PID 2201 | __start_tp_report 09:59:39 INFO - PID 2201 | _x_x_mozilla_page_load 09:59:39 INFO - PID 2201 | _x_x_mozilla_page_load_details 09:59:39 INFO - PID 2201 | |i|pagename|runs| 09:59:39 INFO - PID 2201 | |0;ai-astar;103;118;116;114;115;117;117;110;116;118 09:59:39 INFO - PID 2201 | |1;audio-beat-detection;151;115;112;153;111;114;114;139;173;116 09:59:39 INFO - PID 2201 | |2;audio-dft;199;200;243;272;243;201;194;209;200;205 09:59:39 INFO - PID 2201 | |3;audio-fft;89;58;59;86;61;66;50;78;106;87 09:59:39 INFO - PID 2201 | |4;audio-oscillator;78;71;87;72;71;72;86;71;76;117 09:59:39 INFO - PID 2201 | |5;imaging-gaussian-blur;127;105;89;91;106;138;92;136;92;136 09:59:39 INFO - PID 2201 | |6;imaging-darkroom;97;98;105;117;97;99;99;97;98;106 09:59:39 INFO - PID 2201 | |7;imaging-desaturate;91;95;91;105;90;91;92;92;90;93 09:59:39 INFO - PID 2201 | |8;json-parse-financial;67;67;87;106;70;68;68;69;70;68 09:59:39 INFO - PID 2201 | |9;json-stringify-tinderbox;65;58;59;75;67;65;61;85;88;90 09:59:39 INFO - PID 2201 | |10;stanford-crypto-aes;86;86;87;86;84;85;83;86;86;84 09:59:39 INFO - PID 2201 | |11;stanford-crypto-ccm;143;165;152;167;125;134;161;147;217;154 09:59:39 INFO - PID 2201 | |12;stanford-crypto-pbkdf2;184;246;186;224;245;228;202;247;235;187 09:59:39 INFO - PID 2201 | |13;stanford-crypto-sha256-iterative;71;84;102;100;67;73;100;104;92;93 09:59:39 INFO - PID 2201 | __end_tp_report 09:59:39 INFO - PID 2201 | __start_cc_report 09:59:39 INFO - PID 2201 | _x_x_mozilla_cycle_collect,1027 09:59:39 INFO - PID 2201 | __end_cc_report 09:59:39 INFO - PID 2201 | __startTimestamp1490288379179__endTimestamp 09:59:39 INFO - PID 2201 | 09:59:39 INFO - PID 2201 | ------- Summary: start ------- 09:59:39 INFO - PID 2201 | Number of tests: 14 09:59:39 INFO - PID 2201 | 09:59:39 INFO - PID 2201 | [#0] ai-astar Cycles:10 Average:114.40 Median:116.50 stddev:4.65 (4.0%) stddev-sans-first:2.50 09:59:39 INFO - PID 2201 | Values: 103.0 118.0 116.0 114.0 115.0 117.0 117.0 110.0 116.0 118.0 09:59:39 INFO - PID 2201 | 09:59:39 INFO - PID 2201 | [#1] audio-beat-detection Cycles:10 Average:129.80 Median:127.50 stddev:22.40 (17.6%) stddev-sans-first:22.41 09:59:39 INFO - PID 2201 | Values: 151.0 115.0 112.0 153.0 111.0 114.0 114.0 139.0 173.0 116.0 09:59:39 INFO - PID 2201 | 09:59:39 INFO - PID 2201 | [#2] audio-dft Cycles:10 Average:216.60 Median:207.00 stddev:26.40 (12.8%) stddev-sans-first:27.22 09:59:39 INFO - PID 2201 | Values: 199.0 200.0 243.0 272.0 243.0 201.0 194.0 209.0 200.0 205.0 09:59:39 INFO - PID 2201 | 09:59:39 INFO - PID 2201 | [#3] audio-fft Cycles:10 Average:74.00 Median:72.00 stddev:17.85 (24.8%) stddev-sans-first:18.09 09:59:39 INFO - PID 2201 | Values: 89.0 58.0 59.0 86.0 61.0 66.0 50.0 78.0 106.0 87.0 09:59:39 INFO - PID 2201 | 09:59:39 INFO - PID 2201 | [#4] audio-oscillator Cycles:10 Average:80.10 Median:74.00 stddev:14.32 (19.3%) stddev-sans-first:15.17 09:59:39 INFO - PID 2201 | Values: 78.0 71.0 87.0 72.0 71.0 72.0 86.0 71.0 76.0 117.0 09:59:39 INFO - PID 2201 | 09:59:39 INFO - PID 2201 | [#5] imaging-gaussian-blur Cycles:10 Average:111.20 Median:113.50 stddev:20.82 (18.3%) stddev-sans-first:21.28 09:59:39 INFO - PID 2201 | Values: 127.0 105.0 89.0 91.0 106.0 138.0 92.0 136.0 92.0 136.0 09:59:39 INFO - PID 2201 | 09:59:39 INFO - PID 2201 | [#6] imaging-darkroom Cycles:10 Average:101.30 Median:97.50 stddev:6.41 (6.6%) stddev-sans-first:6.61 09:59:39 INFO - PID 2201 | Values: 97.0 98.0 105.0 117.0 97.0 99.0 99.0 97.0 98.0 106.0 09:59:39 INFO - PID 2201 | 09:59:39 INFO - PID 2201 | [#7] imaging-desaturate Cycles:10 Average:93.00 Median:91.50 stddev:4.47 (4.9%) stddev-sans-first:4.68 09:59:39 INFO - PID 2201 | Values: 91.0 95.0 91.0 105.0 90.0 91.0 92.0 92.0 90.0 93.0 09:59:39 INFO - PID 2201 | 09:59:39 INFO - PID 2201 | [#8] json-parse-financial Cycles:10 Average:74.00 Median:68.50 stddev:12.72 (18.6%) stddev-sans-first:13.24 09:59:39 INFO - PID 2201 | Values: 67.0 67.0 87.0 106.0 70.0 68.0 68.0 69.0 70.0 68.0 09:59:39 INFO - PID 2201 | 09:59:39 INFO - PID 2201 | [#9] json-stringify-tinderbox Cycles:10 Average:71.30 Median:71.00 stddev:12.30 (17.3%) stddev-sans-first:12.84 09:59:39 INFO - PID 2201 | Values: 65.0 58.0 59.0 75.0 67.0 65.0 61.0 85.0 88.0 90.0 09:59:39 INFO - PID 2201 | 09:59:39 INFO - PID 2201 | [#10] stanford-crypto-aes Cycles:10 Average:85.30 Median:86.00 stddev:1.25 (1.5%) stddev-sans-first:1.30 09:59:39 INFO - PID 2201 | Values: 86.0 86.0 87.0 86.0 84.0 85.0 83.0 86.0 86.0 84.0 09:59:39 INFO - PID 2201 | 09:59:39 INFO - PID 2201 | [#11] stanford-crypto-ccm Cycles:10 Average:156.50 Median:157.50 stddev:25.08 (15.9%) stddev-sans-first:26.12 09:59:39 INFO - PID 2201 | Values: 143.0 165.0 152.0 167.0 125.0 134.0 161.0 147.0 217.0 154.0 09:59:39 INFO - PID 2201 | 09:59:39 INFO - PID 2201 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:218.40 Median:231.50 stddev:26.19 (11.3%) stddev-sans-first:24.65 09:59:39 INFO - PID 2201 | Values: 184.0 246.0 186.0 224.0 245.0 228.0 202.0 247.0 235.0 187.0 09:59:39 INFO - PID 2201 | 09:59:39 INFO - PID 2201 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:88.60 Median:72.00 stddev:13.94 (19.4%) stddev-sans-first:13.25 09:59:39 INFO - PID 2201 | Values: 71.0 84.0 102.0 100.0 67.0 73.0 100.0 104.0 92.0 93.0 09:59:39 INFO - PID 2201 | -------- Summary: end -------- 09:59:39 INFO - PID 2201 | 09:59:40 INFO - TEST-INFO | 2201: exit 0 09:59:40 INFO - TEST-OK | kraken | took 62552ms 09:59:40 INFO - SUITE-END | took 1180s 09:59:40 INFO - Completed test suite (00:19:40) 09:59:40 INFO - javascript benchmark 09:59:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7549.572534196819, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3253.4794432890744, 3480.1125712943212, 3521.753477256593, 3577.856885724571, 3586.4441011643203, 5011.398632164145, 6083.0875368694715, 6149.69251537423, 6152.538559608036, 6157.692115394229, 6036.607620504664, 6101.725422355994, 6123.265208175025, 6130.632162070279, 6181.876362472748, 5528.889422211553, 5566.888662226743, 5601.383847776741, 5717.684932465535, 5722.713864306768, 4918.36061312029, 5554.792369887752, 5606.439356064402, 5672.262605861232, 5725.683092888638, 3546.627604101571, 5593.636413633141, 5644.858878528049, 5662.575306851982, 5675.489205971462, 4751.0497900419705, 5147.150720131174, 5446.91829622556, 5471.343438787331, 5541.778328866841, 5424.810131645413, 5489.643173193769, 5514.944850551524, 5526.364468086182, 5529.281193444866, 5945.791897283574, 6054.878902421927, 6187.443130118266, 6224.844378890519, 6231.345708700612, 6287.6856157192415, 6383.2978372379, 6462.935370646281, 6549.476041916635, 6564.179477565304, 6639.800805975829, 6660.400563949268, 6666.100076489709, 6682.799516014527, 6694.866102677968, 6264.780732674426, 6264.906026409653, 6268.811935641938, 6277.7175027123885, 6293.433590976788, 6484.805455836333, 6495.382938620824, 6738.663066846638, 6755.93244067553, 6767.0, 5906.40935906406, 6237.126802247689, 6442.581232219891, 6458.515611329169, 6468.288488266287, 6427.967860160669, 6440.871182576322, 6547.574407663486, 6630.0, 6635.767748128889, 6381.1704478417505, 6517.250516190604, 6527.249366322839, 6534.869302614016, 6536.379043990813, 6316.178896743394, 6342.682865856688, 6474.12599299098, 6556.737730490727, 6564.146660934143, 5241.31862857826, 5280.049591073644, 5286.497782710637, 5294.92057619136, 5309.495597918269, 4664.743439110882, 4669.95330046702, 4673.415823022122, 4682.81268749253, 4684.4378674559275, 6131.172291740647, 6172.166757487682, 6277.52918531112, 6283.497320214463, 6301.590396624205, 6416.03759436079, 6589.9341006590275, 6606.8017959461295, 6649.767258145942, 6674.8998765017595, 6202.534809889276, 6228.128062071313, 6241.21984751906, 6266.216722909637, 6274.0, 4808.783604737795, 5749.568782341341, 5832.416758324133, 5846.492104737209, 5848.239728835224, 6448.290688024314, 6562.081308616702, 6682.064510968371, 6714.597124172566, 6776.457883369417, 6628.900566491603, 6635.966820166062, 6640.169978752656, 6645.767398141042, 6655.367740064686, 6683.598984060972, 6735.898961515483, 6754.8649027020165, 6768.661566921536, 6807.965960170168, 6260.0, 6361.7137228823885, 6490.805275841543, 6491.513136514686, 6553.344665533408, 6589.0, 6640.867182656223, 6649.700763465559, 6652.002199670088, 6668.966655166693, 4929.704217746947, 6145.324014358328, 6347.952587822958, 6365.61806291624, 6391.808245752635, 6298.811035668754, 6350.174477317919, 6372.171617689671, 6389.87220255583, 6400.167978162809, 5910.793122240873, 6159.076138579249, 6240.906386404116, 6244.2819075806865, 6301.243850737941, 5149.253358262956, 6313.0, 6371.585846919936, 6380.1067850501895, 6406.135171751754, 6032.728527216374, 6191.0, 6194.101855230876, 6213.627182369072, 6219.844503887439], "value": 5990.438575776948, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [669.5848573884192, 709.5317090720124, 800.4516905919446, 1274.1272228523455, 1281.9230846149233, 32130.0, 36423.4536481953, 37394.626053739456, 37450.812745936266, 37899.24201515972, 37058.44412333817, 37101.25797484049, 37269.81365093178, 37385.81307093461, 37386.6914657987, 6597.307282735315, 8030.959845200766, 8230.135835737252, 8475.83048339033, 8499.532525711084, 8355.331573474108, 8427.83144337113, 8484.745457636282, 8486.490810551355, 8490.10853860345, 8384.161583841598, 8418.452800567975, 8498.575071246412, 8500.617472213766, 8511.404201705884, 8451.408401411903, 8481.194286542768, 8493.108223636491, 8493.915060849375, 8497.787555311104, 8347.207015333533, 8397.832043359098, 8404.327653787683, 8420.073791882887, 8424.115467875907, 37104.62895371039, 37345.06637334089, 37391.62608373909, 37491.4376284356, 37534.8123259382, 2648.0599387217644, 2978.3447641518833, 2997.5503674448787, 3019.773516986235, 3070.6929307069336, 27177.320566985785, 27209.0, 27270.5909411359, 28048.579271310948, 28435.715642843512, 2754.5454999924987, 2775.111964171466, 2781.735735105162, 2853.857307134656, 2872.4255148970287, 159679.0, 195433.0, 271608.64195679093, 294803.0, 297253.0, 47394.0, 47458.05083898372, 47556.28665570019, 47600.285995710095, 47613.76193119012, 49200.0159996798, 49470.752646237255, 49516.50483495191, 49586.0, 49658.75170624196, 14318.069325494018, 14400.20798856052, 14435.0, 14441.85558144426, 14532.927335363467, 11786.29282243051, 11819.936205741524, 11830.349330786717, 11892.643220703392, 11935.582254621047, 11243.775124497464, 13456.0, 13493.325333733274, 13499.055066145473, 13583.86416135826, 5061.620378471629, 6008.279006519246, 6059.575829691968, 6068.241469816273, 6069.147500674764, 948.4878165790396, 955.861400096982, 963.5760265483165, 993.9900600994042, 1006.1498034161123, 28169.43661126766, 28475.00337488178, 28509.71490285112, 28879.278018049295, 29220.415591688474, 1032.9070383665505, 1037.1754455208127, 1056.0917610854658, 1076.1444651502075, 1079.06121674144, 11641.941790290994, 12130.696732581757, 12222.449989750305, 12242.44908979098, 12329.198602090835, 471481.0, 472028.0, 474936.0, 475654.0, 475718.0, 42270.94322641897, 45361.31958020632, 52627.210591841154, 53313.0, 53422.0, 1453.2007395932194, 1492.7462331403688, 1503.7443634582148, 1506.284514855435, 1516.4692357674726, 17697.82302177013, 20559.588808223452, 22371.44071398228, 23169.768302316763, 23513.17703880356, 37707.43438848365, 45853.77073114613, 46836.0, 46846.0, 46934.765326173154, 391087.0, 393817.0, 394064.0, 394600.0, 398460.02309964795, 26431.603525946743, 26915.92336306526, 31993.520097199027, 32122.87569935041, 33132.1716957078, 31977.200569985936, 37635.0, 38622.806885966515, 40490.1901961965, 42068.0, 25577.104801331865, 25651.871740641178, 29450.852745736993, 29506.0, 29527.86609874662, 28370.57444138298, 29649.25876853096, 30205.942792002174, 30381.544276836306, 30590.235244119074, 35731.642683572834, 35876.28247434984, 36019.0, 36497.452538211415, 36790.0, 21904.0, 23462.0, 29410.705892941653, 29676.55485167681, 29866.70133298639, 20755.584888302452, 23533.29400117999, 23641.763582364645, 23869.761302386753, 23918.52162956766], "value": 17258.17136661778, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [422.07565431704586, 426.56532508640373, 427.3333599584648, 441.4217375238438, 451.8418553506273, 20768.688469672947, 21445.356639300808, 21476.241657709877, 21618.243361482353, 21961.121555137815, 22225.666615000788, 22399.216027439, 22829.31512054637, 23261.767382326172, 23390.64914026291, 4366.585174408426, 6047.848803779919, 6210.068489726532, 6249.250089989185, 6263.123162757218, 6087.178230938833, 6172.166757487749, 6234.189555357797, 6238.344973777734, 6244.56288059836, 6013.128096426016, 6077.3314935357075, 6132.816015519519, 6195.504359651217, 6196.628202307854, 6103.664298463583, 6156.56904016719, 6174.66039367839, 6175.104609831594, 6190.473809726179, 5222.138347172712, 5402.081646120129, 5402.972985135049, 5427.267318911936, 5435.565154787608, 26787.79454924533, 26941.057063002896, 27022.86488567564, 27304.180874573594, 27524.0, 1620.335662378419, 1983.4446355020618, 1993.3820515640198, 2018.5256464730776, 2024.4230394337544, 12831.807522887164, 14145.434182632682, 15731.82011349142, 15950.840491595052, 16473.337561107226, 1354.9661258468518, 1611.3796188467525, 1625.089949628212, 1628.145223757525, 1639.098495827302, 141689.29155354158, 150230.0, 153973.0, 155238.0, 155676.0, 11840.940795295968, 12597.370131493388, 12659.556915507914, 12694.238345699288, 12732.172408793398, 12971.767682070189, 16137.112458814878, 16187.433439829549, 16209.83790162107, 16269.918650406673, 8713.390062695551, 8922.776930576658, 8925.955370223108, 8927.33045021626, 8939.061398553189, 8541.572921353907, 8556.0, 8575.0, 8686.391952563386, 8732.257758090633, 7774.611269436506, 7842.254985776342, 7902.683892644345, 7907.920920790833, 7936.6031698414845, 1803.92784288627, 2139.8074173324476, 2151.8386121040985, 2156.590247852903, 2347.1223021582664, 807.834393949239, 904.7602385367883, 914.9432405571575, 944.15498129174, 951.9428834269966, 17178.656426871392, 20097.095630696655, 20400.693989590167, 20480.0, 20510.0, 904.7740311877443, 928.3501548915785, 936.8735220745249, 958.7842735384622, 964.358701463533, 8906.643734250558, 10144.28989970695, 10170.186385089175, 10377.533011014522, 10775.892241077645, 659363.0, 659421.8115637661, 672015.0, 682616.0, 687027.0, 123590.38204808919, 123942.38028809978, 142680.0, 144413.0, 151039.48960510475, 1046.890076541952, 1163.959261425846, 1176.4294317256124, 1230.2741382584318, 1234.4198226833373, 20797.48006299794, 20902.37292881216, 21044.071720064647, 21114.788852111284, 21346.75859034779, 48552.271715924995, 50517.74741126271, 58000.4199957995, 62964.37035629768, 63597.456101755415, 90645.54677226572, 91963.62054569053, 92809.0, 92992.53503732439, 93288.3659346968, 8301.335893128657, 8416.28461580748, 8485.575721214038, 8498.53258070812, 8640.611172497127, 11222.82160373149, 11876.346800925687, 12394.75210495803, 12966.675833104247, 13232.073754837069, 9958.751031224278, 10029.44838033886, 10215.540300686338, 10219.437930913871, 10612.681619551426, 9663.710088697351, 9697.32118751709, 10341.689749307532, 10704.625338113128, 10705.625303114353, 10522.421266830397, 10588.047075763217, 10828.620998265023, 11503.311979563736, 11771.823422648496, 7459.962700186465, 7984.640691169027, 8339.708110216114, 8370.958145209479, 8384.958075209584, 9891.010898910052, 10519.684409467729, 11629.476673549541, 11947.701307467383, 11970.880291197325], "value": 10130.409163344591, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1359.1369480379958, 1414.8231471066117, 1419.41094445805, 1432.91402515849, 1445.869871711546, 10530.0, 10917.072048875854, 11008.834867476984, 11009.339439633628, 11273.774524509505, 9883.91276959534, 10375.273730838844, 10882.347059176454, 10953.17851161164, 11050.944745276262, 2460.692413448319, 2492.5887228607257, 2501.562226610336, 2505.6742623458927, 2513.0, 2098.412444515538, 2371.691680081587, 2424.7454017328105, 2438.0979037756056, 2481.4292712676097, 2004.0180311647334, 2434.3914021494625, 2442.8656423896678, 2443.1082654831034, 2496.1762618335997, 2353.352827972304, 2415.492746523232, 2426.4783071639595, 2433.7809597136256, 2450.5711500487523, 2396.0894859953105, 2453.374389530676, 2468.790152837011, 2471.159805666064, 2492.2274095030416, 11020.614278500214, 11085.50115244816, 11252.718682032933, 11285.605003824827, 11333.206675532716, 5409.648372855791, 6256.436920677115, 6385.074164246204, 6489.967550162266, 6534.183227096613, 3682.797446140461, 3791.848326066954, 3993.101552150772, 4066.1054567994993, 4126.628603425706, 1178.723000094977, 1185.9762804743857, 1204.3556697167062, 1240.640214337841, 1245.806899930503, 13591.0, 13790.103643263255, 14670.413183472541, 15286.923565382102, 15440.45958391451, 5266.368035835648, 5282.128448805981, 5446.564274858081, 5513.58648101394, 5540.36285827127, 11515.25150865191, 11522.827157592643, 11576.36330001841, 11651.242669226473, 11669.241499302518, 6247.562670613013, 6359.650219237894, 6359.904601430982, 6371.713272902731, 6375.043743438521, 5746.482816546531, 5974.103884417285, 5977.880442391128, 6045.697715114226, 6083.9391606084255, 4196.643285320721, 4319.719218250803, 4331.306990881507, 4358.847440339573, 4398.714083584557, 1061.856649352343, 1284.794432890733, 1287.620152055146, 1304.6347022833622, 1307.2091384712112, 736.8578703010313, 752.5221484357363, 755.8110472381904, 780.9180036096242, 793.686493834932, 6014.218151640259, 6083.878322433527, 6144.723487443076, 6658.4340331071435, 6682.866342673119, 558.7651290647633, 571.7027145884117, 574.635106707244, 579.7593998490579, 587.9470847623735, 3793.203427280302, 3870.651641352292, 4215.304474761691, 4299.462567179103, 4326.545712700184, 9979.950100249598, 11124.88751124881, 11381.577302837146, 14160.78758818598, 14177.645558860904, 13432.932835335761, 13434.731305373838, 13643.59069227905, 14077.507287244896, 14124.717505649829, 8733.563321833883, 10149.64476243328, 10323.63867264642, 10359.0, 10363.999999999849, 5674.57440691958, 6245.937540624535, 6430.453411460078, 6583.80248592543, 6733.293004234485, 10104.595816167564, 12086.395680215777, 12291.078169137172, 12299.93850030744, 12363.75272494563, 11730.70673233176, 12904.677383065124, 12956.8056479151, 12987.740245195233, 13084.542041028899, 9018.323625727966, 9122.179003889682, 9122.224610908148, 9132.863007054766, 9159.313051521296, 9614.95192524033, 9992.800143997225, 10084.54619542137, 10215.233857460571, 10225.48872556384, 7418.406527477897, 8226.177382261725, 8507.361947853811, 8543.829123417621, 8639.95680021596, 8474.788130296793, 8478.23695867375, 8479.448835825651, 8509.957450212709, 8635.309175265838, 7605.277498637621, 7715.267049630276, 7768.378529717722, 7776.377889768918, 7787.377009839312, 5411.2153737708595, 5626.690532020778, 5738.770449181986, 5817.2146760188525, 5929.288485381781, 5645.520130788929, 5782.739776709974, 5830.53355731549, 5938.495227905676, 5988.850278743066], "value": 5077.389592283324, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.70803543324786, 400.1436924980541, 402.49084907591885, 409.4308910614246, 410.9301418758811, 5136.3579552555975, 6022.904424991005, 6230.781922632709, 6291.15069465622, 6413.198350206224, 4904.534069263424, 5934.821955341346, 6155.26136863577, 6170.0, 6205.503559715224, 4826.0, 4831.68594041387, 4843.152448321547, 4869.147899117658, 4878.512148785111, 4044.6562042226446, 4233.941514621345, 4831.855044348657, 4843.297721830332, 4891.975540122295, 3859.9614003859883, 4376.277914144162, 4801.087793319258, 4801.951980480186, 4824.324594556747, 4506.59440650343, 4842.297866809293, 4842.9031419371595, 4843.733594652311, 4850.253908592904, 3436.4020730360253, 3565.1265439967374, 3801.851953646181, 4000.6999475039197, 4279.358096285582, 5901.406620212519, 5996.550258730569, 6000.879982400371, 6017.789377371771, 6024.397560243984, 5322.04203243416, 5379.946200538023, 5402.838389746224, 5410.269613602152, 5425.321834770653, 6398.936010639881, 6511.251206111263, 6526.477881769447, 6547.345265473462, 6566.737330506774, 3675.0, 4106.2198182345255, 4431.578999994996, 4432.468103827529, 4436.511983681792, 6360.0, 6367.5861069030825, 6483.902741458882, 6960.477964152658, 6981.0, 4041.939370909439, 4397.934030989538, 4470.709403888802, 4505.954940450553, 4518.43519560055, 4583.472900616405, 4604.562566556172, 4613.5386461354265, 4618.630509559227, 4644.606618014582, 5138.075146473596, 5374.973125134349, 5410.1614249790555, 5430.755615997289, 5440.721430463838, 6050.909236361458, 6545.738170473128, 6631.551593171524, 6637.070810086591, 6653.835578773792, 1367.4803574641574, 1385.8198434203691, 1552.3480138341924, 1554.3471741868443, 1573.732465480871, 970.7670159161751, 1028.1928685981468, 1035.409816404642, 1039.4854546999284, 1070.8072546941469, 6157.322694503601, 6176.657191529295, 6188.721507532172, 6198.535109866688, 6274.309825919146, 6861.622610756356, 6866.313368663094, 6878.209005964378, 6893.1398416886705, 6914.965425172943, 5291.629585929025, 5897.64614123154, 6024.97575412181, 6109.747501762128, 6233.189685340877, 3377.6115746689447, 3523.0, 3594.993401847487, 3596.8021758803516, 3666.4133738602227, 5445.019896418604, 5681.358168451689, 6089.421504957022, 6815.386615204656, 7044.753433629799, 5256.922330922152, 6385.69093335865, 6564.67176641166, 6672.799816005528, 7017.824554386181, 5907.232059832194, 6372.426481616584, 6404.477537354436, 6705.932940670531, 7044.471664625071, 5903.439173278532, 6856.5200435969, 6860.279670634512, 6876.587404755731, 6877.828054298682, 4373.387725718466, 5618.550515958632, 6021.819345419644, 6786.93213067863, 6858.3141685831015, 5812.651240925558, 7155.248698886542, 7171.42628589722, 7184.209736928829, 7194.70495310849, 3161.4151381994407, 4265.808038638331, 4313.641203021039, 4525.456945166601, 4556.316552517149, 6003.369646187088, 6371.808845734636, 6930.826729331807, 6965.33829286217, 6981.930180698128, 5730.169125476699, 5882.000119959234, 6656.334366563498, 6674.199096108498, 6715.026321183499, 4810.759462026955, 5822.155787410887, 6595.208574971034, 6645.800625981228, 6647.867042659216, 5990.400959903975, 6439.195100612424, 6459.644719540471, 6475.287718351072, 6482.513811464253, 6195.597286176384, 6417.2299324081405, 6515.0, 6520.902186467112, 6527.281998980204, 5685.516731077904, 6418.972964325685, 6423.839404014937, 6441.806745797634, 6455.709493072917], "value": 4779.609941818911, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [577.3533642320605, 700.8107810891063, 714.2000958926004, 726.4696771356909, 750.5159172333845, 15093.0, 15157.545273641781, 15613.921930390361, 15629.452969146083, 15674.216289185551, 14749.631259218524, 14858.0, 14964.625884352896, 14971.47599834006, 15289.388424463035, 914.387360468486, 948.7485816258662, 950.6625148072438, 951.1819834941945, 954.1555723184962, 923.9630414783401, 949.0936155971066, 951.8619800128977, 952.6379975609259, 952.8427809411439, 1000.67978246961, 1005.5826831864767, 1011.0698157694939, 1011.7774089700254, 1013.2096964367805, 998.2762497189516, 998.5656239535753, 1008.8184126857162, 1011.1152741351318, 1012.4026824173699, 740.7185269597572, 742.6175519607407, 745.4856149257049, 747.9813004674927, 748.3115533709001, 15543.689126217525, 15795.921020394939, 15892.841071589251, 16207.756883646754, 16517.504474865775, 11872.853693771158, 14360.497382591664, 14368.0, 14721.55835324942, 14947.775783363259, 11361.375124368156, 11473.713157171054, 11505.0, 11601.477933493014, 12103.878961210452, 2957.9112626621236, 2961.9851900740573, 3003.354278830062, 3059.0669845697053, 3145.4495463293833, 101015.98984010139, 109207.0, 110830.0, 116181.0, 117077.0, 9394.95302523483, 9533.52332383378, 10546.0, 11215.719607009727, 11775.23460975034, 25238.116665916605, 25246.36884077876, 25255.62116568253, 25280.0, 25303.0, 7233.529820561646, 7470.140933792574, 7497.812554686177, 7662.310392064741, 7722.810701083202, 3325.9501107483406, 3496.4405695088662, 3824.541055073409, 3959.544652364957, 3963.7621742695533, 3496.7727097738566, 3990.0, 4163.3130533462245, 4173.728707633994, 4176.874693759192, 793.9245771651683, 810.8297257575975, 817.435969181263, 819.2135549872071, 819.8893149424871, 537.7872896618101, 542.4494138449477, 555.9583031272671, 556.8162506372887, 560.9186667933127, 8884.939139465105, 9486.430814151174, 9633.819352206883, 9809.705708828747, 9858.507074646239, 516.19215927074, 557.7044166591713, 565.5193085876972, 580.8809194115196, 588.4733163818358, 4175.665946724254, 4188.023999682092, 4386.0, 4583.977080114578, 4586.4266966629175, 97149.0, 100411.0, 100855.0, 101942.0, 105343.94656053341, 83752.88545729456, 87751.56124219466, 89560.55219723859, 89696.0, 92642.0, 67984.6600767003, 77157.0, 78292.0, 78669.81995270075, 78813.21186788059, 8509.787255318666, 8511.228030231974, 8754.956225218833, 8817.691380801642, 10620.946895265783, 26710.465790684466, 29467.0, 34348.0, 35549.289014220085, 36530.8173459131, 60236.69881650564, 61159.694201528706, 61285.774284514955, 61776.0, 63794.40513987187, 12427.75144497123, 13527.0, 13735.519256825963, 14232.573022809333, 14735.410583576966, 11987.501562304711, 13743.862561374659, 13858.263030249196, 14723.0, 14858.597256951227, 12476.563320283747, 12809.615711528297, 13248.602541923758, 13362.465501379836, 13381.598552043455, 11343.943280283545, 11348.148888833206, 11431.0, 11872.643820685393, 13042.934785326013, 11521.05294503017, 13029.937964578126, 13034.4134513949, 13234.205947643171, 13516.93241533786, 9330.440173589606, 9397.953010235178, 9482.762930926781, 9527.38072025316, 9603.327767056238, 7087.0, 7275.308845659654, 8615.0, 9115.035875578407, 10228.48857557134], "value": 7284.327360366282, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1614.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 118.0, 116.0, 114.0, 115.0, 117.0, 117.0, 110.0, 116.0, 118.0], "value": 114.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 115.0, 112.0, 153.0, 111.0, 114.0, 114.0, 139.0, 173.0, 116.0], "value": 129.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 200.0, 243.0, 272.0, 243.0, 201.0, 194.0, 209.0, 200.0, 205.0], "value": 216.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 58.0, 59.0, 86.0, 61.0, 66.0, 50.0, 78.0, 106.0, 87.0], "value": 74.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 71.0, 87.0, 72.0, 71.0, 72.0, 86.0, 71.0, 76.0, 117.0], "value": 80.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 105.0, 89.0, 91.0, 106.0, 138.0, 92.0, 136.0, 92.0, 136.0], "value": 111.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 105.0, 117.0, 97.0, 99.0, 99.0, 97.0, 98.0, 106.0], "value": 101.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 95.0, 91.0, 105.0, 90.0, 91.0, 92.0, 92.0, 90.0, 93.0], "value": 93.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 87.0, 106.0, 70.0, 68.0, 68.0, 69.0, 70.0, 68.0], "value": 74.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 58.0, 59.0, 75.0, 67.0, 65.0, 61.0, 85.0, 88.0, 90.0], "value": 71.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 87.0, 86.0, 84.0, 85.0, 83.0, 86.0, 86.0, 84.0], "value": 85.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 165.0, 152.0, 167.0, 125.0, 134.0, 161.0, 147.0, 217.0, 154.0], "value": 156.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 246.0, 186.0, 224.0, 245.0, 228.0, 202.0, 247.0, 235.0, 187.0], "value": 218.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 84.0, 102.0, 100.0, 67.0, 73.0, 100.0, 104.0, 92.0, 93.0], "value": 88.6, "unit": "score"}]}]} 09:59:40 INFO - Return code: 0 09:59:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:59:40 ERROR - # TBPL SUCCESS # 09:59:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:59:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:59:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:59:40 INFO - 2017-03-23 09:59:40.497 screenresolution[2206:24607] starting screenresolution argv=screenresolution get 09:59:40 INFO - 2017-03-23 09:59:40.500 screenresolution[2206:24607] Display 0: 1600x1200x32@60 09:59:40 INFO - 2017-03-23 09:59:40.506 screenresolution[2207:24610] starting screenresolution argv=screenresolution list 09:59:40 INFO - Available Modes on Display 0 09:59:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:59:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:59:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:59:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:59:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:59:40 INFO - Intel Iris: 09:59:40 INFO - Chipset Model: Intel Iris 09:59:40 INFO - Type: GPU 09:59:40 INFO - Bus: Built-In 09:59:40 INFO - VRAM (Dynamic, Max): 1536 MB 09:59:40 INFO - Vendor: Intel (0x8086) 09:59:40 INFO - Device ID: 0x0a2e 09:59:40 INFO - Revision ID: 0x0009 09:59:40 INFO - Displays: 09:59:40 INFO - Display: 09:59:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:59:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:59:40 INFO - Display Serial Number: 1600x1200 60 09:59:40 INFO - Main Display: Yes 09:59:40 INFO - Mirror: Off 09:59:40 INFO - Online: Yes 09:59:40 INFO - Rotation: Supported 09:59:40 INFO - Return code: 0 09:59:40 INFO - Running post-action listener: _resource_record_post_action 09:59:40 INFO - [mozharness: 2017-03-23 16:59:40.743092Z] Finished run-tests step (success) 09:59:40 INFO - Running post-run listener: _resource_record_post_run 09:59:40 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 311296; Write bytes: 517897216; Read time: 5; Write time: 1619 09:59:40 INFO - TinderboxPrint: CPU usage
54.1% 09:59:40 INFO - TinderboxPrint: I/O read bytes / time
311,296 / 5 09:59:40 INFO - TinderboxPrint: I/O write bytes / time
517,897,216 / 1,619 09:59:40 INFO - TinderboxPrint: CPU idle
2,206.8 (45.9%) 09:59:40 INFO - TinderboxPrint: CPU system
966.5 (20.1%) 09:59:40 INFO - TinderboxPrint: CPU user
1,632.4 (34.0%) 09:59:40 INFO - TinderboxPrint: Swap in / out
210,743,296 / 0 09:59:40 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 204210688; Write bytes: 204545536; Read time: 15701; Write time: 437 09:59:40 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 311296; Write bytes: 302538240; Read time: 5; Write time: 1149 09:59:41 INFO - Running post-run listener: _upload_blobber_files 09:59:41 INFO - Blob upload gear active. 09:59:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:59:41 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:59:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:59:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:59:41 INFO - (blobuploader) - INFO - Open directory for files ... 09:59:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:59:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:59:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:59:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:59:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:59:42 INFO - (blobuploader) - INFO - Done attempting. 09:59:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:59:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:59:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:59:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:59:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:59:43 INFO - (blobuploader) - INFO - Done attempting. 09:59:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:59:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:59:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:59:44 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:59:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:59:44 INFO - (blobuploader) - INFO - Done attempting. 09:59:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:59:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:59:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:59:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:59:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:59:47 INFO - (blobuploader) - INFO - Done attempting. 09:59:47 INFO - (blobuploader) - INFO - Iteration through files over. 09:59:47 INFO - Return code: 0 09:59:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:59:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:59:47 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e9d639a4ee98920eccedf77f5725857a1fe9974f55806fd69b438213933412496cf9cc762886abb43c5740696db99923b2b8dbc56a8b01a825095c69bb6d323", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c43219af52c6d012c831c636152ba2aeb8678d5ea032f9b063702f2422e1a4d0bee20334a3c2fa010cfa14a48ce1a39ff0d2c1875ce3228b7923a3f8ec0ba31b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/08592205395784f8606e6def855e654e74c364bb4a055c514ee73774a95829867a39da4b4ed08e7300443f1017ec46c3ebc7f346469e5f3f21839444f38b5ea2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e225f271db598f120d428d53f27f71d7bca1897cfdd16513036c62f9cef09a99bdb3df42b6ea282a71b69482b97696f6cfb5647dedcf0ef9915085073c459ea0"} 09:59:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:59:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:59:47 INFO - Contents: 09:59:47 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e9d639a4ee98920eccedf77f5725857a1fe9974f55806fd69b438213933412496cf9cc762886abb43c5740696db99923b2b8dbc56a8b01a825095c69bb6d323", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c43219af52c6d012c831c636152ba2aeb8678d5ea032f9b063702f2422e1a4d0bee20334a3c2fa010cfa14a48ce1a39ff0d2c1875ce3228b7923a3f8ec0ba31b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/08592205395784f8606e6def855e654e74c364bb4a055c514ee73774a95829867a39da4b4ed08e7300443f1017ec46c3ebc7f346469e5f3f21839444f38b5ea2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e225f271db598f120d428d53f27f71d7bca1897cfdd16513036c62f9cef09a99bdb3df42b6ea282a71b69482b97696f6cfb5647dedcf0ef9915085073c459ea0"} 09:59:47 INFO - Running post-run listener: copy_logs_to_upload_dir 09:59:47 INFO - Copying logs to upload dir... 09:59:47 INFO - mkdir: /builds/slave/test/build/upload/logs 09:59:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1283.781774 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-03-23 09:59:47.176116) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-23 09:59:47.180121) ========= 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.R5ZMznFSp7/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.AZeUWB7za3/Listeners TMPDIR=/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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/7e9d639a4ee98920eccedf77f5725857a1fe9974f55806fd69b438213933412496cf9cc762886abb43c5740696db99923b2b8dbc56a8b01a825095c69bb6d323", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c43219af52c6d012c831c636152ba2aeb8678d5ea032f9b063702f2422e1a4d0bee20334a3c2fa010cfa14a48ce1a39ff0d2c1875ce3228b7923a3f8ec0ba31b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/08592205395784f8606e6def855e654e74c364bb4a055c514ee73774a95829867a39da4b4ed08e7300443f1017ec46c3ebc7f346469e5f3f21839444f38b5ea2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e225f271db598f120d428d53f27f71d7bca1897cfdd16513036c62f9cef09a99bdb3df42b6ea282a71b69482b97696f6cfb5647dedcf0ef9915085073c459ea0"} build_url:https://queue.taskcluster.net/v1/task/GXppFqLrTvioF211EqXANg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011245 build_url: 'https://queue.taskcluster.net/v1/task/GXppFqLrTvioF211EqXANg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7e9d639a4ee98920eccedf77f5725857a1fe9974f55806fd69b438213933412496cf9cc762886abb43c5740696db99923b2b8dbc56a8b01a825095c69bb6d323", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c43219af52c6d012c831c636152ba2aeb8678d5ea032f9b063702f2422e1a4d0bee20334a3c2fa010cfa14a48ce1a39ff0d2c1875ce3228b7923a3f8ec0ba31b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/08592205395784f8606e6def855e654e74c364bb4a055c514ee73774a95829867a39da4b4ed08e7300443f1017ec46c3ebc7f346469e5f3f21839444f38b5ea2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e225f271db598f120d428d53f27f71d7bca1897cfdd16513036c62f9cef09a99bdb3df42b6ea282a71b69482b97696f6cfb5647dedcf0ef9915085073c459ea0"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-23 09:59:47.217548) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 09:59:47.218161) ========= 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.R5ZMznFSp7/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.AZeUWB7za3/Listeners TMPDIR=/var/folders/sv/7xl3wp255lq9blb11pnmfnq000000w/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.005261 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-23 09:59:47.269469) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-23 09:59:47.269834) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-23 09:59:47.270198) ========= ========= Total master_lag: 0.20 =========