builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0075 starttime: 1479562160.66 results: success (0) buildid: 20161119030204 builduid: 02292f9e3509430fa804597553cb229d revision: f09e137ead39230eaa94f47988ccce2cfcda4195 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-19 05:29:20.656959) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-19 05:29:20.657410) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-19 05:29:20.657690) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-19 05:29:20.684268) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-19 05:29:20.684683) ========= 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.lCDUoNCnjL/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.RcYrPR7qVL/Listeners TMPDIR=/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 --2016-11-19 05:29:20-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 12.9M=0.001s 2016-11-19 05:29:20 (12.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.120367 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-19 05:29:20.828285) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-19 05:29:20.828661) ========= 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.lCDUoNCnjL/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.RcYrPR7qVL/Listeners TMPDIR=/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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.027178 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-19 05:29:20.877128) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-19 05:29:20.877528) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f09e137ead39230eaa94f47988ccce2cfcda4195 --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 f09e137ead39230eaa94f47988ccce2cfcda4195 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lCDUoNCnjL/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.RcYrPR7qVL/Listeners TMPDIR=/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 2016-11-19 05:29:20,974 truncating revision to first 12 chars 2016-11-19 05:29:20,974 Setting DEBUG logging. 2016-11-19 05:29:20,974 attempt 1/10 2016-11-19 05:29:20,974 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f09e137ead39?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-19 05:29:22,082 unpacking tar archive at: mozilla-central-f09e137ead39/testing/mozharness/ program finished with exit code 0 elapsedTime=1.398403 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-19 05:29:22.305533) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-19 05:29:22.306075) ========= script_repo_revision: f09e137ead39230eaa94f47988ccce2cfcda4195 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-19 05:29:22.306696) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-19 05:29:22.307153) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-19 05:29:22.331905) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-11-19 05:29:22.332460) ========= /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.lCDUoNCnjL/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.RcYrPR7qVL/Listeners TMPDIR=/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 05:29:22 INFO - MultiFileLogger online at 20161119 05:29:22 in /builds/slave/test 05:29:22 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 05:29:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:29:22 INFO - {'append_to_log': False, 05:29:22 INFO - 'base_work_dir': '/builds/slave/test', 05:29:22 INFO - 'blob_upload_branch': 'Firefox', 05:29:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:29:22 INFO - 'branch': 'Firefox', 05:29:22 INFO - 'buildbot_json_path': 'buildprops.json', 05:29:22 INFO - 'config_files': ('talos/mac_config.py',), 05:29:22 INFO - 'default_actions': ('clobber', 05:29:22 INFO - 'read-buildbot-config', 05:29:22 INFO - 'download-and-extract', 05:29:22 INFO - 'populate-webroot', 05:29:22 INFO - 'create-virtualenv', 05:29:22 INFO - 'install', 05:29:22 INFO - 'run-tests'), 05:29:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:29:22 INFO - 'download_minidump_stackwalk': True, 05:29:22 INFO - 'download_symbols': 'ondemand', 05:29:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:29:22 INFO - 'tooltool.py': '/tools/tooltool.py', 05:29:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:29:22 INFO - '/tools/misc-python/virtualenv.py')}, 05:29:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:29:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:29:22 INFO - 'installer_path': 'installer.exe', 05:29:22 INFO - 'log_level': 'info', 05:29:22 INFO - 'log_name': 'talos', 05:29:22 INFO - 'log_to_console': True, 05:29:22 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:29:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:29:22 INFO - 'opt_config_files': (), 05:29:22 INFO - 'pip_index': False, 05:29:22 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:29:22 INFO - 'cmd': ('bash', 05:29:22 INFO - '-c', 05:29:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:29:22 INFO - 'enabled': True, 05:29:22 INFO - 'halt_on_failure': False, 05:29:22 INFO - 'name': 'check_screen_resolution'},), 05:29:22 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:29:22 INFO - 'cmd': ('bash', 05:29:22 INFO - '-c', 05:29:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:29:22 INFO - 'enabled': True, 05:29:22 INFO - 'halt_on_failure': False, 05:29:22 INFO - 'name': 'check_screen_resolution'},), 05:29:22 INFO - 'run_cmd_checks_enabled': True, 05:29:22 INFO - 'sps_profile': False, 05:29:22 INFO - 'sps_profile_interval': 0, 05:29:22 INFO - 'suite': 'dromaeojs', 05:29:22 INFO - 'system_bits': '32', 05:29:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:29:22 INFO - 'title': 't-yosemite-r7-0075', 05:29:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:29:22 INFO - 'use_talos_json': True, 05:29:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:29:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:29:22 INFO - 'work_dir': 'build'} 05:29:22 INFO - [mozharness: 2016-11-19 13:29:22.545308Z] Running clobber step. 05:29:22 INFO - Running pre-action listener: _resource_record_pre_action 05:29:22 INFO - Running main action method: clobber 05:29:22 INFO - rmtree: /builds/slave/test/build 05:29:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:29:25 INFO - Running post-action listener: _resource_record_post_action 05:29:25 INFO - [mozharness: 2016-11-19 13:29:25.508460Z] Finished clobber step (success) 05:29:25 INFO - [mozharness: 2016-11-19 13:29:25.508590Z] Running read-buildbot-config step. 05:29:25 INFO - Running pre-action listener: _resource_record_pre_action 05:29:25 INFO - Running main action method: read_buildbot_config 05:29:25 INFO - Using buildbot properties: 05:29:25 INFO - { 05:29:25 INFO - "project": "", 05:29:25 INFO - "product": "firefox", 05:29:25 INFO - "script_repo_revision": "production", 05:29:25 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:29:25 INFO - "repository": "", 05:29:25 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:29:25 INFO - "buildid": "20161119030204", 05:29:25 INFO - "stage_platform": "macosx64", 05:29:25 INFO - "basedir": "/builds/slave/test", 05:29:25 INFO - "nightly_build": "True", 05:29:25 INFO - "buildnumber": 17, 05:29:25 INFO - "slavename": "t-yosemite-r7-0075", 05:29:25 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 05:29:25 INFO - "platform": "yosemite_r7", 05:29:25 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:29:25 INFO - "branch": "mozilla-central", 05:29:25 INFO - "repo_path": "mozilla-central", 05:29:25 INFO - "revision": "f09e137ead39230eaa94f47988ccce2cfcda4195", 05:29:25 INFO - "pgo_build": "False", 05:29:25 INFO - "builduid": "02292f9e3509430fa804597553cb229d", 05:29:25 INFO - "slavebuilddir": "test" 05:29:25 INFO - } 05:29:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/SuUx8_XzTliUCQraHjx8LQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 05:29:25 INFO - Running post-action listener: _resource_record_post_action 05:29:25 INFO - [mozharness: 2016-11-19 13:29:25.512042Z] Finished read-buildbot-config step (success) 05:29:25 INFO - [mozharness: 2016-11-19 13:29:25.512148Z] Running download-and-extract step. 05:29:25 INFO - Running pre-action listener: _resource_record_pre_action 05:29:25 INFO - Running main action method: download_and_extract 05:29:25 INFO - mkdir: /builds/slave/test/build/tests 05:29:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:29:25 INFO - trying https://queue.taskcluster.net/v1/task/SuUx8_XzTliUCQraHjx8LQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 05:29:25 INFO - Downloading https://queue.taskcluster.net/v1/task/SuUx8_XzTliUCQraHjx8LQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 05:29:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SuUx8_XzTliUCQraHjx8LQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 05:29:26 INFO - Downloaded 1199 bytes. 05:29:26 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 05:29:26 INFO - Using the following test package requirements: 05:29:26 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 05:29:26 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:29:26 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 05:29:26 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:29:26 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 05:29:26 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:29:26 INFO - u'jsshell-mac.zip'], 05:29:26 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:29:26 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 05:29:26 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 05:29:26 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:29:26 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 05:29:26 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:29:26 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 05:29:26 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:29:26 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 05:29:26 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 05:29:26 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 05:29:26 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:29:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SuUx8_XzTliUCQraHjx8LQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 05:29:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SuUx8_XzTliUCQraHjx8LQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:29:26 INFO - Fetch https://queue.taskcluster.net/v1/task/SuUx8_XzTliUCQraHjx8LQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 05:29:27 INFO - Http code: 200 05:29:27 INFO - via: 1.1 d897b81191cbfd68a1b6fc44f98c52b0.cloudfront.net (CloudFront) 05:29:27 INFO - x-amz-cf-id: 2uqbXEB81M4xavY545vKjwhzfmfq-OEbScurFoN6urHCC_DTwHQ0_g== 05:29:27 INFO - x-amz-version-id: 5MwntJMIA1vPEUZ2guR4TMaw71acYLNT 05:29:27 INFO - Expected file size: 16265647 05:29:27 INFO - Obtained file size: 16265647 05:29:34 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:29:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SuUx8_XzTliUCQraHjx8LQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 05:29:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SuUx8_XzTliUCQraHjx8LQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:29:34 INFO - Fetch https://queue.taskcluster.net/v1/task/SuUx8_XzTliUCQraHjx8LQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 05:29:34 INFO - Http code: 200 05:29:34 INFO - via: 1.1 ccaa055c9a26ce13da709353563e04a5.cloudfront.net (CloudFront) 05:29:34 INFO - x-amz-cf-id: wyFyp7uwACEK8K2UNqqfhBKVrhPf4ZhO2YCQu_VV7x9mOYCtQLbGcg== 05:29:34 INFO - x-amz-version-id: 5MwntJMIA1vPEUZ2guR4TMaw71acYLNT 05:29:35 INFO - Expected file size: 16265647 05:29:35 INFO - Obtained file size: 16265647 05:29:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SuUx8_XzTliUCQraHjx8LQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 05:29:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SuUx8_XzTliUCQraHjx8LQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:29:41 INFO - Fetch https://queue.taskcluster.net/v1/task/SuUx8_XzTliUCQraHjx8LQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 05:29:43 INFO - Http code: 200 05:29:43 INFO - via: 1.1 e1eb7447ab46f530f6009f1d93917c54.cloudfront.net (CloudFront) 05:29:43 INFO - x-amz-cf-id: eZRzHEurXM06ih39-q11ciPDCAeVSGYmtoWogNhIsXOC1AVQWu9CPQ== 05:29:43 INFO - x-amz-version-id: WxLWUm3uSjoCRdjzuZkydHNE3WkP.EPW 05:29:43 INFO - Expected file size: 14409392 05:29:43 INFO - Obtained file size: 14409392 05:29:43 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')]} 05:29:43 INFO - trying https://queue.taskcluster.net/v1/task/SuUx8_XzTliUCQraHjx8LQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 05:29:43 INFO - Downloading https://queue.taskcluster.net/v1/task/SuUx8_XzTliUCQraHjx8LQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 05:29:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SuUx8_XzTliUCQraHjx8LQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 05:29:46 INFO - Downloaded 107683205 bytes. 05:29:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SuUx8_XzTliUCQraHjx8LQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 05:29:46 INFO - mkdir: /builds/slave/test/properties 05:29:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:29:46 INFO - Writing to file /builds/slave/test/properties/build_url 05:29:46 INFO - Contents: 05:29:46 INFO - build_url:https://queue.taskcluster.net/v1/task/SuUx8_XzTliUCQraHjx8LQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 05:29:47 INFO - Running post-action listener: _resource_record_post_action 05:29:47 INFO - Running post-action listener: set_extra_try_arguments 05:29:47 INFO - [mozharness: 2016-11-19 13:29:47.093455Z] Finished download-and-extract step (success) 05:29:47 INFO - [mozharness: 2016-11-19 13:29:47.093631Z] Running populate-webroot step. 05:29:47 INFO - Running pre-action listener: _resource_record_pre_action 05:29:47 INFO - Running main action method: populate_webroot 05:29:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:29:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:29:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:29:47 INFO - '--tppagecycles', 05:29:47 INFO - '7'], 05:29:47 INFO - 'tests': ['tsvgm']}}, 05:29:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:29:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:29:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:29:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:29:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:29:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:29:47 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:29:47 INFO - 'talos_options': ['--disable-e10s'], 05:29:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:29:47 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:29:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:29:47 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:29:47 INFO - 'talos_options': ['--disable-e10s'], 05:29:47 INFO - 'tests': ['damp', 'tps']}, 05:29:47 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:29:47 INFO - 'tests': ['damp', 'tps']}, 05:29:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:29:47 INFO - 'tests': ['dromaeo_dom']}, 05:29:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:29:47 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:29:47 INFO - 'tests': ['basic_compositor_video']}, 05:29:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:29:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:29:47 INFO - 'tests': ['a11yr', 05:29:47 INFO - 'ts_paint', 05:29:47 INFO - 'tpaint', 05:29:47 INFO - 'sessionrestore', 05:29:47 INFO - 'sessionrestore_no_auto_restore', 05:29:47 INFO - 'tabpaint']}, 05:29:47 INFO - 'other-e10s': {'tests': ['a11yr', 05:29:47 INFO - 'ts_paint', 05:29:47 INFO - 'tpaint', 05:29:47 INFO - 'sessionrestore', 05:29:47 INFO - 'sessionrestore_no_auto_restore', 05:29:47 INFO - 'tabpaint']}, 05:29:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:29:47 INFO - 'ts_paint', 05:29:47 INFO - 'tpaint', 05:29:47 INFO - 'sessionrestore', 05:29:47 INFO - 'sessionrestore_no_auto_restore', 05:29:47 INFO - 'tabpaint']}, 05:29:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:29:47 INFO - 'ts_paint', 05:29:47 INFO - 'tpaint', 05:29:47 INFO - 'sessionrestore', 05:29:47 INFO - 'sessionrestore_no_auto_restore', 05:29:47 INFO - 'tabpaint']}, 05:29:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:29:47 INFO - 'tests': ['a11yr', 05:29:47 INFO - 'ts_paint', 05:29:47 INFO - 'tpaint', 05:29:47 INFO - 'sessionrestore', 05:29:47 INFO - 'sessionrestore_no_auto_restore', 05:29:47 INFO - 'tabpaint']}, 05:29:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:29:47 INFO - 'tests': ['a11yr', 05:29:47 INFO - 'ts_paint', 05:29:47 INFO - 'tpaint', 05:29:47 INFO - 'sessionrestore', 05:29:47 INFO - 'sessionrestore_no_auto_restore', 05:29:47 INFO - 'tabpaint']}, 05:29:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:29:47 INFO - 'tests': ['tsvgx', 05:29:47 INFO - 'tsvgr_opacity', 05:29:47 INFO - 'tart', 05:29:47 INFO - 'tscrollx', 05:29:47 INFO - 'cart']}, 05:29:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:29:47 INFO - 'tsvgr_opacity', 05:29:47 INFO - 'tart', 05:29:47 INFO - 'tscrollx', 05:29:47 INFO - 'cart']}, 05:29:47 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:29:47 INFO - 'talos_options': ['--disable-e10s'], 05:29:47 INFO - 'tests': ['tp5o']}, 05:29:47 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:29:47 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:29:47 INFO - 'talos_options': ['--disable-e10s', 05:29:47 INFO - '--xperf_path', 05:29:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:29:47 INFO - 'tests': ['tp5n']}, 05:29:47 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:29:47 INFO - 'talos_options': ['--xperf_path', 05:29:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:29:47 INFO - 'tests': ['tp5n']}}, 05:29:47 INFO - 'talos.zip': {'path': '', 05:29:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:29:47 INFO - Running post-action listener: _resource_record_post_action 05:29:47 INFO - [mozharness: 2016-11-19 13:29:47.111573Z] Finished populate-webroot step (success) 05:29:47 INFO - [mozharness: 2016-11-19 13:29:47.111708Z] Running create-virtualenv step. 05:29:47 INFO - Running pre-action listener: _resource_record_pre_action 05:29:47 INFO - Running main action method: create_virtualenv 05:29:47 INFO - Creating virtualenv /builds/slave/test/build/venv 05:29:47 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 05:29:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:29:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:29:47 INFO - Using real prefix '/tools/python27' 05:29:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:29:47 INFO - Installing distribute.............................................................................................................................................................................................done. 05:29:50 INFO - Installing pip.................done. 05:29:50 INFO - Return code: 0 05:29:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:29:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:29:51 INFO - Reading from file tmpfile_stdout 05:29:51 INFO - Output received: 05:29:51 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) 05:29:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:29:51 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')]} 05:29:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:51 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')]} 05:29:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:51 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 0x1057f53f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10576b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99e94d78b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10560bd30>, '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 0x105472db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105603b20>, '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.lCDUoNCnjL/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.RcYrPR7qVL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 05:29:51 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 05:29:51 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 05:29:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lCDUoNCnjL/Render', 05:29:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:29:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:29:51 INFO - 'HOME': '/Users/cltbld', 05:29:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:29:51 INFO - 'LOGNAME': 'cltbld', 05:29:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:51 INFO - 'MOZ_NO_REMOTE': '1', 05:29:51 INFO - 'NO_EM_RESTART': '1', 05:29:51 INFO - 'PAGER': '/bin/cat', 05:29:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:29:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:51 INFO - 'PWD': '/builds/slave/test', 05:29:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:29:51 INFO - 'SHELL': '/bin/bash', 05:29:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RcYrPR7qVL/Listeners', 05:29:51 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 05:29:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:29:51 INFO - 'USER': 'cltbld', 05:29:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:29:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:29:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:51 INFO - 'XPC_FLAGS': '0x0', 05:29:51 INFO - 'XPC_SERVICE_NAME': '0', 05:29:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:29:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:51 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 05:29:51 INFO - Cleaning up... 05:29:51 INFO - Return code: 0 05:29:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:29:51 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')]} 05:29:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:51 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')]} 05:29:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:51 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 0x1057f53f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10576b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99e94d78b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10560bd30>, '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 0x105472db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105603b20>, '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.lCDUoNCnjL/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.RcYrPR7qVL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 05:29:51 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 05:29:51 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 05:29:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lCDUoNCnjL/Render', 05:29:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:29:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:29:51 INFO - 'HOME': '/Users/cltbld', 05:29:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:29:51 INFO - 'LOGNAME': 'cltbld', 05:29:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:51 INFO - 'MOZ_NO_REMOTE': '1', 05:29:51 INFO - 'NO_EM_RESTART': '1', 05:29:51 INFO - 'PAGER': '/bin/cat', 05:29:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:29:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:51 INFO - 'PWD': '/builds/slave/test', 05:29:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:29:51 INFO - 'SHELL': '/bin/bash', 05:29:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RcYrPR7qVL/Listeners', 05:29:51 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 05:29:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:29:51 INFO - 'USER': 'cltbld', 05:29:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:29:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:29:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:51 INFO - 'XPC_FLAGS': '0x0', 05:29:51 INFO - 'XPC_SERVICE_NAME': '0', 05:29:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:29:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:51 INFO - Downloading/unpacking psutil>=3.1.1 05:29:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:29:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:29:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:29:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:29:55 INFO - Installing collected packages: psutil 05:29:55 INFO - Running setup.py install for psutil 05:29:55 INFO - building 'psutil._psutil_osx' extension 05:29:55 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 05:29:56 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 05:29:56 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 05:29:56 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 05:29:56 INFO - building 'psutil._psutil_posix' extension 05:29:56 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 05:29:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:29:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:29:56 INFO - ^ 05:29:56 INFO - 1 warning generated. 05:29:56 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 05:29:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:29:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:29:56 INFO - Successfully installed psutil 05:29:56 INFO - Cleaning up... 05:29:56 INFO - Return code: 0 05:29:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:29:56 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')]} 05:29:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:56 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')]} 05:29:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:56 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 0x1057f53f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10576b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99e94d78b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10560bd30>, '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 0x105472db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105603b20>, '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.lCDUoNCnjL/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.RcYrPR7qVL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 05:29:56 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 05:29:56 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 05:29:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lCDUoNCnjL/Render', 05:29:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:29:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:29:56 INFO - 'HOME': '/Users/cltbld', 05:29:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:29:56 INFO - 'LOGNAME': 'cltbld', 05:29:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:56 INFO - 'MOZ_NO_REMOTE': '1', 05:29:56 INFO - 'NO_EM_RESTART': '1', 05:29:56 INFO - 'PAGER': '/bin/cat', 05:29:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:29:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:56 INFO - 'PWD': '/builds/slave/test', 05:29:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:29:56 INFO - 'SHELL': '/bin/bash', 05:29:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RcYrPR7qVL/Listeners', 05:29:56 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 05:29:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:29:56 INFO - 'USER': 'cltbld', 05:29:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:29:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:29:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:56 INFO - 'XPC_FLAGS': '0x0', 05:29:56 INFO - 'XPC_SERVICE_NAME': '0', 05:29:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:29:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:29:57 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 05:29:57 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 05:29:57 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 05:29:57 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 05:29:57 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 05:29:57 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 05:29:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:29:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:29:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:29:59 INFO - Installing collected packages: mozsystemmonitor 05:29:59 INFO - Running setup.py install for mozsystemmonitor 05:29:59 INFO - Successfully installed mozsystemmonitor 05:29:59 INFO - Cleaning up... 05:29:59 INFO - Return code: 0 05:29:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:29: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')]} 05:29:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29: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')]} 05:29:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29: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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1057f53f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10576b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99e94d78b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10560bd30>, '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 0x105472db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105603b20>, '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.lCDUoNCnjL/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.RcYrPR7qVL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 05:29: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==2.5.1'] in /builds/slave/test/build 05:29: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==2.5.1 05:29:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lCDUoNCnjL/Render', 05:29:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:29:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:29:59 INFO - 'HOME': '/Users/cltbld', 05:29:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:29:59 INFO - 'LOGNAME': 'cltbld', 05:29:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:59 INFO - 'MOZ_NO_REMOTE': '1', 05:29:59 INFO - 'NO_EM_RESTART': '1', 05:29:59 INFO - 'PAGER': '/bin/cat', 05:29:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:29:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:29:59 INFO - 'PWD': '/builds/slave/test', 05:29:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:29:59 INFO - 'SHELL': '/bin/bash', 05:29:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RcYrPR7qVL/Listeners', 05:29:59 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 05:29:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:29:59 INFO - 'USER': 'cltbld', 05:29:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:29:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:29:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:59 INFO - 'XPC_FLAGS': '0x0', 05:29:59 INFO - 'XPC_SERVICE_NAME': '0', 05:29:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:00 INFO - Downloading/unpacking jsonschema==2.5.1 05:30:00 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 05:30:00 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 05:30:00 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 05:30:00 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 05:30:00 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 05:30:00 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 05:30:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:30:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:30:02 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 05:30:02 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 05:30:02 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 05:30:02 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 05:30:02 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 05:30:02 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 05:30:03 INFO - Downloading functools32-3.2.3-2.tar.gz 05:30:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:30:03 INFO - warning: no files found matching '*.txt' 05:30:03 INFO - no previously-included directories found matching 'build' 05:30:03 INFO - no previously-included directories found matching 'dist' 05:30:03 INFO - no previously-included directories found matching '.git*' 05:30:03 INFO - Installing collected packages: jsonschema, functools32 05:30:03 INFO - Running setup.py install for functools32 05:30:03 INFO - warning: no files found matching '*.txt' 05:30:03 INFO - no previously-included directories found matching 'build' 05:30:03 INFO - no previously-included directories found matching 'dist' 05:30:03 INFO - no previously-included directories found matching '.git*' 05:30:03 INFO - Successfully installed jsonschema functools32 05:30:03 INFO - Cleaning up... 05:30:03 INFO - Return code: 0 05:30:03 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:30:03 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')]} 05:30:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:03 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')]} 05:30:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:03 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 0x1057f53f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10576b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99e94d78b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10560bd30>, '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 0x105472db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105603b20>, '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.lCDUoNCnjL/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.RcYrPR7qVL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 05:30:03 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 05:30:03 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 05:30:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lCDUoNCnjL/Render', 05:30:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:03 INFO - 'HOME': '/Users/cltbld', 05:30:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:03 INFO - 'LOGNAME': 'cltbld', 05:30:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:03 INFO - 'MOZ_NO_REMOTE': '1', 05:30:03 INFO - 'NO_EM_RESTART': '1', 05:30:03 INFO - 'PAGER': '/bin/cat', 05:30:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:03 INFO - 'PWD': '/builds/slave/test', 05:30:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:03 INFO - 'SHELL': '/bin/bash', 05:30:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RcYrPR7qVL/Listeners', 05:30:03 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 05:30:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:03 INFO - 'USER': 'cltbld', 05:30:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:03 INFO - 'XPC_FLAGS': '0x0', 05:30:03 INFO - 'XPC_SERVICE_NAME': '0', 05:30:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:03 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:30:03 INFO - Cleaning up... 05:30:03 INFO - Return code: 0 05:30:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:30:03 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')]} 05:30:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:03 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')]} 05:30:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:03 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 0x1057f53f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10576b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99e94d78b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10560bd30>, '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 0x105472db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105603b20>, '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.lCDUoNCnjL/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.RcYrPR7qVL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 05:30:03 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 05:30:03 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 05:30:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lCDUoNCnjL/Render', 05:30:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:03 INFO - 'HOME': '/Users/cltbld', 05:30:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:03 INFO - 'LOGNAME': 'cltbld', 05:30:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:03 INFO - 'MOZ_NO_REMOTE': '1', 05:30:03 INFO - 'NO_EM_RESTART': '1', 05:30:03 INFO - 'PAGER': '/bin/cat', 05:30:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:03 INFO - 'PWD': '/builds/slave/test', 05:30:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:03 INFO - 'SHELL': '/bin/bash', 05:30:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RcYrPR7qVL/Listeners', 05:30:03 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 05:30:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:03 INFO - 'USER': 'cltbld', 05:30:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:03 INFO - 'XPC_FLAGS': '0x0', 05:30:03 INFO - 'XPC_SERVICE_NAME': '0', 05:30:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:03 INFO - Downloading/unpacking blobuploader==1.2.4 05:30:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:06 INFO - Downloading blobuploader-1.2.4.tar.gz 05:30:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:30:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:30:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:30:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:30:07 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 05:30:07 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 05:30:07 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 05:30:07 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 05:30:07 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 05:30:07 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 05:30:07 INFO - Downloading docopt-0.6.1.tar.gz 05:30:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:30:07 INFO - Installing collected packages: blobuploader, requests, docopt 05:30:07 INFO - Running setup.py install for blobuploader 05:30:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:30:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:30:07 INFO - Running setup.py install for requests 05:30:08 INFO - Running setup.py install for docopt 05:30:08 INFO - Successfully installed blobuploader requests docopt 05:30:08 INFO - Cleaning up... 05:30:08 INFO - Return code: 0 05:30:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:30:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:08 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 0x1057f53f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10576b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99e94d78b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10560bd30>, '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 0x105472db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105603b20>, '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.lCDUoNCnjL/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.RcYrPR7qVL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 05:30:08 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 05:30:08 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 05:30:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lCDUoNCnjL/Render', 05:30:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:08 INFO - 'HOME': '/Users/cltbld', 05:30:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:08 INFO - 'LOGNAME': 'cltbld', 05:30:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:08 INFO - 'MOZ_NO_REMOTE': '1', 05:30:08 INFO - 'NO_EM_RESTART': '1', 05:30:08 INFO - 'PAGER': '/bin/cat', 05:30:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:08 INFO - 'PWD': '/builds/slave/test', 05:30:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:08 INFO - 'SHELL': '/bin/bash', 05:30:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RcYrPR7qVL/Listeners', 05:30:08 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 05:30:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:08 INFO - 'USER': 'cltbld', 05:30:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:08 INFO - 'XPC_FLAGS': '0x0', 05:30:08 INFO - 'XPC_SERVICE_NAME': '0', 05:30:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:30:08 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-vKtnd2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:30:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:30:09 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-Topkov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:30:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:30:09 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-jNEOLq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:30:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:30:09 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-UvoJhC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:30:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:30:09 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-kqxO7T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:30:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:30:09 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-KUH5bJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:30:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:30:09 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-mvkgiL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:30:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:30:09 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-bbYyh0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:30:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:30:09 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-033z55-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:30:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:30:09 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-0nofSw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:30:10 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-u8frRO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:30:10 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-QHxW5u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:30:10 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-p82lt2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:30:10 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-tc7CSd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:30:10 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-f7RGve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:30:10 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-8T9swH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:30:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:30:10 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-oUY4YP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:30:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:30:10 INFO - Running setup.py install for manifestparser 05:30:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:30:10 INFO - Running setup.py install for mozcrash 05:30:11 INFO - Running setup.py install for mozdebug 05:30:11 INFO - Running setup.py install for mozdevice 05:30:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:30:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:30:11 INFO - Running setup.py install for mozfile 05:30:11 INFO - Running setup.py install for mozhttpd 05:30:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:30:11 INFO - Running setup.py install for mozinfo 05:30:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:30:11 INFO - Running setup.py install for mozInstall 05:30:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:30:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:30:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:30:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:30:11 INFO - Running setup.py install for mozleak 05:30:12 INFO - Running setup.py install for mozlog 05:30:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:30:12 INFO - Running setup.py install for moznetwork 05:30:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:30:12 INFO - Running setup.py install for mozprocess 05:30:12 INFO - Running setup.py install for mozprofile 05:30:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:30:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:30:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:30:12 INFO - Running setup.py install for mozrunner 05:30:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:30:12 INFO - Running setup.py install for mozscreenshot 05:30:13 INFO - Running setup.py install for moztest 05:30:13 INFO - Running setup.py install for mozversion 05:30:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:30:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:30:13 INFO - Cleaning up... 05:30:13 INFO - Return code: 0 05:30:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:30:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:13 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 0x1057f53f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10576b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99e94d78b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10560bd30>, '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 0x105472db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105603b20>, '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.lCDUoNCnjL/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.RcYrPR7qVL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 05:30:13 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 05:30:13 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 05:30:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lCDUoNCnjL/Render', 05:30:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:13 INFO - 'HOME': '/Users/cltbld', 05:30:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:13 INFO - 'LOGNAME': 'cltbld', 05:30:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:13 INFO - 'MOZ_NO_REMOTE': '1', 05:30:13 INFO - 'NO_EM_RESTART': '1', 05:30:13 INFO - 'PAGER': '/bin/cat', 05:30:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:13 INFO - 'PWD': '/builds/slave/test', 05:30:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:13 INFO - 'SHELL': '/bin/bash', 05:30:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RcYrPR7qVL/Listeners', 05:30:13 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 05:30:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:13 INFO - 'USER': 'cltbld', 05:30:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:13 INFO - 'XPC_FLAGS': '0x0', 05:30:13 INFO - 'XPC_SERVICE_NAME': '0', 05:30:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:30:13 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-k_Jy46-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:30:13 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)) 05:30:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:30:13 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-wvA2MZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:30:13 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)) 05:30:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:30:13 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-JysAWM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:30:13 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)) 05:30:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:30:13 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-jA3EqE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:30:14 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)) 05:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:30:14 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-_TmyCG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:30:14 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)) 05:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:30:14 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-570vNt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:30:14 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)) 05:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:30:14 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-_rAEcI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:30:14 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)) 05:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:30:14 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-ueE5SN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:30:14 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)) 05:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:30:14 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-DRNIGR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:30:14 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)) 05:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:30:14 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-R389Xe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:30:14 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)) 05:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:30:14 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-eeniZ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:30:14 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)) 05:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:30:14 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-YUcAsZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:30:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 05:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:30:14 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-CEBjRV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:30:15 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)) 05:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:30:15 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-bmAJLB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:30:15 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)) 05:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:30:15 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-IuwnaA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:30:15 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)) 05:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:30:15 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-hT6RXE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:30:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 05:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:30:15 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-yjqu_E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:30:15 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)) 05:30:15 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)) 05:30:15 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)) 05:30:15 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)) 05:30:15 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)) 05:30:15 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)) 05:30:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:30:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:30:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:30:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:30:17 INFO - Downloading blessings-1.6.tar.gz 05:30:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:30:18 INFO - Installing collected packages: blessings 05:30:18 INFO - Running setup.py install for blessings 05:30:18 INFO - Successfully installed blessings 05:30:18 INFO - Cleaning up... 05:30:18 INFO - Return code: 0 05:30:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:30:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:30:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:30:18 INFO - Reading from file tmpfile_stdout 05:30:18 INFO - Current package versions: 05:30:18 INFO - blessings == 1.6 05:30:18 INFO - blobuploader == 1.2.4 05:30:18 INFO - docopt == 0.6.1 05:30:18 INFO - functools32 == 3.2.3-2 05:30:18 INFO - jsonschema == 2.5.1 05:30:18 INFO - manifestparser == 1.1 05:30:18 INFO - mozInstall == 1.12 05:30:18 INFO - mozcrash == 1.0 05:30:18 INFO - mozdebug == 0.1 05:30:18 INFO - mozdevice == 0.49 05:30:18 INFO - mozfile == 1.2 05:30:18 INFO - mozhttpd == 0.7 05:30:18 INFO - mozinfo == 0.9 05:30:18 INFO - mozleak == 0.1 05:30:18 INFO - mozlog == 3.4 05:30:18 INFO - moznetwork == 0.27 05:30:18 INFO - mozprocess == 0.23 05:30:18 INFO - mozprofile == 0.28 05:30:18 INFO - mozrunner == 6.13 05:30:18 INFO - mozscreenshot == 0.1 05:30:18 INFO - mozsystemmonitor == 0.3 05:30:18 INFO - moztest == 0.7 05:30:18 INFO - mozversion == 1.4 05:30:18 INFO - psutil == 3.1.1 05:30:18 INFO - requests == 1.2.3 05:30:18 INFO - wsgiref == 0.1.2 05:30:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:30:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:18 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 0x1057f53f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10576b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99e94d78b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10560bd30>, '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 0x105472db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105603b20>, '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.lCDUoNCnjL/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.RcYrPR7qVL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 05:30:18 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 05:30:18 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 05:30:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lCDUoNCnjL/Render', 05:30:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:18 INFO - 'HOME': '/Users/cltbld', 05:30:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:18 INFO - 'LOGNAME': 'cltbld', 05:30:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:18 INFO - 'MOZ_NO_REMOTE': '1', 05:30:18 INFO - 'NO_EM_RESTART': '1', 05:30:18 INFO - 'PAGER': '/bin/cat', 05:30:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:18 INFO - 'PWD': '/builds/slave/test', 05:30:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:18 INFO - 'SHELL': '/bin/bash', 05:30:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RcYrPR7qVL/Listeners', 05:30:18 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 05:30:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:18 INFO - 'USER': 'cltbld', 05:30:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:18 INFO - 'XPC_FLAGS': '0x0', 05:30:18 INFO - 'XPC_SERVICE_NAME': '0', 05:30:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:18 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)) 05:30:18 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)) 05:30:18 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)) 05:30:18 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)) 05:30:18 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)) 05:30:18 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)) 05:30:18 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)) 05:30:18 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)) 05:30:18 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)) 05:30:18 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)) 05:30:18 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)) 05:30:18 INFO - Cleaning up... 05:30:18 INFO - Return code: 0 05:30:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:30:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:30:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:30:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:30:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:30:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1057f53f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10576b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99e94d78b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10560bd30>, '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 0x105472db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105603b20>, '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.lCDUoNCnjL/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.RcYrPR7qVL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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 05:30:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:30:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:30:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lCDUoNCnjL/Render', 05:30:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:18 INFO - 'HOME': '/Users/cltbld', 05:30:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:18 INFO - 'LOGNAME': 'cltbld', 05:30:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:18 INFO - 'MOZ_NO_REMOTE': '1', 05:30:18 INFO - 'NO_EM_RESTART': '1', 05:30:18 INFO - 'PAGER': '/bin/cat', 05:30:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:18 INFO - 'PWD': '/builds/slave/test', 05:30:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:18 INFO - 'SHELL': '/bin/bash', 05:30:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RcYrPR7qVL/Listeners', 05:30:18 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 05:30:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:18 INFO - 'USER': 'cltbld', 05:30:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:18 INFO - 'XPC_FLAGS': '0x0', 05:30:18 INFO - 'XPC_SERVICE_NAME': '0', 05:30:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:30:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:30:19 INFO - Cleaning up... 05:30:19 INFO - Return code: 0 05:30:19 INFO - Running post-action listener: _resource_record_post_action 05:30:19 INFO - Running post-action listener: _start_resource_monitoring 05:30:19 INFO - Starting resource monitoring. 05:30:19 INFO - [mozharness: 2016-11-19 13:30:19.224096Z] Finished create-virtualenv step (success) 05:30:19 INFO - [mozharness: 2016-11-19 13:30:19.225121Z] Running install step. 05:30:19 INFO - Running pre-action listener: _resource_record_pre_action 05:30:19 INFO - Running main action method: install 05:30:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:30:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:30:19 INFO - Reading from file tmpfile_stdout 05:30:19 INFO - Detecting whether we're running mozinstall >=1.0... 05:30:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:30:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:30:19 INFO - Reading from file tmpfile_stdout 05:30:19 INFO - Output received: 05:30:19 INFO - Usage: mozinstall [options] installer 05:30:19 INFO - Options: 05:30:19 INFO - -h, --help show this help message and exit 05:30:19 INFO - -d DEST, --destination=DEST 05:30:19 INFO - Directory to install application into. [default: 05:30:19 INFO - "/builds/slave/test"] 05:30:19 INFO - --app=APP Application being installed. [default: firefox] 05:30:19 INFO - mkdir: /builds/slave/test/build/application 05:30:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:30:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:30:52 INFO - Reading from file tmpfile_stdout 05:30:52 INFO - Output received: 05:30:52 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:30:52 INFO - Running post-action listener: _resource_record_post_action 05:30:52 INFO - [mozharness: 2016-11-19 13:30:52.193015Z] Finished install step (success) 05:30:52 INFO - [mozharness: 2016-11-19 13:30:52.193137Z] Running run-tests step. 05:30:52 INFO - Running pre-action listener: _resource_record_pre_action 05:30:52 INFO - Running main action method: run_tests 05:30:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:30:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:30:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:30:52 INFO - 2016-11-19 05:30:52.207 screenresolution[1632:8954] starting screenresolution argv=screenresolution get 05:30:52 INFO - 2016-11-19 05:30:52.210 screenresolution[1632:8954] Display 0: 1600x1200x32@60 05:30:52 INFO - 2016-11-19 05:30:52.216 screenresolution[1633:8956] starting screenresolution argv=screenresolution list 05:30:52 INFO - Available Modes on Display 0 05:30:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:30:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:30:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:30:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:30:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:30:52 INFO - Intel Iris: 05:30:52 INFO - Chipset Model: Intel Iris 05:30:52 INFO - Type: GPU 05:30:52 INFO - Bus: Built-In 05:30:52 INFO - VRAM (Dynamic, Max): 1536 MB 05:30:52 INFO - Vendor: Intel (0x8086) 05:30:52 INFO - Device ID: 0x0a2e 05:30:52 INFO - Revision ID: 0x0009 05:30:52 INFO - Displays: 05:30:52 INFO - Display: 05:30:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:30:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:30:52 INFO - Display Serial Number: 1600x1200 60 05:30:52 INFO - Main Display: Yes 05:30:52 INFO - Mirror: Off 05:30:52 INFO - Online: Yes 05:30:52 INFO - Rotation: Supported 05:30:52 INFO - Return code: 0 05:30:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:30:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:30:52 INFO - Python 2.7.3 05:30:52 INFO - Return code: 0 05:30:52 INFO - grabbing minidump binary from tooltool 05:30:52 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')]} 05:30:52 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 0x10560bd30>, '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 0x105472db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105603b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:30:52 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 05:30:52 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 05:30:52 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 05:30:52 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:30:52 INFO - Return code: 0 05:30:52 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:30:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:30:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:30:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:30:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:30:52 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SuUx8_XzTliUCQraHjx8LQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0075', '--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 05:30:52 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/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SuUx8_XzTliUCQraHjx8LQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0075 --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 05:30:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lCDUoNCnjL/Render', 05:30:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:30:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:30:52 INFO - 'HOME': '/Users/cltbld', 05:30:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:30:52 INFO - 'LOGNAME': 'cltbld', 05:30:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:30:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:30:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:30:52 INFO - 'MOZ_NO_REMOTE': '1', 05:30:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:30:52 INFO - 'NO_EM_RESTART': '1', 05:30:52 INFO - 'PAGER': '/bin/cat', 05:30:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:30:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:30:52 INFO - 'PWD': '/builds/slave/test', 05:30:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:30:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:30:52 INFO - 'SHELL': '/bin/bash', 05:30:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RcYrPR7qVL/Listeners', 05:30:52 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 05:30:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:30:52 INFO - 'USER': 'cltbld', 05:30:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:30:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:30:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:30:52 INFO - 'XPC_FLAGS': '0x0', 05:30:52 INFO - 'XPC_SERVICE_NAME': '0', 05:30:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:30:52 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SuUx8_XzTliUCQraHjx8LQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0075', '--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 05:30:52 INFO - mozversion application_buildid: 20161119030204 05:30:52 INFO - mozversion application_changeset: f09e137ead39230eaa94f47988ccce2cfcda4195 05:30:52 INFO - mozversion application_display_name: Nightly 05:30:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:30:52 INFO - mozversion application_name: Firefox 05:30:52 INFO - mozversion application_remotingname: firefox 05:30:52 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:30:52 INFO - mozversion application_vendor: Mozilla 05:30:52 INFO - mozversion application_version: 53.0a1 05:30:52 INFO - mozversion platform_buildid: 20161119030204 05:30:52 INFO - mozversion platform_changeset: f09e137ead39230eaa94f47988ccce2cfcda4195 05:30:52 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:30:52 INFO - mozversion platform_version: 53.0a1 05:30:52 INFO - using testdate: 1479562252 05:30:52 INFO - actual date: 1479562252 05:30:52 INFO - starting webserver on 'localhost:49241' 05:30:52 INFO - SUITE-START | Running 2 tests 05:30:52 INFO - TEST-START | dromaeo_css 05:30:52 INFO - operating with platform_type : mac_ 05:30:52 INFO - Initialising browser for dromaeo_css test... 05:30:52 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/tmpcVvkM4/profile http://localhost:49241/getInfo.html) 05:30:55 INFO - PROCESS | 1643 | __metrics Screen width/height:1600/1200 05:30:55 INFO - PROCESS | 1643 | colorDepth:24 05:30:55 INFO - PROCESS | 1643 | Browser inner width/height: 1024/689 05:30:55 INFO - PROCESS | 1643 | __metrics 05:30:56 INFO - PROCESS | 1643 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:30:56 INFO - PROCESS | 1643 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:30:57 INFO - TEST-INFO | 1643: exit 0 05:30:57 INFO - Browser initialized. 05:30:57 INFO - Running cycle 1/1 for dromaeo_css test... 05:30:57 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/tmpcVvkM4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:31:09 INFO - PROCESS | 1647 | RSS: Main: 244207616 05:31:09 INFO - PROCESS | 1647 | 05:32:00 INFO - PROCESS | 1647 | 1479562320213 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:34:14 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 05:34:16 INFO - PROCESS | 1647 | RSS: Main: 351174656 05:34:16 INFO - PROCESS | 1647 | 05:37:18 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 05:37:19 INFO - PROCESS | 1647 | RSS: Main: 369803264 05:37:19 INFO - PROCESS | 1647 | 05:40:21 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 05:40:22 INFO - PROCESS | 1647 | RSS: Main: 343007232 05:40:22 INFO - PROCESS | 1647 | 05:43:24 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 05:43:25 INFO - PROCESS | 1647 | RSS: Main: 337772544 05:43:25 INFO - PROCESS | 1647 | 05:46:29 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 05:46:29 INFO - PROCESS | 1647 | RSS: Main: 324980736 05:46:29 INFO - PROCESS | 1647 | 05:49:32 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 05:49:33 INFO - PROCESS | 1647 | RSS: Main: 466305024 05:49:33 INFO - PROCESS | 1647 | 05:49:33 INFO - PROCESS | 1647 | __start_tp_report 05:49:33 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 05:49:33 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 05:49:33 INFO - PROCESS | 1647 | |i|pagename|runs| 05:49:33 INFO - PROCESS | 1647 | |0;dojo.html;3266.8366581670916;3295.3574053059647;3318.971118953123;3341.682540158684;3391.8812841550534;5570.136628822539;5629.099344104944;5629.465200805927;5748.396418376068;5781.595288329819;5692.402297758737;5709.4290570942885;5711.628744131628;5711.7715291388395;5729.742161602728;5125.308083408748;5156.716380599084;5163.7418129093585;5191.766370513336;5201.479852014787;4287.271163902128;5227.163653815391;5238.842834714965;5238.895222095556;5245.213218017289;5134.358205224347;5218.947810521884;5224.320838291017;5232.686038837682;5251.737413129328;5253.9474605253845;5254.8686282842855;5270.57835373173;5273.894522109575;5280.6039547034125;5060;5077.771500282496;5089.491050894918;5215.973920130413;5234.293370395024;5608.074667679828;5657.264555607786;5677.37548869624;5699.202111704365;5746.339170995348;6249.937500624981;6269.404406581368;6269.467095296905;6284.685765711696;6291.5281353898645;6123.598024902948;6188.752449901998;6191.102290167901;6311.558190926637;6314.526410519229;4804.25534042224;5741.942580574183;5808.506276966421;5811.302643682785;5853.502452291603;6210.720517576629;6216.968915155486;6224.813255602339;6226.968865155736;6234.875302494015;4919.2129259318335;6030.366811484819;6115.755369785248;6144.8771024579255;6146.7233974471255;6124.330384950791;6166.136740856284;6204.627722336584;6276.592021518587;6284.43440090385;6232.002879539252;6236.719347629368;6260.499160067184;6260.812175634738;6263.874722505616;6191.473724733358;6243.8126856194385;6290.559660823698;6293.402126797947;6305.211848518935;4819.108464933998;4865.294532292868;4883.291922671264;4886.315915771795;4942.555170034714;4390.8024138913825;4410.955890441054;4416.3596278539435;4442.178197033559;4494.16857881293;5792.073268277056;5827.271591051119;5833.183354330397;5841.9415805842245;5862.413758624103;5533.944660553423;6104.969475152596;6271.310155882849;6282.994720844643;6298.779542715983;5508.421615730371;5691.886162276731;5742.655440673573;5748.367679555246;5850.912236316459;5363.651362661492;5394;5399.919001214985;5400.135978243541;5418.376886658085;6252.5935814171935;6270.592411493239;6277.52918531112;6282.403171698681;6288.465480434214;6107.401224262618;6126.945200426759;6132.601380910227;6266.37336266388;6278.6546739929745;6216.44052035319;6269.059641053696;6282.371762823864;6292.056191571301;6314.084457753692;6208.627482351073;6217.440430361289;6230.532710046674;6243.094751261133;6243.656598887105;6101.913673707429;6176.598521096114;6180.227471566054;6185.6907154641185;6231.906521402273;6118.071005858243;6133.805804406886;6135.018397056449;6136.88672321611;6155.922713525205;5355.3305836770405;5949.405059494016;6001.849953751192;6100.572959892765;6115.266168059861;5724.284464441945;5786.768529258951;5840.678762668094;5910.113482977587;5964.880702385841;5231.686098834082;6076.66578338178;6127.509799215963;6136.23297087864;6158.568900177123;5751.913721294268;5893.2044174035955;5914.526837852875;5927.911081333697;5994.490468310242 05:49:33 INFO - PROCESS | 1647 | |1;ext.html;499.31344401448007;510.9795608175673;512.7820676212609;514.1234195696338;523.502672461162;29886.551701724464;38969.61030389695;40211.99470013251;40337.59662403375;41986;41364.379534306936;41570.792146039304;41720.99999999993;41751.37372939401;41812.027857353576;9200.815983680306;9400.952995235048;9402.482863442508;9406.717798466058;9409.012053734363;8712.695055673054;9419.717408477722;9422.623095076224;9429.81140377192;9433.386829856072;9365.11031452011;9375.5312234388;9424.198943089845;9431.24550035999;9450.527473626325;9287.024862389488;9384.483853388061;9427.85858212124;9465.384749991228;9468.621255149787;9226.58480368385;9290;9296.628134874596;9302.953485232598;9304.674336398193;39293.41059884104;39599.40600890989;39653.80173099116;39693.206135877124;39714.404283935764;3301.900942971715;3317.5853018372704;3370.932581348384;3464.22055037617;3503.316853213624;29367;30315.69684303173;30365.392692146033;30774;30800.229994250098;2706.6075419064323;3168.334649723538;3232.208109013307;3315.386653469116;3344.0302312329395;469666;471397;471598;471985;472095;32819.3436131276;33637;57952.71023644939;58273;58376;34799.826000869834;34851.12872178216;35046;35056.298874022374;35122;13772.449102035805;13879.653008674662;13884.236366999912;13886.02797804144;13897.79153312701;11631.47658355376;11734;11752.059835213162;11766.352850593299;11772.411379430994;7629.771106866692;7718.5368877866385;7767.922320776833;7778.222177782177;7784.92215077842;3128.6245650522083;3188.2029492626843;3193.9779270633176;3194;3219.2917558137174;927.9118483744033;938.6339327662217;959.9712008639752;1032.8192566300868;1033.7388386168861;29090;29476.115716528537;29772.404551909272;29910.850445747634;30033.89762716589;1026.9435181064964;1036.0209601926258;1049.58541376156;1089.760252744395;1091.6288461922984;14550.854491455162;15092.54722358331;15297.066459370597;15444.382224711111;15506.844931550766;645981;652910;653701;654970;655767;55328.723356383765;56141.438585614436;56199;56361.154582681294;56492.87014259774;1298.8181654568368;1337.498438085718;1345.2197725319095;1345.2869978911192;1361.4486133116104;27994.300142496602;28811.99158029459;28951.85524072366;29577.40845183041;29629.25926851846;49945.250821238435;50295.49704503055;50342;50354.748226258635;50431.99136017186;564343;564972;566707;568006;568212;25682.486350273262;26012.8699356502;26038.739612603633;26113;26128.34679133037;44361.556384435746;44537.331940020274;45091.32363014492;45763;45993.080138397716;36772.26455470822;36801.447978280885;37090.62909370872;37891.62108378991;38030.429543557424;36298.733114019866;36967.81516092402;37376.439353410264;37555.624443755216;38168;26448.471030578894;32480.187995300308;36902.07744806401;38310.80844595759;38568;28703.712962870104;32582.51126233061;33633.88237172634;33676.663233367355;34263.486047709805;23139.76860231376;24502.387440314134;24644.890979905587;25209;25320.746792532576 05:49:33 INFO - PROCESS | 1647 | |2;jquery.html;341.02296919326136;359.67629133779593;365.0872817955112;371.8754217337191;392.75452841973765;13504.662383440418;13672.453101875926;14485.985980981322;19509.219631214735;20110.698339524883;18685.065746712644;19306;19504.512387190327;19541.804581954213;19584.510387240327;5442;5802.274715660543;5806.477417032466;5812.3025236971625;5824.0390335594575;4805.903881922377;5618.353889302721;5639.774409023634;5688.658680479164;5752.338481074688;5695.686737229451;5698.943010569903;5700.857478563028;5707.686077265748;5727.513161381287;5437.864053398657;5687.772489100431;5704.372519022904;5719.313682358102;5728.312602487728;4650.441946966352;4703.012367402849;4996.1756310208775;5008.674436161674;5017.34774479319;24101.638475422882;25033.87483062591;25118.729815931154;25201.117960871466;25282.367940801443;1849.2695385322722;2239.5520895820737;2408.7952524035304;2979.1826985538805;3024.7882648214636;6767.864642707118;6821.283765204632;14414.99095063346;15037.248137593077;15818.525444236691;1515.4847351900407;1758.0506526475688;1760.5774614092654;1789.123329568502;1796.5329014456204;93623.53188234083;117064;117245.41377293144;117330.41334793271;117423;10300.742481438025;10522.631707890187;10731.83902241467;10772.569097236179;10971.670849874517;13306.334683265797;13327.400266988008;13447.798283025762;13460.394282257323;13466.124701894345;8209.79475513105;8235.29999950011;8282.751517454486;8410.285125764256;8426.789330266793;6492.67536623167;8134.105248422668;8136.1050284468665;8178.836423271501;8308.750737477885;6362.427381535684;6371.139896113966;6432.388923052303;6508.21901371829;6596.076549283104;1729.930802767875;1776.9022703751418;1777.70667839805;1779.181576474836;1784.2149454240093;879.2394578689515;923.5336155241695;928.1507420709975;931.1759093202429;948.4262021477045;19413.90293048554;21432.78567214308;21446.78553214448;21538.89230553837;21572.352829415144;769.3844924060729;832.7293629570414;906.2930913887178;955.4697143085515;973.1971123822874;10985.450727463754;11292.54829806815;11380.601678941362;11422.714432139262;11649.47577359021;486038;560724;567345;572040;575623;77492;87840.6823897642;90206;110037;114186.42906785413;871.2986046232843;915.3455279475207;970.4759429907771;973.3332667123043;975.3124047546463;19044;19343.936083515542;19406;19443.70834437456;19449;49634.25548616846;49995;50121.24818127804;51618.741906290226;55565;71088.28911710817;72148.27851721416;72875.27124728897;74253;76265.61867190628;6815.182178138655;7548.169701332739;7769.611519423893;7793.805154871174;8129.552874591955;10155.339902906288;11928.880711192776;12126.818097728717;12569.87430125687;12694.619161425171;10768.38474228872;10808.02727754506;10808.89191108079;10871.402072886067;10891.945540272565;9288.674896378594;9596.23230141601;10532.57869685204;10570.048695617432;10930.234883558074;10802.513886875267;10831.2959657622;11021.118310535297;11359;11406.942965285452;7677.500962437423;7911.090224624012;8016.438849280495;8031.638576263966;8092.028956525254;9561.139497445263;10430.53062612169;10773.51519181623;10833.837492437777;10854.891451085388 05:49:33 INFO - PROCESS | 1647 | |3;mootools.html;1485.0569888121042;1493.5967288832016;1672.2391311953054;1776.413783451461;1837.7427160197574;8076.798080048002;8496.447730897502;8504.107068757776;8514.233718965299;8518.872216916736;7346.669399877005;7654.9617251913805;7660.693572257117;7738.729144479944;7740.032495938008;2074.9896250518727;2118.54451292972;2224.1214720185517;2306.3772781349026;2310.364649721323;2346.5893468643003;2348.0842471436067;2351.9647205291935;2354.1760383865594;2358.7759162879497;2242.3385101395047;2309.642005489152;2313.2250696016854;2315.6063469210276;2322.6167682332393;2270.818334533242;2281.2927992322434;2300.4478925057865;2305.342977251492;2308.2613563659634;2263.4228271790753;2295.8278129140213;2320.953580928372;2326.6161083421216;2333.428310064028;8386.035605905277;8479.703210387668;8479.745607631781;8487.02399224091;8487.278581320596;5011.448740638527;5225;5228.058949389108;5242.97378513105;5248.580113590903;3289.3092450585405;3544.220271540257;3614.1145419372424;3621.728370372233;3632.400653892105;1025.2976710953021;1052.273930987616;1078.4230436716318;1095.0965453500814;1098.3519723363254;10939.56241750337;10994.90050994902;11010.229283950046;11026.062784663312;11028.062614677761;4437.667174961891;4538.88652783683;4719.669623126417;4751.667383283137;4751.976240118846;9493.382930109521;9502.524873756285;9505.952470237604;9506.524673766422;9524.333296669167;5172.689638621695;5289.180177072483;5294.100002999499;5306.257124002643;5320.627556071116;4725.125851717443;4786.1863483207935;4807.254875494304;4835.298881662139;4837.854864354075;2930.6190195274476;3633.3096711624707;3642.5264715586804;3719.1817800083936;3884.9611503885167;1252.9060320476;1252.9686757831128;1262.1985039499984;1269.1433282534254;1272.7072773262016;865.8873347748114;877.4165180155253;883.1565854608833;896.233720169259;923.6074668266094;4930.260460930817;5499.387407488529;5584.692841893735;5891.911621325684;5909.172715819789;892.5537231384308;993.3295025857517;1001.8146642871092;1022.539857064324;1059.1579694143174;3550.6981906538235;3784.962150378516;3788.431735239681;3805.5813860475328;3890.7860067696547;7727.806804829812;10247.231457640706;10394.960503949544;10414.843777343345;10416.166706663449;8528.616212270463;10331.586736530455;10368.481575921174;10371.792564148825;10383.688489345332;7473.177950425449;7821.217878212134;7978.401619878532;7986.600669966479;7997.960265165491;5679.403662615531;5797.942020579909;5809.535237180931;5900.557458190567;5915;9265.675701350421;9371.718848434557;9444.763880903032;9510.572024259063;9542.284328675238;9583.424994500352;9712.562934667816;9713.56288966984;9756.902430975599;9847.950760246153;7153.996880873364;7311.305425984523;7312.451566132455;7322.597257150908;7349.228331025379;7702.961485192763;7993.560354180576;8026.317762990211;8038.477498962548;8188.549629770419;6495.2855185928565;6940.548864323803;7147.714091436284;7249.710011599688;7395.926040739524;7026.648667566499;7066.858662826818;7072.5402848814665;7081.468889833386;7128.322809333105;6393.104965304768;6596.769113081019;6704.865902682017;6716.966415167893;6790.830229244309;4764.928526072181;4825.30033145199;4861.4895435980125;4863.975680121577;4996.850094497171;4815.951840481691;4851.175300198904;4939.234418665113;4986.975065124796;5007.949920500749 05:49:33 INFO - PROCESS | 1647 | |4;prototype.html;271.3974975554269;282.9886804527819;295.55814057983304;301.4558721507679;316.03924070824695;5091.872703182422;5112.8977420451565;5123.411742359871;5141.254518094874;5173.172292433207;3625.7730619748445;4762.452317983433;4777.474477807438;4933.654644174909;5132.101882170615;3116.5169398743174;3835.791725606439;3930.724849260553;3933.5869733677982;3960.25748670584;3407.8807241746545;3900.8244628991765;3914.0801911550907;3932.921341573167;3939.9606003940025;3653.5615726112906;3766.736328457009;3813.599572044923;3895.2988462076814;3896.7661940283674;3531.823408829548;3884.2814079395403;3907.902302442433;3913.4716813230134;3917.843286268546;3372.8819491317686;3387.44107222308;3399.3371292597953;3415.8292085395633;3436.7078798302173;4300.763458009808;4728.905421891577;4957.1248675767965;4979.15354389751;4983.6013118950395;4406.955930440687;4469.7988590513505;4475.1944649963;4494.07871386365;4512.34570987205;4469.011289975962;4927.852164435073;5089.618278629117;5356;5379.354477462691;3309.23887505875;3459.840953280629;3486.3741708623;3785.6403641653997;3790.2040571480024;4616.760870977607;4803.159447096744;5077.593792496591;5405.432414606341;5452.6183167178315;3576.8390422431053;3725.776453412804;3728.850845966131;3730.813459327077;3731.869384571527;3497.845710915392;3744.625537446288;3756.417755247941;3775.9433608495897;3784.6972242220554;4066.861278841929;4195.8181745492175;4325.8269669213505;4351.760653164107;4361.825526978949;4838.564529192319;5386.29978102852;5400.758685145699;5403.486668766461;5413.675179489164;1091.7608514336287;1207.9033677305795;1225.957091501811;1229.6987238126537;1246.4515613130193;673.0778832998823;777.3819813248481;800.4116974024088;802.8916096327038;826.5454000299811;4702.694324868872;4717.386739723883;4731.29030645406;4746.863911239956;4795.544423279783;4664.323673067386;4985.130576033987;5141.871453213699;5147.742612869341;5156.149235376196;4095.9180816383505;4368.475782906008;4719;4726.565818603422;4730.290456431494;2302.551002554519;2497.8126640501673;2710.035224462249;2734.767544758678;2735.2478068530913;4737.715737055788;4854.514548545188;5143.971205758863;5310.636766638809;5377.462253774591;4664.766761661904;4995.375578052744;5025.135672666618;5170.663906846043;5253.4221235664045;4711.95288047122;4929.827456039022;5161.954446876286;5176.352955880515;5347.839564813062;4506.994387497532;4969.403671559436;5116.565091967075;5117.616178786531;5197.244521263002;4667.8330417395655;4706.694064885772;4806;5060.608332708534;5091.490850914879;4628.051249493778;5237.397699264633;5304.9734751326;5343.278657381204;5363.329583802025;3174.8095114293214;3216.6622504636675;3513.8594456221463;3527.7177825773365;3529.347070791912;4371.672124590605;4625.779625779577;4747.691400058985;5088.313077734607;5160.48395160496;4098.590140985997;4904.719305522949;5014.6991180529285;5038.2190760432195;5043.604287963188;4351.325544540601;4633.907321853611;4992.500749924979;5007.349044624176;5048.3941926969;4311.741295522279;4402.427684401007;4836.758162091811;4892.4373697023875;4903.436104847988;4414.889627759332;5095.108356037605;5269.78920843162;5318.7074710891275;5349.732513374238;5304.920426193533;5323.840284791462;5332.840014799563;5337.839864804062;5346.438623944586 05:49:33 INFO - PROCESS | 1647 | |5;yui.html;585.5257241634276;672.398203607771;739.3419856327872;770.6339488742848;780.562502175134;11294.830577541334;14421.990460667745;14444.855551444496;14667.339969701363;14698.706025879503;13220.669483262924;13327.46690132396;13432.462701491928;13467.989900757459;13535.52625658102;647.8607099473612;669.2504395077495;670.2392784189931;790.5217343507181;812.2283830361166;831.6382373667474;832.7127141136298;836.861917783565;840.0633293877344;841.2680967558233;858.2833334165979;883.7923088074298;886.175856453498;887.7203680840514;890.977725556863;675.4427597232303;697.8674051930117;879.6217626420637;879.6349514951255;883.425773247388;592.8617055253923;601.1343665122254;621.8445388652837;648.4941745438565;648.6302807399784;13950.860491395058;14141.717165656732;14158;14170.362333694906;14194.645133871736;12937.223766574036;13055.347232638425;13165.078444508792;13292.534761283405;13434.462621495128;11004.284721493077;11231.10151187903;12174.756504869942;12211;12298.631041068782;4269.786510674485;4276.679249056333;4317.071829556629;4321.200577893069;4409.36064270679;90295.54852225762;90536;91809.54095229547;92480.53759731226;93038;10578.735531611617;10618.256722029384;10619.84070238947;10722.946385268024;10939.726506837234;20340.389788306373;20452.0796564152;20493.2827351042;20525.38423847287;20813.1674733012;6117.357677443804;7409.333160015624;7445.404367650575;7543.622818859145;7592.8861067082935;4047.433359329696;4164.104717485755;4189.581041895847;4193.454850869368;4210.726302790309;3255.983720081432;3360;3365.865365385357;3595.9280814383565;3804.2011177653003;570.968596727184;676.0670275020539;684.3259389501337;690.9205441374206;693.9236683964759;576.3573615418861;647.6276141218724;654.9508786841006;660.2688662410325;675.7330854312519;8063.798405039804;8201.810737443157;9079.273658107335;9106.590203440861;9156.816863662689;469.41557760588285;577.3158806813939;582.6445868020504;624.2914292278206;664.3124366280877;4180;4460.799264033061;4465.955340446554;4481.731096134177;4526.185286648369;114459;116664.41667791607;117128;118669.40665296618;118923;80719.789203162;82637.58681206676;83100.75348869772;101401;102070;64902.67548662226;87649;89846.10153898508;91358;91985;7660.846783064419;8507.447015943944;8663.610137543952;8668;8783.736487905373;41704.16591668089;52918.94162116813;53796.5096806119;56248.718756407594;57165;66687;66790;67556;73110.63444682742;77344;12522.561710340095;12630.179038362478;12642.87357126417;13013.414396351998;13075.803862941873;13915.652108696959;14051.789223161457;14318.14091154534;14431.42274309058;14440.855591443951;11554.075673945896;12743.87256127427;13132.606021819362;13241.006924480509;13305.068645194744;13124.409401577142;13203.801942970671;13268.203907765565;13523.459061637424;13690.31548422555;12669.113162078565;12752.043596730096;12920.870791291967;13420.261885596386;13465.528706495226;10086.445245511568;10678.73303167427;10847.620333288298;11021.559137634405;11024.669259922215;10133.594656213669;11125.054370378608;11239.09006960494;11343.602973895873;11348.659540213806 05:49:33 INFO - PROCESS | 1647 | __end_tp_report 05:49:33 INFO - PROCESS | 1647 | __start_cc_report 05:49:33 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,1305 05:49:33 INFO - PROCESS | 1647 | __end_cc_report 05:49:33 INFO - PROCESS | 1647 | __startTimestamp1479563373166__endTimestamp 05:49:33 INFO - PROCESS | 1647 | 05:49:33 INFO - PROCESS | 1647 | ------- Summary: start ------- 05:49:33 INFO - PROCESS | 1647 | Number of tests: 6 05:49:33 INFO - PROCESS | 1647 | 05:49:33 INFO - PROCESS | 1647 | [#0] dojo.html Cycles:165 Average:5707.41 Median:5862.41 stddev:655.71 (11.2%) stddev-sans-first:629.15 05:49:33 INFO - PROCESS | 1647 | Values: 3266.8 3295.4 3319.0 3341.7 3391.9 5570.1 5629.1 5629.5 5748.4 5781.6 5692.4 5709.4 5711.6 5711.8 5729.7 5125.3 5156.7 5163.7 5191.8 5201.5 4287.3 5227.2 5238.8 5238.9 5245.2 5134.4 5218.9 5224.3 5232.7 5251.7 5253.9 5254.9 5270.6 5273.9 5280.6 5060.0 5077.8 5089.5 5216.0 5234.3 5608.1 5657.3 5677.4 5699.2 5746.3 6249.9 6269.4 6269.5 6284.7 6291.5 6123.6 6188.8 6191.1 6311.6 6314.5 4804.3 5741.9 5808.5 5811.3 5853.5 6210.7 6217.0 6224.8 6227.0 6234.9 4919.2 6030.4 6115.8 6144.9 6146.7 6124.3 6166.1 6204.6 6276.6 6284.4 6232.0 6236.7 6260.5 6260.8 6263.9 6191.5 6243.8 6290.6 6293.4 6305.2 4819.1 4865.3 4883.3 4886.3 4942.6 4390.8 4411.0 4416.4 4442.2 4494.2 5792.1 5827.3 5833.2 5841.9 5862.4 5533.9 6105.0 6271.3 6283.0 6298.8 5508.4 5691.9 5742.7 5748.4 5850.9 5363.7 5394.0 5399.9 5400.1 5418.4 6252.6 6270.6 6277.5 6282.4 6288.5 6107.4 6126.9 6132.6 6266.4 6278.7 6216.4 6269.1 6282.4 6292.1 6314.1 6208.6 6217.4 6230.5 6243.1 6243.7 6101.9 6176.6 6180.2 6185.7 6231.9 6118.1 6133.8 6135.0 6136.9 6155.9 5355.3 5949.4 6001.8 6100.6 6115.3 5724.3 5786.8 5840.7 5910.1 5964.9 5231.7 6076.7 6127.5 6136.2 6158.6 5751.9 5893.2 5914.5 5927.9 5994.5 05:49:33 INFO - PROCESS | 1647 | 05:49:33 INFO - PROCESS | 1647 | [#1] ext.html Cycles:180 Average:67633.36 Median:37961.03 stddev:152320.87 (401.3%) stddev-sans-first:152664.77 05:49:33 INFO - PROCESS | 1647 | Values: 499.3 511.0 512.8 514.1 523.5 29886.6 38969.6 40212.0 40337.6 41986.0 41364.4 41570.8 41721.0 41751.4 41812.0 9200.8 9401.0 9402.5 9406.7 9409.0 8712.7 9419.7 9422.6 9429.8 9433.4 9365.1 9375.5 9424.2 9431.2 9450.5 9287.0 9384.5 9427.9 9465.4 9468.6 9226.6 9290.0 9296.6 9303.0 9304.7 39293.4 39599.4 39653.8 39693.2 39714.4 3301.9 3317.6 3370.9 3464.2 3503.3 29367.0 30315.7 30365.4 30774.0 30800.2 2706.6 3168.3 3232.2 3315.4 3344.0 469666.0 471397.0 471598.0 471985.0 472095.0 32819.3 33637.0 57952.7 58273.0 58376.0 34799.8 34851.1 35046.0 35056.3 35122.0 13772.4 13879.7 13884.2 13886.0 13897.8 11631.5 11734.0 11752.1 11766.4 11772.4 7629.8 7718.5 7767.9 7778.2 7784.9 3128.6 3188.2 3194.0 3194.0 3219.3 927.9 938.6 960.0 1032.8 1033.7 29090.0 29476.1 29772.4 29910.9 30033.9 1026.9 1036.0 1049.6 1089.8 1091.6 14550.9 15092.5 15297.1 15444.4 15506.8 645981.0 652910.0 653701.0 654970.0 655767.0 55328.7 56141.4 56199.0 56361.2 56492.9 1298.8 1337.5 1345.2 1345.3 1361.4 27994.3 28812.0 28951.9 29577.4 29629.3 49945.3 50295.5 50342.0 50354.7 50432.0 564343.0 564972.0 566707.0 568006.0 568212.0 25682.5 26012.9 26038.7 26113.0 26128.3 44361.6 44537.3 45091.3 45763.0 45993.1 36772.3 36801.4 37090.6 37891.6 38030.4 36298.7 36967.8 37376.4 37555.6 38168.0 26448.5 32480.2 36902.1 38310.8 38568.0 28703.7 32582.5 33633.9 33676.7 34263.5 23139.8 24502.4 24644.9 25209.0 25320.7 05:49:33 INFO - PROCESS | 1647 | 05:49:33 INFO - PROCESS | 1647 | [#2] jquery.html Cycles:180 Average:32303.51 Median:2521.60 stddev:91930.94 (3645.7%) stddev-sans-first:92157.50 05:49:33 INFO - PROCESS | 1647 | Values: 341.0 359.7 365.1 371.9 392.8 13504.7 13672.5 14486.0 19509.2 20110.7 18685.1 19306.0 19504.5 19541.8 19584.5 5442.0 5802.3 5806.5 5812.3 5824.0 4805.9 5618.4 5639.8 5688.7 5752.3 5695.7 5698.9 5700.9 5707.7 5727.5 5437.9 5687.8 5704.4 5719.3 5728.3 4650.4 4703.0 4996.2 5008.7 5017.3 24101.6 25033.9 25118.7 25201.1 25282.4 1849.3 2239.6 2408.8 2979.2 3024.8 6767.9 6821.3 14415.0 15037.2 15818.5 1515.5 1758.1 1760.6 1789.1 1796.5 93623.5 117064.0 117245.4 117330.4 117423.0 10300.7 10522.6 10731.8 10772.6 10971.7 13306.3 13327.4 13447.8 13460.4 13466.1 8209.8 8235.3 8282.8 8410.3 8426.8 6492.7 8134.1 8136.1 8178.8 8308.8 6362.4 6371.1 6432.4 6508.2 6596.1 1729.9 1776.9 1777.7 1779.2 1784.2 879.2 923.5 928.2 931.2 948.4 19413.9 21432.8 21446.8 21538.9 21572.4 769.4 832.7 906.3 955.5 973.2 10985.5 11292.5 11380.6 11422.7 11649.5 486038.0 560724.0 567345.0 572040.0 575623.0 77492.0 87840.7 90206.0 110037.0 114186.4 871.3 915.3 970.5 973.3 975.3 19044.0 19343.9 19406.0 19443.7 19449.0 49634.3 49995.0 50121.2 51618.7 55565.0 71088.3 72148.3 72875.3 74253.0 76265.6 6815.2 7548.2 7769.6 7793.8 8129.6 10155.3 11928.9 12126.8 12569.9 12694.6 10768.4 10808.0 10808.9 10871.4 10891.9 9288.7 9596.2 10532.6 10570.0 10930.2 10802.5 10831.3 11021.1 11359.0 11406.9 7677.5 7911.1 8016.4 8031.6 8092.0 9561.1 10430.5 10773.5 10833.8 10854.9 05:49:33 INFO - PROCESS | 1647 | 05:49:33 INFO - PROCESS | 1647 | [#3] mootools.html Cycles:180 Average:5476.89 Median:5002.40 stddev:2999.37 (60.0%) stddev-sans-first:2992.78 05:49:33 INFO - PROCESS | 1647 | Values: 1485.1 1493.6 1672.2 1776.4 1837.7 8076.8 8496.4 8504.1 8514.2 8518.9 7346.7 7655.0 7660.7 7738.7 7740.0 2075.0 2118.5 2224.1 2306.4 2310.4 2346.6 2348.1 2352.0 2354.2 2358.8 2242.3 2309.6 2313.2 2315.6 2322.6 2270.8 2281.3 2300.4 2305.3 2308.3 2263.4 2295.8 2321.0 2326.6 2333.4 8386.0 8479.7 8479.7 8487.0 8487.3 5011.4 5225.0 5228.1 5243.0 5248.6 3289.3 3544.2 3614.1 3621.7 3632.4 1025.3 1052.3 1078.4 1095.1 1098.4 10939.6 10994.9 11010.2 11026.1 11028.1 4437.7 4538.9 4719.7 4751.7 4752.0 9493.4 9502.5 9506.0 9506.5 9524.3 5172.7 5289.2 5294.1 5306.3 5320.6 4725.1 4786.2 4807.3 4835.3 4837.9 2930.6 3633.3 3642.5 3719.2 3885.0 1252.9 1253.0 1262.2 1269.1 1272.7 865.9 877.4 883.2 896.2 923.6 4930.3 5499.4 5584.7 5891.9 5909.2 892.6 993.3 1001.8 1022.5 1059.2 3550.7 3785.0 3788.4 3805.6 3890.8 7727.8 10247.2 10395.0 10414.8 10416.2 8528.6 10331.6 10368.5 10371.8 10383.7 7473.2 7821.2 7978.4 7986.6 7998.0 5679.4 5797.9 5809.5 5900.6 5915.0 9265.7 9371.7 9444.8 9510.6 9542.3 9583.4 9712.6 9713.6 9756.9 9848.0 7154.0 7311.3 7312.5 7322.6 7349.2 7703.0 7993.6 8026.3 8038.5 8188.5 6495.3 6940.5 7147.7 7249.7 7395.9 7026.6 7066.9 7072.5 7081.5 7128.3 6393.1 6596.8 6704.9 6717.0 6790.8 4764.9 4825.3 4861.5 4864.0 4996.9 4816.0 4851.2 4939.2 4987.0 5007.9 05:49:33 INFO - PROCESS | 1647 | 05:49:33 INFO - PROCESS | 1647 | [#4] prototype.html Cycles:180 Average:4181.80 Median:4709.32 stddev:1250.79 (26.6%) stddev-sans-first:1219.38 05:49:33 INFO - PROCESS | 1647 | Values: 271.4 283.0 295.6 301.5 316.0 5091.9 5112.9 5123.4 5141.3 5173.2 3625.8 4762.5 4777.5 4933.7 5132.1 3116.5 3835.8 3930.7 3933.6 3960.3 3407.9 3900.8 3914.1 3932.9 3940.0 3653.6 3766.7 3813.6 3895.3 3896.8 3531.8 3884.3 3907.9 3913.5 3917.8 3372.9 3387.4 3399.3 3415.8 3436.7 4300.8 4728.9 4957.1 4979.2 4983.6 4407.0 4469.8 4475.2 4494.1 4512.3 4469.0 4927.9 5089.6 5356.0 5379.4 3309.2 3459.8 3486.4 3785.6 3790.2 4616.8 4803.2 5077.6 5405.4 5452.6 3576.8 3725.8 3728.9 3730.8 3731.9 3497.8 3744.6 3756.4 3775.9 3784.7 4066.9 4195.8 4325.8 4351.8 4361.8 4838.6 5386.3 5400.8 5403.5 5413.7 1091.8 1207.9 1226.0 1229.7 1246.5 673.1 777.4 800.4 802.9 826.5 4702.7 4717.4 4731.3 4746.9 4795.5 4664.3 4985.1 5141.9 5147.7 5156.1 4095.9 4368.5 4719.0 4726.6 4730.3 2302.6 2497.8 2710.0 2734.8 2735.2 4737.7 4854.5 5144.0 5310.6 5377.5 4664.8 4995.4 5025.1 5170.7 5253.4 4712.0 4929.8 5162.0 5176.4 5347.8 4507.0 4969.4 5116.6 5117.6 5197.2 4667.8 4706.7 4806.0 5060.6 5091.5 4628.1 5237.4 5305.0 5343.3 5363.3 3174.8 3216.7 3513.9 3527.7 3529.3 4371.7 4625.8 4747.7 5088.3 5160.5 4098.6 4904.7 5014.7 5038.2 5043.6 4351.3 4633.9 4992.5 5007.3 5048.4 4311.7 4402.4 4836.8 4892.4 4903.4 4414.9 5095.1 5269.8 5318.7 5349.7 5304.9 5323.8 5332.8 5337.8 5346.4 05:49:33 INFO - PROCESS | 1647 | 05:49:33 INFO - PROCESS | 1647 | [#5] yui.html Cycles:180 Average:20539.66 Median:4296.88 stddev:30375.11 (706.9%) stddev-sans-first:30423.36 05:49:33 INFO - PROCESS | 1647 | Values: 585.5 672.4 739.3 770.6 780.6 11294.8 14422.0 14444.9 14667.3 14698.7 13220.7 13327.5 13432.5 13468.0 13535.5 647.9 669.3 670.2 790.5 812.2 831.6 832.7 836.9 840.1 841.3 858.3 883.8 886.2 887.7 891.0 675.4 697.9 879.6 879.6 883.4 592.9 601.1 621.8 648.5 648.6 13950.9 14141.7 14158.0 14170.4 14194.6 12937.2 13055.3 13165.1 13292.5 13434.5 11004.3 11231.1 12174.8 12211.0 12298.6 4269.8 4276.7 4317.1 4321.2 4409.4 90295.5 90536.0 91809.5 92480.5 93038.0 10578.7 10618.3 10619.8 10722.9 10939.7 20340.4 20452.1 20493.3 20525.4 20813.2 6117.4 7409.3 7445.4 7543.6 7592.9 4047.4 4164.1 4189.6 4193.5 4210.7 3256.0 3360.0 3365.9 3595.9 3804.2 571.0 676.1 684.3 690.9 693.9 576.4 647.6 655.0 660.3 675.7 8063.8 8201.8 9079.3 9106.6 9156.8 469.4 577.3 582.6 624.3 664.3 4180.0 4460.8 4466.0 4481.7 4526.2 114459.0 116664.4 117128.0 118669.4 118923.0 80719.8 82637.6 83100.8 101401.0 102070.0 64902.7 87649.0 89846.1 91358.0 91985.0 7660.8 8507.4 8663.6 8668.0 8783.7 41704.2 52918.9 53796.5 56248.7 57165.0 66687.0 66790.0 67556.0 73110.6 77344.0 12522.6 12630.2 12642.9 13013.4 13075.8 13915.7 14051.8 14318.1 14431.4 14440.9 11554.1 12743.9 13132.6 13241.0 13305.1 13124.4 13203.8 13268.2 13523.5 13690.3 12669.1 12752.0 12920.9 13420.3 13465.5 10086.4 10678.7 10847.6 11021.6 11024.7 10133.6 11125.1 11239.1 11343.6 11348.7 05:49:33 INFO - PROCESS | 1647 | -------- Summary: end -------- 05:49:33 INFO - PROCESS | 1647 | 05:49:33 INFO - TEST-INFO | 1647: exit 0 05:49:33 INFO - TEST-OK | dromaeo_css | took 1121048ms 05:49:33 INFO - TEST-START | kraken 05:49:33 INFO - operating with platform_type : mac_ 05:49:33 INFO - Initialising browser for kraken test... 05:49:33 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/tmponOTGN/profile http://localhost:49241/getInfo.html) 05:49:36 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 05:49:36 INFO - PROCESS | 1652 | colorDepth:24 05:49:36 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 05:49:36 INFO - PROCESS | 1652 | __metrics 05:49:36 INFO - PROCESS | 1652 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:49:36 INFO - PROCESS | 1652 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:49:37 INFO - TEST-INFO | 1652: exit 0 05:49:37 INFO - Browser initialized. 05:49:37 INFO - Running cycle 1/1 for kraken test... 05:49:37 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/tmponOTGN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:49:49 INFO - PROCESS | 1654 | RSS: Main: 244051968 05:49:49 INFO - PROCESS | 1654 | 05:49:52 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 05:49:53 INFO - PROCESS | 1654 | RSS: Main: 293916672 05:49:53 INFO - PROCESS | 1654 | 05:49:55 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 05:49:56 INFO - PROCESS | 1654 | RSS: Main: 296288256 05:49:56 INFO - PROCESS | 1654 | 05:50:00 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 05:50:00 INFO - PROCESS | 1654 | RSS: Main: 386023424 05:50:00 INFO - PROCESS | 1654 | 05:50:02 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 05:50:03 INFO - PROCESS | 1654 | RSS: Main: 290729984 05:50:03 INFO - PROCESS | 1654 | 05:50:05 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:50:06 INFO - PROCESS | 1654 | RSS: Main: 293437440 05:50:06 INFO - PROCESS | 1654 | 05:50:08 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 05:50:09 INFO - PROCESS | 1654 | RSS: Main: 335466496 05:50:09 INFO - PROCESS | 1654 | 05:50:12 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 05:50:12 INFO - PROCESS | 1654 | RSS: Main: 336486400 05:50:12 INFO - PROCESS | 1654 | 05:50:15 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 05:50:16 INFO - PROCESS | 1654 | RSS: Main: 334065664 05:50:16 INFO - PROCESS | 1654 | 05:50:17 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:50:18 INFO - PROCESS | 1654 | RSS: Main: 307654656 05:50:18 INFO - PROCESS | 1654 | 05:50:20 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:50:21 INFO - PROCESS | 1654 | RSS: Main: 313409536 05:50:21 INFO - PROCESS | 1654 | 05:50:23 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:50:23 INFO - PROCESS | 1654 | RSS: Main: 314728448 05:50:23 INFO - PROCESS | 1654 | 05:50:27 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:50:27 INFO - PROCESS | 1654 | RSS: Main: 331251712 05:50:27 INFO - PROCESS | 1654 | 05:50:30 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:50:31 INFO - PROCESS | 1654 | RSS: Main: 325079040 05:50:31 INFO - PROCESS | 1654 | 05:50:33 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 05:50:33 INFO - PROCESS | 1654 | RSS: Main: 368435200 05:50:33 INFO - PROCESS | 1654 | 05:50:33 INFO - PROCESS | 1654 | __start_tp_report 05:50:33 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 05:50:33 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 05:50:33 INFO - PROCESS | 1654 | |i|pagename|runs| 05:50:33 INFO - PROCESS | 1654 | |0;ai-astar;114;114;114;113;113;115;115;114;115;115 05:50:33 INFO - PROCESS | 1654 | |1;audio-beat-detection;148;112;114;152;111;111;112;111;142;111 05:50:33 INFO - PROCESS | 1654 | |2;audio-dft;187;182;182;187;183;185;184;183;181;184 05:50:33 INFO - PROCESS | 1654 | |3;audio-fft;79;59;58;88;61;59;57;59;85;58 05:50:33 INFO - PROCESS | 1654 | |4;audio-oscillator;81;70;95;71;89;100;80;98;100;126 05:50:33 INFO - PROCESS | 1654 | |5;imaging-gaussian-blur;86;83;88;84;87;84;88;84;88;84 05:50:33 INFO - PROCESS | 1654 | |6;imaging-darkroom;95;95;96;95;94;95;94;95;95;94 05:50:33 INFO - PROCESS | 1654 | |7;imaging-desaturate;85;85;86;88;87;85;85;84;88;87 05:50:33 INFO - PROCESS | 1654 | |8;json-parse-financial;55;56;54;52;53;53;55;55;44;46 05:50:33 INFO - PROCESS | 1654 | |9;json-stringify-tinderbox;63;70;74;75;73;69;71;71;69;71 05:50:33 INFO - PROCESS | 1654 | |10;stanford-crypto-aes;90;88;88;88;98;106;107;74;100;98 05:50:33 INFO - PROCESS | 1654 | |11;stanford-crypto-ccm;151;149;149;152;158;162;149;164;207;175 05:50:33 INFO - PROCESS | 1654 | |12;stanford-crypto-pbkdf2;178;172;176;174;172;171;174;170;177;173 05:50:33 INFO - PROCESS | 1654 | |13;stanford-crypto-sha256-iterative;68;68;69;65;69;69;67;66;67;68 05:50:33 INFO - PROCESS | 1654 | __end_tp_report 05:50:33 INFO - PROCESS | 1654 | __start_cc_report 05:50:33 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,1994 05:50:33 INFO - PROCESS | 1654 | __end_cc_report 05:50:33 INFO - PROCESS | 1654 | __startTimestamp1479563433343__endTimestamp 05:50:33 INFO - PROCESS | 1654 | 05:50:33 INFO - PROCESS | 1654 | ------- Summary: start ------- 05:50:33 INFO - PROCESS | 1654 | Number of tests: 14 05:50:33 INFO - PROCESS | 1654 | 05:50:33 INFO - PROCESS | 1654 | [#0] ai-astar Cycles:10 Average:114.20 Median:114.50 stddev:0.79 (0.7%) stddev-sans-first:0.83 05:50:33 INFO - PROCESS | 1654 | Values: 114.0 114.0 114.0 113.0 113.0 115.0 115.0 114.0 115.0 115.0 05:50:33 INFO - PROCESS | 1654 | 05:50:33 INFO - PROCESS | 1654 | [#1] audio-beat-detection Cycles:10 Average:122.40 Median:113.00 stddev:17.39 (15.4%) stddev-sans-first:15.79 05:50:33 INFO - PROCESS | 1654 | Values: 148.0 112.0 114.0 152.0 111.0 111.0 112.0 111.0 142.0 111.0 05:50:33 INFO - PROCESS | 1654 | 05:50:33 INFO - PROCESS | 1654 | [#2] audio-dft Cycles:10 Average:183.80 Median:184.00 stddev:2.04 (1.1%) stddev-sans-first:1.81 05:50:33 INFO - PROCESS | 1654 | Values: 187.0 182.0 182.0 187.0 183.0 185.0 184.0 183.0 181.0 184.0 05:50:33 INFO - PROCESS | 1654 | 05:50:33 INFO - PROCESS | 1654 | [#3] audio-fft Cycles:10 Average:66.30 Median:60.00 stddev:12.45 (20.7%) stddev-sans-first:12.32 05:50:33 INFO - PROCESS | 1654 | Values: 79.0 59.0 58.0 88.0 61.0 59.0 57.0 59.0 85.0 58.0 05:50:33 INFO - PROCESS | 1654 | 05:50:33 INFO - PROCESS | 1654 | [#4] audio-oscillator Cycles:10 Average:91.00 Median:80.50 stddev:16.73 (20.8%) stddev-sans-first:17.35 05:50:33 INFO - PROCESS | 1654 | Values: 81.0 70.0 95.0 71.0 89.0 100.0 80.0 98.0 100.0 126.0 05:50:33 INFO - PROCESS | 1654 | 05:50:33 INFO - PROCESS | 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:85.60 Median:86.50 stddev:2.01 (2.3%) stddev-sans-first:2.13 05:50:33 INFO - PROCESS | 1654 | Values: 86.0 83.0 88.0 84.0 87.0 84.0 88.0 84.0 88.0 84.0 05:50:33 INFO - PROCESS | 1654 | 05:50:33 INFO - PROCESS | 1654 | [#6] imaging-darkroom Cycles:10 Average:94.80 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 05:50:33 INFO - PROCESS | 1654 | Values: 95.0 95.0 96.0 95.0 94.0 95.0 94.0 95.0 95.0 94.0 05:50:33 INFO - PROCESS | 1654 | 05:50:33 INFO - PROCESS | 1654 | [#7] imaging-desaturate Cycles:10 Average:86.00 Median:86.50 stddev:1.41 (1.6%) stddev-sans-first:1.45 05:50:33 INFO - PROCESS | 1654 | Values: 85.0 85.0 86.0 88.0 87.0 85.0 85.0 84.0 88.0 87.0 05:50:33 INFO - PROCESS | 1654 | 05:50:33 INFO - PROCESS | 1654 | [#8] json-parse-financial Cycles:10 Average:52.30 Median:54.50 stddev:4.06 (7.4%) stddev-sans-first:4.18 05:50:33 INFO - PROCESS | 1654 | Values: 55.0 56.0 54.0 52.0 53.0 53.0 55.0 55.0 44.0 46.0 05:50:33 INFO - PROCESS | 1654 | 05:50:33 INFO - PROCESS | 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:70.60 Median:71.00 stddev:3.34 (4.7%) stddev-sans-first:2.13 05:50:33 INFO - PROCESS | 1654 | Values: 63.0 70.0 74.0 75.0 73.0 69.0 71.0 71.0 69.0 71.0 05:50:33 INFO - PROCESS | 1654 | 05:50:33 INFO - PROCESS | 1654 | [#10] stanford-crypto-aes Cycles:10 Average:93.70 Median:88.00 stddev:10.02 (11.4%) stddev-sans-first:10.54 05:50:33 INFO - PROCESS | 1654 | Values: 90.0 88.0 88.0 88.0 98.0 106.0 107.0 74.0 100.0 98.0 05:50:33 INFO - PROCESS | 1654 | 05:50:33 INFO - PROCESS | 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:161.60 Median:160.00 stddev:18.08 (11.3%) stddev-sans-first:18.76 05:50:33 INFO - PROCESS | 1654 | Values: 151.0 149.0 149.0 152.0 158.0 162.0 149.0 164.0 207.0 175.0 05:50:33 INFO - PROCESS | 1654 | 05:50:33 INFO - PROCESS | 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.70 Median:174.00 stddev:2.63 (1.5%) stddev-sans-first:2.28 05:50:33 INFO - PROCESS | 1654 | Values: 178.0 172.0 176.0 174.0 172.0 171.0 174.0 170.0 177.0 173.0 05:50:33 INFO - PROCESS | 1654 | 05:50:33 INFO - PROCESS | 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.60 Median:68.00 stddev:1.35 (2.0%) stddev-sans-first:1.42 05:50:33 INFO - PROCESS | 1654 | Values: 68.0 68.0 69.0 65.0 69.0 69.0 67.0 66.0 67.0 68.0 05:50:33 INFO - PROCESS | 1654 | -------- Summary: end -------- 05:50:33 INFO - PROCESS | 1654 | 05:50:34 INFO - TEST-INFO | 1654: exit 0 05:50:34 INFO - TEST-OK | kraken | took 60577ms 05:50:34 INFO - SUITE-END | took 1181s 05:50:34 INFO - Completed test suite (00:19:41) 05:50:34 INFO - javascript benchmark 05:50:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3266.8366581670916, 3295.3574053059647, 3318.971118953123, 3341.682540158684, 3391.8812841550534, 5570.136628822539, 5629.099344104944, 5629.465200805927, 5748.396418376068, 5781.595288329819, 5692.402297758737, 5709.4290570942885, 5711.628744131628, 5711.7715291388395, 5729.742161602728, 5125.308083408748, 5156.716380599084, 5163.7418129093585, 5191.766370513336, 5201.479852014787, 4287.271163902128, 5227.163653815391, 5238.842834714965, 5238.895222095556, 5245.213218017289, 5134.358205224347, 5218.947810521884, 5224.320838291017, 5232.686038837682, 5251.737413129328, 5253.9474605253845, 5254.8686282842855, 5270.57835373173, 5273.894522109575, 5280.6039547034125, 5060.0, 5077.771500282496, 5089.491050894918, 5215.973920130413, 5234.293370395024, 5608.074667679828, 5657.264555607786, 5677.37548869624, 5699.202111704365, 5746.339170995348, 6249.937500624981, 6269.404406581368, 6269.467095296905, 6284.685765711696, 6291.5281353898645, 6123.598024902948, 6188.752449901998, 6191.102290167901, 6311.558190926637, 6314.526410519229, 4804.25534042224, 5741.942580574183, 5808.506276966421, 5811.302643682785, 5853.502452291603, 6210.720517576629, 6216.968915155486, 6224.813255602339, 6226.968865155736, 6234.875302494015, 4919.2129259318335, 6030.366811484819, 6115.755369785248, 6144.8771024579255, 6146.7233974471255, 6124.330384950791, 6166.136740856284, 6204.627722336584, 6276.592021518587, 6284.43440090385, 6232.002879539252, 6236.719347629368, 6260.499160067184, 6260.812175634738, 6263.874722505616, 6191.473724733358, 6243.8126856194385, 6290.559660823698, 6293.402126797947, 6305.211848518935, 4819.108464933998, 4865.294532292868, 4883.291922671264, 4886.315915771795, 4942.555170034714, 4390.8024138913825, 4410.955890441054, 4416.3596278539435, 4442.178197033559, 4494.16857881293, 5792.073268277056, 5827.271591051119, 5833.183354330397, 5841.9415805842245, 5862.413758624103, 5533.944660553423, 6104.969475152596, 6271.310155882849, 6282.994720844643, 6298.779542715983, 5508.421615730371, 5691.886162276731, 5742.655440673573, 5748.367679555246, 5850.912236316459, 5363.651362661492, 5394.0, 5399.919001214985, 5400.135978243541, 5418.376886658085, 6252.5935814171935, 6270.592411493239, 6277.52918531112, 6282.403171698681, 6288.465480434214, 6107.401224262618, 6126.945200426759, 6132.601380910227, 6266.37336266388, 6278.6546739929745, 6216.44052035319, 6269.059641053696, 6282.371762823864, 6292.056191571301, 6314.084457753692, 6208.627482351073, 6217.440430361289, 6230.532710046674, 6243.094751261133, 6243.656598887105, 6101.913673707429, 6176.598521096114, 6180.227471566054, 6185.6907154641185, 6231.906521402273, 6118.071005858243, 6133.805804406886, 6135.018397056449, 6136.88672321611, 6155.922713525205, 5355.3305836770405, 5949.405059494016, 6001.849953751192, 6100.572959892765, 6115.266168059861, 5724.284464441945, 5786.768529258951, 5840.678762668094, 5910.113482977587, 5964.880702385841, 5231.686098834082, 6076.66578338178, 6127.509799215963, 6136.23297087864, 6158.568900177123, 5751.913721294268, 5893.2044174035955, 5914.526837852875, 5927.911081333697, 5994.490468310242], "value": 5665.573978595607, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [499.31344401448007, 510.9795608175673, 512.7820676212609, 514.1234195696338, 523.502672461162, 29886.551701724464, 38969.61030389695, 40211.99470013251, 40337.59662403375, 41986.0, 41364.379534306936, 41570.792146039304, 41720.99999999993, 41751.37372939401, 41812.027857353576, 9200.815983680306, 9400.952995235048, 9402.482863442508, 9406.717798466058, 9409.012053734363, 8712.695055673054, 9419.717408477722, 9422.623095076224, 9429.81140377192, 9433.386829856072, 9365.11031452011, 9375.5312234388, 9424.198943089845, 9431.24550035999, 9450.527473626325, 9287.024862389488, 9384.483853388061, 9427.85858212124, 9465.384749991228, 9468.621255149787, 9226.58480368385, 9290.0, 9296.628134874596, 9302.953485232598, 9304.674336398193, 39293.41059884104, 39599.40600890989, 39653.80173099116, 39693.206135877124, 39714.404283935764, 3301.900942971715, 3317.5853018372704, 3370.932581348384, 3464.22055037617, 3503.316853213624, 29367.0, 30315.69684303173, 30365.392692146033, 30774.0, 30800.229994250098, 2706.6075419064323, 3168.334649723538, 3232.208109013307, 3315.386653469116, 3344.0302312329395, 469666.0, 471397.0, 471598.0, 471985.0, 472095.0, 32819.3436131276, 33637.0, 57952.71023644939, 58273.0, 58376.0, 34799.826000869834, 34851.12872178216, 35046.0, 35056.298874022374, 35122.0, 13772.449102035805, 13879.653008674662, 13884.236366999912, 13886.02797804144, 13897.79153312701, 11631.47658355376, 11734.0, 11752.059835213162, 11766.352850593299, 11772.411379430994, 7629.771106866692, 7718.5368877866385, 7767.922320776833, 7778.222177782177, 7784.92215077842, 3128.6245650522083, 3188.2029492626843, 3193.9779270633176, 3194.0, 3219.2917558137174, 927.9118483744033, 938.6339327662217, 959.9712008639752, 1032.8192566300868, 1033.7388386168861, 29090.0, 29476.115716528537, 29772.404551909272, 29910.850445747634, 30033.89762716589, 1026.9435181064964, 1036.0209601926258, 1049.58541376156, 1089.760252744395, 1091.6288461922984, 14550.854491455162, 15092.54722358331, 15297.066459370597, 15444.382224711111, 15506.844931550766, 645981.0, 652910.0, 653701.0, 654970.0, 655767.0, 55328.723356383765, 56141.438585614436, 56199.0, 56361.154582681294, 56492.87014259774, 1298.8181654568368, 1337.498438085718, 1345.2197725319095, 1345.2869978911192, 1361.4486133116104, 27994.300142496602, 28811.99158029459, 28951.85524072366, 29577.40845183041, 29629.25926851846, 49945.250821238435, 50295.49704503055, 50342.0, 50354.748226258635, 50431.99136017186, 564343.0, 564972.0, 566707.0, 568006.0, 568212.0, 25682.486350273262, 26012.8699356502, 26038.739612603633, 26113.0, 26128.34679133037, 44361.556384435746, 44537.331940020274, 45091.32363014492, 45763.0, 45993.080138397716, 36772.26455470822, 36801.447978280885, 37090.62909370872, 37891.62108378991, 38030.429543557424, 36298.733114019866, 36967.81516092402, 37376.439353410264, 37555.624443755216, 38168.0, 26448.471030578894, 32480.187995300308, 36902.07744806401, 38310.80844595759, 38568.0, 28703.712962870104, 32582.51126233061, 33633.88237172634, 33676.663233367355, 34263.486047709805, 23139.76860231376, 24502.387440314134, 24644.890979905587, 25209.0, 25320.746792532576], "value": 18138.36829169711, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.02296919326136, 359.67629133779593, 365.0872817955112, 371.8754217337191, 392.75452841973765, 13504.662383440418, 13672.453101875926, 14485.985980981322, 19509.219631214735, 20110.698339524883, 18685.065746712644, 19306.0, 19504.512387190327, 19541.804581954213, 19584.510387240327, 5442.0, 5802.274715660543, 5806.477417032466, 5812.3025236971625, 5824.0390335594575, 4805.903881922377, 5618.353889302721, 5639.774409023634, 5688.658680479164, 5752.338481074688, 5695.686737229451, 5698.943010569903, 5700.857478563028, 5707.686077265748, 5727.513161381287, 5437.864053398657, 5687.772489100431, 5704.372519022904, 5719.313682358102, 5728.312602487728, 4650.441946966352, 4703.012367402849, 4996.1756310208775, 5008.674436161674, 5017.34774479319, 24101.638475422882, 25033.87483062591, 25118.729815931154, 25201.117960871466, 25282.367940801443, 1849.2695385322722, 2239.5520895820737, 2408.7952524035304, 2979.1826985538805, 3024.7882648214636, 6767.864642707118, 6821.283765204632, 14414.99095063346, 15037.248137593077, 15818.525444236691, 1515.4847351900407, 1758.0506526475688, 1760.5774614092654, 1789.123329568502, 1796.5329014456204, 93623.53188234083, 117064.0, 117245.41377293144, 117330.41334793271, 117423.0, 10300.742481438025, 10522.631707890187, 10731.83902241467, 10772.569097236179, 10971.670849874517, 13306.334683265797, 13327.400266988008, 13447.798283025762, 13460.394282257323, 13466.124701894345, 8209.79475513105, 8235.29999950011, 8282.751517454486, 8410.285125764256, 8426.789330266793, 6492.67536623167, 8134.105248422668, 8136.1050284468665, 8178.836423271501, 8308.750737477885, 6362.427381535684, 6371.139896113966, 6432.388923052303, 6508.21901371829, 6596.076549283104, 1729.930802767875, 1776.9022703751418, 1777.70667839805, 1779.181576474836, 1784.2149454240093, 879.2394578689515, 923.5336155241695, 928.1507420709975, 931.1759093202429, 948.4262021477045, 19413.90293048554, 21432.78567214308, 21446.78553214448, 21538.89230553837, 21572.352829415144, 769.3844924060729, 832.7293629570414, 906.2930913887178, 955.4697143085515, 973.1971123822874, 10985.450727463754, 11292.54829806815, 11380.601678941362, 11422.714432139262, 11649.47577359021, 486038.0, 560724.0, 567345.0, 572040.0, 575623.0, 77492.0, 87840.6823897642, 90206.0, 110037.0, 114186.42906785413, 871.2986046232843, 915.3455279475207, 970.4759429907771, 973.3332667123043, 975.3124047546463, 19044.0, 19343.936083515542, 19406.0, 19443.70834437456, 19449.0, 49634.25548616846, 49995.0, 50121.24818127804, 51618.741906290226, 55565.0, 71088.28911710817, 72148.27851721416, 72875.27124728897, 74253.0, 76265.61867190628, 6815.182178138655, 7548.169701332739, 7769.611519423893, 7793.805154871174, 8129.552874591955, 10155.339902906288, 11928.880711192776, 12126.818097728717, 12569.87430125687, 12694.619161425171, 10768.38474228872, 10808.02727754506, 10808.89191108079, 10871.402072886067, 10891.945540272565, 9288.674896378594, 9596.23230141601, 10532.57869685204, 10570.048695617432, 10930.234883558074, 10802.513886875267, 10831.2959657622, 11021.118310535297, 11359.0, 11406.942965285452, 7677.500962437423, 7911.090224624012, 8016.438849280495, 8031.638576263966, 8092.028956525254, 9561.139497445263, 10430.53062612169, 10773.51519181623, 10833.837492437777, 10854.891451085388], "value": 9236.010394547175, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1485.0569888121042, 1493.5967288832016, 1672.2391311953054, 1776.413783451461, 1837.7427160197574, 8076.798080048002, 8496.447730897502, 8504.107068757776, 8514.233718965299, 8518.872216916736, 7346.669399877005, 7654.9617251913805, 7660.693572257117, 7738.729144479944, 7740.032495938008, 2074.9896250518727, 2118.54451292972, 2224.1214720185517, 2306.3772781349026, 2310.364649721323, 2346.5893468643003, 2348.0842471436067, 2351.9647205291935, 2354.1760383865594, 2358.7759162879497, 2242.3385101395047, 2309.642005489152, 2313.2250696016854, 2315.6063469210276, 2322.6167682332393, 2270.818334533242, 2281.2927992322434, 2300.4478925057865, 2305.342977251492, 2308.2613563659634, 2263.4228271790753, 2295.8278129140213, 2320.953580928372, 2326.6161083421216, 2333.428310064028, 8386.035605905277, 8479.703210387668, 8479.745607631781, 8487.02399224091, 8487.278581320596, 5011.448740638527, 5225.0, 5228.058949389108, 5242.97378513105, 5248.580113590903, 3289.3092450585405, 3544.220271540257, 3614.1145419372424, 3621.728370372233, 3632.400653892105, 1025.2976710953021, 1052.273930987616, 1078.4230436716318, 1095.0965453500814, 1098.3519723363254, 10939.56241750337, 10994.90050994902, 11010.229283950046, 11026.062784663312, 11028.062614677761, 4437.667174961891, 4538.88652783683, 4719.669623126417, 4751.667383283137, 4751.976240118846, 9493.382930109521, 9502.524873756285, 9505.952470237604, 9506.524673766422, 9524.333296669167, 5172.689638621695, 5289.180177072483, 5294.100002999499, 5306.257124002643, 5320.627556071116, 4725.125851717443, 4786.1863483207935, 4807.254875494304, 4835.298881662139, 4837.854864354075, 2930.6190195274476, 3633.3096711624707, 3642.5264715586804, 3719.1817800083936, 3884.9611503885167, 1252.9060320476, 1252.9686757831128, 1262.1985039499984, 1269.1433282534254, 1272.7072773262016, 865.8873347748114, 877.4165180155253, 883.1565854608833, 896.233720169259, 923.6074668266094, 4930.260460930817, 5499.387407488529, 5584.692841893735, 5891.911621325684, 5909.172715819789, 892.5537231384308, 993.3295025857517, 1001.8146642871092, 1022.539857064324, 1059.1579694143174, 3550.6981906538235, 3784.962150378516, 3788.431735239681, 3805.5813860475328, 3890.7860067696547, 7727.806804829812, 10247.231457640706, 10394.960503949544, 10414.843777343345, 10416.166706663449, 8528.616212270463, 10331.586736530455, 10368.481575921174, 10371.792564148825, 10383.688489345332, 7473.177950425449, 7821.217878212134, 7978.401619878532, 7986.600669966479, 7997.960265165491, 5679.403662615531, 5797.942020579909, 5809.535237180931, 5900.557458190567, 5915.0, 9265.675701350421, 9371.718848434557, 9444.763880903032, 9510.572024259063, 9542.284328675238, 9583.424994500352, 9712.562934667816, 9713.56288966984, 9756.902430975599, 9847.950760246153, 7153.996880873364, 7311.305425984523, 7312.451566132455, 7322.597257150908, 7349.228331025379, 7702.961485192763, 7993.560354180576, 8026.317762990211, 8038.477498962548, 8188.549629770419, 6495.2855185928565, 6940.548864323803, 7147.714091436284, 7249.710011599688, 7395.926040739524, 7026.648667566499, 7066.858662826818, 7072.5402848814665, 7081.468889833386, 7128.322809333105, 6393.104965304768, 6596.769113081019, 6704.865902682017, 6716.966415167893, 6790.830229244309, 4764.928526072181, 4825.30033145199, 4861.4895435980125, 4863.975680121577, 4996.850094497171, 4815.951840481691, 4851.175300198904, 4939.234418665113, 4986.975065124796, 5007.949920500749], "value": 4449.068533871535, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [271.3974975554269, 282.9886804527819, 295.55814057983304, 301.4558721507679, 316.03924070824695, 5091.872703182422, 5112.8977420451565, 5123.411742359871, 5141.254518094874, 5173.172292433207, 3625.7730619748445, 4762.452317983433, 4777.474477807438, 4933.654644174909, 5132.101882170615, 3116.5169398743174, 3835.791725606439, 3930.724849260553, 3933.5869733677982, 3960.25748670584, 3407.8807241746545, 3900.8244628991765, 3914.0801911550907, 3932.921341573167, 3939.9606003940025, 3653.5615726112906, 3766.736328457009, 3813.599572044923, 3895.2988462076814, 3896.7661940283674, 3531.823408829548, 3884.2814079395403, 3907.902302442433, 3913.4716813230134, 3917.843286268546, 3372.8819491317686, 3387.44107222308, 3399.3371292597953, 3415.8292085395633, 3436.7078798302173, 4300.763458009808, 4728.905421891577, 4957.1248675767965, 4979.15354389751, 4983.6013118950395, 4406.955930440687, 4469.7988590513505, 4475.1944649963, 4494.07871386365, 4512.34570987205, 4469.011289975962, 4927.852164435073, 5089.618278629117, 5356.0, 5379.354477462691, 3309.23887505875, 3459.840953280629, 3486.3741708623, 3785.6403641653997, 3790.2040571480024, 4616.760870977607, 4803.159447096744, 5077.593792496591, 5405.432414606341, 5452.6183167178315, 3576.8390422431053, 3725.776453412804, 3728.850845966131, 3730.813459327077, 3731.869384571527, 3497.845710915392, 3744.625537446288, 3756.417755247941, 3775.9433608495897, 3784.6972242220554, 4066.861278841929, 4195.8181745492175, 4325.8269669213505, 4351.760653164107, 4361.825526978949, 4838.564529192319, 5386.29978102852, 5400.758685145699, 5403.486668766461, 5413.675179489164, 1091.7608514336287, 1207.9033677305795, 1225.957091501811, 1229.6987238126537, 1246.4515613130193, 673.0778832998823, 777.3819813248481, 800.4116974024088, 802.8916096327038, 826.5454000299811, 4702.694324868872, 4717.386739723883, 4731.29030645406, 4746.863911239956, 4795.544423279783, 4664.323673067386, 4985.130576033987, 5141.871453213699, 5147.742612869341, 5156.149235376196, 4095.9180816383505, 4368.475782906008, 4719.0, 4726.565818603422, 4730.290456431494, 2302.551002554519, 2497.8126640501673, 2710.035224462249, 2734.767544758678, 2735.2478068530913, 4737.715737055788, 4854.514548545188, 5143.971205758863, 5310.636766638809, 5377.462253774591, 4664.766761661904, 4995.375578052744, 5025.135672666618, 5170.663906846043, 5253.4221235664045, 4711.95288047122, 4929.827456039022, 5161.954446876286, 5176.352955880515, 5347.839564813062, 4506.994387497532, 4969.403671559436, 5116.565091967075, 5117.616178786531, 5197.244521263002, 4667.8330417395655, 4706.694064885772, 4806.0, 5060.608332708534, 5091.490850914879, 4628.051249493778, 5237.397699264633, 5304.9734751326, 5343.278657381204, 5363.329583802025, 3174.8095114293214, 3216.6622504636675, 3513.8594456221463, 3527.7177825773365, 3529.347070791912, 4371.672124590605, 4625.779625779577, 4747.691400058985, 5088.313077734607, 5160.48395160496, 4098.590140985997, 4904.719305522949, 5014.6991180529285, 5038.2190760432195, 5043.604287963188, 4351.325544540601, 4633.907321853611, 4992.500749924979, 5007.349044624176, 5048.3941926969, 4311.741295522279, 4402.427684401007, 4836.758162091811, 4892.4373697023875, 4903.436104847988, 4414.889627759332, 5095.108356037605, 5269.78920843162, 5318.7074710891275, 5349.732513374238, 5304.920426193533, 5323.840284791462, 5332.840014799563, 5337.839864804062, 5346.438623944586], "value": 3778.832452923223, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [585.5257241634276, 672.398203607771, 739.3419856327872, 770.6339488742848, 780.562502175134, 11294.830577541334, 14421.990460667745, 14444.855551444496, 14667.339969701363, 14698.706025879503, 13220.669483262924, 13327.46690132396, 13432.462701491928, 13467.989900757459, 13535.52625658102, 647.8607099473612, 669.2504395077495, 670.2392784189931, 790.5217343507181, 812.2283830361166, 831.6382373667474, 832.7127141136298, 836.861917783565, 840.0633293877344, 841.2680967558233, 858.2833334165979, 883.7923088074298, 886.175856453498, 887.7203680840514, 890.977725556863, 675.4427597232303, 697.8674051930117, 879.6217626420637, 879.6349514951255, 883.425773247388, 592.8617055253923, 601.1343665122254, 621.8445388652837, 648.4941745438565, 648.6302807399784, 13950.860491395058, 14141.717165656732, 14158.0, 14170.362333694906, 14194.645133871736, 12937.223766574036, 13055.347232638425, 13165.078444508792, 13292.534761283405, 13434.462621495128, 11004.284721493077, 11231.10151187903, 12174.756504869942, 12211.0, 12298.631041068782, 4269.786510674485, 4276.679249056333, 4317.071829556629, 4321.200577893069, 4409.36064270679, 90295.54852225762, 90536.0, 91809.54095229547, 92480.53759731226, 93038.0, 10578.735531611617, 10618.256722029384, 10619.84070238947, 10722.946385268024, 10939.726506837234, 20340.389788306373, 20452.0796564152, 20493.2827351042, 20525.38423847287, 20813.1674733012, 6117.357677443804, 7409.333160015624, 7445.404367650575, 7543.622818859145, 7592.8861067082935, 4047.433359329696, 4164.104717485755, 4189.581041895847, 4193.454850869368, 4210.726302790309, 3255.983720081432, 3360.0, 3365.865365385357, 3595.9280814383565, 3804.2011177653003, 570.968596727184, 676.0670275020539, 684.3259389501337, 690.9205441374206, 693.9236683964759, 576.3573615418861, 647.6276141218724, 654.9508786841006, 660.2688662410325, 675.7330854312519, 8063.798405039804, 8201.810737443157, 9079.273658107335, 9106.590203440861, 9156.816863662689, 469.41557760588285, 577.3158806813939, 582.6445868020504, 624.2914292278206, 664.3124366280877, 4180.0, 4460.799264033061, 4465.955340446554, 4481.731096134177, 4526.185286648369, 114459.0, 116664.41667791607, 117128.0, 118669.40665296618, 118923.0, 80719.789203162, 82637.58681206676, 83100.75348869772, 101401.0, 102070.0, 64902.67548662226, 87649.0, 89846.10153898508, 91358.0, 91985.0, 7660.846783064419, 8507.447015943944, 8663.610137543952, 8668.0, 8783.736487905373, 41704.16591668089, 52918.94162116813, 53796.5096806119, 56248.718756407594, 57165.0, 66687.0, 66790.0, 67556.0, 73110.63444682742, 77344.0, 12522.561710340095, 12630.179038362478, 12642.87357126417, 13013.414396351998, 13075.803862941873, 13915.652108696959, 14051.789223161457, 14318.14091154534, 14431.42274309058, 14440.855591443951, 11554.075673945896, 12743.87256127427, 13132.606021819362, 13241.006924480509, 13305.068645194744, 13124.409401577142, 13203.801942970671, 13268.203907765565, 13523.459061637424, 13690.31548422555, 12669.113162078565, 12752.043596730096, 12920.870791291967, 13420.261885596386, 13465.528706495226, 10086.445245511568, 10678.73303167427, 10847.620333288298, 11021.559137634405, 11024.669259922215, 10133.594656213669, 11125.054370378608, 11239.09006960494, 11343.602973895873, 11348.659540213806], "value": 7243.311538036206, "unit": "score"}], "name": "dromaeo_css", "value": 6979.476781985893, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 113.0, 113.0, 115.0, 115.0, 114.0, 115.0, 115.0], "value": 114.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 112.0, 114.0, 152.0, 111.0, 111.0, 112.0, 111.0, 142.0, 111.0], "value": 122.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 182.0, 182.0, 187.0, 183.0, 185.0, 184.0, 183.0, 181.0, 184.0], "value": 183.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 59.0, 58.0, 88.0, 61.0, 59.0, 57.0, 59.0, 85.0, 58.0], "value": 66.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 70.0, 95.0, 71.0, 89.0, 100.0, 80.0, 98.0, 100.0, 126.0], "value": 91.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 88.0, 84.0, 87.0, 84.0, 88.0, 84.0, 88.0, 84.0], "value": 85.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 94.0, 95.0, 94.0, 95.0, 95.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 86.0, 88.0, 87.0, 85.0, 85.0, 84.0, 88.0, 87.0], "value": 86.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 54.0, 52.0, 53.0, 53.0, 55.0, 55.0, 44.0, 46.0], "value": 52.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 70.0, 74.0, 75.0, 73.0, 69.0, 71.0, 71.0, 69.0, 71.0], "value": 70.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 88.0, 88.0, 98.0, 106.0, 107.0, 74.0, 100.0, 98.0], "value": 93.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 149.0, 149.0, 152.0, 158.0, 162.0, 149.0, 164.0, 207.0, 175.0], "value": 161.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 172.0, 176.0, 174.0, 172.0, 171.0, 174.0, 170.0, 177.0, 173.0], "value": 173.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 69.0, 65.0, 69.0, 69.0, 67.0, 66.0, 67.0, 68.0], "value": 67.6, "unit": "score"}], "name": "kraken", "value": 1463.6, "alertThreshold": 2.0}]} 05:50:34 INFO - Return code: 0 05:50:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:50:34 INFO - # TBPL SUCCESS # 05:50:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:50:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:50:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:50:34 INFO - 2016-11-19 05:50:34.659 screenresolution[1658:11230] starting screenresolution argv=screenresolution get 05:50:34 INFO - 2016-11-19 05:50:34.662 screenresolution[1658:11230] Display 0: 1600x1200x32@60 05:50:34 INFO - 2016-11-19 05:50:34.668 screenresolution[1659:11233] starting screenresolution argv=screenresolution list 05:50:34 INFO - Available Modes on Display 0 05:50:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:50:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:50:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:50:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:50:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:50:34 INFO - Intel Iris: 05:50:34 INFO - Chipset Model: Intel Iris 05:50:34 INFO - Type: GPU 05:50:34 INFO - Bus: Built-In 05:50:34 INFO - VRAM (Dynamic, Max): 1536 MB 05:50:34 INFO - Vendor: Intel (0x8086) 05:50:34 INFO - Device ID: 0x0a2e 05:50:34 INFO - Revision ID: 0x0009 05:50:34 INFO - Displays: 05:50:34 INFO - Display: 05:50:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:50:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:50:34 INFO - Display Serial Number: 1600x1200 60 05:50:34 INFO - Main Display: Yes 05:50:34 INFO - Mirror: Off 05:50:34 INFO - Online: Yes 05:50:34 INFO - Rotation: Supported 05:50:34 INFO - Return code: 0 05:50:34 INFO - Running post-action listener: _resource_record_post_action 05:50:34 INFO - [mozharness: 2016-11-19 13:50:34.919929Z] Finished run-tests step (success) 05:50:34 INFO - Running post-run listener: _resource_record_post_run 05:50:34 INFO - Total resource usage - Wall time: 1215s; CPU: 54.0%; Read bytes: 33217536; Write bytes: 695044608; Read time: 313; Write time: 1993 05:50:34 INFO - TinderboxPrint: CPU usage
53.8% 05:50:34 INFO - TinderboxPrint: I/O read bytes / time
33,217,536 / 313 05:50:34 INFO - TinderboxPrint: I/O write bytes / time
695,044,608 / 1,993 05:50:34 INFO - TinderboxPrint: CPU idle
2,247.9 (46.2%) 05:50:34 INFO - TinderboxPrint: CPU system
975.0 (20.1%) 05:50:34 INFO - TinderboxPrint: CPU user
1,638.8 (33.7%) 05:50:34 INFO - TinderboxPrint: Swap in / out
412,475,392 / 0 05:50:34 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 362779136; Write bytes: 356746752; Read time: 27580; Write time: 735 05:50:34 INFO - run-tests - Wall time: 1183s; CPU: 54.0%; Read bytes: 32283648; Write bytes: 308958208; Read time: 299; Write time: 1192 05:50:35 INFO - Running post-run listener: _upload_blobber_files 05:50:35 INFO - Blob upload gear active. 05:50:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:50:35 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 05:50:35 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'] 05:50:35 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 05:50:35 INFO - (blobuploader) - INFO - Open directory for files ... 05:50:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:50:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:50:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:50:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:50:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:50:36 INFO - (blobuploader) - INFO - Done attempting. 05:50:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:50:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:50:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:50:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:50:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:50:38 INFO - (blobuploader) - INFO - Done attempting. 05:50:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:50:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:50:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:50:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:50:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:50:40 INFO - (blobuploader) - INFO - Done attempting. 05:50:40 INFO - (blobuploader) - INFO - Iteration through files over. 05:50:40 INFO - Return code: 0 05:50:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:50:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:50:40 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d07b39b6971bea45cd64b5fbacf5facb6c398d6bdcd1b29f7229e91d87e8235a4040e8fd3b4b1b978cba03e114ef947a08ef4ea85c4441294dd24853bab247b0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d71a90c4e54f675fd7a6690450b69e8b7bfdbd3d700d3b77e7ef90dae49a4f9abb90445fd02618e8aa6c9d78a1e510e2d982a9b46abd4acdbcab3458c254550", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/97f751b8187702205452b16139c705cb723f4999f0c25d11441e13814524c117bfb53d009633ee5f1bc8aecc5d54788712cc80a666e71ab82850af3e8fb4214a"} 05:50:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:50:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:50:40 INFO - Contents: 05:50:40 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d07b39b6971bea45cd64b5fbacf5facb6c398d6bdcd1b29f7229e91d87e8235a4040e8fd3b4b1b978cba03e114ef947a08ef4ea85c4441294dd24853bab247b0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d71a90c4e54f675fd7a6690450b69e8b7bfdbd3d700d3b77e7ef90dae49a4f9abb90445fd02618e8aa6c9d78a1e510e2d982a9b46abd4acdbcab3458c254550", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/97f751b8187702205452b16139c705cb723f4999f0c25d11441e13814524c117bfb53d009633ee5f1bc8aecc5d54788712cc80a666e71ab82850af3e8fb4214a"} 05:50:40 INFO - Running post-run listener: copy_logs_to_upload_dir 05:50:40 INFO - Copying logs to upload dir... 05:50:40 INFO - mkdir: /builds/slave/test/build/upload/logs 05:50:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1278.033164 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-11-19 05:50:40.414478) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-19 05:50:40.418635) ========= 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.lCDUoNCnjL/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.RcYrPR7qVL/Listeners TMPDIR=/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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/d07b39b6971bea45cd64b5fbacf5facb6c398d6bdcd1b29f7229e91d87e8235a4040e8fd3b4b1b978cba03e114ef947a08ef4ea85c4441294dd24853bab247b0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d71a90c4e54f675fd7a6690450b69e8b7bfdbd3d700d3b77e7ef90dae49a4f9abb90445fd02618e8aa6c9d78a1e510e2d982a9b46abd4acdbcab3458c254550", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/97f751b8187702205452b16139c705cb723f4999f0c25d11441e13814524c117bfb53d009633ee5f1bc8aecc5d54788712cc80a666e71ab82850af3e8fb4214a"} build_url:https://queue.taskcluster.net/v1/task/SuUx8_XzTliUCQraHjx8LQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010998 build_url: 'https://queue.taskcluster.net/v1/task/SuUx8_XzTliUCQraHjx8LQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d07b39b6971bea45cd64b5fbacf5facb6c398d6bdcd1b29f7229e91d87e8235a4040e8fd3b4b1b978cba03e114ef947a08ef4ea85c4441294dd24853bab247b0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d71a90c4e54f675fd7a6690450b69e8b7bfdbd3d700d3b77e7ef90dae49a4f9abb90445fd02618e8aa6c9d78a1e510e2d982a9b46abd4acdbcab3458c254550", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/97f751b8187702205452b16139c705cb723f4999f0c25d11441e13814524c117bfb53d009633ee5f1bc8aecc5d54788712cc80a666e71ab82850af3e8fb4214a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-19 05:50:40.454149) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-19 05:50:40.454688) ========= 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.lCDUoNCnjL/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.RcYrPR7qVL/Listeners TMPDIR=/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/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.005203 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-19 05:50:40.505333) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-19 05:50:40.505828) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-19 05:50:40.506361) ========= ========= Total master_lag: 0.19 =========