builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0057 starttime: 1490120203.84 results: success (0) buildid: 20170321092323 builduid: 962451bdcb7a4cfea38ac0d989a13a77 revision: 9fb5e850ab7ab0b2b90640c604f66038407b411d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-21 11:16:43.837081) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-21 11:16:43.837512) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-21 11:16:43.837845) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-21 11:16:43.863494) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 11:16:43.863941) ========= 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.I3urnkvzxs/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.siw1ch46wa/Listeners TMPDIR=/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/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-21 11:16:43-- 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% 12.5M=0.001s 2017-03-21 11:16:44 (12.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.120908 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 11:16:44.009920) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 11:16:44.010372) ========= 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.I3urnkvzxs/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.siw1ch46wa/Listeners TMPDIR=/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/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.019554 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 11:16:44.059747) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-21 11:16:44.060083) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 9fb5e850ab7ab0b2b90640c604f66038407b411d --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 9fb5e850ab7ab0b2b90640c604f66038407b411d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.I3urnkvzxs/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.siw1ch46wa/Listeners TMPDIR=/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/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-21 11:16:44,154 truncating revision to first 12 chars 2017-03-21 11:16:44,154 Setting DEBUG logging. 2017-03-21 11:16:44,154 attempt 1/10 2017-03-21 11:16:44,154 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/9fb5e850ab7a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-21 11:16:45,702 unpacking tar archive at: mozilla-central-9fb5e850ab7a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.802516 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-21 11:16:45.886914) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-21 11:16:45.887365) ========= script_repo_revision: 9fb5e850ab7ab0b2b90640c604f66038407b411d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-21 11:16:45.887969) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-21 11:16:45.888365) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-21 11:16:45.908687) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-03-21 11:16:45.908987) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.I3urnkvzxs/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.siw1ch46wa/Listeners TMPDIR=/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/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 11:16:46 INFO - MultiFileLogger online at 20170321 11:16:46 in /builds/slave/test 11:16:46 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 11:16:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:16:46 INFO - {'append_to_log': False, 11:16:46 INFO - 'base_work_dir': '/builds/slave/test', 11:16:46 INFO - 'blob_upload_branch': 'Firefox', 11:16:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:16:46 INFO - 'branch': 'Firefox', 11:16:46 INFO - 'buildbot_json_path': 'buildprops.json', 11:16:46 INFO - 'config_files': ('talos/mac_config.py',), 11:16:46 INFO - 'default_actions': ('clobber', 11:16:46 INFO - 'read-buildbot-config', 11:16:46 INFO - 'download-and-extract', 11:16:46 INFO - 'populate-webroot', 11:16:46 INFO - 'create-virtualenv', 11:16:46 INFO - 'install', 11:16:46 INFO - 'run-tests'), 11:16:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:16:46 INFO - 'download_minidump_stackwalk': True, 11:16:46 INFO - 'download_symbols': 'ondemand', 11:16:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:16:46 INFO - 'tooltool.py': '/tools/tooltool.py', 11:16:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:16:46 INFO - '/tools/misc-python/virtualenv.py')}, 11:16:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:16:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:16:46 INFO - 'gecko_profile': False, 11:16:46 INFO - 'gecko_profile_interval': 0, 11:16:46 INFO - 'installer_path': 'installer.exe', 11:16:46 INFO - 'log_level': 'info', 11:16:46 INFO - 'log_name': 'talos', 11:16:46 INFO - 'log_to_console': True, 11:16:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:16:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:16:46 INFO - 'opt_config_files': (), 11:16:46 INFO - 'pip_index': False, 11:16:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:16:46 INFO - 'cmd': ('bash', 11:16:46 INFO - '-c', 11:16:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:16:46 INFO - 'enabled': True, 11:16:46 INFO - 'halt_on_failure': False, 11:16:46 INFO - 'name': 'check_screen_resolution'},), 11:16:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:16:46 INFO - 'cmd': ('bash', 11:16:46 INFO - '-c', 11:16:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:16:46 INFO - 'enabled': True, 11:16:46 INFO - 'halt_on_failure': False, 11:16:46 INFO - 'name': 'check_screen_resolution'},), 11:16:46 INFO - 'run_cmd_checks_enabled': True, 11:16:46 INFO - 'suite': 'dromaeojs', 11:16:46 INFO - 'system_bits': '32', 11:16:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:16:46 INFO - 'title': 't-yosemite-r7-0057', 11:16:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:16:46 INFO - 'use_talos_json': True, 11:16:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:16:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:16:46 INFO - 'work_dir': 'build'} 11:16:46 INFO - [mozharness: 2017-03-21 18:16:46.120094Z] Running clobber step. 11:16:46 INFO - Running pre-action listener: _resource_record_pre_action 11:16:46 INFO - Running main action method: clobber 11:16:46 INFO - rmtree: /builds/slave/test/build 11:16:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:16:46 INFO - Running post-action listener: _resource_record_post_action 11:16:46 INFO - [mozharness: 2017-03-21 18:16:46.740642Z] Finished clobber step (success) 11:16:46 INFO - [mozharness: 2017-03-21 18:16:46.740898Z] Running read-buildbot-config step. 11:16:46 INFO - Running pre-action listener: _resource_record_pre_action 11:16:46 INFO - Running main action method: read_buildbot_config 11:16:46 INFO - Using buildbot properties: 11:16:46 INFO - { 11:16:46 INFO - "project": "", 11:16:46 INFO - "product": "firefox", 11:16:46 INFO - "script_repo_revision": "production", 11:16:46 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 11:16:46 INFO - "repository": "", 11:16:46 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 11:16:46 INFO - "buildid": "20170321092323", 11:16:46 INFO - "pgo_build": "False", 11:16:46 INFO - "basedir": "/builds/slave/test", 11:16:46 INFO - "buildnumber": 83, 11:16:46 INFO - "slavename": "t-yosemite-r7-0057", 11:16:46 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 11:16:46 INFO - "platform": "yosemite_r7", 11:16:46 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 11:16:46 INFO - "branch": "mozilla-central", 11:16:46 INFO - "repo_path": "mozilla-central", 11:16:46 INFO - "revision": "9fb5e850ab7ab0b2b90640c604f66038407b411d", 11:16:46 INFO - "stage_platform": "macosx64", 11:16:46 INFO - "builduid": "962451bdcb7a4cfea38ac0d989a13a77", 11:16:46 INFO - "slavebuilddir": "test" 11:16:46 INFO - } 11:16:46 INFO - Found installer url https://queue.taskcluster.net/v1/task/DpJ_0uuqQfyqN9k8ZTvquw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 11:16:46 INFO - Running post-action listener: _resource_record_post_action 11:16:46 INFO - [mozharness: 2017-03-21 18:16:46.746098Z] Finished read-buildbot-config step (success) 11:16:46 INFO - [mozharness: 2017-03-21 18:16:46.746258Z] Running download-and-extract step. 11:16:46 INFO - Running pre-action listener: _resource_record_pre_action 11:16:46 INFO - Running main action method: download_and_extract 11:16:46 INFO - mkdir: /builds/slave/test/build/tests 11:16:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:16:46 INFO - trying https://queue.taskcluster.net/v1/task/DpJ_0uuqQfyqN9k8ZTvquw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 11:16:46 INFO - Downloading https://queue.taskcluster.net/v1/task/DpJ_0uuqQfyqN9k8ZTvquw/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 11:16:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DpJ_0uuqQfyqN9k8ZTvquw/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 11:16:48 INFO - Downloaded 1325 bytes. 11:16:48 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 11:16:48 INFO - Using the following test package requirements: 11:16:48 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:16:48 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 11:16:48 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 11:16:48 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:16:48 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 11:16:48 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:16:48 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 11:16:48 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:16:48 INFO - u'jsshell-mac.zip'], 11:16:48 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:16:48 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 11:16:48 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 11:16:48 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:16:48 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 11:16:48 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:16:48 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 11:16:48 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:16:48 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 11:16:48 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:16:48 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 11:16:48 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:16:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DpJ_0uuqQfyqN9k8ZTvquw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 11:16:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DpJ_0uuqQfyqN9k8ZTvquw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:16:48 INFO - Fetch https://queue.taskcluster.net/v1/task/DpJ_0uuqQfyqN9k8ZTvquw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 11:16:49 INFO - Expected file size: 52475931 11:16:49 INFO - Obtained file size: 52475931 11:17:06 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 11:17:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DpJ_0uuqQfyqN9k8ZTvquw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 11:17:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DpJ_0uuqQfyqN9k8ZTvquw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:17:06 INFO - Fetch https://queue.taskcluster.net/v1/task/DpJ_0uuqQfyqN9k8ZTvquw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 11:17:08 INFO - Expected file size: 52475931 11:17:08 INFO - Obtained file size: 52475931 11:17:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DpJ_0uuqQfyqN9k8ZTvquw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 11:17:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DpJ_0uuqQfyqN9k8ZTvquw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 11:17:23 INFO - Fetch https://queue.taskcluster.net/v1/task/DpJ_0uuqQfyqN9k8ZTvquw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 11:17:24 INFO - Expected file size: 14404171 11:17:24 INFO - Obtained file size: 14404171 11:17:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:17:24 INFO - trying https://queue.taskcluster.net/v1/task/DpJ_0uuqQfyqN9k8ZTvquw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 11:17:24 INFO - Downloading https://queue.taskcluster.net/v1/task/DpJ_0uuqQfyqN9k8ZTvquw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 11:17:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DpJ_0uuqQfyqN9k8ZTvquw/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 11:17:27 INFO - Downloaded 75233548 bytes. 11:17:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DpJ_0uuqQfyqN9k8ZTvquw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 11:17:27 INFO - mkdir: /builds/slave/test/properties 11:17:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:17:27 INFO - Writing to file /builds/slave/test/properties/build_url 11:17:27 INFO - Contents: 11:17:27 INFO - build_url:https://queue.taskcluster.net/v1/task/DpJ_0uuqQfyqN9k8ZTvquw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 11:17:27 INFO - Running post-action listener: _resource_record_post_action 11:17:27 INFO - Running post-action listener: set_extra_try_arguments 11:17:27 INFO - [mozharness: 2017-03-21 18:17:27.728466Z] Finished download-and-extract step (success) 11:17:27 INFO - [mozharness: 2017-03-21 18:17:27.728587Z] Running populate-webroot step. 11:17:27 INFO - Running pre-action listener: _resource_record_pre_action 11:17:27 INFO - Running main action method: populate_webroot 11:17:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:17:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:17:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:17:27 INFO - '--tppagecycles', 11:17:27 INFO - '7'], 11:17:27 INFO - 'tests': ['tsvgm']}}, 11:17:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:17:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:17:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:17:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:17:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:17:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:17:27 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 11:17:27 INFO - 'talos_options': ['--disable-e10s'], 11:17:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:17:27 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:17:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:17:27 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 11:17:27 INFO - 'talos_options': ['--disable-e10s'], 11:17:27 INFO - 'tests': ['damp', 'tps']}, 11:17:27 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:17:27 INFO - 'tests': ['damp', 'tps']}, 11:17:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:17:27 INFO - 'tests': ['dromaeo_dom']}, 11:17:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:17:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:17:27 INFO - 'tests': ['basic_compositor_video']}, 11:17:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:17:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:17:27 INFO - 'tests': ['a11yr', 11:17:27 INFO - 'ts_paint', 11:17:27 INFO - 'tpaint', 11:17:27 INFO - 'sessionrestore', 11:17:27 INFO - 'sessionrestore_no_auto_restore', 11:17:27 INFO - 'tabpaint']}, 11:17:27 INFO - 'other-e10s': {'tests': ['a11yr', 11:17:27 INFO - 'ts_paint', 11:17:27 INFO - 'tpaint', 11:17:27 INFO - 'sessionrestore', 11:17:27 INFO - 'sessionrestore_no_auto_restore', 11:17:27 INFO - 'tabpaint']}, 11:17:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:17:27 INFO - 'ts_paint', 11:17:27 INFO - 'tpaint', 11:17:27 INFO - 'sessionrestore', 11:17:27 INFO - 'sessionrestore_no_auto_restore', 11:17:27 INFO - 'tabpaint']}, 11:17:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:17:27 INFO - 'ts_paint', 11:17:27 INFO - 'tpaint', 11:17:27 INFO - 'sessionrestore', 11:17:27 INFO - 'sessionrestore_no_auto_restore', 11:17:27 INFO - 'tabpaint']}, 11:17:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:17:27 INFO - 'tests': ['a11yr', 11:17:27 INFO - 'ts_paint', 11:17:27 INFO - 'tpaint', 11:17:27 INFO - 'sessionrestore', 11:17:27 INFO - 'sessionrestore_no_auto_restore', 11:17:27 INFO - 'tabpaint']}, 11:17:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:17:27 INFO - 'tests': ['a11yr', 11:17:27 INFO - 'ts_paint', 11:17:27 INFO - 'tpaint', 11:17:27 INFO - 'sessionrestore', 11:17:27 INFO - 'sessionrestore_no_auto_restore', 11:17:27 INFO - 'tabpaint']}, 11:17:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:17:27 INFO - 'tests': ['tsvgx', 11:17:27 INFO - 'tsvgr_opacity', 11:17:27 INFO - 'tart', 11:17:27 INFO - 'tscrollx', 11:17:27 INFO - 'cart', 11:17:27 INFO - 'tsvg_static']}, 11:17:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:17:27 INFO - 'tsvgr_opacity', 11:17:27 INFO - 'tart', 11:17:27 INFO - 'tscrollx', 11:17:27 INFO - 'cart', 11:17:27 INFO - 'tsvg_static']}, 11:17:27 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 11:17:27 INFO - 'talos_options': ['--disable-e10s'], 11:17:27 INFO - 'tests': ['tp5o']}, 11:17:27 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:17:27 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 11:17:27 INFO - 'talos_options': ['--disable-e10s', 11:17:27 INFO - '--xperf_path', 11:17:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:17:27 INFO - 'tests': ['tp5n']}, 11:17:27 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:17:27 INFO - 'talos_options': ['--xperf_path', 11:17:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:17:27 INFO - 'tests': ['tp5n']}}, 11:17:27 INFO - 'talos.zip': {'path': '', 11:17:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:17:27 INFO - Running post-action listener: _resource_record_post_action 11:17:27 INFO - [mozharness: 2017-03-21 18:17:27.746886Z] Finished populate-webroot step (success) 11:17:27 INFO - [mozharness: 2017-03-21 18:17:27.746992Z] Running create-virtualenv step. 11:17:27 INFO - Running pre-action listener: _resource_record_pre_action 11:17:27 INFO - Running main action method: create_virtualenv 11:17:27 INFO - Creating virtualenv /builds/slave/test/build/venv 11:17:27 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 11:17:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:17:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:17:27 INFO - Using real prefix '/tools/python27' 11:17:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:17:28 INFO - Installing distribute.............................................................................................................................................................................................done. 11:17:31 INFO - Installing pip.................done. 11:17:31 INFO - Return code: 0 11:17:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:17:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:17:31 INFO - Reading from file tmpfile_stdout 11:17:31 INFO - Output received: 11:17:31 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) 11:17:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:17:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:17:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:17:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:17:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:17:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:17:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:17:31 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 0x1008d0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008d3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd040d55400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006aed30>, '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 0x1005d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1006a3be8>, '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.I3urnkvzxs/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.siw1ch46wa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:17:31 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 11:17:31 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 11:17:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.I3urnkvzxs/Render', 11:17:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:17:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:17:31 INFO - 'HOME': '/Users/cltbld', 11:17:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:17:31 INFO - 'LOGNAME': 'cltbld', 11:17:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:17:31 INFO - 'MOZ_NO_REMOTE': '1', 11:17:31 INFO - 'NO_EM_RESTART': '1', 11:17:31 INFO - 'PAGER': '/bin/cat', 11:17:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:17:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:17:31 INFO - 'PWD': '/builds/slave/test', 11:17:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:17:31 INFO - 'SHELL': '/bin/bash', 11:17:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.siw1ch46wa/Listeners', 11:17:31 INFO - 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/', 11:17:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:17:31 INFO - 'USER': 'cltbld', 11:17:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:17:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:17:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:17:31 INFO - 'XPC_FLAGS': '0x0', 11:17:31 INFO - 'XPC_SERVICE_NAME': '0', 11:17:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:17:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:17:32 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 11:17:32 INFO - Cleaning up... 11:17:32 INFO - Return code: 0 11:17:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:17: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')]} 11:17:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:17:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:17: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')]} 11:17:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:17:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:17:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1008d0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008d3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd040d55400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006aed30>, '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 0x1005d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1006a3be8>, '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.I3urnkvzxs/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.siw1ch46wa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:17:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:17:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:17:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.I3urnkvzxs/Render', 11:17:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:17:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:17:32 INFO - 'HOME': '/Users/cltbld', 11:17:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:17:32 INFO - 'LOGNAME': 'cltbld', 11:17:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:17:32 INFO - 'MOZ_NO_REMOTE': '1', 11:17:32 INFO - 'NO_EM_RESTART': '1', 11:17:32 INFO - 'PAGER': '/bin/cat', 11:17:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:17:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:17:32 INFO - 'PWD': '/builds/slave/test', 11:17:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:17:32 INFO - 'SHELL': '/bin/bash', 11:17:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.siw1ch46wa/Listeners', 11:17:32 INFO - 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/', 11:17:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:17:32 INFO - 'USER': 'cltbld', 11:17:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:17:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:17:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:17:32 INFO - 'XPC_FLAGS': '0x0', 11:17:32 INFO - 'XPC_SERVICE_NAME': '0', 11:17:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:17:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:17:32 INFO - Downloading/unpacking psutil>=3.1.1 11:17:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:17:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:17:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:17:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:17:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:17:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:17:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:17:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:17:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:17:35 INFO - Installing collected packages: psutil 11:17:35 INFO - Running setup.py install for psutil 11:17:36 INFO - building 'psutil._psutil_osx' extension 11:17:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 11:17:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 11:17:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 11:17:36 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 11:17:36 INFO - building 'psutil._psutil_posix' extension 11:17:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 11:17:36 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:17:36 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:17:36 INFO - ^ 11:17:36 INFO - 1 warning generated. 11:17:36 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 11:17:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:17:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:17:36 INFO - Successfully installed psutil 11:17:36 INFO - Cleaning up... 11:17:36 INFO - Return code: 0 11:17:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:17:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:17:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:17:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:17:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:17:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:17:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:17:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1008d0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008d3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd040d55400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006aed30>, '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 0x1005d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1006a3be8>, '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.I3urnkvzxs/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.siw1ch46wa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:17:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:17:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:17:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.I3urnkvzxs/Render', 11:17:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:17:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:17:36 INFO - 'HOME': '/Users/cltbld', 11:17:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:17:36 INFO - 'LOGNAME': 'cltbld', 11:17:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:17:36 INFO - 'MOZ_NO_REMOTE': '1', 11:17:36 INFO - 'NO_EM_RESTART': '1', 11:17:36 INFO - 'PAGER': '/bin/cat', 11:17:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:17:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:17:36 INFO - 'PWD': '/builds/slave/test', 11:17:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:17:36 INFO - 'SHELL': '/bin/bash', 11:17:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.siw1ch46wa/Listeners', 11:17:36 INFO - 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/', 11:17:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:17:36 INFO - 'USER': 'cltbld', 11:17:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:17:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:17:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:17:36 INFO - 'XPC_FLAGS': '0x0', 11:17:36 INFO - 'XPC_SERVICE_NAME': '0', 11:17:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:17:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:17:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:17:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:17:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:17:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:17:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:17:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:17:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:17:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:17:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:17:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:17:39 INFO - Installing collected packages: mozsystemmonitor 11:17:39 INFO - Running setup.py install for mozsystemmonitor 11:17:39 INFO - Successfully installed mozsystemmonitor 11:17:39 INFO - Cleaning up... 11:17:40 INFO - Return code: 0 11:17:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:17:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:17:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:17:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:17:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:17:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:17:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:17:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1008d0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008d3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd040d55400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006aed30>, '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 0x1005d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1006a3be8>, '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.I3urnkvzxs/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.siw1ch46wa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:17:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:17:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:17:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.I3urnkvzxs/Render', 11:17:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:17:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:17:40 INFO - 'HOME': '/Users/cltbld', 11:17:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:17:40 INFO - 'LOGNAME': 'cltbld', 11:17:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:17:40 INFO - 'MOZ_NO_REMOTE': '1', 11:17:40 INFO - 'NO_EM_RESTART': '1', 11:17:40 INFO - 'PAGER': '/bin/cat', 11:17:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:17:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:17:40 INFO - 'PWD': '/builds/slave/test', 11:17:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:17:40 INFO - 'SHELL': '/bin/bash', 11:17:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.siw1ch46wa/Listeners', 11:17:40 INFO - 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/', 11:17:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:17:40 INFO - 'USER': 'cltbld', 11:17:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:17:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:17:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:17:40 INFO - 'XPC_FLAGS': '0x0', 11:17:40 INFO - 'XPC_SERVICE_NAME': '0', 11:17:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:17:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:17:40 INFO - Downloading/unpacking jsonschema==2.5.1 11:17:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:17:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:17:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:17:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:17:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:17:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:17:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:17:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:17:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:17:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:17:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:17:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:17:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:17:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:17:43 INFO - Downloading functools32-3.2.3-2.tar.gz 11:17:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:17:43 INFO - warning: no files found matching '*.txt' 11:17:43 INFO - no previously-included directories found matching 'build' 11:17:43 INFO - no previously-included directories found matching 'dist' 11:17:43 INFO - no previously-included directories found matching '.git*' 11:17:43 INFO - Installing collected packages: jsonschema, functools32 11:17:43 INFO - Running setup.py install for functools32 11:17:44 INFO - warning: no files found matching '*.txt' 11:17:44 INFO - no previously-included directories found matching 'build' 11:17:44 INFO - no previously-included directories found matching 'dist' 11:17:44 INFO - no previously-included directories found matching '.git*' 11:17:44 INFO - Successfully installed jsonschema functools32 11:17:44 INFO - Cleaning up... 11:17:44 INFO - Return code: 0 11:17:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:17:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:17:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:17:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:17:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:17:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:17:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:17:44 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 0x1008d0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008d3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd040d55400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006aed30>, '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 0x1005d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1006a3be8>, '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.I3urnkvzxs/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.siw1ch46wa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:17:44 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 11:17:44 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 11:17:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.I3urnkvzxs/Render', 11:17:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:17:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:17:44 INFO - 'HOME': '/Users/cltbld', 11:17:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:17:44 INFO - 'LOGNAME': 'cltbld', 11:17:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:17:44 INFO - 'MOZ_NO_REMOTE': '1', 11:17:44 INFO - 'NO_EM_RESTART': '1', 11:17:44 INFO - 'PAGER': '/bin/cat', 11:17:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:17:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:17:44 INFO - 'PWD': '/builds/slave/test', 11:17:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:17:44 INFO - 'SHELL': '/bin/bash', 11:17:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.siw1ch46wa/Listeners', 11:17:44 INFO - 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/', 11:17:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:17:44 INFO - 'USER': 'cltbld', 11:17:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:17:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:17:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:17:44 INFO - 'XPC_FLAGS': '0x0', 11:17:44 INFO - 'XPC_SERVICE_NAME': '0', 11:17:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:17:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:17:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:17:44 INFO - Cleaning up... 11:17:44 INFO - Return code: 0 11:17:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:17:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:17:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:17:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:17:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:17:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:17:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:17:44 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 0x1008d0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008d3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd040d55400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006aed30>, '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 0x1005d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1006a3be8>, '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.I3urnkvzxs/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.siw1ch46wa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:17:44 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 11:17:44 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 11:17:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.I3urnkvzxs/Render', 11:17:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:17:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:17:44 INFO - 'HOME': '/Users/cltbld', 11:17:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:17:44 INFO - 'LOGNAME': 'cltbld', 11:17:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:17:44 INFO - 'MOZ_NO_REMOTE': '1', 11:17:44 INFO - 'NO_EM_RESTART': '1', 11:17:44 INFO - 'PAGER': '/bin/cat', 11:17:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:17:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:17:44 INFO - 'PWD': '/builds/slave/test', 11:17:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:17:44 INFO - 'SHELL': '/bin/bash', 11:17:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.siw1ch46wa/Listeners', 11:17:44 INFO - 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/', 11:17:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:17:44 INFO - 'USER': 'cltbld', 11:17:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:17:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:17:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:17:44 INFO - 'XPC_FLAGS': '0x0', 11:17:44 INFO - 'XPC_SERVICE_NAME': '0', 11:17:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:17:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:17:44 INFO - Downloading/unpacking blobuploader==1.2.4 11:17:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:17:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:17:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:17:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:17:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:17:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:17:47 INFO - Downloading blobuploader-1.2.4.tar.gz 11:17:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:17:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:17:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:17:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:17:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:17:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:17:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:17:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:17:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:17:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:17:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:17:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:17:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:17:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:17:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:17:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:17:48 INFO - Downloading docopt-0.6.1.tar.gz 11:17:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:17:48 INFO - Installing collected packages: blobuploader, requests, docopt 11:17:48 INFO - Running setup.py install for blobuploader 11:17:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:17:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:17:48 INFO - Running setup.py install for requests 11:17:49 INFO - Running setup.py install for docopt 11:17:49 INFO - Successfully installed blobuploader requests docopt 11:17:49 INFO - Cleaning up... 11:17:49 INFO - Return code: 0 11:17:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:17:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:17:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:17:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:17:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:17:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:17:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:17:49 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 0x1008d0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008d3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd040d55400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006aed30>, '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 0x1005d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1006a3be8>, '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.I3urnkvzxs/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.siw1ch46wa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:17:49 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 11:17:49 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 11:17:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.I3urnkvzxs/Render', 11:17:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:17:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:17:49 INFO - 'HOME': '/Users/cltbld', 11:17:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:17:49 INFO - 'LOGNAME': 'cltbld', 11:17:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:17:49 INFO - 'MOZ_NO_REMOTE': '1', 11:17:49 INFO - 'NO_EM_RESTART': '1', 11:17:49 INFO - 'PAGER': '/bin/cat', 11:17:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:17:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:17:49 INFO - 'PWD': '/builds/slave/test', 11:17:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:17:49 INFO - 'SHELL': '/bin/bash', 11:17:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.siw1ch46wa/Listeners', 11:17:49 INFO - 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/', 11:17:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:17:49 INFO - 'USER': 'cltbld', 11:17:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:17:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:17:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:17:49 INFO - 'XPC_FLAGS': '0x0', 11:17:49 INFO - 'XPC_SERVICE_NAME': '0', 11:17:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:17:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:17:49 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-_pp6pU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:17:49 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-58Abw4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:17:49 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-R7iUIi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:17:50 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-DaYXwr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:17:50 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-IjZIwY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:17:50 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-zMLxfZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:17:50 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-9RPObF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:17:50 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-UqfvXD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:17:50 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-xwwddP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:17:50 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-DATE2B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:17:50 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-J1NZ64-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:17:50 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-7ELxD3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:17:50 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-uSqd1Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:17:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:17:51 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-vGX2Z9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:17:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:17:51 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-RgjvHz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:17:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:17:51 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-UY8lAH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:17:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:17:51 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-VGkMYX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:17:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:17:51 INFO - Running setup.py install for manifestparser 11:17:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:17:51 INFO - Running setup.py install for mozcrash 11:17:51 INFO - Running setup.py install for mozdebug 11:17:51 INFO - Running setup.py install for mozdevice 11:17:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:17:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:17:52 INFO - Running setup.py install for mozfile 11:17:52 INFO - Running setup.py install for mozhttpd 11:17:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:17:52 INFO - Running setup.py install for mozinfo 11:17:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:17:52 INFO - Running setup.py install for mozInstall 11:17:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:17:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:17:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:17:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:17:52 INFO - Running setup.py install for mozleak 11:17:52 INFO - Running setup.py install for mozlog 11:17:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:17:53 INFO - Running setup.py install for moznetwork 11:17:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:17:53 INFO - Running setup.py install for mozprocess 11:17:53 INFO - Running setup.py install for mozprofile 11:17:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:17:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:17:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:17:53 INFO - Running setup.py install for mozrunner 11:17:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:17:53 INFO - Running setup.py install for mozscreenshot 11:17:53 INFO - Running setup.py install for moztest 11:17:53 INFO - Running setup.py install for mozversion 11:17:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:17:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:17:54 INFO - Cleaning up... 11:17:54 INFO - Return code: 0 11:17:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:17:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:17:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:17:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:17:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:17:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:17:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:17:54 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 0x1008d0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008d3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd040d55400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006aed30>, '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 0x1005d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1006a3be8>, '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.I3urnkvzxs/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.siw1ch46wa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:17:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:17:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:17:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.I3urnkvzxs/Render', 11:17:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:17:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:17:54 INFO - 'HOME': '/Users/cltbld', 11:17:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:17:54 INFO - 'LOGNAME': 'cltbld', 11:17:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:17:54 INFO - 'MOZ_NO_REMOTE': '1', 11:17:54 INFO - 'NO_EM_RESTART': '1', 11:17:54 INFO - 'PAGER': '/bin/cat', 11:17:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:17:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:17:54 INFO - 'PWD': '/builds/slave/test', 11:17:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:17:54 INFO - 'SHELL': '/bin/bash', 11:17:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.siw1ch46wa/Listeners', 11:17:54 INFO - 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/', 11:17:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:17:54 INFO - 'USER': 'cltbld', 11:17:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:17:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:17:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:17:54 INFO - 'XPC_FLAGS': '0x0', 11:17:54 INFO - 'XPC_SERVICE_NAME': '0', 11:17:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:17:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:17:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:17:54 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-8XDnta-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:17:54 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 11:17:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:17:54 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-iH9qtS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:17:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:17:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:17:54 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-ZX4hxs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:17:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:17:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:17:54 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-KzTDeK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:17:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:17:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:17:54 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-BCXRa_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:17:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 11:17:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:17:54 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-V1YNz9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:17:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 11:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:17:55 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-53yM2f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:17:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 11:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:17:55 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-IJYwXz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:17:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:17:55 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-y8YWYm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:17:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 11:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:17:55 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-peoxfW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:17:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:17:55 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-kFhkHT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:17:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 11:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:17:55 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-uFTyLw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:17:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 11:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:17:55 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-4Sg1oQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:17:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 11:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:17:55 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-v5CZLw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:17:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 11:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:17:55 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-F1afFu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:17:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 11:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:17:56 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-uekADX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:17:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 11:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:17:56 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-nSA1fI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:17:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 11:17:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:17:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:17:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:17:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:17:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:17:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:17:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:17:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:17:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:17:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:17:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:17:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:17:58 INFO - Downloading blessings-1.6.tar.gz 11:17:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:17:58 INFO - Installing collected packages: blessings 11:17:58 INFO - Running setup.py install for blessings 11:17:59 INFO - Successfully installed blessings 11:17:59 INFO - Cleaning up... 11:17:59 INFO - Return code: 0 11:17:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:17:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:17:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:17:59 INFO - Reading from file tmpfile_stdout 11:17:59 INFO - Current package versions: 11:17:59 INFO - blessings == 1.6 11:17:59 INFO - blobuploader == 1.2.4 11:17:59 INFO - docopt == 0.6.1 11:17:59 INFO - functools32 == 3.2.3-2 11:17:59 INFO - jsonschema == 2.5.1 11:17:59 INFO - manifestparser == 1.1 11:17:59 INFO - mozInstall == 1.12 11:17:59 INFO - mozcrash == 1.0 11:17:59 INFO - mozdebug == 0.1 11:17:59 INFO - mozdevice == 0.49 11:17:59 INFO - mozfile == 1.2 11:17:59 INFO - mozhttpd == 0.7 11:17:59 INFO - mozinfo == 0.9 11:17:59 INFO - mozleak == 0.1 11:17:59 INFO - mozlog == 3.4 11:17:59 INFO - moznetwork == 0.27 11:17:59 INFO - mozprocess == 0.25 11:17:59 INFO - mozprofile == 0.28 11:17:59 INFO - mozrunner == 6.13 11:17:59 INFO - mozscreenshot == 0.1 11:17:59 INFO - mozsystemmonitor == 0.3 11:17:59 INFO - moztest == 0.8 11:17:59 INFO - mozversion == 1.4 11:17:59 INFO - psutil == 3.1.1 11:17:59 INFO - requests == 1.2.3 11:17:59 INFO - wsgiref == 0.1.2 11:17:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:17: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')]} 11:17:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:17:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:17: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')]} 11:17:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:17:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:17:59 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 0x1008d0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008d3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd040d55400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006aed30>, '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 0x1005d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1006a3be8>, '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.I3urnkvzxs/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.siw1ch46wa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:17:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 11:17:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:17:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.I3urnkvzxs/Render', 11:17:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:17:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:17:59 INFO - 'HOME': '/Users/cltbld', 11:17:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:17:59 INFO - 'LOGNAME': 'cltbld', 11:17:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:17:59 INFO - 'MOZ_NO_REMOTE': '1', 11:17:59 INFO - 'NO_EM_RESTART': '1', 11:17:59 INFO - 'PAGER': '/bin/cat', 11:17:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:17:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:17:59 INFO - 'PWD': '/builds/slave/test', 11:17:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:17:59 INFO - 'SHELL': '/bin/bash', 11:17:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.siw1ch46wa/Listeners', 11:17:59 INFO - 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/', 11:17:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:17:59 INFO - 'USER': 'cltbld', 11:17:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:17:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:17:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:17:59 INFO - 'XPC_FLAGS': '0x0', 11:17:59 INFO - 'XPC_SERVICE_NAME': '0', 11:17:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:17:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:17:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:17:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 11:17:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 11:17:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:17:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 11:17:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 11:17:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 11:17:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 11:17:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 11:17:59 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:17:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:17:59 INFO - Cleaning up... 11:17:59 INFO - Return code: 0 11:17:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:17: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')]} 11:17:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:17:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:17: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')]} 11:17:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:17:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:17:59 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 0x1008d0b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1008d3768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd040d55400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1006aed30>, '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 0x1005d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1006a3be8>, '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.I3urnkvzxs/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.siw1ch46wa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:17:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:17:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:17:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.I3urnkvzxs/Render', 11:17:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:17:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:17:59 INFO - 'HOME': '/Users/cltbld', 11:17:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:17:59 INFO - 'LOGNAME': 'cltbld', 11:17:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:17:59 INFO - 'MOZ_NO_REMOTE': '1', 11:17:59 INFO - 'NO_EM_RESTART': '1', 11:17:59 INFO - 'PAGER': '/bin/cat', 11:17:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:17:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:17:59 INFO - 'PWD': '/builds/slave/test', 11:17:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:17:59 INFO - 'SHELL': '/bin/bash', 11:17:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.siw1ch46wa/Listeners', 11:17:59 INFO - 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/', 11:17:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:17:59 INFO - 'USER': 'cltbld', 11:17:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:17:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:17:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:17:59 INFO - 'XPC_FLAGS': '0x0', 11:17:59 INFO - 'XPC_SERVICE_NAME': '0', 11:17:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:18:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:18:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:18:00 INFO - Cleaning up... 11:18:00 INFO - Return code: 0 11:18:00 INFO - Running post-action listener: _resource_record_post_action 11:18:00 INFO - Running post-action listener: _start_resource_monitoring 11:18:00 INFO - Starting resource monitoring. 11:18:00 INFO - [mozharness: 2017-03-21 18:18:00.100018Z] Finished create-virtualenv step (success) 11:18:00 INFO - [mozharness: 2017-03-21 18:18:00.101067Z] Running install step. 11:18:00 INFO - Running pre-action listener: _resource_record_pre_action 11:18:00 INFO - Running main action method: install 11:18:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:18:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:18:00 INFO - Reading from file tmpfile_stdout 11:18:00 INFO - Detecting whether we're running mozinstall >=1.0... 11:18:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:18:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:18:00 INFO - Reading from file tmpfile_stdout 11:18:00 INFO - Output received: 11:18:00 INFO - Usage: mozinstall [options] installer 11:18:00 INFO - Options: 11:18:00 INFO - -h, --help show this help message and exit 11:18:00 INFO - -d DEST, --destination=DEST 11:18:00 INFO - Directory to install application into. [default: 11:18:00 INFO - "/builds/slave/test"] 11:18:00 INFO - --app=APP Application being installed. [default: firefox] 11:18:00 INFO - mkdir: /builds/slave/test/build/application 11:18:00 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'] 11:18:00 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 11:18:20 INFO - Reading from file tmpfile_stdout 11:18:20 INFO - Output received: 11:18:20 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:18:20 INFO - Running post-action listener: _resource_record_post_action 11:18:20 INFO - [mozharness: 2017-03-21 18:18:20.611515Z] Finished install step (success) 11:18:20 INFO - [mozharness: 2017-03-21 18:18:20.611681Z] Running run-tests step. 11:18:20 INFO - Running pre-action listener: _resource_record_pre_action 11:18:20 INFO - Running main action method: run_tests 11:18:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:18:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:18:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:18:20 INFO - 2017-03-21 11:18:20.626 screenresolution[29464:241270] starting screenresolution argv=screenresolution get 11:18:20 INFO - 2017-03-21 11:18:20.628 screenresolution[29464:241270] Display 0: 1600x1200x32@60 11:18:20 INFO - 2017-03-21 11:18:20.635 screenresolution[29465:241272] starting screenresolution argv=screenresolution list 11:18:20 INFO - Available Modes on Display 0 11:18:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:18:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:18:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:18:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:18:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:18:20 INFO - Intel Iris: 11:18:20 INFO - Chipset Model: Intel Iris 11:18:20 INFO - Type: GPU 11:18:20 INFO - Bus: Built-In 11:18:20 INFO - VRAM (Dynamic, Max): 1536 MB 11:18:20 INFO - Vendor: Intel (0x8086) 11:18:20 INFO - Device ID: 0x0a2e 11:18:20 INFO - Revision ID: 0x0009 11:18:20 INFO - Displays: 11:18:20 INFO - Display: 11:18:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:18:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:18:20 INFO - Display Serial Number: 1600x1200 60 11:18:20 INFO - Main Display: Yes 11:18:20 INFO - Mirror: Off 11:18:20 INFO - Online: Yes 11:18:20 INFO - Rotation: Supported 11:18:20 INFO - Return code: 0 11:18:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:18:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:18:20 INFO - Python 2.7.3 11:18:20 INFO - Return code: 0 11:18:20 INFO - grabbing minidump binary from tooltool 11:18:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:18:20 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 0x1006aed30>, '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 0x1005d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1006a3be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:18:20 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 11:18:20 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 11:18:20 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 11:18:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:18:20 INFO - Return code: 0 11:18:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:18:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:18:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:18:20 INFO - ENV: RUST_BACKTRACE is now 1 11:18:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:18:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:18:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DpJ_0uuqQfyqN9k8ZTvquw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0057', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 11:18:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DpJ_0uuqQfyqN9k8ZTvquw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0057 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 11:18:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.I3urnkvzxs/Render', 11:18:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:18:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:18:20 INFO - 'HOME': '/Users/cltbld', 11:18:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:18:20 INFO - 'LOGNAME': 'cltbld', 11:18:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:18:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:18:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:18:20 INFO - 'MOZ_NO_REMOTE': '1', 11:18:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:18:20 INFO - 'NO_EM_RESTART': '1', 11:18:20 INFO - 'PAGER': '/bin/cat', 11:18:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:18:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:18:20 INFO - 'PWD': '/builds/slave/test', 11:18:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:18:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:18:20 INFO - 'RUST_BACKTRACE': '1', 11:18:20 INFO - 'SHELL': '/bin/bash', 11:18:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.siw1ch46wa/Listeners', 11:18:20 INFO - 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/', 11:18:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:18:20 INFO - 'USER': 'cltbld', 11:18:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:18:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:18:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:18:21 INFO - 'XPC_FLAGS': '0x0', 11:18:21 INFO - 'XPC_SERVICE_NAME': '0', 11:18:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:18:21 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DpJ_0uuqQfyqN9k8ZTvquw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0057', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 11:18:21 INFO - mozversion application_buildid: 20170321092323 11:18:21 INFO - mozversion application_changeset: 9fb5e850ab7ab0b2b90640c604f66038407b411d 11:18:21 INFO - mozversion application_display_name: Nightly 11:18:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:18:21 INFO - mozversion application_name: Firefox 11:18:21 INFO - mozversion application_remotingname: firefox 11:18:21 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:18:21 INFO - mozversion application_vendor: Mozilla 11:18:21 INFO - mozversion application_version: 55.0a1 11:18:21 INFO - mozversion platform_buildid: 20170321092323 11:18:21 INFO - mozversion platform_changeset: 9fb5e850ab7ab0b2b90640c604f66038407b411d 11:18:21 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:18:21 INFO - mozversion platform_version: 55.0a1 11:18:21 INFO - using testdate: 1490120301 11:18:21 INFO - actual date: 1490120301 11:18:21 INFO - starting webserver on 'localhost:49286' 11:18:21 INFO - SUITE-START | Running 2 tests 11:18:21 INFO - TEST-START | dromaeo_css 11:18:21 INFO - operating with platform_type : mac_ 11:18:21 INFO - Initialising browser for dromaeo_css test... 11:18:21 INFO - TEST-INFO | started process 29475 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/tmpp9IWOG/profile http://localhost:49286/getInfo.html) 11:18:23 INFO - PID 29475 | __metrics Screen width/height:1600/1200 11:18:23 INFO - PID 29475 | colorDepth:24 11:18:23 INFO - PID 29475 | Browser inner width/height: 1024/689 11:18:23 INFO - PID 29475 | __metrics 11:18:23 INFO - PID 29475 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:18:23 INFO - PID 29475 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:18:24 INFO - TEST-INFO | 29475: exit 0 11:18:24 INFO - Browser initialized. 11:18:24 INFO - Running cycle 1/1 for dromaeo_css test... 11:18:24 INFO - TEST-INFO | started process 29478 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/tmpp9IWOG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:18:36 INFO - PID 29478 | RSS: Main: 233893888 11:18:36 INFO - PID 29478 | 11:21:41 INFO - PID 29478 | Cycle 1(1): loaded http://localhost:49286/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49286/tests/dromaeo/cssquery-ext.html) 11:21:43 INFO - PID 29478 | RSS: Main: 408285184 11:21:43 INFO - PID 29478 | 11:23:26 INFO - PID 29478 | ************************* 11:23:26 INFO - PID 29478 | A coding exception was thrown and uncaught in a Task. 11:23:26 INFO - PID 29478 | 11:23:26 ERROR - PID 29478 | Full message: TypeError: NetworkError when attempting to fetch resource. 11:23:26 INFO - PID 29478 | Full stack: 11:23:26 INFO - PID 29478 | ************************* 11:24:46 INFO - PID 29478 | Cycle 1(1): loaded http://localhost:49286/tests/dromaeo/cssquery-ext.html (next: http://localhost:49286/tests/dromaeo/cssquery-jquery.html) 11:24:47 INFO - PID 29478 | RSS: Main: 502640640 11:24:47 INFO - PID 29478 | 11:27:49 INFO - PID 29478 | Cycle 1(1): loaded http://localhost:49286/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49286/tests/dromaeo/cssquery-mootools.html) 11:27:50 INFO - PID 29478 | RSS: Main: 354430976 11:27:50 INFO - PID 29478 | 11:30:52 INFO - PID 29478 | Cycle 1(1): loaded http://localhost:49286/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49286/tests/dromaeo/cssquery-prototype.html) 11:30:53 INFO - PID 29478 | RSS: Main: 338419712 11:30:53 INFO - PID 29478 | 11:33:57 INFO - PID 29478 | Cycle 1(1): loaded http://localhost:49286/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49286/tests/dromaeo/cssquery-yui.html) 11:33:57 INFO - PID 29478 | RSS: Main: 340246528 11:33:57 INFO - PID 29478 | 11:37:01 INFO - PID 29478 | Cycle 1(1): loaded http://localhost:49286/tests/dromaeo/cssquery-yui.html (next: http://localhost:49286/tests/dromaeo/cssquery-dojo.html) 11:37:01 INFO - PID 29478 | RSS: Main: 396140544 11:37:01 INFO - PID 29478 | 11:37:01 INFO - PID 29478 | __start_tp_report 11:37:01 INFO - PID 29478 | _x_x_mozilla_page_load 11:37:01 INFO - PID 29478 | _x_x_mozilla_page_load_details 11:37:01 INFO - PID 29478 | |i|pagename|runs| 11:37:01 INFO - PID 29478 | |0;dojo.html;3454.8790792322266;3460.083077984333;3470.357983773001;3503.52702385178;3536.54024976753;6025.457708806207;6108.908366374497;6123.724432400541;6134.754609815607;6160;6120.602160859541;6145.661988590626;6151.692415379224;6152.015677491602;6197.938020619804;4760.071786001731;5300.363956325246;5356.839294821161;5527.861803454925;5579.9163012554845;5603.8599035024245;5604.887902241952;5634.915476267859;5637.746301416426;5694.430556944314;5408.756605952742;5478.808241711561;5495.038368285574;5512.91730624041;5558.91661625078;5564.944350556523;5571.693556854371;5582.804601838957;5592.7762889484375;5612.438756124396;5464.23500709901;5482.5888058395385;5491.697956612385;5493.093639549469;5505.724713764296;4916.237983112624;6171.969140154315;6176.7838125665385;6182.752689892399;6207.4723648489935;6330.905036424458;6381.202349706286;6389.041643753428;6507.4794016479045;6581.14445122131;6603.537752357337;6633.137692100045;6634.469242460592;6644.335566443366;6676.599404035774;5201;6127.816165515042;6271.780487682955;6278.811635650984;6316.494680425555;6568.211814582185;6637.668116594247;6663.20041595002;6829.112215412064;6864.622445765431;6409.487241020707;6477.77327793525;6504.15445992018;6508;6509.381608747161;6600.075989361493;6629.469642428594;6652.767153149616;6653.434458071021;6678.465722742169;6347.714352854133;6523.054157147282;6591.967040164768;6593.142891424084;6611.305812889577;6411.871762564722;6432;6441.3880681335195;6446.677666116675;6496.902546461807;4266.4240327556;5232.555232805178;5317.335333083364;5320.361556613231;5328.92006619901;4624.421947256593;4651.883702907387;4684.625229981593;4688.991866748665;4736.3369128322065;6118.162361256266;6176.722047507873;6313.810585682437;6314.747410103544;6342.904856427157;6491.448226900672;6559.180102487189;6652.900206496906;6694.665266736643;6704.094947182068;6297.905531417033;6316.9368306317265;6322.841928951721;6326.398992095744;6330.68346582669;5844.444777746107;5853.590248682552;5882.764689412461;5912.201852749909;5914.4381283777975;6678.966605166943;6767.086443330088;6778.084958530535;6855.451563874978;6887.242403335729;6460.838479038061;6551.246606640298;6557.508186885907;6712.228093769084;6726.730930762715;6678.365555272241;6810.965945170243;6823.692933817891;6828.453723702191;6909.2399836019;6531.836704082246;6541.836454088686;6552.901706474311;6566.704498297492;6570.211574611078;6387.616742995435;6501.674916254263;6670.266270710315;6670.9332906670315;6680.498962577729;6318.273398559225;6329.746810127727;6341.8731625368155;6391.58454700443;6392.872142557216;6443.259025212161;6464.8707025858275;6467.094606754964;6467.644279564481;6475.611463312216;6278.686065696788;6316.178896743578;6328.6202827830475;6336.968315158395;6343.9682801585695;6224.62652240867;6285.214348206474;6317.747290108344;6342.778002769881;6483.546151769331;6343.080253363144;6345.90481142774;6360.840978975562;6372.840678983062;6373.776917807854 11:37:01 INFO - PID 29478 | |1;ext.html;725.7713820146654;729.4055344893915;743.4869939741578;748.6069813347995;813.1218284253006;27274.727252727465;27754.167374978733;27801.443971120592;27850.582241266373;35713.28573428536;37730.24539509208;38038.80980595093;38070.80964595173;38072;38140;2773.77809775218;2823.308289469083;2826.7173282671774;2829.5472724364104;2831.957520637192;2487.6268559716095;2542.5677634802037;2810.12886005338;2845.388241528071;2849.5013372659805;2841.9715802841915;2900.144457385066;2900.5069138246445;2903.1580841555924;2907.185987923385;2884.351021020275;2903.3612605226817;2908.6073380093735;2910.126961911418;2910.970890291091;2479.330580743207;2533.050106210171;2859.0993836941298;2897.203269100993;2902.1438675590753;35388.16095344265;35927.82036089829;35945.64054359449;35994.820025899964;36049.45925811115;2927.5608658701153;2999.610050693396;3004.7746419018663;3011.4428830666398;3012.8988797684647;25899.482010359687;25935;26161.607575886377;26363;26441.735582644313;2751.147144385247;2759.530879750447;2765.9031933882216;2772.4177922636272;2773.0987429085626;250303;252095;254092;295427.5228623865;296521;47287;47400;47414.76292618515;47470.52529474661;47533;49722.50277497251;49784.502154977985;49829.003419931396;49836;49873.753156171384;14037.438502459992;14303.427862885368;14314.785278220836;14323.641908952359;14333.64165895861;10766.838497422545;10876.891231087746;11721.003714684348;11731.41342932867;11740.471678774476;11180.329180249211;11297.830532542019;12990.870091298966;13169.934150329187;13580.86419135796;5907.497862681635;5959;6028.487578555872;6035.8792824143275;6036.245469316335;798.4769975665948;959.7168835193635;963.9878127965608;968.9660861869942;983;23179.768202317762;23488.647670284958;23600.40998975005;23607.29178124659;23611.409714756926;1065.5790962569897;1074.9570017199223;1100.1913593508764;1100.983485247722;1108.0083325423861;11607.187496875138;12324.630261092181;12334.259944403364;12359.814602780965;12369.948554372799;467339;470566;470915;471769;472231;55842;56047.71976140175;56093;56152.71923640356;56282.43717562854;1470.3016067368128;1483.5920121966547;1530.4566878758103;1532.9540113796604;1539.8768098552089;20791.27230546984;23775.881120594287;23809.880950595136;23860.88069559641;23861;37936.43095353627;37945;37955.24089518139;37984.81007594944;37989.43015854709;428273;429724;429852;430191;430393;33147;33668.663313367535;33772.81795137158;33828.15429614279;33878.830605846815;30178.698213018466;41257.96855078647;41388.1722365557;41421;44609;30956.69043309538;31088;33720.32559348848;35743;35962.820185898905;29753.256168595093;33308.33383332368;34066;34377.48433773445;35356.11609709778;34871.82564087163;35602.287954241285;35668.643313566536;35675.46486802648;35741;25679.972801087748;30482.695173047985;30670.539941900442;30969.690303096682;31170.220744480663;22810.88594557083;22930.770692293532;23158.305250842503;23373.766262337158;23441.648375274726 11:37:01 INFO - PID 29478 | |2;jquery.html;419.4091678903344;430.8987387963829;430.90304681446656;431.69781153192764;451.7831440908364;19388.321408750715;20785.584288314225;21282;21560.89219553902;21617.81102039391;19358.903205483988;20906.89546552265;21893.890530547364;22169;22251.666225006637;4252.213340531996;4407.779611019452;4408.845690400836;4410.713303635253;4411.360352748849;3774.698024158061;3964.7026473014494;4352.194843953863;4388.912221755563;4397.362382454558;3798.335291324021;3798.373268410709;3805.771653700787;3816.5610954740146;3825.751326163804;3816.045988502888;3819.217060502618;3822.636849499293;3825.674817640504;3827.789471579062;3506.544149260605;3547.450145227494;3956.109875278068;3995.1210733638554;4051.7163798534116;28137.718622813918;28148;28179.85910070457;28190.859045704845;28452;1976.6217827511987;1994.783182258821;2004.2083377066124;2024.068928292987;2025.8379329653592;15687.058776473448;16198.35206591735;16427.425040123657;16772.916135419244;17203.22585483656;1603.6700709487434;1610;1616.5877701186123;1665.275605111768;1675.6481138960833;132261;140157;140521;140701.2964935169;142091;9836.6720492734;11816.527338906348;11850;12141.392930353448;12309;16100.919495402448;16113.27490262941;16122.435714749927;16275.755863662054;16577.254023568967;8791.912080879109;8967.551622418852;9003.909960900308;9133.086691330947;9154.221891139194;8627.956860215785;8631.482111073356;8655.783605409915;8790.692325768696;8793.824123517494;7681.615919204018;7814.405373528127;7826.804329891798;7860.803479912934;7869.213078692085;2090.1639344262417;2105.757603014198;2144.3888491779767;2174.6846707227364;2382.344855164809;820.0856045509214;918.9816203675889;927.2396634759433;951.3911096897991;964.286428043243;20021.99890005494;20485.897570512054;20539.075741591663;20621.793782061988;20972.685409718866;932.48247222792;958.6980101268078;979.9412035277907;981.1954197558076;982.9115379615725;9836.262280329003;10428.895711042944;10496.947515262527;10598.576056957789;10622.872179631382;664858;675454;695347;718231;718397;91287;133921;133935.99999999805;137250;138811;1132.5073592987078;1141.14414189358;1163.4764356039816;1167.643868620062;1169.929804211733;15249.3900243989;15854;18932.580056496092;20860.68708969397;20869.582608347446;58024;58775.70612146912;59343.1098533531;60058.39941600703;61168.69415652893;83425;85093;86357.56821215854;86719.92960492705;87415;8506.149385061444;8524.062353141273;8712.172343627346;8745.387822852339;8821.64713411482;13716.519921802688;13754.587362379145;13777.726675098496;14189.148651080968;14260.500882469063;11588.015018723503;11748.823767643664;11857.940710296394;11942.940285298519;11950.34273114987;10166.796664066529;11018.834717479405;11365.54537818478;11488.712782180179;11634.011109055487;11499.482523286304;11717.707057323294;12029;12087.395630218278;12273.631791046282;8439.831203375776;8491.405601607828;8499.95750021246;8729.388942773945;8922.68770593026;9998.850017249602;10121.443320617142;11026.503807328529;11111.166662500184;11533.312430635406 11:37:01 INFO - PID 29478 | |3;mootools.html;1356.9864301356986;1422.765243734783;1441.5242969819947;1448.5943935698006;1449.4926775628526;10139.797204055914;10551.208659350541;10568.15454763619;10581.100606448452;10582.52378642961;10288.279820412574;10421.947890260557;10551.73620659482;10636;10673;2389.545986262613;2437.6709144265565;2443.3402981195068;2452.0314475782056;2454.803615710739;2320.431494283903;2410.9035638574437;2428.2836563213805;2430.416699991998;2431.465077682907;2032.278541117904;2105.3683894831565;2114.68279758036;2119.2688522459803;2120.300300900699;2088.498760297533;2120.4062862398478;2333.0434521845964;2427.6965379327585;2433.6714543536573;2248.359217622986;2250.5723912456583;2255.560165767676;2259.2318611671944;2260.7287125544876;10679.092277156451;10751.946240268748;10756.73108172294;10773;10781.299215550964;5280.867978300535;5481.424450432687;5964.254468191476;6007.068904319837;6228;3604.333198358312;3866.6520013198665;3950.0914789598555;3966.841326346943;3974.8608798691907;926.7080869526078;992.892924389308;1135.6025391113126;1191.6186820217533;1195.665213740154;14728.18994955277;14763.26183690822;14871.405143794234;14885.925570372186;14908.62728431787;4966.950330496721;5170.689758614495;5210.687358758487;5228.9477105229225;5255.3168088148295;11061.502232399562;11061.612843550438;11072.723181920517;11076;11083.168762342857;6151.9384806151365;6157.415045570664;6161.229846269216;6169.5064394848305;6182.598131121463;5440;5501.092319767274;5535.197396377503;5571.665700057928;5603.971980140154;3396.8641254350046;3924.843006279717;4088.6115818997146;4134.297169481196;4153.688473364449;1208.957686480969;1211.1340391620033;1223.7980733178927;1226.0743138930052;1228.8033914573625;743.9144498382647;752.1989081628049;773.3156156801272;779.9532028078333;798.594473726246;5767;5777.537796976232;5919.970400148058;5963.4931030862;5979.282486101609;489.3980404102902;521.3483146067416;565.4402141879592;577.6707276852154;579.5045236322883;3312.4865645824934;3439.638837922032;4384.561543845653;4460.977695111504;4510.368548403226;13039.608811735663;13678.452861885413;13715;13718.93140534291;13733;13159;13473.258970756702;13663.18020918748;13666.794998075036;13687.04190706641;9638.807223855483;10055.044770746767;10068.647597334058;10091.747706307255;10108.444035578113;4931.3835770528685;6585.670716464253;6627.768028118992;6694.933050669431;6710.731570737116;11901.642950711492;12176.269423834598;12193.146479746687;12194.451249693608;12290;12157.270563766202;12184.268943863397;12421.44103515362;12565.246085234916;12642.431090600761;7837.725679601187;7954.482958607672;8007.83984320322;8022.117567067501;8043.557604331818;8154;9700.805983880424;9917.45454000008;9919.702408927744;9939.552720127756;8822.911770882209;8825.205731484197;8856.778580535538;8934.061923498199;8954.955225223832;7083.574985500886;8542.18849209324;8597.957010214908;8599.785005374915;8798.340124490816;7552.169261381134;7743.148253692202;7745.57399343019;7760.379169666526;7768.106667733283;5177.042247184283;5282.97358513205;5588.189712491747;5736.799212027559;5865.94134058654;5763.855903602276;5874.353821079592;5874.7662990772415;5913.142594323885;5922.289325280994 11:37:01 INFO - PID 29478 | |4;prototype.html;333.5713608013702;388.4891367851275;393.23319526922506;395.35162333772615;399.51259463454585;4838.500064979854;5258.526732594061;5516.448355164482;5724.942750572493;5821.592488525805;5727.766171193238;5845.210896528967;5926.911096333559;5929.169916211734;5970.432808883149;4119.8146083426245;4295.892602684927;4472.1055788842195;4474.932876006863;4717.551832575917;3929.5873933237026;4185.518665353477;4433.689641725081;4466.766616691654;4478.454933048093;4420.027593929331;4435.356873253377;4442.267025940716;4451.287793952968;4476.843310484133;3581.946270805953;4242.7242229255;4369.213541562549;4434.512203657595;4459.308807134867;3125.453045717013;3770.3024940386117;3786.5456145262747;3798.734088613798;3819.446180303868;5723.7996670116345;5728.25532680753;5734.827955161351;5741.167530708066;5767.5962682612235;4061.614146656063;4240.893977650552;4916.483769204254;5169.353830771153;5176.870578235536;5747.971260143714;6177;6313.936860631381;6316.715747791361;6338.144350512668;4261.957380426187;4262.46719160105;4265.744055356658;4267.335739061768;4279.657627389802;4537.546245375436;5684.062129748587;6146.739918316779;6495.935040649628;6574.539782215199;4167.1457351242925;4301.290287102593;4313.719608225455;4324.56754324565;4334.544872788375;4377.190219809346;4408.625266852354;4412.382266482695;4414.536473670282;4449.7997590108525;4993.290733230569;5171.379434467888;5177.352830896138;5191.636585439059;5191.948080519146;5897.662615333131;6023.909641355383;6297.70133901132;6328.873422531524;6338.334474880164;1483.4881965721809;1497.2064805653022;1497.5133081748259;1498.4380857178558;1508.5775982724854;945.8061097475002;989.2185173712686;991.453332800833;994.627014869424;1009.3540134314045;5529.640573362799;5548.4174161713245;5626.43735626434;6036.849078773066;6052.848678782978;5123.7438128093445;5392.838214853561;6546.312637173123;6553.475721942234;6567.080608714784;5028.64799464034;5518.503334699896;5694.914576281359;5719;6021.397860214031;2818;3051.3744682340066;3307.6030876294517;3400.2009527760956;3417.3165366926714;5958.314793798769;6412.807615771534;6653.667316634246;6782.593044417351;6785.796426107225;5381.246625472437;6293.30773614902;6330.461910737639;6471.255805582324;6677.432418244406;5682.522544138554;6582.80251592453;6720.833083488171;6729.360710732472;6761.628110453972;5667.971660141673;6217.440430361289;6434.388733070351;6537.921242994853;6540.149780528501;5869.325027621708;6367.42693157618;6406.359364063556;6423.792415967549;6443.581167224115;5328.520433161034;5699.261725173696;6384.170057892445;6734.3602357775935;6798.660066996532;4063.674906007453;4210.347396153601;4279.16556271524;4309.353596960481;4329.372241024971;6399.9680001601555;6400.583962042452;6429.51778616615;6448.742050317935;6463.7395707838405;5191.948080519298;5311.309529761106;5970.194023806731;6254.906176407267;6263.843403914939;6223.937760622336;6226.875462490815;6246.843828904313;6269.341719119427;6285.308616052139;5395.757190926339;6034.672372078136;6042.939570604238;6100.267967843887;6124.418180272867;5588.720563971867;5707.1153971134545;5997.760089596367;6213.912565301144;6214.5028397728975;4859.222524396079;5017.397912250553;6076.574639775174;6085.391460853879;6086.482648974887 11:37:01 INFO - PID 29478 | |5;yui.html;707.2114592229663;717.2146499582955;717.8288893949446;724.1852915470098;726.0742553244615;15019.24903754812;15394.230288485587;15645.921770391147;15839.762403563927;15939.442119525818;14484;14801.407943682267;14814.333354999024;14821.962462627593;15458.381664733426;654.1855390039408;672.3612568060338;932.6968735161066;944.36255527519;968.099667309402;837.3426859914968;938.1134827587898;974.5955428497165;975.2539307429822;975.5707488704948;1026.091908660833;1026.0970346095426;1027.193652982409;1030.6650338639934;1032.0969151992008;1019.2304809868576;1020.2654089055868;1021.019820971868;1021.2136654775856;1022.4223313827664;758.3023618271204;760.0954863712165;763.2634507700096;765.3532764813724;766.0500978786265;15112.622184445367;15169.317380717894;15462.922685386613;15894;16173.838261617468;14193.574192774233;14490.275486225646;14539.78190327146;14638.853611463855;14786.03890747098;11452.484638191218;11626.069914406911;11873.643790686294;12172.208806427643;12198.512059517609;2826.5053615617185;3027.2431892026993;3063.540468929656;3070.6929307069336;3074.093142522961;103807;107517.46241268744;108853;109788.35317470244;110691.44654276758;8052.838943221102;9498.620055197853;10050.798984020279;10283.228757843042;10364.963503649726;19607.705884411746;19647.508812279808;19657.41027769169;22946.967386467648;23783;7361.705531778776;7670.4247181461615;7701.383889288955;7749.651265692944;8063.879041814377;4042.130941847517;4045.615666511677;4060.045889216032;4130.173965206971;4143.212789569972;4050.9797451013146;4168.3955826405;4168.91662166761;4169.061961058798;4172.520160181557;724.2757242757243;816.77946954322;817.3496076721879;821.8191997760483;835.5822088955522;521.5019656228293;561.2927711083981;583.7198144890476;585.8916100521417;603.384547761281;8884.777880552909;9989.922707664238;10016;10116.317779577577;10303.124234440007;541.6614615865084;560.7000254863628;592.8725324055349;593.2821286243608;596.9403059693996;4034.9596504034585;4153.875383738493;4782.880427989328;4793.257045158006;4828.782704778293;83849;86073;86782;89008.664870027;89562.10437895668;73746.26253737502;75151.24848751443;75871;85837.57081214679;90039.09960900307;47172.7641361791;70984;71110.933336;72219.55560888753;74667.50664986779;10372.145993823166;10667.306625069346;10812.135029197803;10896.891031089588;10926.617568385069;34653.48019779756;36178;36588;36723.26553468969;37253.441198382585;45000;45032.596088469596;45762;46477.302840456745;46480.070398592514;13139.080264381402;13239.007074469646;13287.13633613812;13313.201207927556;13588.864111359155;14332.283385830458;14348.139111653334;14348.139111653334;14353.497627582985;14379.784303235669;13080.411381488046;13281.53514627022;13316;13365.26491043002;13474.258915759727;13380.464781408635;13450.529231476852;13650.590482285548;13848.446062157402;13892.583222503341;12876.163049401759;12958.22250664963;13017.869821302045;13326.666833329244;13480.46078156902;10431.426271555138;10636.012887315701;10825.296355736851;10887.129029677448;11692.298462092302;9166.141944769282;9652.855207172039;10855.348679078965;11162.888371116185;11318.434078295888 11:37:01 INFO - PID 29478 | __end_tp_report 11:37:01 INFO - PID 29478 | __start_cc_report 11:37:01 INFO - PID 29478 | _x_x_mozilla_cycle_collect,1970 11:37:01 INFO - PID 29478 | __end_cc_report 11:37:01 INFO - PID 29478 | __startTimestamp1490121421517__endTimestamp 11:37:01 INFO - PID 29478 | 11:37:01 INFO - PID 29478 | ------- Summary: start ------- 11:37:01 INFO - PID 29478 | Number of tests: 6 11:37:01 INFO - PID 29478 | 11:37:01 INFO - PID 29478 | [#0] dojo.html Cycles:165 Average:6094.84 Median:6330.91 stddev:709.82 (11.2%) stddev-sans-first:681.12 11:37:01 INFO - PID 29478 | Values: 3454.9 3460.1 3470.4 3503.5 3536.5 6025.5 6108.9 6123.7 6134.8 6160.0 6120.6 6145.7 6151.7 6152.0 6197.9 4760.1 5300.4 5356.8 5527.9 5579.9 5603.9 5604.9 5634.9 5637.7 5694.4 5408.8 5478.8 5495.0 5512.9 5558.9 5564.9 5571.7 5582.8 5592.8 5612.4 5464.2 5482.6 5491.7 5493.1 5505.7 4916.2 6172.0 6176.8 6182.8 6207.5 6330.9 6381.2 6389.0 6507.5 6581.1 6603.5 6633.1 6634.5 6644.3 6676.6 5201.0 6127.8 6271.8 6278.8 6316.5 6568.2 6637.7 6663.2 6829.1 6864.6 6409.5 6477.8 6504.2 6508.0 6509.4 6600.1 6629.5 6652.8 6653.4 6678.5 6347.7 6523.1 6592.0 6593.1 6611.3 6411.9 6432.0 6441.4 6446.7 6496.9 4266.4 5232.6 5317.3 5320.4 5328.9 4624.4 4651.9 4684.6 4689.0 4736.3 6118.2 6176.7 6313.8 6314.7 6342.9 6491.4 6559.2 6652.9 6694.7 6704.1 6297.9 6316.9 6322.8 6326.4 6330.7 5844.4 5853.6 5882.8 5912.2 5914.4 6679.0 6767.1 6778.1 6855.5 6887.2 6460.8 6551.2 6557.5 6712.2 6726.7 6678.4 6811.0 6823.7 6828.5 6909.2 6531.8 6541.8 6552.9 6566.7 6570.2 6387.6 6501.7 6670.3 6670.9 6680.5 6318.3 6329.7 6341.9 6391.6 6392.9 6443.3 6464.9 6467.1 6467.6 6475.6 6278.7 6316.2 6328.6 6337.0 6344.0 6224.6 6285.2 6317.7 6342.8 6483.5 6343.1 6345.9 6360.8 6372.8 6373.8 11:37:01 INFO - PID 29478 | 11:37:01 INFO - PID 29478 | [#1] ext.html Cycles:180 Average:51287.53 Median:163137.13 stddev:106710.88 (65.4%) stddev-sans-first:106942.71 11:37:01 INFO - PID 29478 | Values: 725.8 729.4 743.5 748.6 813.1 27274.7 27754.2 27801.4 27850.6 35713.3 37730.2 38038.8 38070.8 38072.0 38140.0 2773.8 2823.3 2826.7 2829.5 2832.0 2487.6 2542.6 2810.1 2845.4 2849.5 2842.0 2900.1 2900.5 2903.2 2907.2 2884.4 2903.4 2908.6 2910.1 2911.0 2479.3 2533.1 2859.1 2897.2 2902.1 35388.2 35927.8 35945.6 35994.8 36049.5 2927.6 2999.6 3004.8 3011.4 3012.9 25899.5 25935.0 26161.6 26363.0 26441.7 2751.1 2759.5 2765.9 2772.4 2773.1 250303.0 252095.0 254092.0 295427.5 296521.0 47287.0 47400.0 47414.8 47470.5 47533.0 49722.5 49784.5 49829.0 49836.0 49873.8 14037.4 14303.4 14314.8 14323.6 14333.6 10766.8 10876.9 11721.0 11731.4 11740.5 11180.3 11297.8 12990.9 13169.9 13580.9 5907.5 5959.0 6028.5 6035.9 6036.2 798.5 959.7 964.0 969.0 983.0 23179.8 23488.6 23600.4 23607.3 23611.4 1065.6 1075.0 1100.2 1101.0 1108.0 11607.2 12324.6 12334.3 12359.8 12369.9 467339.0 470566.0 470915.0 471769.0 472231.0 55842.0 56047.7 56093.0 56152.7 56282.4 1470.3 1483.6 1530.5 1533.0 1539.9 20791.3 23775.9 23809.9 23860.9 23861.0 37936.4 37945.0 37955.2 37984.8 37989.4 428273.0 429724.0 429852.0 430191.0 430393.0 33147.0 33668.7 33772.8 33828.2 33878.8 30178.7 41258.0 41388.2 41421.0 44609.0 30956.7 31088.0 33720.3 35743.0 35962.8 29753.3 33308.3 34066.0 34377.5 35356.1 34871.8 35602.3 35668.6 35675.5 35741.0 25680.0 30482.7 30670.5 30969.7 31170.2 22810.9 22930.8 23158.3 23373.8 23441.6 11:37:01 INFO - PID 29478 | 11:37:01 INFO - PID 29478 | [#2] jquery.html Cycles:180 Average:38933.54 Median:15260.03 stddev:115711.01 (758.3%) stddev-sans-first:115999.47 11:37:01 INFO - PID 29478 | Values: 419.4 430.9 430.9 431.7 451.8 19388.3 20785.6 21282.0 21560.9 21617.8 19358.9 20906.9 21893.9 22169.0 22251.7 4252.2 4407.8 4408.8 4410.7 4411.4 3774.7 3964.7 4352.2 4388.9 4397.4 3798.3 3798.4 3805.8 3816.6 3825.8 3816.0 3819.2 3822.6 3825.7 3827.8 3506.5 3547.5 3956.1 3995.1 4051.7 28137.7 28148.0 28179.9 28190.9 28452.0 1976.6 1994.8 2004.2 2024.1 2025.8 15687.1 16198.4 16427.4 16772.9 17203.2 1603.7 1610.0 1616.6 1665.3 1675.6 132261.0 140157.0 140521.0 140701.3 142091.0 9836.7 11816.5 11850.0 12141.4 12309.0 16100.9 16113.3 16122.4 16275.8 16577.3 8791.9 8967.6 9003.9 9133.1 9154.2 8628.0 8631.5 8655.8 8790.7 8793.8 7681.6 7814.4 7826.8 7860.8 7869.2 2090.2 2105.8 2144.4 2174.7 2382.3 820.1 919.0 927.2 951.4 964.3 20022.0 20485.9 20539.1 20621.8 20972.7 932.5 958.7 979.9 981.2 982.9 9836.3 10428.9 10496.9 10598.6 10622.9 664858.0 675454.0 695347.0 718231.0 718397.0 91287.0 133921.0 133936.0 137250.0 138811.0 1132.5 1141.1 1163.5 1167.6 1169.9 15249.4 15854.0 18932.6 20860.7 20869.6 58024.0 58775.7 59343.1 60058.4 61168.7 83425.0 85093.0 86357.6 86719.9 87415.0 8506.1 8524.1 8712.2 8745.4 8821.6 13716.5 13754.6 13777.7 14189.1 14260.5 11588.0 11748.8 11857.9 11942.9 11950.3 10166.8 11018.8 11365.5 11488.7 11634.0 11499.5 11717.7 12029.0 12087.4 12273.6 8439.8 8491.4 8500.0 8729.4 8922.7 9998.9 10121.4 11026.5 11111.2 11533.3 11:37:01 INFO - PID 29478 | 11:37:01 INFO - PID 29478 | [#3] mootools.html Cycles:180 Average:6564.22 Median:3735.49 stddev:4117.75 (110.2%) stddev-sans-first:4110.71 11:37:01 INFO - PID 29478 | Values: 1357.0 1422.8 1441.5 1448.6 1449.5 10139.8 10551.2 10568.2 10581.1 10582.5 10288.3 10421.9 10551.7 10636.0 10673.0 2389.5 2437.7 2443.3 2452.0 2454.8 2320.4 2410.9 2428.3 2430.4 2431.5 2032.3 2105.4 2114.7 2119.3 2120.3 2088.5 2120.4 2333.0 2427.7 2433.7 2248.4 2250.6 2255.6 2259.2 2260.7 10679.1 10751.9 10756.7 10773.0 10781.3 5280.9 5481.4 5964.3 6007.1 6228.0 3604.3 3866.7 3950.1 3966.8 3974.9 926.7 992.9 1135.6 1191.6 1195.7 14728.2 14763.3 14871.4 14885.9 14908.6 4967.0 5170.7 5210.7 5228.9 5255.3 11061.5 11061.6 11072.7 11076.0 11083.2 6151.9 6157.4 6161.2 6169.5 6182.6 5440.0 5501.1 5535.2 5571.7 5604.0 3396.9 3924.8 4088.6 4134.3 4153.7 1209.0 1211.1 1223.8 1226.1 1228.8 743.9 752.2 773.3 780.0 798.6 5767.0 5777.5 5920.0 5963.5 5979.3 489.4 521.3 565.4 577.7 579.5 3312.5 3439.6 4384.6 4461.0 4510.4 13039.6 13678.5 13715.0 13718.9 13733.0 13159.0 13473.3 13663.2 13666.8 13687.0 9638.8 10055.0 10068.6 10091.7 10108.4 4931.4 6585.7 6627.8 6694.9 6710.7 11901.6 12176.3 12193.1 12194.5 12290.0 12157.3 12184.3 12421.4 12565.2 12642.4 7837.7 7954.5 8007.8 8022.1 8043.6 8154.0 9700.8 9917.5 9919.7 9939.6 8822.9 8825.2 8856.8 8934.1 8955.0 7083.6 8542.2 8598.0 8599.8 8798.3 7552.2 7743.1 7745.6 7760.4 7768.1 5177.0 5283.0 5588.2 5736.8 5865.9 5763.9 5874.4 5874.8 5913.1 5922.3 11:37:01 INFO - PID 29478 | 11:37:01 INFO - PID 29478 | [#4] prototype.html Cycles:180 Average:5023.76 Median:5675.25 stddev:1553.26 (27.4%) stddev-sans-first:1517.20 11:37:01 INFO - PID 29478 | Values: 333.6 388.5 393.2 395.4 399.5 4838.5 5258.5 5516.4 5724.9 5821.6 5727.8 5845.2 5926.9 5929.2 5970.4 4119.8 4295.9 4472.1 4474.9 4717.6 3929.6 4185.5 4433.7 4466.8 4478.5 4420.0 4435.4 4442.3 4451.3 4476.8 3581.9 4242.7 4369.2 4434.5 4459.3 3125.5 3770.3 3786.5 3798.7 3819.4 5723.8 5728.3 5734.8 5741.2 5767.6 4061.6 4240.9 4916.5 5169.4 5176.9 5748.0 6177.0 6313.9 6316.7 6338.1 4262.0 4262.5 4265.7 4267.3 4279.7 4537.5 5684.1 6146.7 6495.9 6574.5 4167.1 4301.3 4313.7 4324.6 4334.5 4377.2 4408.6 4412.4 4414.5 4449.8 4993.3 5171.4 5177.4 5191.6 5191.9 5897.7 6023.9 6297.7 6328.9 6338.3 1483.5 1497.2 1497.5 1498.4 1508.6 945.8 989.2 991.5 994.6 1009.4 5529.6 5548.4 5626.4 6036.8 6052.8 5123.7 5392.8 6546.3 6553.5 6567.1 5028.6 5518.5 5694.9 5719.0 6021.4 2818.0 3051.4 3307.6 3400.2 3417.3 5958.3 6412.8 6653.7 6782.6 6785.8 5381.2 6293.3 6330.5 6471.3 6677.4 5682.5 6582.8 6720.8 6729.4 6761.6 5668.0 6217.4 6434.4 6537.9 6540.1 5869.3 6367.4 6406.4 6423.8 6443.6 5328.5 5699.3 6384.2 6734.4 6798.7 4063.7 4210.3 4279.2 4309.4 4329.4 6400.0 6400.6 6429.5 6448.7 6463.7 5191.9 5311.3 5970.2 6254.9 6263.8 6223.9 6226.9 6246.8 6269.3 6285.3 5395.8 6034.7 6042.9 6100.3 6124.4 5588.7 5707.1 5997.8 6213.9 6214.5 4859.2 5017.4 6076.6 6085.4 6086.5 11:37:01 INFO - PID 29478 | 11:37:01 INFO - PID 29478 | [#5] yui.html Cycles:180 Average:18559.74 Median:3045.39 stddev:26143.08 (858.4%) stddev-sans-first:26182.05 11:37:01 INFO - PID 29478 | Values: 707.2 717.2 717.8 724.2 726.1 15019.2 15394.2 15645.9 15839.8 15939.4 14484.0 14801.4 14814.3 14822.0 15458.4 654.2 672.4 932.7 944.4 968.1 837.3 938.1 974.6 975.3 975.6 1026.1 1026.1 1027.2 1030.7 1032.1 1019.2 1020.3 1021.0 1021.2 1022.4 758.3 760.1 763.3 765.4 766.1 15112.6 15169.3 15462.9 15894.0 16173.8 14193.6 14490.3 14539.8 14638.9 14786.0 11452.5 11626.1 11873.6 12172.2 12198.5 2826.5 3027.2 3063.5 3070.7 3074.1 103807.0 107517.5 108853.0 109788.4 110691.4 8052.8 9498.6 10050.8 10283.2 10365.0 19607.7 19647.5 19657.4 22947.0 23783.0 7361.7 7670.4 7701.4 7749.7 8063.9 4042.1 4045.6 4060.0 4130.2 4143.2 4051.0 4168.4 4168.9 4169.1 4172.5 724.3 816.8 817.3 821.8 835.6 521.5 561.3 583.7 585.9 603.4 8884.8 9989.9 10016.0 10116.3 10303.1 541.7 560.7 592.9 593.3 596.9 4035.0 4153.9 4782.9 4793.3 4828.8 83849.0 86073.0 86782.0 89008.7 89562.1 73746.3 75151.2 75871.0 85837.6 90039.1 47172.8 70984.0 71110.9 72219.6 74667.5 10372.1 10667.3 10812.1 10896.9 10926.6 34653.5 36178.0 36588.0 36723.3 37253.4 45000.0 45032.6 45762.0 46477.3 46480.1 13139.1 13239.0 13287.1 13313.2 13588.9 14332.3 14348.1 14348.1 14353.5 14379.8 13080.4 13281.5 13316.0 13365.3 13474.3 13380.5 13450.5 13650.6 13848.4 13892.6 12876.2 12958.2 13017.9 13326.7 13480.5 10431.4 10636.0 10825.3 10887.1 11692.3 9166.1 9652.9 10855.3 11162.9 11318.4 11:37:01 INFO - PID 29478 | -------- Summary: end -------- 11:37:01 INFO - PID 29478 | 11:37:02 INFO - TEST-INFO | 29478: exit 0 11:37:02 INFO - TEST-OK | dromaeo_css | took 1120991ms 11:37:02 INFO - TEST-START | kraken 11:37:02 INFO - operating with platform_type : mac_ 11:37:02 INFO - Initialising browser for kraken test... 11:37:02 INFO - TEST-INFO | started process 29485 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/tmpvYaeeJ/profile http://localhost:49286/getInfo.html) 11:37:04 INFO - PID 29485 | __metrics Screen width/height:1600/1200 11:37:04 INFO - PID 29485 | colorDepth:24 11:37:04 INFO - PID 29485 | Browser inner width/height: 1024/689 11:37:04 INFO - PID 29485 | __metrics 11:37:04 INFO - PID 29485 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:37:04 INFO - PID 29485 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:37:05 INFO - TEST-INFO | 29485: exit 0 11:37:05 INFO - Browser initialized. 11:37:05 INFO - Running cycle 1/1 for kraken test... 11:37:05 INFO - TEST-INFO | started process 29486 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/tmpvYaeeJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:37:17 INFO - PID 29486 | RSS: Main: 234151936 11:37:17 INFO - PID 29486 | 11:37:20 INFO - PID 29486 | Cycle 1(1): loaded http://localhost:49286/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49286/tests/kraken/driver.html?testName=audio-beat-detection) 11:37:21 INFO - PID 29486 | RSS: Main: 284680192 11:37:21 INFO - PID 29486 | 11:37:24 INFO - PID 29486 | Cycle 1(1): loaded http://localhost:49286/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49286/tests/kraken/driver.html?testName=audio-dft) 11:37:25 INFO - PID 29486 | RSS: Main: 285913088 11:37:25 INFO - PID 29486 | 11:37:28 INFO - PID 29486 | Cycle 1(1): loaded http://localhost:49286/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49286/tests/kraken/driver.html?testName=audio-fft) 11:37:29 INFO - PID 29486 | RSS: Main: 376639488 11:37:29 INFO - PID 29486 | 11:37:31 INFO - PID 29486 | Cycle 1(1): loaded http://localhost:49286/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49286/tests/kraken/driver.html?testName=audio-oscillator) 11:37:31 INFO - PID 29486 | RSS: Main: 280510464 11:37:31 INFO - PID 29486 | 11:37:33 INFO - PID 29486 | Cycle 1(1): loaded http://localhost:49286/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49286/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:37:34 INFO - PID 29486 | RSS: Main: 281362432 11:37:34 INFO - PID 29486 | 11:37:37 INFO - PID 29486 | Cycle 1(1): loaded http://localhost:49286/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49286/tests/kraken/driver.html?testName=imaging-darkroom) 11:37:38 INFO - PID 29486 | RSS: Main: 323633152 11:37:38 INFO - PID 29486 | 11:37:41 INFO - PID 29486 | Cycle 1(1): loaded http://localhost:49286/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49286/tests/kraken/driver.html?testName=imaging-desaturate) 11:37:41 INFO - PID 29486 | RSS: Main: 336719872 11:37:41 INFO - PID 29486 | 11:37:44 INFO - PID 29486 | Cycle 1(1): loaded http://localhost:49286/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49286/tests/kraken/driver.html?testName=json-parse-financial) 11:37:45 INFO - PID 29486 | RSS: Main: 342671360 11:37:45 INFO - PID 29486 | 11:37:46 INFO - PID 29486 | Cycle 1(1): loaded http://localhost:49286/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49286/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:37:47 INFO - PID 29486 | RSS: Main: 303034368 11:37:47 INFO - PID 29486 | 11:37:49 INFO - PID 29486 | Cycle 1(1): loaded http://localhost:49286/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49286/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:37:49 INFO - PID 29486 | RSS: Main: 303542272 11:37:49 INFO - PID 29486 | 11:37:52 INFO - PID 29486 | Cycle 1(1): loaded http://localhost:49286/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49286/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:37:52 INFO - PID 29486 | RSS: Main: 305627136 11:37:52 INFO - PID 29486 | 11:37:55 INFO - PID 29486 | Cycle 1(1): loaded http://localhost:49286/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49286/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:37:56 INFO - PID 29486 | RSS: Main: 326725632 11:37:56 INFO - PID 29486 | 11:37:59 INFO - PID 29486 | Cycle 1(1): loaded http://localhost:49286/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49286/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:38:00 INFO - PID 29486 | RSS: Main: 320749568 11:38:00 INFO - PID 29486 | 11:38:02 INFO - PID 29486 | Cycle 1(1): loaded http://localhost:49286/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49286/tests/kraken/driver.html?testName=ai-astar) 11:38:02 INFO - PID 29486 | RSS: Main: 382685184 11:38:02 INFO - PID 29486 | 11:38:02 INFO - PID 29486 | __start_tp_report 11:38:02 INFO - PID 29486 | _x_x_mozilla_page_load 11:38:02 INFO - PID 29486 | _x_x_mozilla_page_load_details 11:38:02 INFO - PID 29486 | |i|pagename|runs| 11:38:02 INFO - PID 29486 | |0;ai-astar;111;121;167;149;113;111;113;113;112;110 11:38:02 INFO - PID 29486 | |1;audio-beat-detection;144;115;93;171;146;142;111;112;134;111 11:38:02 INFO - PID 29486 | |2;audio-dft;184;185;187;200;211;184;180;182;183;184 11:38:02 INFO - PID 29486 | |3;audio-fft;80;60;61;91;67;83;87;86;78;58 11:38:02 INFO - PID 29486 | |4;audio-oscillator;91;70;89;69;69;70;83;70;68;84 11:38:02 INFO - PID 29486 | |5;imaging-gaussian-blur;86;92;134;87;87;86;86;87;86;87 11:38:02 INFO - PID 29486 | |6;imaging-darkroom;94;94;94;94;94;94;94;95;93;94 11:38:02 INFO - PID 29486 | |7;imaging-desaturate;86;85;84;83;84;85;90;84;82;82 11:38:02 INFO - PID 29486 | |8;json-parse-financial;62;60;60;61;62;61;63;62;62;62 11:38:02 INFO - PID 29486 | |9;json-stringify-tinderbox;55;54;62;64;62;63;58;57;60;58 11:38:02 INFO - PID 29486 | |10;stanford-crypto-aes;101;106;106;107;83;83;83;83;82;82 11:38:02 INFO - PID 29486 | |11;stanford-crypto-ccm;142;139;140;141;138;143;139;137;176;157 11:38:02 INFO - PID 29486 | |12;stanford-crypto-pbkdf2;192;196;229;185;193;189;185;186;185;182 11:38:02 INFO - PID 29486 | |13;stanford-crypto-sha256-iterative;68;69;68;68;68;68;68;68;68;70 11:38:02 INFO - PID 29486 | __end_tp_report 11:38:02 INFO - PID 29486 | __start_cc_report 11:38:02 INFO - PID 29486 | _x_x_mozilla_cycle_collect,2138 11:38:02 INFO - PID 29486 | __end_cc_report 11:38:02 INFO - PID 29486 | __startTimestamp1490121482413__endTimestamp 11:38:02 INFO - PID 29486 | 11:38:02 INFO - PID 29486 | ------- Summary: start ------- 11:38:02 INFO - PID 29486 | Number of tests: 14 11:38:02 INFO - PID 29486 | 11:38:02 INFO - PID 29486 | [#0] ai-astar Cycles:10 Average:122.00 Median:113.00 stddev:19.68 (17.4%) stddev-sans-first:20.46 11:38:02 INFO - PID 29486 | Values: 111.0 121.0 167.0 149.0 113.0 111.0 113.0 113.0 112.0 110.0 11:38:02 INFO - PID 29486 | 11:38:02 INFO - PID 29486 | [#1] audio-beat-detection Cycles:10 Average:127.90 Median:143.00 stddev:23.31 (16.3%) stddev-sans-first:23.98 11:38:02 INFO - PID 29486 | Values: 144.0 115.0 93.0 171.0 146.0 142.0 111.0 112.0 134.0 111.0 11:38:02 INFO - PID 29486 | 11:38:02 INFO - PID 29486 | [#2] audio-dft Cycles:10 Average:188.00 Median:184.50 stddev:9.75 (5.3%) stddev-sans-first:10.24 11:38:02 INFO - PID 29486 | Values: 184.0 185.0 187.0 200.0 211.0 184.0 180.0 182.0 183.0 184.0 11:38:02 INFO - PID 29486 | 11:38:02 INFO - PID 29486 | [#3] audio-fft Cycles:10 Average:75.10 Median:81.50 stddev:12.44 (15.3%) stddev-sans-first:13.07 11:38:02 INFO - PID 29486 | Values: 80.0 60.0 61.0 91.0 67.0 83.0 87.0 86.0 78.0 58.0 11:38:02 INFO - PID 29486 | 11:38:02 INFO - PID 29486 | [#4] audio-oscillator Cycles:10 Average:76.30 Median:76.50 stddev:9.29 (12.1%) stddev-sans-first:8.19 11:38:02 INFO - PID 29486 | Values: 91.0 70.0 89.0 69.0 69.0 70.0 83.0 70.0 68.0 84.0 11:38:02 INFO - PID 29486 | 11:38:02 INFO - PID 29486 | [#5] imaging-gaussian-blur Cycles:10 Average:91.80 Median:87.00 stddev:14.94 (17.2%) stddev-sans-first:15.69 11:38:02 INFO - PID 29486 | Values: 86.0 92.0 134.0 87.0 87.0 86.0 86.0 87.0 86.0 87.0 11:38:02 INFO - PID 29486 | 11:38:02 INFO - PID 29486 | [#6] imaging-darkroom Cycles:10 Average:94.00 Median:94.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 11:38:02 INFO - PID 29486 | Values: 94.0 94.0 94.0 94.0 94.0 94.0 94.0 95.0 93.0 94.0 11:38:02 INFO - PID 29486 | 11:38:02 INFO - PID 29486 | [#7] imaging-desaturate Cycles:10 Average:84.50 Median:84.50 stddev:2.32 (2.7%) stddev-sans-first:2.40 11:38:02 INFO - PID 29486 | Values: 86.0 85.0 84.0 83.0 84.0 85.0 90.0 84.0 82.0 82.0 11:38:02 INFO - PID 29486 | 11:38:02 INFO - PID 29486 | [#8] json-parse-financial Cycles:10 Average:61.50 Median:62.00 stddev:0.97 (1.6%) stddev-sans-first:1.01 11:38:02 INFO - PID 29486 | Values: 62.0 60.0 60.0 61.0 62.0 61.0 63.0 62.0 62.0 62.0 11:38:02 INFO - PID 29486 | 11:38:02 INFO - PID 29486 | [#9] json-stringify-tinderbox Cycles:10 Average:59.30 Median:61.00 stddev:3.43 (5.6%) stddev-sans-first:3.27 11:38:02 INFO - PID 29486 | Values: 55.0 54.0 62.0 64.0 62.0 63.0 58.0 57.0 60.0 58.0 11:38:02 INFO - PID 29486 | 11:38:02 INFO - PID 29486 | [#10] stanford-crypto-aes Cycles:10 Average:91.60 Median:82.50 stddev:11.64 (14.1%) stddev-sans-first:11.84 11:38:02 INFO - PID 29486 | Values: 101.0 106.0 106.0 107.0 83.0 83.0 83.0 83.0 82.0 82.0 11:38:02 INFO - PID 29486 | 11:38:02 INFO - PID 29486 | [#11] stanford-crypto-ccm Cycles:10 Average:145.20 Median:141.50 stddev:12.22 (8.6%) stddev-sans-first:12.90 11:38:02 INFO - PID 29486 | Values: 142.0 139.0 140.0 141.0 138.0 143.0 139.0 137.0 176.0 157.0 11:38:02 INFO - PID 29486 | 11:38:02 INFO - PID 29486 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.20 Median:190.50 stddev:13.65 (7.2%) stddev-sans-first:14.48 11:38:02 INFO - PID 29486 | Values: 192.0 196.0 229.0 185.0 193.0 189.0 185.0 186.0 185.0 182.0 11:38:02 INFO - PID 29486 | 11:38:02 INFO - PID 29486 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:68.00 stddev:0.67 (1.0%) stddev-sans-first:0.71 11:38:02 INFO - PID 29486 | Values: 68.0 69.0 68.0 68.0 68.0 68.0 68.0 68.0 68.0 70.0 11:38:02 INFO - PID 29486 | -------- Summary: end -------- 11:38:02 INFO - PID 29486 | 11:38:03 INFO - TEST-INFO | 29486: exit 0 11:38:03 INFO - TEST-OK | kraken | took 61161ms 11:38:03 INFO - SUITE-END | took 1182s 11:38:03 INFO - Completed test suite (00:19:42) 11:38:03 INFO - javascript benchmark 11:38:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7226.499228708297, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3454.8790792322266, 3460.083077984333, 3470.357983773001, 3503.52702385178, 3536.54024976753, 6025.457708806207, 6108.908366374497, 6123.724432400541, 6134.754609815607, 6160.0, 6120.602160859541, 6145.661988590626, 6151.692415379224, 6152.015677491602, 6197.938020619804, 4760.071786001731, 5300.363956325246, 5356.839294821161, 5527.861803454925, 5579.9163012554845, 5603.8599035024245, 5604.887902241952, 5634.915476267859, 5637.746301416426, 5694.430556944314, 5408.756605952742, 5478.808241711561, 5495.038368285574, 5512.91730624041, 5558.91661625078, 5564.944350556523, 5571.693556854371, 5582.804601838957, 5592.7762889484375, 5612.438756124396, 5464.23500709901, 5482.5888058395385, 5491.697956612385, 5493.093639549469, 5505.724713764296, 4916.237983112624, 6171.969140154315, 6176.7838125665385, 6182.752689892399, 6207.4723648489935, 6330.905036424458, 6381.202349706286, 6389.041643753428, 6507.4794016479045, 6581.14445122131, 6603.537752357337, 6633.137692100045, 6634.469242460592, 6644.335566443366, 6676.599404035774, 5201.0, 6127.816165515042, 6271.780487682955, 6278.811635650984, 6316.494680425555, 6568.211814582185, 6637.668116594247, 6663.20041595002, 6829.112215412064, 6864.622445765431, 6409.487241020707, 6477.77327793525, 6504.15445992018, 6508.0, 6509.381608747161, 6600.075989361493, 6629.469642428594, 6652.767153149616, 6653.434458071021, 6678.465722742169, 6347.714352854133, 6523.054157147282, 6591.967040164768, 6593.142891424084, 6611.305812889577, 6411.871762564722, 6432.0, 6441.3880681335195, 6446.677666116675, 6496.902546461807, 4266.4240327556, 5232.555232805178, 5317.335333083364, 5320.361556613231, 5328.92006619901, 4624.421947256593, 4651.883702907387, 4684.625229981593, 4688.991866748665, 4736.3369128322065, 6118.162361256266, 6176.722047507873, 6313.810585682437, 6314.747410103544, 6342.904856427157, 6491.448226900672, 6559.180102487189, 6652.900206496906, 6694.665266736643, 6704.094947182068, 6297.905531417033, 6316.9368306317265, 6322.841928951721, 6326.398992095744, 6330.68346582669, 5844.444777746107, 5853.590248682552, 5882.764689412461, 5912.201852749909, 5914.4381283777975, 6678.966605166943, 6767.086443330088, 6778.084958530535, 6855.451563874978, 6887.242403335729, 6460.838479038061, 6551.246606640298, 6557.508186885907, 6712.228093769084, 6726.730930762715, 6678.365555272241, 6810.965945170243, 6823.692933817891, 6828.453723702191, 6909.2399836019, 6531.836704082246, 6541.836454088686, 6552.901706474311, 6566.704498297492, 6570.211574611078, 6387.616742995435, 6501.674916254263, 6670.266270710315, 6670.9332906670315, 6680.498962577729, 6318.273398559225, 6329.746810127727, 6341.8731625368155, 6391.58454700443, 6392.872142557216, 6443.259025212161, 6464.8707025858275, 6467.094606754964, 6467.644279564481, 6475.611463312216, 6278.686065696788, 6316.178896743578, 6328.6202827830475, 6336.968315158395, 6343.9682801585695, 6224.62652240867, 6285.214348206474, 6317.747290108344, 6342.778002769881, 6483.546151769331, 6343.080253363144, 6345.90481142774, 6360.840978975562, 6372.840678983062, 6373.776917807854], "value": 6047.652793378276, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [725.7713820146654, 729.4055344893915, 743.4869939741578, 748.6069813347995, 813.1218284253006, 27274.727252727465, 27754.167374978733, 27801.443971120592, 27850.582241266373, 35713.28573428536, 37730.24539509208, 38038.80980595093, 38070.80964595173, 38072.0, 38140.0, 2773.77809775218, 2823.308289469083, 2826.7173282671774, 2829.5472724364104, 2831.957520637192, 2487.6268559716095, 2542.5677634802037, 2810.12886005338, 2845.388241528071, 2849.5013372659805, 2841.9715802841915, 2900.144457385066, 2900.5069138246445, 2903.1580841555924, 2907.185987923385, 2884.351021020275, 2903.3612605226817, 2908.6073380093735, 2910.126961911418, 2910.970890291091, 2479.330580743207, 2533.050106210171, 2859.0993836941298, 2897.203269100993, 2902.1438675590753, 35388.16095344265, 35927.82036089829, 35945.64054359449, 35994.820025899964, 36049.45925811115, 2927.5608658701153, 2999.610050693396, 3004.7746419018663, 3011.4428830666398, 3012.8988797684647, 25899.482010359687, 25935.0, 26161.607575886377, 26363.0, 26441.735582644313, 2751.147144385247, 2759.530879750447, 2765.9031933882216, 2772.4177922636272, 2773.0987429085626, 250303.0, 252095.0, 254092.0, 295427.5228623865, 296521.0, 47287.0, 47400.0, 47414.76292618515, 47470.52529474661, 47533.0, 49722.50277497251, 49784.502154977985, 49829.003419931396, 49836.0, 49873.753156171384, 14037.438502459992, 14303.427862885368, 14314.785278220836, 14323.641908952359, 14333.64165895861, 10766.838497422545, 10876.891231087746, 11721.003714684348, 11731.41342932867, 11740.471678774476, 11180.329180249211, 11297.830532542019, 12990.870091298966, 13169.934150329187, 13580.86419135796, 5907.497862681635, 5959.0, 6028.487578555872, 6035.8792824143275, 6036.245469316335, 798.4769975665948, 959.7168835193635, 963.9878127965608, 968.9660861869942, 983.0, 23179.768202317762, 23488.647670284958, 23600.40998975005, 23607.29178124659, 23611.409714756926, 1065.5790962569897, 1074.9570017199223, 1100.1913593508764, 1100.983485247722, 1108.0083325423861, 11607.187496875138, 12324.630261092181, 12334.259944403364, 12359.814602780965, 12369.948554372799, 467339.0, 470566.0, 470915.0, 471769.0, 472231.0, 55842.0, 56047.71976140175, 56093.0, 56152.71923640356, 56282.43717562854, 1470.3016067368128, 1483.5920121966547, 1530.4566878758103, 1532.9540113796604, 1539.8768098552089, 20791.27230546984, 23775.881120594287, 23809.880950595136, 23860.88069559641, 23861.0, 37936.43095353627, 37945.0, 37955.24089518139, 37984.81007594944, 37989.43015854709, 428273.0, 429724.0, 429852.0, 430191.0, 430393.0, 33147.0, 33668.663313367535, 33772.81795137158, 33828.15429614279, 33878.830605846815, 30178.698213018466, 41257.96855078647, 41388.1722365557, 41421.0, 44609.0, 30956.69043309538, 31088.0, 33720.32559348848, 35743.0, 35962.820185898905, 29753.256168595093, 33308.33383332368, 34066.0, 34377.48433773445, 35356.11609709778, 34871.82564087163, 35602.287954241285, 35668.643313566536, 35675.46486802648, 35741.0, 25679.972801087748, 30482.695173047985, 30670.539941900442, 30969.690303096682, 31170.220744480663, 22810.88594557083, 22930.770692293532, 23158.305250842503, 23373.766262337158, 23441.648375274726], "value": 14822.272726280613, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [419.4091678903344, 430.8987387963829, 430.90304681446656, 431.69781153192764, 451.7831440908364, 19388.321408750715, 20785.584288314225, 21282.0, 21560.89219553902, 21617.81102039391, 19358.903205483988, 20906.89546552265, 21893.890530547364, 22169.0, 22251.666225006637, 4252.213340531996, 4407.779611019452, 4408.845690400836, 4410.713303635253, 4411.360352748849, 3774.698024158061, 3964.7026473014494, 4352.194843953863, 4388.912221755563, 4397.362382454558, 3798.335291324021, 3798.373268410709, 3805.771653700787, 3816.5610954740146, 3825.751326163804, 3816.045988502888, 3819.217060502618, 3822.636849499293, 3825.674817640504, 3827.789471579062, 3506.544149260605, 3547.450145227494, 3956.109875278068, 3995.1210733638554, 4051.7163798534116, 28137.718622813918, 28148.0, 28179.85910070457, 28190.859045704845, 28452.0, 1976.6217827511987, 1994.783182258821, 2004.2083377066124, 2024.068928292987, 2025.8379329653592, 15687.058776473448, 16198.35206591735, 16427.425040123657, 16772.916135419244, 17203.22585483656, 1603.6700709487434, 1610.0, 1616.5877701186123, 1665.275605111768, 1675.6481138960833, 132261.0, 140157.0, 140521.0, 140701.2964935169, 142091.0, 9836.6720492734, 11816.527338906348, 11850.0, 12141.392930353448, 12309.0, 16100.919495402448, 16113.27490262941, 16122.435714749927, 16275.755863662054, 16577.254023568967, 8791.912080879109, 8967.551622418852, 9003.909960900308, 9133.086691330947, 9154.221891139194, 8627.956860215785, 8631.482111073356, 8655.783605409915, 8790.692325768696, 8793.824123517494, 7681.615919204018, 7814.405373528127, 7826.804329891798, 7860.803479912934, 7869.213078692085, 2090.1639344262417, 2105.757603014198, 2144.3888491779767, 2174.6846707227364, 2382.344855164809, 820.0856045509214, 918.9816203675889, 927.2396634759433, 951.3911096897991, 964.286428043243, 20021.99890005494, 20485.897570512054, 20539.075741591663, 20621.793782061988, 20972.685409718866, 932.48247222792, 958.6980101268078, 979.9412035277907, 981.1954197558076, 982.9115379615725, 9836.262280329003, 10428.895711042944, 10496.947515262527, 10598.576056957789, 10622.872179631382, 664858.0, 675454.0, 695347.0, 718231.0, 718397.0, 91287.0, 133921.0, 133935.99999999805, 137250.0, 138811.0, 1132.5073592987078, 1141.14414189358, 1163.4764356039816, 1167.643868620062, 1169.929804211733, 15249.3900243989, 15854.0, 18932.580056496092, 20860.68708969397, 20869.582608347446, 58024.0, 58775.70612146912, 59343.1098533531, 60058.39941600703, 61168.69415652893, 83425.0, 85093.0, 86357.56821215854, 86719.92960492705, 87415.0, 8506.149385061444, 8524.062353141273, 8712.172343627346, 8745.387822852339, 8821.64713411482, 13716.519921802688, 13754.587362379145, 13777.726675098496, 14189.148651080968, 14260.500882469063, 11588.015018723503, 11748.823767643664, 11857.940710296394, 11942.940285298519, 11950.34273114987, 10166.796664066529, 11018.834717479405, 11365.54537818478, 11488.712782180179, 11634.011109055487, 11499.482523286304, 11717.707057323294, 12029.0, 12087.395630218278, 12273.631791046282, 8439.831203375776, 8491.405601607828, 8499.95750021246, 8729.388942773945, 8922.68770593026, 9998.850017249602, 10121.443320617142, 11026.503807328529, 11111.166662500184, 11533.312430635406], "value": 9698.533325242981, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1356.9864301356986, 1422.765243734783, 1441.5242969819947, 1448.5943935698006, 1449.4926775628526, 10139.797204055914, 10551.208659350541, 10568.15454763619, 10581.100606448452, 10582.52378642961, 10288.279820412574, 10421.947890260557, 10551.73620659482, 10636.0, 10673.0, 2389.545986262613, 2437.6709144265565, 2443.3402981195068, 2452.0314475782056, 2454.803615710739, 2320.431494283903, 2410.9035638574437, 2428.2836563213805, 2430.416699991998, 2431.465077682907, 2032.278541117904, 2105.3683894831565, 2114.68279758036, 2119.2688522459803, 2120.300300900699, 2088.498760297533, 2120.4062862398478, 2333.0434521845964, 2427.6965379327585, 2433.6714543536573, 2248.359217622986, 2250.5723912456583, 2255.560165767676, 2259.2318611671944, 2260.7287125544876, 10679.092277156451, 10751.946240268748, 10756.73108172294, 10773.0, 10781.299215550964, 5280.867978300535, 5481.424450432687, 5964.254468191476, 6007.068904319837, 6228.0, 3604.333198358312, 3866.6520013198665, 3950.0914789598555, 3966.841326346943, 3974.8608798691907, 926.7080869526078, 992.892924389308, 1135.6025391113126, 1191.6186820217533, 1195.665213740154, 14728.18994955277, 14763.26183690822, 14871.405143794234, 14885.925570372186, 14908.62728431787, 4966.950330496721, 5170.689758614495, 5210.687358758487, 5228.9477105229225, 5255.3168088148295, 11061.502232399562, 11061.612843550438, 11072.723181920517, 11076.0, 11083.168762342857, 6151.9384806151365, 6157.415045570664, 6161.229846269216, 6169.5064394848305, 6182.598131121463, 5440.0, 5501.092319767274, 5535.197396377503, 5571.665700057928, 5603.971980140154, 3396.8641254350046, 3924.843006279717, 4088.6115818997146, 4134.297169481196, 4153.688473364449, 1208.957686480969, 1211.1340391620033, 1223.7980733178927, 1226.0743138930052, 1228.8033914573625, 743.9144498382647, 752.1989081628049, 773.3156156801272, 779.9532028078333, 798.594473726246, 5767.0, 5777.537796976232, 5919.970400148058, 5963.4931030862, 5979.282486101609, 489.3980404102902, 521.3483146067416, 565.4402141879592, 577.6707276852154, 579.5045236322883, 3312.4865645824934, 3439.638837922032, 4384.561543845653, 4460.977695111504, 4510.368548403226, 13039.608811735663, 13678.452861885413, 13715.0, 13718.93140534291, 13733.0, 13159.0, 13473.258970756702, 13663.18020918748, 13666.794998075036, 13687.04190706641, 9638.807223855483, 10055.044770746767, 10068.647597334058, 10091.747706307255, 10108.444035578113, 4931.3835770528685, 6585.670716464253, 6627.768028118992, 6694.933050669431, 6710.731570737116, 11901.642950711492, 12176.269423834598, 12193.146479746687, 12194.451249693608, 12290.0, 12157.270563766202, 12184.268943863397, 12421.44103515362, 12565.246085234916, 12642.431090600761, 7837.725679601187, 7954.482958607672, 8007.83984320322, 8022.117567067501, 8043.557604331818, 8154.0, 9700.805983880424, 9917.45454000008, 9919.702408927744, 9939.552720127756, 8822.911770882209, 8825.205731484197, 8856.778580535538, 8934.061923498199, 8954.955225223832, 7083.574985500886, 8542.18849209324, 8597.957010214908, 8599.785005374915, 8798.340124490816, 7552.169261381134, 7743.148253692202, 7745.57399343019, 7760.379169666526, 7768.106667733283, 5177.042247184283, 5282.97358513205, 5588.189712491747, 5736.799212027559, 5865.94134058654, 5763.855903602276, 5874.353821079592, 5874.7662990772415, 5913.142594323885, 5922.289325280994], "value": 4931.604561330466, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.5713608013702, 388.4891367851275, 393.23319526922506, 395.35162333772615, 399.51259463454585, 4838.500064979854, 5258.526732594061, 5516.448355164482, 5724.942750572493, 5821.592488525805, 5727.766171193238, 5845.210896528967, 5926.911096333559, 5929.169916211734, 5970.432808883149, 4119.8146083426245, 4295.892602684927, 4472.1055788842195, 4474.932876006863, 4717.551832575917, 3929.5873933237026, 4185.518665353477, 4433.689641725081, 4466.766616691654, 4478.454933048093, 4420.027593929331, 4435.356873253377, 4442.267025940716, 4451.287793952968, 4476.843310484133, 3581.946270805953, 4242.7242229255, 4369.213541562549, 4434.512203657595, 4459.308807134867, 3125.453045717013, 3770.3024940386117, 3786.5456145262747, 3798.734088613798, 3819.446180303868, 5723.7996670116345, 5728.25532680753, 5734.827955161351, 5741.167530708066, 5767.5962682612235, 4061.614146656063, 4240.893977650552, 4916.483769204254, 5169.353830771153, 5176.870578235536, 5747.971260143714, 6177.0, 6313.936860631381, 6316.715747791361, 6338.144350512668, 4261.957380426187, 4262.46719160105, 4265.744055356658, 4267.335739061768, 4279.657627389802, 4537.546245375436, 5684.062129748587, 6146.739918316779, 6495.935040649628, 6574.539782215199, 4167.1457351242925, 4301.290287102593, 4313.719608225455, 4324.56754324565, 4334.544872788375, 4377.190219809346, 4408.625266852354, 4412.382266482695, 4414.536473670282, 4449.7997590108525, 4993.290733230569, 5171.379434467888, 5177.352830896138, 5191.636585439059, 5191.948080519146, 5897.662615333131, 6023.909641355383, 6297.70133901132, 6328.873422531524, 6338.334474880164, 1483.4881965721809, 1497.2064805653022, 1497.5133081748259, 1498.4380857178558, 1508.5775982724854, 945.8061097475002, 989.2185173712686, 991.453332800833, 994.627014869424, 1009.3540134314045, 5529.640573362799, 5548.4174161713245, 5626.43735626434, 6036.849078773066, 6052.848678782978, 5123.7438128093445, 5392.838214853561, 6546.312637173123, 6553.475721942234, 6567.080608714784, 5028.64799464034, 5518.503334699896, 5694.914576281359, 5719.0, 6021.397860214031, 2818.0, 3051.3744682340066, 3307.6030876294517, 3400.2009527760956, 3417.3165366926714, 5958.314793798769, 6412.807615771534, 6653.667316634246, 6782.593044417351, 6785.796426107225, 5381.246625472437, 6293.30773614902, 6330.461910737639, 6471.255805582324, 6677.432418244406, 5682.522544138554, 6582.80251592453, 6720.833083488171, 6729.360710732472, 6761.628110453972, 5667.971660141673, 6217.440430361289, 6434.388733070351, 6537.921242994853, 6540.149780528501, 5869.325027621708, 6367.42693157618, 6406.359364063556, 6423.792415967549, 6443.581167224115, 5328.520433161034, 5699.261725173696, 6384.170057892445, 6734.3602357775935, 6798.660066996532, 4063.674906007453, 4210.347396153601, 4279.16556271524, 4309.353596960481, 4329.372241024971, 6399.9680001601555, 6400.583962042452, 6429.51778616615, 6448.742050317935, 6463.7395707838405, 5191.948080519298, 5311.309529761106, 5970.194023806731, 6254.906176407267, 6263.843403914939, 6223.937760622336, 6226.875462490815, 6246.843828904313, 6269.341719119427, 6285.308616052139, 5395.757190926339, 6034.672372078136, 6042.939570604238, 6100.267967843887, 6124.418180272867, 5588.720563971867, 5707.1153971134545, 5997.760089596367, 6213.912565301144, 6214.5028397728975, 4859.222524396079, 5017.397912250553, 6076.574639775174, 6085.391460853879, 6086.482648974887], "value": 4544.882991555314, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [707.2114592229663, 717.2146499582955, 717.8288893949446, 724.1852915470098, 726.0742553244615, 15019.24903754812, 15394.230288485587, 15645.921770391147, 15839.762403563927, 15939.442119525818, 14484.0, 14801.407943682267, 14814.333354999024, 14821.962462627593, 15458.381664733426, 654.1855390039408, 672.3612568060338, 932.6968735161066, 944.36255527519, 968.099667309402, 837.3426859914968, 938.1134827587898, 974.5955428497165, 975.2539307429822, 975.5707488704948, 1026.091908660833, 1026.0970346095426, 1027.193652982409, 1030.6650338639934, 1032.0969151992008, 1019.2304809868576, 1020.2654089055868, 1021.019820971868, 1021.2136654775856, 1022.4223313827664, 758.3023618271204, 760.0954863712165, 763.2634507700096, 765.3532764813724, 766.0500978786265, 15112.622184445367, 15169.317380717894, 15462.922685386613, 15894.0, 16173.838261617468, 14193.574192774233, 14490.275486225646, 14539.78190327146, 14638.853611463855, 14786.03890747098, 11452.484638191218, 11626.069914406911, 11873.643790686294, 12172.208806427643, 12198.512059517609, 2826.5053615617185, 3027.2431892026993, 3063.540468929656, 3070.6929307069336, 3074.093142522961, 103807.0, 107517.46241268744, 108853.0, 109788.35317470244, 110691.44654276758, 8052.838943221102, 9498.620055197853, 10050.798984020279, 10283.228757843042, 10364.963503649726, 19607.705884411746, 19647.508812279808, 19657.41027769169, 22946.967386467648, 23783.0, 7361.705531778776, 7670.4247181461615, 7701.383889288955, 7749.651265692944, 8063.879041814377, 4042.130941847517, 4045.615666511677, 4060.045889216032, 4130.173965206971, 4143.212789569972, 4050.9797451013146, 4168.3955826405, 4168.91662166761, 4169.061961058798, 4172.520160181557, 724.2757242757243, 816.77946954322, 817.3496076721879, 821.8191997760483, 835.5822088955522, 521.5019656228293, 561.2927711083981, 583.7198144890476, 585.8916100521417, 603.384547761281, 8884.777880552909, 9989.922707664238, 10016.0, 10116.317779577577, 10303.124234440007, 541.6614615865084, 560.7000254863628, 592.8725324055349, 593.2821286243608, 596.9403059693996, 4034.9596504034585, 4153.875383738493, 4782.880427989328, 4793.257045158006, 4828.782704778293, 83849.0, 86073.0, 86782.0, 89008.664870027, 89562.10437895668, 73746.26253737502, 75151.24848751443, 75871.0, 85837.57081214679, 90039.09960900307, 47172.7641361791, 70984.0, 71110.933336, 72219.55560888753, 74667.50664986779, 10372.145993823166, 10667.306625069346, 10812.135029197803, 10896.891031089588, 10926.617568385069, 34653.48019779756, 36178.0, 36588.0, 36723.26553468969, 37253.441198382585, 45000.0, 45032.596088469596, 45762.0, 46477.302840456745, 46480.070398592514, 13139.080264381402, 13239.007074469646, 13287.13633613812, 13313.201207927556, 13588.864111359155, 14332.283385830458, 14348.139111653334, 14348.139111653334, 14353.497627582985, 14379.784303235669, 13080.411381488046, 13281.53514627022, 13316.0, 13365.26491043002, 13474.258915759727, 13380.464781408635, 13450.529231476852, 13650.590482285548, 13848.446062157402, 13892.583222503341, 12876.163049401759, 12958.22250664963, 13017.869821302045, 13326.666833329244, 13480.46078156902, 10431.426271555138, 10636.012887315701, 10825.296355736851, 10887.129029677448, 11692.298462092302, 9166.141944769282, 9652.855207172039, 10855.348679078965, 11162.888371116185, 11318.434078295888], "value": 7308.3738718582745, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1477.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 121.0, 167.0, 149.0, 113.0, 111.0, 113.0, 113.0, 112.0, 110.0], "value": 122.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 115.0, 93.0, 171.0, 146.0, 142.0, 111.0, 112.0, 134.0, 111.0], "value": 127.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 187.0, 200.0, 211.0, 184.0, 180.0, 182.0, 183.0, 184.0], "value": 188.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 60.0, 61.0, 91.0, 67.0, 83.0, 87.0, 86.0, 78.0, 58.0], "value": 75.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 70.0, 89.0, 69.0, 69.0, 70.0, 83.0, 70.0, 68.0, 84.0], "value": 76.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 92.0, 134.0, 87.0, 87.0, 86.0, 86.0, 87.0, 86.0, 87.0], "value": 91.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 95.0, 93.0, 94.0], "value": 94.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 84.0, 83.0, 84.0, 85.0, 90.0, 84.0, 82.0, 82.0], "value": 84.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 60.0, 60.0, 61.0, 62.0, 61.0, 63.0, 62.0, 62.0, 62.0], "value": 61.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 62.0, 64.0, 62.0, 63.0, 58.0, 57.0, 60.0, 58.0], "value": 59.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 106.0, 106.0, 107.0, 83.0, 83.0, 83.0, 83.0, 82.0, 82.0], "value": 91.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 139.0, 140.0, 141.0, 138.0, 143.0, 139.0, 137.0, 176.0, 157.0], "value": 145.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 196.0, 229.0, 185.0, 193.0, 189.0, 185.0, 186.0, 185.0, 182.0], "value": 192.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 68.0, 68.0, 68.0, 68.0, 68.0, 68.0, 68.0, 70.0], "value": 68.3, "unit": "score"}]}]} 11:38:03 INFO - Return code: 0 11:38:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:38:03 ERROR - # TBPL SUCCESS # 11:38:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:38:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:38:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:38:03 INFO - 2017-03-21 11:38:03.758 screenresolution[29490:243446] starting screenresolution argv=screenresolution get 11:38:03 INFO - 2017-03-21 11:38:03.761 screenresolution[29490:243446] Display 0: 1600x1200x32@60 11:38:03 INFO - 2017-03-21 11:38:03.768 screenresolution[29491:243449] starting screenresolution argv=screenresolution list 11:38:03 INFO - Available Modes on Display 0 11:38:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:38:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:38:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:38:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:38:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:38:03 INFO - Intel Iris: 11:38:03 INFO - Chipset Model: Intel Iris 11:38:03 INFO - Type: GPU 11:38:03 INFO - Bus: Built-In 11:38:03 INFO - VRAM (Dynamic, Max): 1536 MB 11:38:03 INFO - Vendor: Intel (0x8086) 11:38:03 INFO - Device ID: 0x0a2e 11:38:03 INFO - Revision ID: 0x0009 11:38:03 INFO - Displays: 11:38:04 INFO - Display: 11:38:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:38:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:38:04 INFO - Display Serial Number: 1600x1200 60 11:38:04 INFO - Main Display: Yes 11:38:04 INFO - Mirror: Off 11:38:04 INFO - Online: Yes 11:38:04 INFO - Rotation: Supported 11:38:04 INFO - Return code: 0 11:38:04 INFO - Running post-action listener: _resource_record_post_action 11:38:04 INFO - [mozharness: 2017-03-21 18:38:04.004604Z] Finished run-tests step (success) 11:38:04 INFO - Running post-run listener: _resource_record_post_run 11:38:04 INFO - Total resource usage - Wall time: 1203s; CPU: 53.0%; Read bytes: 29670400; Write bytes: 512659456; Read time: 305; Write time: 2069 11:38:04 INFO - TinderboxPrint: CPU usage
53.4% 11:38:04 INFO - TinderboxPrint: I/O read bytes / time
29,670,400 / 305 11:38:04 INFO - TinderboxPrint: I/O write bytes / time
512,659,456 / 2,069 11:38:04 INFO - TinderboxPrint: CPU idle
2,244.3 (46.6%) 11:38:04 INFO - TinderboxPrint: CPU system
957.7 (19.9%) 11:38:04 INFO - TinderboxPrint: CPU user
1,611.8 (33.5%) 11:38:04 INFO - TinderboxPrint: Swap in / out
234,876,928 / 0 11:38:04 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 205847040; Write bytes: 203724800; Read time: 15773; Write time: 643 11:38:04 INFO - run-tests - Wall time: 1183s; CPU: 53.0%; Read bytes: 29494272; Write bytes: 298063872; Read time: 302; Write time: 1390 11:38:04 INFO - Running post-run listener: _upload_blobber_files 11:38:04 INFO - Blob upload gear active. 11:38:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:38:04 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 11:38:04 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'] 11:38:04 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 11:38:04 INFO - (blobuploader) - INFO - Open directory for files ... 11:38:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:38:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:38:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:38:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:38:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:38:06 INFO - (blobuploader) - INFO - Done attempting. 11:38:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:38:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:38:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:38:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:38:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:38:07 INFO - (blobuploader) - INFO - Done attempting. 11:38:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:38:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:38:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:38:08 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:38:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:38:08 INFO - (blobuploader) - INFO - Done attempting. 11:38:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:38:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:38:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:38:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:38:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:38:11 INFO - (blobuploader) - INFO - Done attempting. 11:38:11 INFO - (blobuploader) - INFO - Iteration through files over. 11:38:11 INFO - Return code: 0 11:38:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:38:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:38:11 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fa0f37438a85b06b719d0182f30c09b65cf48da2d52a2e254afba29222c7e5f45b235ea27c0db9298458df35ff327b88befe1fb5122013e68487ff532e5627f1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0382716ee2d9f28e321807a863fd574f831a3f4cd256f18966a2bb02777cc4ea44b27dea2263f6f8a19d443672e023b7fb27f7ae2f0a58bf44be5083cda044bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6fafc75137f417950d50776bdb96218545335931b8fe2edc1fe7f9c6d26e6e665bba0acea2e4cd3e927568d14f40e54ffe73904de91b9b4903c605d6291ff45b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7d2c4089599f1cba7a84d0c69744fb871ef1d46f0a7cb6cfbabba01097c70df1eb2d856cb07e2089e0ec02ad16101260bf122b28ca38b3e800f7d2173c08f647"} 11:38:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:38:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:38:11 INFO - Contents: 11:38:11 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fa0f37438a85b06b719d0182f30c09b65cf48da2d52a2e254afba29222c7e5f45b235ea27c0db9298458df35ff327b88befe1fb5122013e68487ff532e5627f1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0382716ee2d9f28e321807a863fd574f831a3f4cd256f18966a2bb02777cc4ea44b27dea2263f6f8a19d443672e023b7fb27f7ae2f0a58bf44be5083cda044bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6fafc75137f417950d50776bdb96218545335931b8fe2edc1fe7f9c6d26e6e665bba0acea2e4cd3e927568d14f40e54ffe73904de91b9b4903c605d6291ff45b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7d2c4089599f1cba7a84d0c69744fb871ef1d46f0a7cb6cfbabba01097c70df1eb2d856cb07e2089e0ec02ad16101260bf122b28ca38b3e800f7d2173c08f647"} 11:38:11 INFO - Running post-run listener: copy_logs_to_upload_dir 11:38:11 INFO - Copying logs to upload dir... 11:38:11 INFO - mkdir: /builds/slave/test/build/upload/logs 11:38:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1285.988748 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-03-21 11:38:11.940631) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-21 11:38:11.946043) ========= 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.I3urnkvzxs/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.siw1ch46wa/Listeners TMPDIR=/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fa0f37438a85b06b719d0182f30c09b65cf48da2d52a2e254afba29222c7e5f45b235ea27c0db9298458df35ff327b88befe1fb5122013e68487ff532e5627f1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0382716ee2d9f28e321807a863fd574f831a3f4cd256f18966a2bb02777cc4ea44b27dea2263f6f8a19d443672e023b7fb27f7ae2f0a58bf44be5083cda044bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6fafc75137f417950d50776bdb96218545335931b8fe2edc1fe7f9c6d26e6e665bba0acea2e4cd3e927568d14f40e54ffe73904de91b9b4903c605d6291ff45b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7d2c4089599f1cba7a84d0c69744fb871ef1d46f0a7cb6cfbabba01097c70df1eb2d856cb07e2089e0ec02ad16101260bf122b28ca38b3e800f7d2173c08f647"} build_url:https://queue.taskcluster.net/v1/task/DpJ_0uuqQfyqN9k8ZTvquw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011221 build_url: 'https://queue.taskcluster.net/v1/task/DpJ_0uuqQfyqN9k8ZTvquw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fa0f37438a85b06b719d0182f30c09b65cf48da2d52a2e254afba29222c7e5f45b235ea27c0db9298458df35ff327b88befe1fb5122013e68487ff532e5627f1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0382716ee2d9f28e321807a863fd574f831a3f4cd256f18966a2bb02777cc4ea44b27dea2263f6f8a19d443672e023b7fb27f7ae2f0a58bf44be5083cda044bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6fafc75137f417950d50776bdb96218545335931b8fe2edc1fe7f9c6d26e6e665bba0acea2e4cd3e927568d14f40e54ffe73904de91b9b4903c605d6291ff45b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7d2c4089599f1cba7a84d0c69744fb871ef1d46f0a7cb6cfbabba01097c70df1eb2d856cb07e2089e0ec02ad16101260bf122b28ca38b3e800f7d2173c08f647"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-21 11:38:11.984819) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 11:38:11.985137) ========= 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.I3urnkvzxs/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.siw1ch46wa/Listeners TMPDIR=/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/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.005481 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 11:38:12.034232) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-21 11:38:12.034575) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-21 11:38:12.034884) ========= ========= Total master_lag: 0.19 =========